Hallo Zusammen,
heute ab 16:28 war es mal wieder soweit. Es tauchten etliche Freezes meines FHEM Servers auf, welche das gesamte System lahmlegten.
2014.11.24 16:28:19 1: Perfmon: possible freeze starting at 16:28:08, delay is 11.96
2014.11.24 16:28:22 1: Perfmon: possible freeze starting at 16:28:21, delay is 1.121
2014.11.24 16:28:24 1: Perfmon: possible freeze starting at 16:28:23, delay is 1.086
2014.11.24 16:28:25 1: HMLAN_Parse: HMLAN1 new condition timeout
2014.11.24 16:28:25 1: 192.168.77.27:1000 disconnected, waiting to reappear (HMLAN1)
2014.11.24 16:28:25 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.11.24 16:28:26 2: di_hmlanonoff: set Pushover1 msg 'HMLAN1' 'disconnected' ''0'': OK
2014.11.24 16:29:58 1: Perfmon: possible freeze starting at 16:29:31, delay is 27.55
2014.11.24 16:30:06 1: Perfmon: possible freeze starting at 16:30:01, delay is 5.107
2014.11.24 16:30:38 1: Perfmon: possible freeze starting at 16:30:11, delay is 27.996
2014.11.24 16:32:11 3: CUL_HM set SW_HALLEKUGEL on
2014.11.24 16:32:11 3: CUL_HM set SW_HALLEKUGEL on
2014.11.24 16:33:07 1: Perfmon: possible freeze starting at 16:33:02, delay is 5.116
2014.11.24 16:33:58 1: Perfmon: possible freeze starting at 16:33:31, delay is 27.551
2014.11.24 16:34:38 1: Perfmon: possible freeze starting at 16:34:11, delay is 27.946
2014.11.24 16:35:01 1: Perfmon: possible freeze starting at 16:34:51, delay is 10.15
2014.11.24 16:35:04 3: CUL_HM set LS13_Sw on-for-timer 3000
2014.11.24 16:35:30 1: Perfmon: possible freeze starting at 16:35:08, delay is 22.659
2014.11.24 16:36:00 1: Perfmon: possible freeze starting at 16:35:59, delay is 1.215
2014.11.24 16:36:30 1: Perfmon: possible freeze starting at 16:36:07, delay is 23.543
2014.11.24 16:36:50 1: Perfmon: possible freeze starting at 16:36:41, delay is 9.279
2014.11.24 16:37:00 1: Perfmon: possible freeze starting at 16:36:51, delay is 9.607
2014.11.24 16:37:07 1: Perfmon: possible freeze starting at 16:37:02, delay is 5.116
2014.11.24 16:37:07 2: FLUKSO FluksoStromS0: ParseSensorResponse.263 Error while requesting http://192.168.77.39:8080/sensor/aa021f6ed32bbce3c3815588b76014c4?version=1.0&interval=minute&unit=watt&jsonp_callback=realtime - read from http://192.168.77.39:8080 timed out
2014.11.24 16:37:07 1: PERL WARNING: Exiting subroutine via last at ./FHEM/23_FLUKSO.pm line 265.
2014.11.24 16:37:20 1: Perfmon: possible freeze starting at 16:37:11, delay is 9.55
2014.11.24 16:37:50 1: Perfmon: possible freeze starting at 16:37:41, delay is 9.608
2014.11.24 16:38:07 3: CUL_HM set SW_KELLERVORN on
2014.11.24 16:38:07 3: CUL_HM set LS11_Sw on-for-timer 600
2014.11.24 16:38:08 3: CUL_HM set PCB1_HEIZUNG2 on-for-timer 0.75
2014.11.24 16:38:10 1: Perfmon: possible freeze starting at 16:38:07, delay is 3.63
2014.11.24 16:38:30 1: Perfmon: possible freeze starting at 16:38:21, delay is 9.619
2014.11.24 16:39:30 1: Perfmon: possible freeze starting at 16:39:08, delay is 22.647
2014.11.24 16:39:30 3: CUL_HM set LS13_Sw on-for-timer 3000
2014.11.24 16:40:06 1: Perfmon: possible freeze starting at 16:40:01, delay is 5.113
2014.11.24 16:41:01 1: Perfmon: possible freeze starting at 16:40:51, delay is 10.235
2014.11.24 16:41:07 1: Perfmon: possible freeze starting at 16:41:03, delay is 4.141
Danach habe ich verbose=5 zugeschalten und weiter nach Freezes im Log gesucht, wie z.B.:
2014.11.24 16:44:06 5: FBAHA/RAW: /0703001c00000040001600000000000c0000000f00040000000000000703002000000040001600000000001000000017000800000000009d000000000703001c00000040001600000000000c0000001300040000000376eb0703001c00000040001600000000000c0000001200040000000000000703001c00000040001600000000000c00000015000400000000d6f30703001c00000040001600000000000c000000160004000000000000
2014.11.24 16:44:06 5: fb7490 dispatch 0703001c00000040001600000000000c0000000f0004000000000000
2014.11.24 16:44:06 4: Payload: 0000000f0004000000000000 -> state: off
2014.11.24 16:44:06 5: FBDECT_Parse for device FBDECT_22 done
2014.11.24 16:44:06 1: Perfmon: possible freeze starting at 16:44:01, delay is 5.096
2014.11.24 16:55:30 5: FBAHA/RAW: /0703001c00000040001100000000000c0000000f0004000000000001070300200000004000110000000000100000001700080000000000f1000000000703001c00000040001100000000000c000000130004000000037c870703001c00000040001100000000000c00000012000400000000025f0703001c00000040001100000000000c00000015000400000002285a0703001c00000040001100000000000c00000016000400000000010f
2014.11.24 16:55:30 5: fb7490 dispatch 0703001c00000040001100000000000c0000000f0004000000000001
2014.11.24 16:55:30 4: Payload: 0000000f0004000000000001 -> state: on
2014.11.24 16:55:30 5: FBDECT_Parse for device FBDECT_17 done
2014.11.24 16:55:30 5: fb7490 dispatch 070300200000004000110000000000100000001700080000000000f100000000
2014.11.24 16:55:30 4: Payload: 0000001700080000000000f100000000 -> temperature: 24.1 C (measured)
2014.11.24 16:55:30 5: FBDECT_Parse for device FBDECT_17 done
2014.11.24 16:55:30 5: fb7490 dispatch 0703001c00000040001100000000000c000000130004000000037c87
2014.11.24 16:55:30 4: Payload: 000000130004000000037c87 -> voltage: 228.487 V
2014.11.24 16:55:30 5: FBDECT_Parse for device FBDECT_17 done
2014.11.24 16:55:30 5: Triggering FBDECT_17 (1 changes)
2014.11.24 16:55:30 5: Notify loop for FBDECT_17 voltage: 228.487 V
2014.11.24 16:55:30 1: Perfmon: possible freeze starting at 16:55:08, delay is 22.357
2014.11.24 17:06:05 5: HMLAN/RAW: /E251D9A,0000,0822CA82,FF,FFBC,4E845E251D9A0000008010F3000000000008CCFF
2014.11.24 17:06:05 5: HMLAN_Parse: HMLAN1 R:E251D9A stat:0000 t:0822CA82 d:FF r:FFBC m:4E 845E 251D9A 000000 8010F3000000000008CCFF
2014.11.24 17:06:05 5: HMLAN1 dispatch A144E845E251D9A0000008010F3000000000008CCFF::-68:HMLAN1
2014.11.24 17:06:05 5: Triggering LS9_KTHERMO_Pwr (1 changes)
2014.11.24 17:06:05 5: Notify loop for LS9_KTHERMO_Pwr eState: E: 433.9 P: 0 I: 0 U: 225.2 f: 49.99
2014.11.24 17:06:05 5: Triggering LS9_KTHERMO_SenF (1 changes)
2014.11.24 17:06:05 5: Notify loop for LS9_KTHERMO_SenF 49.99
2014.11.24 17:06:05 5: Triggering LS9_KTHERMO_SenU (1 changes)
2014.11.24 17:06:05 5: Notify loop for LS9_KTHERMO_SenU 225.2
2014.11.24 17:06:05 1: Perfmon: possible freeze starting at 17:06:03, delay is 2.282
Den Aktor SW_DACH wollte ich dann testhalber anschalten, was nicht mehr funktionierte. Es kam lediglich ein IOerr.
(https://lh4.googleusercontent.com/-JF2V0kzl-Zo/VHNmV_IJvbI/AAAAAAAAT2U/x4oDnUGLKk8/s640/2014-11-24_17h44_35.png)
2014.11.24 17:07:38 3: CUL_HM set SW_DACH on
2014.11.24 17:07:39 3: CUL_HM set SW_DACH on
2014.11.24 17:07:39 5: HMLAN/RAW: /E2D6611,0000,08243C55,FF,FFB0,6184702D661100000000BE43
2014.11.24 17:07:39 5: HMLAN_Parse: HMLAN1 R:E2D6611 stat:0000 t:08243C55 d:FF r:FFB0 m:61 8470 2D6611 000000 00BE43
2014.11.24 17:07:39 5: HMLAN1 dispatch A0C6184702D661100000000BE43::-80:HMLAN1
2014.11.24 17:07:41 3: CUL_HM set SW_DACH on
2014.11.24 17:07:41 5: HMLAN/RAW: /E22B1E5,0000,0824438E,FF,FFBF,0E861022B1E50000000AA8D80D3919
2014.11.24 17:07:41 5: HMLAN_Parse: HMLAN1 R:E22B1E5 stat:0000 t:0824438E d:FF r:FFBF m:0E 8610 22B1E5 000000 0AA8D80D3919
2014.11.24 17:07:41 5: HMLAN1 dispatch A0F0E861022B1E50000000AA8D80D3919::-65:HMLAN1
2014.11.24 17:07:41 5: Triggering HEIZ_ELTWOHN (1 changes)
2014.11.24 17:07:41 5: Notify loop for HEIZ_ELTWOHN measured-temp: 21.6
2014.11.24 17:07:41 5: Triggering HEIZ_ELTWOHN_Clima (2 changes)
2014.11.24 17:07:41 5: Notify loop for HEIZ_ELTWOHN_Clima measured-temp: 21.6
2014.11.24 17:07:41 5: Triggering HEIZ_ELTWOHN_Weather (2 changes)
2014.11.24 17:07:41 5: Notify loop for HEIZ_ELTWOHN_Weather measured-temp: 21.6
2014.11.24 17:07:41 3: CUL_HM set SW_DACH on
2014.11.24 17:07:42 5: HMLAN/RAW: /E25526D,0000,08244624,FF,FFD2,AE861025526D0000000A84A70E0818
Danach wollte ich bei einigen Aktoren einen statusRequest anfordern, was auch nicht mehr ging.
2014.11.24 17:09:36 5: Cmd: >set SW_HALLE2 statusRequest<
2014.11.24 17:09:36 5: CUL_HM SW_HALLE2 protEvent:CMDs_pending pending:1
2014.11.24 17:09:36 3: CUL_HM set SW_HALLE2 statusRequest
2014.11.24 17:09:36 5: HMLAN_Send: HMLAN1 S:SE290BD44 stat: 00 t:00000000 d:01 r:E290BD44 m:5B A001 272EA5 20CA59 010E
2014.11.24 17:09:36 5: CUL_HM SW_HALLE2 protEvent:CMDs_processing... pending:0
2014.11.24 17:09:36 4: /fhem?XHR=1&cmd.SW_HALLE2=set%20SW_HALLE2%20statusRequest&room=Favourites / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2014.11.24 17:09:36 5: HMLAN/RAW: /E20CA59,0000,08260534,FF,FFBE,5BA41020CA59272EA50601000042
2014.11.24 17:09:36 5: HMLAN_Parse: HMLAN1 R:E20CA59 stat:0000 t:08260534 d:FF r:FFBE m:5B A410 20CA59 272EA5 0601000042
2014.11.24 17:09:36 5: HMLAN1 dispatch A0E5BA41020CA59272EA50601000042::-66:HMLAN1
2014.11.24 17:09:36 5: HMLAN: Skip ACK
2014.11.24 17:09:36 5: CUL_HM SW_HALLE2 protEvent:CMDs_done
2014.11.24 17:09:36 5: CUL_HM SW_HALLE2 sent ACK:2
2014.11.24 17:09:36 5: HMLAN/RAW: /RE290BD44,0001,08260539,FF,FFBE,5BA41020CA59272EA50601000042
2014.11.24 17:09:36 5: HMLAN_Parse: HMLAN1 R:RE290BD44 stat:0001 t:08260539 d:FF r:FFBE m:5B A410 20CA59 272EA5 0601000042
2014.11.24 17:09:36 5: HMLAN1 dispatch A0E5BA41020CA59272EA50601000042::-66:HMLAN1
2014.11.24 17:09:36 4: CUL_HM SW_HALLE2 dupe: dont process
2014.11.24 17:09:37 4: HTTP FHEMWEB:192.168.77.25:5871 GET /fhem?XHR=1&cmd.SW_HALLE1=set%20SW_HALLE1%20statusRequest&room=Favourites
2014.11.24 17:09:37 5: Cmd: >set SW_HALLE1 statusRequest<
2014.11.24 17:09:37 5: CUL_HM SW_HALLE1 protEvent:CMDs_pending pending:1
2014.11.24 17:09:37 3: CUL_HM set SW_HALLE1 statusRequest
2014.11.24 17:09:37 5: HMLAN_Send: HMLAN1 S:+20CA43,00,01,00
2014.11.24 17:09:37 5: HMLAN_Send: HMLAN1 S:SE290BFA9 stat: 00 t:00000000 d:01 r:E290BFA9 m:5C A001 272EA5 20CA43 010E
2014.11.24 17:09:37 5: CUL_HM SW_HALLE1 protEvent:CMDs_processing... pending:0
2014.11.24 17:09:37 4: /fhem?XHR=1&cmd.SW_HALLE1=set%20SW_HALLE1%20statusRequest&room=Favourites / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2014.11.24 17:09:37 5: HMLAN/RAW: /E20CA43,0000,08260799,FF,FFBB,5CA41020CA43272EA50601000045
2014.11.24 17:09:37 5: HMLAN_Parse: HMLAN1 R:E20CA43 stat:0000 t:08260799 d:FF r:FFBB m:5C A410 20CA43 272EA5 0601000045
2014.11.24 17:09:37 5: HMLAN1 dispatch A0E5CA41020CA43272EA50601000045::-69:HMLAN1
2014.11.24 17:09:37 5: HMLAN: Skip ACK
2014.11.24 17:09:37 5: CUL_HM SW_HALLE1 protEvent:CMDs_done
2014.11.24 17:09:37 5: CUL_HM SW_HALLE1 sent ACK:2
2014.11.24 17:09:37 5: Triggering SW_HALLE1 (1 changes)
2014.11.24 17:09:37 5: Notify loop for SW_HALLE1 deviceMsg: off (to vccu)
2014.11.24 17:09:37 5: HMLAN/RAW: /E270504,0000,082607CB,FF,FFBA,5D8470270504000000009E4F
2014.11.24 17:09:37 5: HMLAN_Parse: HMLAN1 R:E270504 stat:0000 t:082607CB d:FF r:FFBA m:5D 8470 270504 000000 009E4F
2014.11.24 17:09:37 5: HMLAN1 dispatch A0C5D8470270504000000009E4F::-70:HMLAN1
2014.11.24 17:09:37 5: HMLAN/RAW: /RE290BFA9,0001,0826079E,FF,FFBB,5CA41020CA43272EA50601000045
2014.11.24 17:09:37 5: HMLAN_Parse: HMLAN1 R:RE290BFA9 stat:0001 t:0826079E d:FF r:FFBB m:5C A410 20CA43 272EA5 0601000045
2014.11.24 17:09:37 5: HMLAN1 dispatch A0E5CA41020CA43272EA50601000045::-69:HMLAN1
2014.11.24 17:09:37 4: CUL_HM SW_HALLE1 dupe: dont process
2014.11.24 17:09:38 4: HTTP FHEMWEB:192.168.77.25:5871 GET /fhem?XHR=1&cmd.SW_DACH=set%20SW_DACH%20statusRequest&room=Favourites
2014.11.24 17:09:38 5: Cmd: >set SW_DACH statusRequest<
2014.11.24 17:09:38 5: CUL_HM SW_DACH protEvent:CMDs_pending pending:1
2014.11.24 17:09:38 3: CUL_HM set SW_DACH statusRequest
2014.11.24 17:09:38 5: HMLAN_Send: HMLAN1 S:SE290C365 stat: 00 t:00000000 d:01 r:E290C365 m:5D A001 272EA5 20C58C 010E
2014.11.24 17:09:38 5: CUL_HM SW_DACH protEvent:CMDs_processing... pending:0
2014.11.24 17:09:38 4: /fhem?XHR=1&cmd.SW_DACH=set%20SW_DACH%20statusRequest&room=Favourites / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2014.11.24 17:09:38 5: HMLAN/RAW: /E20C58C,0000,08260B54,FF,FFD9,5DA41020C58C272EA5060100002A
2014.11.24 17:09:38 5: HMLAN_Parse: HMLAN1 R:E20C58C stat:0000 t:08260B54 d:FF r:FFD9 m:5D A410 20C58C 272EA5 060100002A
2014.11.24 17:09:38 5: HMLAN1 dispatch A0E5DA41020C58C272EA5060100002A::-39:HMLAN1
2014.11.24 17:09:38 5: HMLAN: Skip ACK
2014.11.24 17:09:38 5: CUL_HM SW_DACH protEvent:CMDs_done
2014.11.24 17:09:38 5: CUL_HM SW_DACH sent ACK:2
2014.11.24 17:09:38 5: HMLAN/RAW: /RE290C365,0001,08260B59,FF,FFD9,5DA41020C58C272EA5060100002A
2014.11.24 17:09:38 5: HMLAN_Parse: HMLAN1 R:RE290C365 stat:0001 t:08260B59 d:FF r:FFD9 m:5D A410 20C58C 272EA5 060100002A
2014.11.24 17:09:38 5: HMLAN1 dispatch A0E5DA41020C58C272EA5060100002A::-39:HMLAN1
2014.11.24 17:09:38 4: CUL_HM SW_DACH dupe: dont process
2014.11.24 17:09:38 4: HTTP FHEMWEB:192.168.77.25:5871 GET /fhem?XHR=1&cmd.LSW_TERRASSENSPOTS=set%20LSW_TERRASSENSPOTS%20statusRequest&room=Favourites
2014.11.24 17:09:38 5: Cmd: >set LSW_TERRASSENSPOTS statusRequest<
2014.11.24 17:09:38 5: CUL_HM LSW_TERRASSENSPOTS protEvent:CMDs_pending pending:1
2014.11.24 17:09:38 3: CUL_HM set LSW_TERRASSENSPOTS statusRequest
2014.11.24 17:09:38 5: HMLAN_Send: HMLAN1 S:SE290C5B9 stat: 00 t:00000000 d:01 r:E290C5B9 m:5E A001 272EA5 29F2A6 010E
2014.11.24 17:09:38 5: CUL_HM LSW_TERRASSENSPOTS protEvent:CMDs_processing... pending:0
2014.11.24 17:09:38 4: /fhem?XHR=1&cmd.LSW_TERRASSENSPOTS=set%20LSW_TERRASSENSPOTS%20statusRequest&room=Favourites / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2014.11.24 17:09:38 5: HMLAN/RAW: /E29F2A6,0000,08260DA8,FF,FFB5,5EA41029F2A6272EA5060100004B
Mein FHEM System ist soweit aktuell:
# $Id: fhem.pl 6913 2014-11-08 10:32:44Z rudolfkoenig $
# $Id: 10_CUL_HM.pm 7047 2014-11-23 09:52:33Z martinp876 $
# $Id: 70_EGPM.pm 5344 2014-03-27 20:06:31Z alexus2033 $
# $Id: 17_EGPM2LAN.pm 5344 2014-03-27 20:06:31Z alexus2033 $
# $Id: 00_FBAHA.pm 6771 2014-10-15 17:59:23Z rudolfkoenig $
# $Id: 10_FBDECT.pm 6771 2014-10-15 17:59:23Z rudolfkoenig $
# $Id: 72_FB_CALLMONITOR.pm 6852 2014-11-01 17:23:29Z markusbloch $
# $Id: 01_FHEMWEB.pm 7024 2014-11-19 23:07:39Z rudolfkoenig $
# $Id: 92_FileLog.pm 7000 2014-11-16 16:51:50Z rudolfkoenig $
# $Id: 59_HCS.pm 4433 2013-12-21 05:30:49Z tobiasfaust $
# $Id: 00_HMLAN.pm 6471 2014-08-27 12:32:38Z martinp876 $
# $Id: 98_HMinfo.pm 7048 2014-11-23 10:49:21Z martinp876 $
# $Id: 99_SUNRISE_EL.pm 6765 2014-10-14 18:24:29Z rudolfkoenig $
# $Id: 98_SVG.pm 7000 2014-11-16 16:51:50Z rudolfkoenig $
# $Id: 99_Utils.pm 6660 2014-10-03 06:35:43Z rudolfkoenig $
# $Id: 71_YAMAHA_AVR.pm 6915 2014-11-08 11:14:55Z markusbloch $
# $Id: 90_at.pm 7000 2014-11-16 16:51:50Z rudolfkoenig $
# $Id: 98_autocreate.pm 6505 2014-09-06 12:24:48Z rudolfkoenig $
# $Id: 98_dewpoint.pm 6757 2014-10-12 18:58:57Z joachim09876 $
# $Id: 98_dummy.pm 4934 2014-02-15 08:23:12Z rudolfkoenig $
# $Id: 91_eventTypes.pm 6792 2014-10-19 16:03:13Z rudolfkoenig $
# $Id: 38_netatmo.pm 6876 2014-11-03 18:24:57Z justme1968 $
# $Id: 91_notify.pm 7002 2014-11-16 17:23:35Z rudolfkoenig $
# $Id: 98_telnet.pm 6611 2014-09-24 07:48:32Z rudolfkoenig $
FHEM läuft bei mir auf einem INTEL NUC via Ubuntu
Fhem info:
Release : 5.6
Branch : DEVELOPMENT
OS : linux
Arch : x86_64-linux-gnu-thread-multi
Perl : v5.18.2
Ich weiß langsam echt nicht mehr weiter, kann mir nicht erklären woher die Probleme kommen. Einzige Rettung war ein "shutdown restart", danach funktionierte wieder alles.
Bin für jede Hilfe dankbar,
Grüße
Chris
Hi,
mit apptime kannst Du das evtl weiter eingrenzen, die freeze sind ja deutlich
vg
jörg
Hi Jörg,
apptime, richtig... oh Mann ;) das habe ich in aller Aufregung schlicht vergessen. Kann ich beim nächsten mal nachreichen.
Kann man aus den oben geposteten logs evtl. schon einen Anhaltspunkt erkennen?
Grüße
Chris
nicht wirklich, nur das das nicht so richtig flutscht :)
apptime dient auch nicht zur Prozessbefriedigung, da stehen hoffentlich die einzelnen Laufzeiten drin ;)
vg
jörg
Guten Morgen
die nächsten Freezes ließen nicht lange auf sich warten. Diesmal legten Sie um 00:47 Uhr bis 08:50 morgens meinen FHEM Server lahm. Man konnte keine HM-Aktoren mehr schalten, das halbe System war lt. ActionDetector tot: alive:42 dead:65 unkn:5 off:0
Die Freezes kann man nur durch ein "shutdown restart" lösen, welches ich heute Früh durchführte. Seitdem läuft es wieder.
Ich habe diesmal apptime, logs und co aufgezeichnet, hier das normale Log der Freezes, etwas gekürzt (übrigens: diese Meldung hatte ich noch nicht: 2014.11.28 02:30:21 1: HMLAN setting owner to 272EA5 from 000000)
2014.11.28 00:44:27 1: Perfmon: possible freeze starting at 00:44:26, delay is 1.179
2014.11.28 00:44:32 1: Perfmon: possible freeze starting at 00:44:31, delay is 1.598
2014.11.28 00:44:35 1: Perfmon: possible freeze starting at 00:44:33, delay is 2.093
2014.11.28 00:44:38 1: Perfmon: possible freeze starting at 00:44:37, delay is 1.612
2014.11.28 00:44:51 1: Perfmon: possible freeze starting at 00:44:40, delay is 11.716
2014.11.28 00:45:03 1: Perfmon: possible freeze starting at 00:44:59, delay is 4.432
2014.11.28 00:45:14 1: Perfmon: possible freeze starting at 00:45:13, delay is 1.01
2014.11.28 00:45:22 1: Perfmon: possible freeze starting at 00:45:18, delay is 4.802
2014.11.28 00:45:32 1: Perfmon: possible freeze starting at 00:45:31, delay is 1.16
2014.11.28 00:45:40 1: Perfmon: possible freeze starting at 00:45:36, delay is 4.01
2014.11.28 00:45:47 1: Perfmon: possible freeze starting at 00:45:42, delay is 5.086
2014.11.28 00:45:53 1: Perfmon: possible freeze starting at 00:45:52, delay is 1.842
2014.11.28 00:46:00 1: Perfmon: possible freeze starting at 00:45:59, delay is 1.765
2014.11.28 00:46:06 1: Perfmon: possible freeze starting at 00:46:02, delay is 4.01
2014.11.28 00:46:13 1: Perfmon: possible freeze starting at 00:46:08, delay is 5.677
2014.11.28 00:46:18 1: Perfmon: possible freeze starting at 00:46:15, delay is 3.141
2014.11.28 00:46:26 1: Perfmon: possible freeze starting at 00:46:21, delay is 5.159
2014.11.28 00:46:36 1: HMLAN_Parse: HMLAN1 new condition timeout
2014.11.28 00:46:36 1: 192.168.77.27:1000 disconnected, waiting to reappear (HMLAN1)
2014.11.28 00:46:36 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.11.28 00:46:51 1: Perfmon: possible freeze starting at 00:46:40, delay is 11.696
2014.11.28 00:48:51 1: Perfmon: possible freeze starting at 00:48:40, delay is 11.716
2014.11.28 00:50:51 1: Perfmon: possible freeze starting at 00:50:40, delay is 11.728
2014.11.28 00:51:19 1: Perfmon: possible freeze starting at 00:51:15, delay is 4.905
2014.11.28 00:52:00 1: Perfmon: possible freeze starting at 00:51:52, delay is 8.728
2014.11.28 00:52:51 1: Perfmon: possible freeze starting at 00:52:40, delay is 11.705
....
2014.11.28 02:06:00 1: Perfmon: possible freeze starting at 02:05:52, delay is 8.729
2014.11.28 02:06:39 1: Perfmon: possible freeze starting at 02:06:11, delay is 28.607
2014.11.28 02:07:20 1: Perfmon: possible freeze starting at 02:07:15, delay is 5.011
2014.11.28 02:08:47 1: Perfmon: possible freeze starting at 02:08:40, delay is 7.288
2014.11.28 02:10:47 1: Perfmon: possible freeze starting at 02:10:40, delay is 7.325
2014.11.28 02:11:20 1: Perfmon: possible freeze starting at 02:11:15, delay is 5.011
2014.11.28 02:11:51 1: Perfmon: possible freeze starting at 02:11:33, delay is 18.919
2014.11.28 02:12:10 1: Perfmon: possible freeze starting at 02:12:01, delay is 9.185
2014.11.28 02:12:47 1: Perfmon: possible freeze starting at 02:12:40, delay is 7.251
2014.11.28 02:13:19 1: Perfmon: possible freeze starting at 02:13:15, delay is 4.993
2014.11.28 02:14:47 1: Perfmon: possible freeze starting at 02:14:40, delay is 7.288
2014.11.28 02:15:20 1: Perfmon: possible freeze starting at 02:15:15, delay is 5.009
2014.11.28 02:15:51 1: Perfmon: possible freeze starting at 02:15:33, delay is 18.908
2014.11.28 02:16:10 1: Perfmon: possible freeze starting at 02:16:01, delay is 9.208
2014.11.28 02:16:47 1: Perfmon: possible freeze starting at 02:16:40, delay is 7.217
2014.11.28 02:17:20 1: Perfmon: possible freeze starting at 02:17:15, delay is 5.011
2014.11.28 02:18:10 1: Perfmon: possible freeze starting at 02:18:01, delay is 9.231
2014.11.28 02:18:47 1: Perfmon: possible freeze starting at 02:18:40, delay is 7.205
2014.11.28 02:22:47 1: Perfmon: possible freeze starting at 02:22:40, delay is 7.216
2014.11.28 02:23:20 1: Perfmon: possible freeze starting at 02:23:15, delay is 5.011
2014.11.28 02:24:00 1: Perfmon: possible freeze starting at 02:23:52, delay is 8.011
2014.11.28 02:24:10 1: Perfmon: possible freeze starting at 02:24:01, delay is 9.208
2014.11.28 02:24:47 1: Perfmon: possible freeze starting at 02:24:40, delay is 7.288
2014.11.28 02:25:20 1: Perfmon: possible freeze starting at 02:25:15, delay is 5.011
2014.11.28 02:25:51 1: Perfmon: possible freeze starting at 02:25:33, delay is 18.919
2014.11.28 02:26:10 1: Perfmon: possible freeze starting at 02:26:01, delay is 9.231
2014.11.28 02:26:47 1: Perfmon: possible freeze starting at 02:26:40, delay is 7.311
2014.11.28 02:27:20 1: Perfmon: possible freeze starting at 02:27:15, delay is 5.011
2014.11.28 02:27:51 1: Perfmon: possible freeze starting at 02:27:33, delay is 18.903
2014.11.28 02:28:10 1: Perfmon: possible freeze starting at 02:28:01, delay is 9.245
2014.11.28 02:28:47 1: Perfmon: possible freeze starting at 02:28:40, delay is 7.228
2014.11.28 02:29:19 1: Perfmon: possible freeze starting at 02:29:15, delay is 4.994
2014.11.28 02:29:51 1: Perfmon: possible freeze starting at 02:29:33, delay is 18.908
2014.11.28 02:29:55 1: HMLAN_Parse: HMLAN1 new condition timeout
2014.11.28 02:29:55 1: 192.168.77.27:1000 disconnected, waiting to reappear (HMLAN1)
2014.11.28 02:29:55 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.11.28 02:29:56 1: 192.168.77.27:1000 reappeared (HMLAN1)
2014.11.28 02:29:56 1: HMLAN_Parse: HMLAN1 new condition init
2014.11.28 02:29:56 1: HMLAN_Parse: HMLAN1 new condition ok
2014.11.28 02:30:21 1: HMLAN setting owner to 272EA5 from 000000
....
2014.11.28 08:32:06 1: Perfmon: possible freeze starting at 08:32:02, delay is 4.933
2014.11.28 08:32:39 1: Perfmon: possible freeze starting at 08:32:11, delay is 28.622
2014.11.28 08:33:14 1: Perfmon: possible freeze starting at 08:33:02, delay is 12.325
2014.11.28 08:33:52 1: Perfmon: possible freeze starting at 08:33:21, delay is 31.028
2014.11.28 08:34:39 1: Perfmon: possible freeze starting at 08:34:11, delay is 28.642
2014.11.28 08:37:14 1: Perfmon: possible freeze starting at 08:36:40, delay is 34.358
2014.11.28 08:38:10 1: Perfmon: possible freeze starting at 08:38:07, delay is 3.204
2014.11.28 08:39:14 1: Perfmon: possible freeze starting at 08:38:40, delay is 34.386
2014.11.28 08:40:00 1: Perfmon: possible freeze starting at 08:39:53, delay is 7.824
2014.11.28 08:40:06 1: Perfmon: possible freeze starting at 08:40:02, delay is 4.918
2014.11.28 08:40:39 1: Perfmon: possible freeze starting at 08:40:11, delay is 28.622
apptime
apptime
name function max count total average maxDly
fb7490 FBAHA_Read 34709 4517 2836120 627.88 0 HASH(fb7490)
HMLAN1 HMLAN_Read 6671 9236 168446 18.24 0 HASH(HMLAN1)
TuerSpeisalert notify_Exec 758 21775 758 0.03 0 HASH(TuerSpeisalert); HASH(TUER_SPEISE)
Pushover1 Pushover_Set 754 7 754 107.71 0 HASH(Pushover1); Pushover1; msg; 'Speise'; 'Tuer; wurde; geoeffnet'; ''0'magic'
HalleVenti notify_Exec 531 21775 531 0.02 0 HASH(HalleVenti); HASH(SW_KELLERVORN)
tmr-CUL_HM_ActCheck ActionDetector 451 76 1931 25.41 21560 ActionDetector
6FachWohn3 notify_Exec 191 21775 191 0.01 0 HASH(6FachWohn3); HASH(6FACH_WOHN_Btn_03)
AV_Receiver YAMAHA_AVR_Set 180 7 180 25.71 0 HASH(AV_Receiver); AV_Receiver; on
tmr-netatmo_poll HASH(0x2836ab8) 125 150 647 4.31 21358 HASH(netatmo_D70:ee:50:03:9e:1a)
HMLAN1 HMLAN_Ready 99 37688 904 0.02 0 HASH(HMLAN1)
tmr-netatmo_refreshTokenTimer HASH(0x2601358) 34 5 45 9.00 3 HASH(Wetter)
tmr-HCS_checkState HASH(0x2864900) 33 150 2386 15.91 21350 HASH(Heizungcontrol)
tmr-FW_closeOldClients 31 751 4116 5.48 26618
hm HMinfo_SetFn 31 10 31 3.10 0 HASH(hm); hm; update
di_yamahaonandoff DOIF_Notify 29 21775 56 0.00 0 HASH(di_yamahaonandoff); HASH(AV_Receiver)
tmr-HMLAN_KeepAliveCheck keepAliveCk:HMLAN1 26 677 26 0.04 5675 keepAliveCk:HMLAN1
tmr-CUL_HM_sndIfOpen sndIfOpen:HMLAN1 22 843 55 0.07 9323 sndIfOpen:HMLAN1
tmr-perfmon_ProcessTimer HASH(0xde34f8) 19 42619 22 0.00 34386 HASH(0xde34f8)
PCB1_HEIZUNG2 CUL_HM_Set 18 49 25 0.51 0 HASH(PCB1_HEIZUNG2); PCB1_HEIZUNG2; on-for-timer; 0.75
AutoFord dummy_Set 15 54 357 6.61 0 HASH(AutoFord); AutoFord; present
AutoatHomeFord notify_Exec 15 21775 379 0.02 0 HASH(AutoatHomeFord); HASH(STEMP_WWOG)
apptime maxDly
apptime maxDly
name function max count total average maxDly
tmr-FLUKSO_StatusTimer FluksoStromS0.STATUS 3 745 1104 1.48 34703 FluksoStromS0.STATUS
tmr-FLUKSO_StatusTimer FluksoGasS0.STATUS 2 745 1070 1.44 34701 FluksoGasS0.STATUS
tmr-FLUKSO_StatusTimer FluksoWasserS0.STATUS 2 745 838 1.12 34700 FluksoWasserS0.STATUS
tmr-YAMAHA_AVR_GetStatus HASH(0x21042f8) 2 968 890 0.92 34698 HASH(AV_Receiver)
tmr-HMLAN_UpdtMsgCnt UpdtMsg:HMLAN1 0 446 0 0.00 34682
tmr-perfmon_ProcessTimer HASH(0xde34f8) 1 41129 1 0.00 34647 HASH(0xde34f8)
tmr-netatmo_poll HASH(0x27b7ec0) 4 150 279 1.86 34603 HASH(netatmo_M03:00:00:00:fb:14)
tmr-netatmo_poll HASH(0x27b8298) 4 150 262 1.75 34603 HASH(netatmo_M03:00:00:00:fa:42)
tmr-netatmo_poll HASH(0x2836ab8) 41 150 306 2.04 34603 HASH(netatmo_D70:ee:50:03:9e:1a)
tmr-netatmo_poll HASH(0x2836ea8) 4 150 238 1.59 34603 HASH(netatmo_M02:00:00:03:9a:4c)
tmr-netatmo_poll HASH(0x2837520) 4 150 287 1.91 34603 HASH(netatmo_M03:00:00:00:fb:a0)
tmr-HCS_checkState HASH(0x2864900) 33 150 2415 16.10 34594 HASH(Heizungcontrol)
tmr-netatmo_poll HASH(0x2837160) 3 150 335 2.23 34480 HASH(netatmo_M05:00:00:00:40:40)
tmr-HMLAN_KeepAliveCheck keepAliveCk:HMLAN1 20 1740 20 0.01 34047 keepAliveCk:HMLAN1
tmr-HMLAN_KeepAlive keepAlive:HMLAN1 1 1733 1 0.00 32340 keepAlive:HMLAN1
tmr-FW_closeOldClients 23 754 3966 5.26 27637
tmr-CUL_HM_ActCheck ActionDetector 35 76 1584 20.84 9922 ActionDetector
tmr-netatmo_refreshTokenTimer HASH(0x2601358) 3 5 11 2.20 8163 HASH(Wetter)
tmr-HttpUtils_ConnErr HASH(0x3a3f548) 13 1 13 13.00 5573 HASH(0x3a3f548)
tmr-SetExtensionsFn SE FBDECT_22 on-for-timer 11 4 33 8.25 5435 SE FBDECT_22 on-for-timer
tmr-HttpUtils_ReadErr HASH(0x3b4f8f8) 0 2 0 0.00 5233
apptime max all (gekürzt)
apptime max all
name function max count total average maxDly
fb7490 FBAHA_Read 35008 4143 4390524 1059.75 0 HASH(fb7490)
HMLAN1 HMLAN_Read 6135 23249 434954 18.71 0 HASH(HMLAN1)
KellerlichtBW notify_Exec 1241 39909 1241 0.03 0 HASH(KellerlichtBW); HASH(BEWEGUNG4)
Pushover1 Pushover_Set 929 8 1683 210.38 0 HASH(Pushover1); Pushover1; msg; 'BEWEGUNG'; 'im; Keller'; ''0'siren'
TuerSpeisalert notify_Exec 758 39909 758 0.02 0 HASH(TuerSpeisalert); HASH(TUER_SPEISE)
HalleVenti notify_Exec 531 39909 2634 0.07 0 HASH(HalleVenti); HASH(SW_KELLERVORN)
6FachWohn3 notify_Exec 191 39909 191 0.00 0 HASH(6FachWohn3); HASH(6FACH_WOHN_Btn_03)
AV_Receiver YAMAHA_AVR_Set 180 7 180 25.71 0 HASH(AV_Receiver); AV_Receiver; on
FHEMWEB:192.168.77.25:36030 FW_Read 130 19 173 9.11 0 HASH(FHEMWEB:192.168.77.25:36030)
tmr-netatmo_poll HASH(0x2836ab8) 41 150 306 2.04 34603 HASH(netatmo_D70:ee:50:03:9e:1a)
HMLAN1 HMLAN_Ready 37 1 37 37.00 0 HASH(HMLAN1)
tmr-CUL_HM_ActCheck ActionDetector 35 76 1584 20.84 9922 ActionDetector
tmr-HCS_checkState HASH(0x2864900) 33 150 2415 16.10 34594 HASH(Heizungcontrol)
hm HMinfo_SetFn 31 10 31 3.10 0 HASH(hm); hm; update
di_yamahaonandoff DOIF_Notify 29 39909 57 0.00 0 HASH(di_yamahaonandoff); HASH(AV_Receiver)
FHEMWEB:192.168.77.25:36032 FW_Read 26 20 87 4.35 0 HASH(FHEMWEB:192.168.77.25:36032)
tmr-FW_closeOldClients 23 754 3966 5.26 27637
tmr-HMLAN_KeepAliveCheck keepAliveCk:HMLAN1 20 1742 20 0.01 34047 keepAliveCk:HMLAN1
PCB1_HEIZUNG2 CUL_HM_Set 18 60 132 2.20 0 HASH(PCB1_HEIZUNG2); PCB1_HEIZUNG2; on-for-timer; 0.75
HKVentilator notify_Exec 17 39909 104 0.00 0 HASH(HKVentilator); HASH(HEIZ_ELTWOHN)
tmr-at_Exec HASH(0x1f1be28) 17 1 17 17.00 2 HASH(KugelleuchteAus)
SW_KELLERVORN CUL_HM_Set 16 91 134 1.47 0 HASH(SW_KELLERVORN); SW_KELLERVORN; off
AutoBus dummy_Set 15 138 905 6.56 0 HASH(AutoBus); AutoBus; present
AutoFord dummy_Set 15 147 934 6.35 0 HASH(AutoFord); AutoFord; present
AutoatHomeBus notify_Exec 15 39909 974 0.02 0 HASH(AutoatHomeBus); HASH(STEMP_WWEG)
AutoatHomeFord notify_Exec 15 39909 986 0.02 0 HASH(AutoatHomeFord); HASH(STEMP_WWOG)
di_30minsleeptimer DOIF_Notify 15 39909 22 0.00 0 HASH(di_30minsleeptimer); HASH(AV_Receiver)
act_on_Taste1SEC4B notify_Exec 14 39909 21 0.00 0 HASH(act_on_Taste1SEC4B); HASH(RC_4B_Btn_02)
tmr-at_Exec HASH(0x1fcebe8) 14 1 14 14.00 20 HASH(Sonnenaufgang)
DI_FluksoWasser DOIF_Notify 13 39909 314 0.01 0 HASH(DI_FluksoWasser); HASH(FluksoWasserS0)
FileLog_HEIZ_HALLE1 FileLog_Log 13 155 13 0.08 0 HASH(FileLog_HEIZ_HALLE1); HASH(HEIZ_HALLE1)
FileLog_WASSER_KUECHE_EG FileLog_Log 13 39909 13 0.00 0 HASH(FileLog_WASSER_KUECHE_EG); HASH(STEMP_SONSTIGC_T1_T2)
LS13_Sw CUL_HM_Set 13 30 13 0.43 0 HASH(LS13_Sw); LS13_Sw; on-for-timer; 3000
LS5_SCHLAFRACK_Sw CUL_HM_Set 13 30 26 0.87 0 HASH(LS5_SCHLAFRACK_Sw); LS5_SCHLAFRACK_Sw; on
PCB4R_KLEID_Sw_04 CUL_HM_Set 13 7 13 1.86 0 HASH(PCB4R_KLEID_Sw_04); PCB4R_KLEID_Sw_04; on-for-timer; 2.25
tmr-HttpUtils_ConnErr HASH(0x3a3f548) 13 1 13 13.00 5573 HASH(0x3a3f548)
BoostModeOn_for10secBad notify_Exec 12 39909 12 0.00 0 HASH(BoostModeOn_for10secBad); HASH(HEIZ_DACH2_Weather)
Ganglichtautoan notify_Exec 12 39909 124 0.00 0 HASH(Ganglichtautoan); HASH(TUER_HAUS)
RC8_Taste1 notify_Exec 12 39909 12 0.00 0 HASH(RC8_Taste1); HASH(LS3_BIENE_Pwr)
WaterComboPushSirenWasch notify_Exec 12 39909 20 0.00 0 HASH(WaterComboPushSirenWasch); HASH(HEIZ_DACH2_Clima)
FBDECT_22 FBDECT_Set 11 129 93 0.72 0 HASH(FBDECT_22); FBDECT_22; off
SW_HALLEKUGEL CUL_HM_Set 11 29 11 0.38 0 HASH(SW_HALLEKUGEL); SW_HALLEKUGEL; off
WaterComboPushSirenHalle notify_Exec 11 39909 11 0.00 0 HASH(WaterComboPushSirenHalle); HASH(FluksoGasS0)
tmr-SetExtensionsFn SE FBDECT_22 on-for-timer 11 4 33 8.25 5435 SE FBDECT_22 on-for-timer
DI_Haustuer_offen DOIF_Notify 9 39909 137 0.00 0 HASH(DI_Haustuer_offen); HASH(TUER_HAUS)
dew_all dewpoint_Notify 9 39909 9 0.00 0 HASH(dew_all); HASH(HEIZ_DACH2_Clima)
6FachHalle6 notify_Exec 8 39909 8 0.00 0 HASH(6FachHalle6); HASH(LS3_BIENE_Pwr)
HalleVentiOff notify_Exec 8 39909 57 0.00 0 HASH(HalleVentiOff); HASH(SW_KELLERVORN)
Hell dummy_Set 8 7 8 1.14 0 HASH(Hell); Hell; Ja
act_on_Taste2SEC4B notify_Exec 8 39909 16 0.00 0 HASH(act_on_Taste2SEC4B); HASH(RC_4B_Btn_01)
act_on_Taste4SEC4B notify_Exec 8 39909 20 0.00 0 HASH(act_on_Taste4SEC4B); HASH(LS3_BIENE_SenPwr)
FileLog_netatmo_Regen FileLog_Log 7 39909 7 0.00 0 HASH(FileLog_netatmo_Regen); HASH(LS9_KTHERMO_SenU)
MP3_BOX CUL_HM_Set 7 8 14 1.75 0 HASH(MP3_BOX); MP3_BOX; playTone; 003
PCB1_GANGLICHTNEU CUL_HM_Set 7 16 70 4.38 0 HASH(PCB1_GANGLICHTNEU); PCB1_GANGLICHTNEU; on-for-timer; 1
PCB1_HAUS CUL_HM_Set 7 7 7 1.00 0 HASH(PCB1_HAUS); PCB1_HAUS; on-for-timer; 1
SW_KELLER CUL_HM_Set 7 29 7 0.24 0 HASH(SW_KELLER); SW_KELLER; on-for-timer; 180
USW_2 CUL_HM_Set 7 30 14 0.47 0 HASH(USW_2); USW_2; on
WaterComboPushSirenKuecheOG notify_Exec 7 39909 7 0.00 0 HASH(WaterComboPushSirenKuecheOG); HASH(HEIZ_DACH2)
WEB FW_Read 6 254 22 0.09 0 HASH(WEB)
tmr-CUL_HM_complConfigTO CUL_HM_complConfigTO 6 1 6 6.00 1 CUL_HM_complConfigTO
eventTypes eventTypes_Get 5 3 15 5.00 0 HASH(eventTypes); eventTypes; list
n_Tag19 notify_Exec 5 39909 5 0.00 0 HASH(n_Tag19); HASH(FluksoGasS0)
6FachHalle2 notify_Exec 4 39909 8 0.00 0 HASH(6FachHalle2); HASH(STEMP_SONSTIGC_T1_T2)
6FachHalle3 notify_Exec 4 39909 4 0.00 0 HASH(6FachHalle3); HASH(STEMP_SONSTIGC_T2_T1)
BoostModeOn_for10secHalle notify_Exec 4 39909 4 0.00 0 HASH(BoostModeOn_for10secHalle); HASH(STEMP_SONSTIGC_T1)
Briefkastenpost notify_Exec 4 39909 4 0.00 0 HASH(Briefkastenpost); HASH(HEIZ_DACH2)
DI_Hallentuer2_offen DOIF_Notify 4 39909 6 0.00 0 HASH(DI_Hallentuer2_offen); HASH(HEIZ_DACH2_Clima)
RC8_Taste3 notify_Exec 4 39909 4 0.00 0 HASH(RC8_Taste3); HASH(STEMP_SONSTIGC_T2)
WaterComboPushSirenDach notify_Exec 4 39909 8 0.00 0 HASH(WaterComboPushSirenDach); HASH(FluksoGasS0)
WaterComboPushSirenKeller notify_Exec 4 39909 8 0.00 0 HASH(WaterComboPushSirenKeller); HASH(LS3_BIENE_SenI)
dew_fan1_off notify_Exec 4 39909 4 0.00 0 HASH(dew_fan1_off); HASH(HEIZ_DACH2_Weather)
tmr-netatmo_poll HASH(0x27b7ec0) 4 150 279 1.86 34603 HASH(netatmo_M03:00:00:00:fb:14)
tmr-netatmo_poll HASH(0x27b8298) 4 150 262 1.75 34603 HASH(netatmo_M03:00:00:00:fa:42)
tmr-netatmo_poll HASH(0x2836ea8) 4 150 238 1.59 34603 HASH(netatmo_M02:00:00:03:9a:4c)
tmr-netatmo_poll HASH(0x2837520) 4 150 287 1.91 34603 HASH(netatmo_M03:00:00:00:fb:a0)
n_Urlaub notify_Exec 4 39909 4 0.00 0 HASH(n_Urlaub); HASH(FBDECT_22)
tmr-FLUKSO_StatusTimer FluksoStromS0.STATUS 3 745 1104 1.48 34703 FluksoStromS0.STATUS
tmr-netatmo_poll HASH(0x2837160) 3 150 335 2.23 34480 HASH(netatmo_M05:00:00:00:40:40)
tmr-netatmo_refreshTokenTimer HASH(0x2601358) 3 5 11 2.20 8163 HASH(Wetter)
n_Desired17 notify_Exec 3 39909 3 0.00 0 HASH(n_Desired17); HASH(LS9_KTHERMO_SenU)
HEIZ_KUECHE_Clima CUL_HM_Set 2 45 5 0.11 0 HASH(HEIZ_KUECHE_Clima); HEIZ_KUECHE_Clima; ?
WaterComboPushSirenKuecheEG notify_Exec 2 39909 2 0.00 0 HASH(WaterComboPushSirenKuecheEG); HASH(FBDECT_24)
tmr-FLUKSO_StatusTimer FluksoGasS0.STATUS 2 745 1070 1.44 34701 FluksoGasS0.STATUS
tmr-FLUKSO_StatusTimer FluksoWasserS0.STATUS 2 745 838 1.12 34700 FluksoWasserS0.STATUS
tmr-YAMAHA_AVR_GetStatus HASH(0x21042f8) 2 968 890 0.92 34698 HASH(AV_Receiver)
DI_Hallentuer_offen DOIF_Notify 1 39909 2 0.00 0 HASH(DI_Hallentuer_offen); HASH(TEMP_GARTEN)
FHEMWEB:192.168.77.25:36035 FW_Read 1 9 4 0.44 0 HASH(FHEMWEB:192.168.77.25:36035)
FHEMWEB:192.168.77.25:36036 FW_Read 1 9 2 0.22 0 HASH(FHEMWEB:192.168.77.25:36036)
FHEMWEB:192.168.77.25:36050 FW_Read 1 3 2 0.67 0 HASH(FHEMWEB:192.168.77.25:36050)
FileLog_FBDECT_23 FileLog_Log 1 1030 3 0.00 0 HASH(FileLog_FBDECT_23); HASH(FBDECT_23)
FileLog_LS5_SCHLAFRACK_Pwr FileLog_Log 1 289 1 0.00 0 HASH(FileLog_LS5_SCHLAFRACK_Pwr); HASH(LS5_SCHLAFRACK_Pwr)
HEIZ_WOHN1_Clima CUL_HM_Set 1 48 3 0.06 0 HASH(HEIZ_WOHN1_Clima); HEIZ_WOHN1_Clima; ?
HEIZ_WOHN2_Clima CUL_HM_Set 1 44 1 0.02 0 HASH(HEIZ_WOHN2_Clima); HEIZ_WOHN2_Clima; ?
STEMP_SONSTIGC_T1 CUL_HM_Set 1 51 1 0.02 0 HASH(STEMP_SONSTIGC_T1); STEMP_SONSTIGC_T1; ?
tmr-CUL_HM_sndIfOpen sndIfOpen:HMLAN1 1 1 1 1.00 0 sndIfOpen:HMLAN1
tmr-HMLAN_KeepAlive keepAlive:HMLAN1 1 1734 1 0.00 32340 keepAlive:HMLAN1
tmr-perfmon_ProcessTimer HASH(0xde34f8) 1 41165 1 0.00 34647 HASH(0xde34f8)
3FM_BLITZWARNER_BLITZ CUL_HM_Set 0 6 0 0.00 0
3FM_SIEDLEKLINGEL_Sw_01 CUL_HM_Set 0 6 0 0.00 0
3FM_SIEDLEKLINGEL_Sw_02 CUL_HM_Set 0 6 0 0.00 0
6FACH_HALLE CUL_HM_Set 0 6 0 0.00 0
6FACH_WOHN CUL_HM_Set 0 6 0 0.00 0
6FachHalle1 notify_Exec 0 39909 0 0.00 0
6FachHalle4 notify_Exec 0 39909 0 0.00 0
6FachHalle5 notify_Exec 0 39909 0 0.00 0
6FachWohn1 notify_Exec 0 39909 0 0.00 0
6FachWohn2 notify_Exec 0 39909 0 0.00 0
6FachWohn4 notify_Exec 0 39909 0 0.00 0
6FachWohn5 notify_Exec 0 39909 0 0.00 0
6FachWohn6 notify_Exec 0 39909 0 0.00 0
Ich habe noch verbose=5 eingeschalten und um 8:45:09 Uhr den AKtor SW_DACH zu schalten. Es war kein Schalten möglich (STATE IOerr)
Hier das log
2014.11.28 08:45:09 3: CUL_HM set SW_DACH on
2014.11.28 08:45:11 3: CUL_HM set SW_DACH on
2014.11.28 08:45:12 5: HMLAN/RAW: /E5299BC,0000,0157BDF5,FF,FFC6,4D865A5299BC000000A0D937
2014.11.28 08:45:12 5: HMLAN_Parse: HMLAN1 R:E5299BC stat:0000 t:0157BDF5 d:FF r:FFC6 m:4D 865A 5299BC 000000 A0D937
2014.11.28 08:45:12 5: HMLAN1 dispatch A0C4D865A5299BC000000A0D937::-58:HMLAN1
2014.11.28 08:45:14 5: FBAHA/RAW: /0703001c0000004d001400000000000c0000001400040000000000b90703001c0000004d001400000000000c0000000f0004000000000001070300200000004d00140000000000100000001700080000000000de000000000703001c0000004d001400000000000c000000130004000000037eed0703001c0000004d001400000000000c00000012000400000000005f
ein FBDECT Schalter (FBDECT_22) ließ sich um 8:47:03 einschalten, verursachte aber wohl einen 23 Sekunden Freeze
2014.11.28 08:47:02 5: Cmd: >set FBDECT_22 on<
2014.11.28 08:47:02 5: SW: 0703001c0000004d001600000000000c0000000f0004000000000001
2014.11.28 08:47:02 5: Triggering FBDECT_22 (1 changes)
2014.11.28 08:47:02 5: Notify loop for FBDECT_22 set_on
2014.11.28 08:47:02 4: /fhem?XHR=1&cmd.FBDECT_22=set%20FBDECT_22%20on&room=Favourites / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2014.11.28 08:47:03 1: Perfmon: possible freeze starting at 08:46:40, delay is 23.055
2014.11.28 08:47:03 5: HMLAN/RAW: /E2D6611,0000,0159703D,FF,FFAD,8484702D661100000000BC3F
2014.11.28 08:47:03 5: HMLAN_Parse: HMLAN1 R:E2D6611 stat:0000 t:0159703D d:FF r:FFAD m:84 8470 2D6611 000000 00BC3F
2014.11.28 08:47:03 5: HMLAN1 dispatch A0C8484702D661100000000BC3F::-83:HMLAN1
2014.11.28 08:47:04 5: HMLAN/RAW: /E2A4FF8,0000,0159720E,FF,FFCE,23845E2A4FF80000008113BC000000000008E6FE
2014.11.28 08:47:04 5: HMLAN_Parse: HMLAN1 R:E2A4FF8 stat:0000 t:0159720E d:FF r:FFCE m:23 845E 2A4FF8 000000 8113BC000000000008E6FE
2014.11.28 08:47:04 5: HMLAN1 dispatch A1423845E2A4FF80000008113BC000000000008E6FE::-50:HMLAN1
2014.11.28 08:47:04 5: Triggering LS5_SCHLAFRACK_Pwr (1 changes)
Ein anschließendes FBEDECT_22 devInfo brachte Folgendes:
FBAHA_ReadAnswer CONFIG_RSP: Timeout
Hier der Log nach dem FBEDECT_22 devInfo, gab wohl danach einige Probleme:
2014.11.28 08:51:16 5: Cmd: >get FBDECT_22 devInfo<
2014.11.28 08:51:16 5: SW: 0503000c0000004d00000000
2014.11.28 08:51:16 5: FBAHA/RAW: /060101680000004d000000000000000000110200000000090000138053616d73756e6720467265657a6572000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000b74303837363120303038353039360000000000000030332e3336000000000000000000000000000000000000000000000200110000000000c00000000f0004000000000001000000230008000000000002000000000000002500400000000000110000000200000001000000140000000500000000000000000000000f0000000000000002000000010000000300000000
2014.11.28 08:51:16 1: FBAHA: resetting buffer as we are out of sync (0)
2014.11.28 08:51:16 5: FBAHA/RAW: 060101680000004d000000000000000000110200000000090000138053616d73756e6720467265657a6572000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000b74303837363120303038353039360000000000000030332e3336000000000000000000000000000000000000000000000200110000000000c00000000f0004000000000001000000230008000000000002000000000000002500400000000000110000000200000001000000140000000500000000000000000000000f0000000000000002000000010000000300000000/000000000000000200120000000000c00000000f0004000000000001000000230008000000000002000000000000002500400000000000120000000200000001000000140000000500000000000000000000000f0000000000000002000000010000000300000000000000000000000f000000000000001200040000000000960000001300040000000379ac0000001400040000000000000000001500040000000156110000001600040000000000000000001700080000000000f70000000000000017000800000000000000000001060001680000004d00000000000000000013020000000009000013804b75656368652d467265657a65722d546865726d
2014.11.28 08:51:16 5: FBAHA/RAW: 000000000000000f000000000000001200040000000000960000001300040000000379ac0000001400040000000000000000001500040000000156110000001600040000000000000000001700080000000000f70000000000000017000800000000000000000001060001680000004d00000000000000000013020000000009000013804b75656368652d467265657a65722d546865726d/6f2d4b61666665650000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000b74303837363120303038313234330000000000000030332e3336000000000000000000000000000000000000000000000200130000000000c00000000f0004000000000001000000230008000000000002000000000000002500400000000000130000000200000001000000140000000500000000000000000000000f0000000000000002000000010000000300000000000000000000000f00000000000000120004000000000dfe000000130004000000037943000000140004000000001e57
2014.11.28 08:51:16 1: FBAHA: resetting buffer as we are out of sync (0)
2014.11.28 08:51:16 1: FBDECT: unknown message type 00
2014.11.28 08:51:16 1: PERL WARNING: substr outside of string at ./FHEM/00_FBAHA.pm line 166.
2014.11.28 08:51:16 1: FBAHA: resetting buffer as we are out of sync (94720)
2014.11.28 08:51:16 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/00_FBAHA.pm line 166.
2014.11.28 08:51:16 5: FBAHA/RAW: /00400000000000140000000200000001000000140000000500000000000000000000000f0000000000000002000000010000000300000000000000000000000f0000000000000012000400000000005f000000130004000000037c8e0000001400040000000000b900000015000400000003152300000016000400000000035d0000001700080000000000de0000000000000017000800000000000000000001060001680000004d0000000000000000001602000000000900001380436f6c6120467265657a65720000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
2014.11.28 08:51:16 1: FBAHA: resetting buffer as we are out of sync (0)
2014.11.28 08:51:16 1: FBAHA: resetting buffer as we are out of sync (0)
2014.11.28 08:51:16 5: FBAHA/RAW: /0000000000000001060001680000004d000000000000000000170200000000090000138048616c6c6520504300000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000b74303837363120303030383731390000000000000030332e3336000000000000000000000000000000000000000000000200170000000000c00000000f0004000000000001000000230008000000000002000000000000002500400000000000170000000200000001000000140000000500000000000000000000000f000000000000000200000001
2014.11.28 08:51:16 1: FBAHA: resetting buffer as we are out of sync (0)
2014.11.28 08:51:16 5: FBAHA/RAW: /0000000300000000000000000000000f000000000000001200040000000001f9000000130004000000037c4e0000001400040000000001ed00000015000400000001e25b0000001600040000000001ab0000001700080000000000b50000000000000017000800000000000000000001060001680000004d0000000000000000001802000000000900001380456c7465726e205043000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000b74303837363120303131313532350000000000000030332e333600000000000000
2014.11.28 08:51:16 1: FBAHA: resetting buffer as we are out of sync (0)
2014.11.28 08:51:16 1: FBAHA: resetting buffer as we are out of sync (6)
2014.11.28 08:51:16 5: FBAHA/RAW: /000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000b74303837363120303131313237390000000000000030332e3336000000000000000000000000000000000000000000000200190000000000c00000000f0004000000000001000000230008000000000002000000000000002500400000000000190000000200000001000000140000000500000000000000000000000f0000000000000002000000010000000300000000000000000000000f000000000000001200040000000003c3000000130004000000037c7200000014
2014.11.28 08:51:16 1: FBAHA: resetting buffer as we are out of sync (0)
2014.11.28 08:51:16 1: FBAHA: resetting buffer as we are out of sync (0)
2014.11.28 08:51:16 5: FBAHA/RAW: /0000000000000001
2014.11.28 08:51:16 1: FBAHA: resetting buffer as we are out of sync (0)
2014.11.28 08:51:19 1: 192.168.77.1:2002 disconnected, waiting to reappear (fb7490)
2014.11.28 08:51:19 5: Triggering fb7490 (1 changes)
2014.11.28 08:51:19 5: Notify loop for fb7490 DISCONNECTED
2014.11.28 08:51:19 4: /fhem&detail=FBDECT_22&dev.getFBDECT_22=FBDECT_22&cmd.getFBDECT_22=get&arg.getFBDECT_22=devInfo&val.getFBDECT_22= / RL:1012 / text/html; charset=UTF-8 / Content-Encoding: gzip
/
2014.11.28 08:51:19 1: 192.168.77.1:2002 reappeared (fb7490)
2014.11.28 08:51:19 1: 192.168.77.1:2002 reappeared (fb7490)
2014.11.28 08:51:19 5: SW: 0003000c0000000000022005
2014.11.28 08:51:19 5: FBAHA/RAW: /010300100000004e0000004e00022005
2014.11.28 08:51:19 1: FBAHA fb7490 registered with handle: 0000004e
2014.11.28 08:51:19 5: SW: 030300100000004e0000038200000000
2014.11.28 08:51:19 5: Triggering fb7490 (1 changes)
2014.11.28 08:51:19 5: Notify loop for fb7490 CONNECTED
2014.11.28 08:51:19 1: Perfmon: possible freeze starting at 08:51:17, delay is 2.908
2014.11.28 08:51:19 4: Connection closed for FHEMWEB:192.168.77.25:36117
2014.11.28 08:51:20 5: FBAHA/RAW: /0703001c0000004e001700000000000c0000001400040000000001e6
2014.11.28 08:51:20 5: fb7490 dispatch 0703001c0000004e001700000000000c0000001400040000000001e6
Grüße
Chris
und was sit jetzt FBAHA? Das Blockiert so 34sec.
Hallo Martin,
das FBAHA Modul verbindet sich mit dem AHA (AVM Home Automation) Server auf der FRITZ!Box. Es dient als "physikalisches" Gegenstück zum FBDECT Modul.
Ich betreibe so einige Fritz DECT!200 Schaltsteckdosen.
Grüße
Chris
jedenfalls bracht der einmal 31sec - das ist zu lang.
wenn du den weglässt, klappt dann alles andere?
Die Freezes kommen leider nur sporadisch, nicht reproduzierbar. Von daher kann ich FBAHA nicht direkt verurteilen - zumal es ja Monate vorher absolut problemlos funktionierte. Ich steuere momentan 8 DECT Schaltsteckdosen über FBAHA, die dringend benötigt werden.
Kannst du aus den geposteten Logs evtl. noch eine andere Fehlerquelle ausmachen?
Grüße
Chris
FBAHA ist "mein" Modul, deswegen habe ich das jetzt nochmal naeher angeschaut:
- ich weiss nicht was Martin mit "31sec" meint, wenn er den Eintrag mit 34.709 aus apptime meint, dann bin ich erstmal ratlos. Es kann dann auftreten, falls select eine Aktivitaet meldet, und sysread einen definierten String der Laenge 0 zurueckliefert. Sowas hielt ich bisher bei TCP/IP fuer unmoeglich. Ich habe DevIo modifiziert, so dass in diesem Fall ein select vor dem "ungluecklichen" sysread vorgeschaltet wird.
- Die Meldung
2014.11.28 08:51:16 5: FBAHA/RAW: /06010168...
2014.11.28 08:51:16 1: FBAHA: resetting buffer as we are out of sync (0)kann ich nur mit einem kaputten perl oder mit einem geaenderten FBAHA.pm erklaeren: "bei out of sync" wird im Klammer der nach dezimal gewandelte und mit 2 multiplizierte 4. bis 8. Stelle des direkt vorher ausgegebenen Strings gemeldet. Sollte in diesem Fall 720 und nicht 0 sein, und deswegen gar keine Meldung kommen.
- generell kann "out of sync" dann kommen, falls der AHA Server auf dem Fritzbox lange Datensaetze meldet (> 256 Byte), und der FHEM Server so stark belastet/blockiert ist, dass es die weiteren Strings erst nach 1 Sekunde abholt. Ich habe jetzt die auf einmal abgeholten Daten von 256 Byte auf 4096 erhoeht, und den Timeout von 1 auf 5 Sekunden geaendert. Achtung: im beigefuegten Log war das nicht der Fall, d.h. meine Aenderung sollte keine Auswirkung haben.
Zitatein FBDECT Schalter (FBDECT_22) ließ sich um 8:47:03 einschalten, verursachte aber wohl einen 23 Sekunden Freeze
Das kann man mAn aus dem gezeigten Log nicht folgern: das FBDECT Modul hat den Befehl um 08:47:02 erhalten, und innerhalb dieser Sekunde war es auch fertig damit. Apropos: beim debuggen dieser Detailtiefe hilft ein "attr global mseclog".
@chriz:
- verwendest du noch weitere Geraete, die auf dem AHA@FB zugreifen, z.Bsp. Dect-Telefone?
- welches OS hat die FritzBox?
- was ist das fuer ein Perl bzw. Hardware was du fuer FHEM verwendest? Die "out of sync" Meldung finde ich sehr verstoerend. Btw. ich habe auf meinem System noch nie ein "out of sync" gesehen.
Hallo Rudolf,
meine aktuellen FHEM Module sind
# $Id: fhem.pl 6913 2014-11-08 10:32:44Z rudolfkoenig $
# $Id: 10_CUL_HM.pm 7047 2014-11-23 09:52:33Z martinp876 $
# $Id: 70_EGPM.pm 5344 2014-03-27 20:06:31Z alexus2033 $
# $Id: 17_EGPM2LAN.pm 5344 2014-03-27 20:06:31Z alexus2033 $
# $Id: 00_FBAHA.pm 6771 2014-10-15 17:59:23Z rudolfkoenig $
# $Id: 10_FBDECT.pm 6771 2014-10-15 17:59:23Z rudolfkoenig $
# $Id: 72_FB_CALLMONITOR.pm 6852 2014-11-01 17:23:29Z markusbloch $
# $Id: 01_FHEMWEB.pm 7024 2014-11-19 23:07:39Z rudolfkoenig $
# $Id: 92_FileLog.pm 7062 2014-11-25 09:15:57Z rudolfkoenig $
# $Id: 59_HCS.pm 4433 2013-12-21 05:30:49Z tobiasfaust $
# $Id: 00_HMLAN.pm 6471 2014-08-27 12:32:38Z martinp876 $
# $Id: 98_HMinfo.pm 7048 2014-11-23 10:49:21Z martinp876 $
# $Id: 99_SUNRISE_EL.pm 6765 2014-10-14 18:24:29Z rudolfkoenig $
# $Id: 98_SVG.pm 7075 2014-11-27 20:59:58Z rudolfkoenig $
# $Id: 99_Utils.pm 6660 2014-10-03 06:35:43Z rudolfkoenig $
# $Id: 71_YAMAHA_AVR.pm 6915 2014-11-08 11:14:55Z markusbloch $
# $Id: 98_apptime.pm 6126 2014-06-17 05:44:54Z martinp876 $
# $Id: 90_at.pm 7000 2014-11-16 16:51:50Z rudolfkoenig $
# $Id: 98_autocreate.pm 6505 2014-09-06 12:24:48Z rudolfkoenig $
# $Id: 98_dewpoint.pm 6757 2014-10-12 18:58:57Z joachim09876 $
# $Id: 98_dummy.pm 4934 2014-02-15 08:23:12Z rudolfkoenig $
# $Id: 91_eventTypes.pm 6792 2014-10-19 16:03:13Z rudolfkoenig $
# $Id: 38_netatmo.pm 6876 2014-11-03 18:24:57Z justme1968 $
# $Id: 91_notify.pm 7002 2014-11-16 17:23:35Z rudolfkoenig $
# $Id: 98_telnet.pm 6611 2014-09-24 07:48:32Z rudolfkoenig $
# $Id: 98_update.pm 6784 2014-10-18 09:12:57Z rudolfkoenig $
Ich nutze via FHEM noch den FB_CALLMONITOR, der greift aber wohl nicht auf FBAHA zu.
An meiner Fritzbox 7490 (aktuelles FritzOS 6.20) sind noch 5 normale Fritz DECT Telefone sowie 1 Fritz DECT Repeater angemeldet.
Als Hardware für den FHEM Server nutze ich einen Intel NUC mit Ubuntu (Ubuntu 14.04.1 LTS GNU/Linux 3.13.0-24-generic x86_64).
In einer Verzweiflungstat bin ich die Tage testweise von einem Intel NUC (Dualcore Celeron N2820 CPU, 4GB RAM, SSD) auf einen wesentlich leistungsstärkeren Intel NUC i3 (Dualcore Core i3-4010U CPU, 8GB RAM, SSD) umgezogen.
Fhem info:
Release : 5.6
Branch : DEVELOPMENT
OS : linux
Arch : x86_64-linux-gnu-thread-multi
Perl : v5.18.2
Hier noch meine devList und Internals der FBDECT Schaltsteckdosen
NAME:Samsung Freezer, ID:17, active, TYPE:AVM FRITZ!Dect 200 PROP:powerMeter,switch
NAME:Geschirrspueler Siemens, ID:18, active, TYPE:AVM FRITZ!Dect 200 PROP:powerMeter,switch
NAME:Kueche-Freezer-Thermo-Kaffee, ID:19, active, TYPE:AVM FRITZ!Dect 200 PROP:powerMeter,switch
NAME:Wasserbett, ID:20, active, TYPE:AVM FRITZ!Dect 200 PROP:powerMeter,switch
NAME:Cola Freezer, ID:22, active, TYPE:AVM FRITZ!Dect 200 PROP:powerMeter,switch
NAME:Halle PC, ID:23, active, TYPE:AVM FRITZ!Dect 200 PROP:powerMeter,switch
NAME:Eltern PC, ID:24, active, TYPE:AVM FRITZ!Dect 200 PROP:powerMeter,switch
NAME:Eltern TV, ID:25, active, TYPE:AVM FRITZ!Dect 200 PROP:powerMeter,switch
NAME:FRITZ!DECT Rep 100 #1, ID:16, active, TYPE:unknown(10) PROP:0,0
---
Clients
:FBDECT:
DEF
192.168.77.1:2002
DeviceName
192.168.77.1:2002
FD
177
HANDLE
00000001
NAME
fb7490
NR
1238
PARTIAL
RAWMSG
0703001c00000001001100000000000c000000160004000000000163
STATE
Initialized
TYPE
FBAHA
fb7490_MSGCNT
2768
fb7490_TIME
2014-11-30 11:46:56
Und danke für den Tipp "attr global mseclog", werde ich bei den nächsten Freezes mit einsetzen.
Grüße
Chris