HMLAN: Regelmässiger Timeout zur Geisterstunde

frank · 18 April 2014, 16:49:01

hallo martin,

ich habe gerade mal meinen cul868 angeschmissen, um den hmlan heute nacht zu monitoren. dabei ist mir aufgefallen dass der cul kein attribut logIDs hat. könnte man für den cul im homematic-mode das attribut logIDs ebenfals spendieren? das ist eigentlich ganz praktisch.

oder ist das nicht da, weil der cul mit nichts gepaired ist?

gruss frank

martinp876 · 18 April 2014, 20:24:04

Zitatoder ist das nicht da, weil der cul mit nichts gepaired ist?

hm - IOs sind nie gepairt... lassen wir das. Die Darstellung in FHEM ist inkorrekt,

HMLAN ist unter meiner Kontrolle - und ist HM spezifisch.
Bei der CUL habe ich es nicht eingebaut, weil es nicht mir "gehört" und nicht HMspezifisch ist...
Machbar ist es schon, klar.
Sicher ist, dass verbose 4 reicht, verbose 5 ist hier nicht sinnvoll

Gruss Martin

frank · 18 April 2014, 21:02:45

Zitathm - IOs sind nie gepairt... lassen wir das. Die Darstellung in FHEM ist inkorrekt,

sorry.

ich verbessere auf "assigned" statt "gepaired".

gruss frank

frank · 19 April 2014, 11:52:37

hallo martin,

die änderung der msgnummer beim timestamp event hat leider keine änderung der timeouts bewirkt. hier ein log mit zusätzlichem cul als monitor.

Code Auswählen

2014.04.19 00:00:17.905 4: CUL_Parse: cul868 A 0C 4F 8670 1BF81B 000000 00C33A1A -61
2014.04.19 00:00:17.926 4: RCV L:0C N:4F F:86 CMD:70 SRC:Thermostat.WZ DST:broadcast 00C33A (WeatherEvent TEMP:19.5 HUM:58) (,WAKEMEUP,CFG,RPTEN)
2014.04.19 00:00:17.998 0: HMLAN_Parse: HMLAN1 R:E1BF81B   stat:0000 t:0A4BCBAC d:FF r:FFD1     m:4F 8670 1BF81B 000000 00C33A
2014.04.19 00:00:18.097 4: CUL_Parse: cul868 A 09 50 A03F 1BF81B 123ABC 1A -61
2014.04.19 00:00:18.107 4: RCV L:09 N:50 F:A0 CMD:3F SRC:Thermostat.WZ DST:123ABC  (TimeStamp) (,BIDI,RPTEN)
2014.04.19 00:00:18.199 0: HMLAN_Send:  HMLAN1 S:+1BF81B,00,01,1E
2014.04.19 00:00:18.201 0: HMLAN_Send:  HMLAN1 S:S76DABF36 stat:  00 t:00000000 d:01 r:76DABF36 m:50 803F 123ABC 1BF81B 02041AE45CF2
2014.04.19 00:00:18.271 0: HMLAN_Parse: HMLAN1 R:E1BF81B   stat:0000 t:0A4BCC6C d:FF r:FFD1     m:50 A03F 1BF81B 123ABC 
2014.04.19 00:00:18.288 4: CUL_Parse: cul868 A 0F 50 803F 123ABC 1BF81B 02041AE45CF24D -35.5
2014.04.19 00:00:21.781 0: HMLAN_Send:  HMLAN1 I:K
2014.04.19 00:00:22.792 0: HMLAN_Send:  HMLAN1 I:K
2014.04.19 00:00:23.801 0: HMLAN_Send:  HMLAN1 I:K
2014.04.19 00:00:24.812 0: HMLAN_Send:  HMLAN1 I:K
2014.04.19 00:00:25.821 1: HMLAN_Parse: HMLAN1 new condition timeout
2014.04.19 00:00:26.025 1: 
                                name             function    max  count    total  average maxDly
                  tmr-HCS_checkState      HASH(BROETJE)    287      1      287   287.00      6 HASH(BROETJE)
                           n_set_hzg          notify_Exec    193      1      193   193.00      0 HASH(n_set_hzg); HASH(BROETJE)
                              cul868             CUL_Read    172      6      269    44.83      0 HASH(cul868)
                              cul433             CUL_Read     84      4       96    24.00      0 HASH(cul433)
                           n_ITT1500          notify_Exec     29      1       29    29.00      0 HASH(n_ITT1500); HASH(cul433)
                      tmr-PID20_Calc             PID20.AZ     23      1       23    23.00     97 PID20.AZ
                      tmr-PID20_Calc            PID20.Bad     23      1       23    23.00    275 PID20.Bad
                      tmr-PID20_Calc         PID20.Kueche     23      1       23    23.00    275 PID20.Kueche
                      tmr-PID20_Calc             PID20.WZ     23      1       23    23.00     78 PID20.WZ
                             BROETJE              HCS_Get     15      1       15    15.00      0 HASH(BROETJE); BROETJE; values
                              HMLAN1           HMLAN_Read     14      2       27    13.50      0 HASH(HMLAN1)
                 tmr-holiday_refresh               events     11      1       11    11.00      7 events
                      tmr-PID20_Calc      HASH(PID20.SZ)     10      1       10    10.00      6 HASH(PID20.SZ)
                      tmr-PID20_Calc             PID20.SZ     10      1       10    10.00    289 PID20.SZ
           FHEMWEB:192.168.1.23:1134            FW_Notify      6      5        6     1.20      0 HASH(FHEMWEB); HASH(BROETJE)
            tmr-HMLAN_KeepAliveCheck   keepAliveCk:HMLAN1      6      4       14     3.50      8 keepAliveCk:HMLAN1
                  FileLog_Technik_IO          FileLog_Log      5      6       21     3.50      0 HASH(FileLog_Technik_IO); HASH(HMLAN1)
              FileLog_Thermostat.Bad          FileLog_Log      4      6       17     2.83      0 HASH(FileLog_Thermostat.Bad); HASH(HMLAN1)
                 tmr-HMLAN_KeepAlive     keepAlive:HMLAN1      4      1        4     4.00      7 keepAlive:HMLAN1
                     FileLog_Broetje          FileLog_Log      3      6       17     2.83      0 HASH(FileLog_Broetje); HASH(BROETJE)
               FileLog_Thermostat.AZ          FileLog_Log      3      6       14     2.33      0 HASH(FileLog_Thermostat.AZ); HASH(cul868)
           FileLog_Thermostat.Kueche          FileLog_Log      3      6       14     2.33      0 HASH(FileLog_Thermostat.Kueche); HASH(cul868)
               FileLog_Thermostat.SZ          FileLog_Log      3      6       15     2.50      0 HASH(FileLog_Thermostat.SZ); HASH(cul868)
               FileLog_Thermostat.WZ          FileLog_Log      3      6       14     2.33      0 HASH(FileLog_Thermostat.WZ); HASH(cul868)
                      FileLog_cul433          FileLog_Log      3      6       15     2.50      0 HASH(FileLog_cul433); HASH(cul868)
               BROETJEDemandRaumTemp            dummy_Set      2      1        2     2.00      0 HASH(BROETJEDemandRaumTemp); BROETJEDemandRaumTemp; 16.2
                      FileLog_cul868          FileLog_Log      2      6        7     1.17      0 HASH(FileLog_cul868); HASH(cul868)
                             Logfile          FileLog_Log      1      6        2     0.33      0 HASH(Logfile); HASH(cul868)
                     n_Broetje_alarm          notify_Exec      1      6        2     0.33      0 HASH(n_Broetje_alarm); HASH(cul433)
                               n_Bxx          notify_Exec      1      6        2     0.33      0 HASH(n_Bxx); HASH(BROETJE)
                        n_alarmgeber          notify_Exec      1      6        5     0.83      0 HASH(n_alarmgeber); HASH(BROETJE)
                           n_apptime          notify_Exec      1      2        1     0.50      0 HASH(n_apptime); HASH(HMLAN1)
                          n_batt_chk          notify_Exec      1      5        5     1.00      0 HASH(n_batt_chk); HASH(BROETJE)
               n_control_TCControler          notify_Exec      1      5        3     0.60      0 HASH(n_control_TCControler); HASH(BROETJE)
                       n_set_desired          notify_Exec      1      5        5     1.00      0 HASH(n_set_desired); HASH(BROETJE)
                     n_valveCtrl_chk          notify_Exec      1      5        2     0.40      0 HASH(n_valveCtrl_chk); HASH(BROETJE)
                             BROETJE              HCS_Set      0      1        0     0.00      0 
                              HMLAN1           HMLAN_Attr      0      1        0     0.00      0 
                              HMLAN1         HMLAN_Notify      0      6        0     0.00      0 
                            PID20.AZ         PID20_Notify      0      6        0     0.00      0 
                           PID20.Bad         PID20_Notify      0      6        0     0.00      0 
                        PID20.Kueche         PID20_Notify      0      6        0     0.00      0 
                            PID20.SZ         PID20_Notify      0      6        0     0.00      0 
                            PID20.WZ         PID20_Notify      0      6        0     0.00      0 
                                 WEB            FW_Notify      0      6        0     0.00      0 
                            WEBphone            FW_Notify      0      6        0     0.00      0 
                           WEBtablet            FW_Notify      0      6        0     0.00      0 
                              cul868             CUL_Attr      0      1        0     0.00      0 
                                  et    eventTypes_Notify      0      6        0     0.00      0 
                    tmr-BlockingKill      HASH(0x15e95a0)      0      1        0     0.00      6 
               tmr-HttpUtils_ConnErr      HASH(0x15b40a0)      0      1        0     0.00      6 
               tmr-HttpUtils_ReadErr      HASH(0x15b40a0)      0      1        0     0.00      7 
            tmr-perfmon_ProcessTimer       HASH(0x9b19b0)      0     25        0     0.00     18 
2014.04.19 00:00:26.039 1: 192.168.1.9:1000 disconnected, waiting to reappear
2014.04.19 00:00:26.070 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.04.19 00:00:26.118 4: CUL_Parse: cul868 A 0C 46 8670 1D252E 000000 00C139FE -75
2014.04.19 00:00:26.132 4: RCV L:0C N:46 F:86 CMD:70 SRC:Thermostat.Kueche DST:broadcast 00C139 (WeatherEvent TEMP:19.3 HUM:57) (,WAKEMEUP,CFG,RPTEN)
2014.04.19 00:00:29.214 1: Perfmon: possible freeze starting at 00:00:26, delay is 3.213
2014.04.19 00:00:29.294 4: CUL_Parse: cul868 A 09 47 A03F 1D252E 123ABC 00 -74
2014.04.19 00:00:29.328 4: RCV L:09 N:47 F:A0 CMD:3F SRC:Thermostat.Kueche DST:123ABC  (TimeStamp) (,BIDI,RPTEN)
2014.04.19 00:00:29.420 4: CUL_Parse: cul868 A 0F 47 803F 123ABC 1D252E 02041AE45CF94D -35.5
2014.04.19 00:00:38.000 4: CUL_Parse: cul868 A 0B 4F A258 1BF81B D4D4D4 000E1B -60.5
2014.04.19 00:00:38.013 4: RCV L:0B N:4F F:A2 CMD:58 SRC:Thermostat.WZ DST:TCControler.WZ 000E (ClimateEvent CMD:0x00 ValvePos:14) (,WAKEMEUP,BIDI,RPTEN)
2014.04.19 00:00:46.068 4: CUL_Parse: cul868 A 0B 46 A258 1D252E D1D1D1 000E01 -73.5
2014.04.19 00:00:46.080 4: RCV L:0B N:46 F:A2 CMD:58 SRC:Thermostat.Kueche DST:TCControler.Kueche 000E (ClimateEvent CMD:0x00 ValvePos:14) (,WAKEMEUP,BIDI,RPTEN)
2014.04.19 00:00:50.648 4: CUL_Parse: cul868 A 0C 75 8670 1936FF 000000 00874BFE -75
2014.04.19 00:00:50.661 4: RCV L:0C N:75 F:86 CMD:70 SRC:Thermostat.Bad DST:broadcast 00874B (WeatherEvent TEMP:13.5 HUM:75) (,WAKEMEUP,CFG,RPTEN)
2014.04.19 00:00:50.838 4: CUL_Parse: cul868 A 09 76 A03F 1936FF 123ABC FE -75
2014.04.19 00:00:50.848 4: RCV L:09 N:76 F:A0 CMD:3F SRC:Thermostat.Bad DST:123ABC  (TimeStamp) (,BIDI,RPTEN)
2014.04.19 00:01:04.060 1: Perfmon: possible freeze starting at 00:01:00, delay is 4.059
2014.04.19 00:01:10.648 4: CUL_Parse: cul868 A 0B 75 A258 1936FF D3D3D3 0000FD -75.5
2014.04.19 00:01:10.660 4: RCV L:0B N:75 F:A2 CMD:58 SRC:Thermostat.Bad DST:TCControler.Bad 0000 (ClimateEvent CMD:0x00 ValvePos:0) (,WAKEMEUP,BIDI,RPTEN)
2014.04.19 00:01:29.227 1: 192.168.1.9:1000 reappeared (HMLAN1)
2014.04.19 00:01:29.230 0: HMLAN_Send:  HMLAN1 I:A123ABC
2014.04.19 00:01:29.232 0: HMLAN_Send:  HMLAN1 I:C
2014.04.19 00:01:29.235 0: HMLAN_Send:  HMLAN1 I:Y01,00,
2014.04.19 00:01:29.237 0: HMLAN_Send:  HMLAN1 I:Y02,00,
2014.04.19 00:01:29.239 0: HMLAN_Send:  HMLAN1 I:Y03,00,
2014.04.19 00:01:29.243 0: HMLAN_Send:  HMLAN1 I:T1AE45D39,04,00,00000000
2014.04.19 00:01:29.245 1: HMLAN_Parse: HMLAN1 new condition init
2014.04.19 00:01:29.325 0: HMLAN_Send:  HMLAN1 S:S76DBD531 stat:  00 t:00000000 d:01 r:76DBD531 m:99 8112 123ABC 000001 
2014.04.19 00:01:29.395 0: HMLAN_Parse: HMLAN1 V:03C1 sNo:JEQ0315335 d:1C671E O:123ABC t:0000C689 IDcnt:0000
2014.04.19 00:01:29.397 0: HMLAN_Parse: HMLAN1 R:E1D252E   stat:0000 t:00001DE4 d:FF r:FFC4     m:46 A258 1D252E D1D1D1 000E
2014.04.19 00:01:29.410 0: HMLAN_Parse: HMLAN1 R:E1936FF   stat:0000 t:00002FCA d:FF r:FFB3     m:75 8670 1936FF 000000 00874B
2014.04.19 00:01:29.513 0: HMLAN_Send:  HMLAN1 S:S76DBD5CD stat:  00 t:00000000 d:01 r:76DBD5CD m:25 A112 123ABC 1936FF 
2014.04.19 00:01:29.588 0: HMLAN_Parse: HMLAN1 R:E1936FF   stat:0000 t:00003088 d:FF r:FFB2     m:76 A03F 1936FF 123ABC 
2014.04.19 00:01:29.653 0: HMLAN_Send:  HMLAN1 I:+1936FF,00,00,
2014.04.19 00:01:29.691 0: HMLAN_Delay: HMLAN1 1936FF
2014.04.19 00:01:29.765 4: CUL_Parse: cul868 A 09 99 8112 123ABC 000001 4D -35.5
2014.04.19 00:01:30.116 0: HMLAN_Parse: HMLAN1 R:E1936FF   stat:0000 t:00007DEC d:FF r:FFB2     m:75 A258 1936FF D3D3D3 0000
2014.04.19 00:01:30.434 0: HMLAN_Parse: HMLAN1 R:R76DBD531 stat:0002 t:00000000 d:FF r:7FFF     m:99 8112 123ABC 000001 
2014.04.19 00:01:30.436 1: HMLAN_Parse: HMLAN1 new condition ok
2014.04.19 00:01:30.525 0: HMLAN_Parse: HMLAN1 R:R76DBD5CD stat:0008 t:00000000 d:FF r:7FFF     m:25 A112 123ABC 1936FF 
2014.04.19 00:01:30.529 0: HMLAN_Parse: HMLAN1 no ACK from 1936FF
2014.04.19 00:01:30.533 4: CUL_Parse: cul868 A 09 25 A112 123ABC 1936FF 4D -35.5
2014.04.19 00:01:30.544 4: CUL_Parse: cul868 A 09 25 A112 123ABC 1936FF 4D -35.5
2014.04.19 00:01:30.555 4: CUL_Parse: cul868 A 09 25 A112 123ABC 1936FF 4D -35.5
2014.04.19 00:01:31.235 0: HMLAN_Send:  HMLAN1 I:+1BFC52,00,00,
2014.04.19 00:01:31.239 0: HMLAN_Send:  HMLAN1 S:S76DBDCAB stat:  00 t:00000000 d:01 r:76DBDCAB m:3B A258 B1B1B1 1BFC52 0000
2014.04.19 00:01:31.310 4: CUL_Parse: cul868 A 0B 3B A258 B1B1B1 1BFC52 00004D -35.5
2014.04.19 00:01:31.320 4: RCV L:0B N:3B F:A2 CMD:58 SRC:VentilControler.Kueche DST:Ventil.Kueche 0000 (ClimateEvent CMD:0x00 ValvePos:0) (,WAKEMEUP,BIDI,RPTEN)
2014.04.19 00:01:31.437 0: HMLAN_Parse: HMLAN1 R:E1BFC52   stat:0000 t:0000CF01 d:FF r:FFD1     m:3B 8202 1BFC52 B1B1B1 010100002E
2014.04.19 00:01:31.530 4: CUL_Parse: cul868 A 0E 3B 8202 1BFC52 B1B1B1 010100002E0E -67
2014.04.19 00:01:31.548 4: CUL_Parse: cul868 A 0B 3B A258 B1B1B1 1BFC52 00004D -35.5
2014.04.19 00:01:31.627 4: CUL_Parse: cul868 A 0B 3B A258 B1B1B1 1BFC52 00004D -35.5
2014.04.19 00:01:31.805 0: HMLAN_Parse: HMLAN1 R:R76DBDCAB stat:0008 t:00000000 d:FF r:7FFF     m:3B A258 B1B1B1 1BFC52 0000
2014.04.19 00:01:31.808 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.04.19 00:01:36.069 4: CUL_Parse: cul868 A 0C B6 8670 1DFDA5 000000 00A23D14 -64
2014.04.19 00:01:36.084 4: RCV L:0C N:B6 F:86 CMD:70 SRC:Thermostat.SZ DST:broadcast 00A23D (WeatherEvent TEMP:16.2 HUM:61) (,WAKEMEUP,CFG,RPTEN)
2014.04.19 00:01:36.261 0: HMLAN_Parse: HMLAN1 R:E1DFDA5   stat:0000 t:0000E13C d:FF r:FFBC     m:B6 8670 1DFDA5 000000 00A23D
2014.04.19 00:01:36.278 0: HMLAN_Parse: HMLAN1 R:E1DFDA5   stat:0000 t:0000E1FD d:FF r:FFBC     m:B7 A03F 1DFDA5 123ABC 
2014.04.19 00:01:36.347 0: HMLAN_Send:  HMLAN1 I:+1DFDA5,00,00,
2014.04.19 00:01:36.381 0: HMLAN_Send:  HMLAN1 S:+1DFDA5,00,01,1E
2014.04.19 00:01:36.383 0: HMLAN_Send:  HMLAN1 S:S76DBF0A2 stat:  00 t:00000000 d:01 r:76DBF0A2 m:B7 803F 123ABC 1DFDA5 02041AE45D40
2014.04.19 00:01:36.451 4: CUL_Parse: cul868 A 09 B7 A03F 1DFDA5 123ABC 14 -64
2014.04.19 00:01:36.465 0: HMLAN_Parse: HMLAN1 R:R76DBF0A2 stat:0002 t:00000000 d:FF r:7FFF     m:B7 803F 123ABC 1DFDA5 02041AE45D40
2014.04.19 00:01:36.471 4: CUL_Parse: cul868 A 0F B7 803F 123ABC 1DFDA5 02041AE45D404D -35.5
2014.04.19 00:01:54.328 0: HMLAN_Send:  HMLAN1 I:K
2014.04.19 00:01:54.341 0: HMLAN_Parse: HMLAN1 V:03C1 sNo:JEQ0315335 d:1C671E O:123ABC t:0001289D IDcnt:0003
2014.04.19 00:01:56.189 4: CUL_Parse: cul868 A 0B B6 A258 1DFDA5 D2D2D2 006C15 -63.5
2014.04.19 00:01:56.204 4: RCV L:0B N:B6 F:A2 CMD:58 SRC:Thermostat.SZ DST:TCControler.SZ 006C (ClimateEvent CMD:0x00 ValvePos:108) (,WAKEMEUP,BIDI,RPTEN)
2014.04.19 00:01:56.292 0: HMLAN_Send:  HMLAN1 S:+1DFDA5,00,01,1E
2014.04.19 00:01:56.294 0: HMLAN_Send:  HMLAN1 S:S76DC3E6A stat:  00 t:00000000 d:01 r:76DC3E6A m:B6 8002 D2D2D2 1DFDA5 0101540000
2014.04.19 00:01:56.624 0: HMLAN_Parse: HMLAN1 R:E1DFDA5   stat:0000 t:00012FD9 d:FF r:FFBC     m:B6 A258 1DFDA5 D2D2D2 006C
2014.04.19 00:01:56.636 0: HMLAN_Parse: HMLAN1 R:R76DC3E6A stat:0002 t:00000000 d:FF r:7FFF     m:B6 8002 D2D2D2 1DFDA5 0101540000
2014.04.19 00:01:56.642 4: CUL_Parse: cul868 A 0E B6 8002 D2D2D2 1DFDA5 01015400004D -35.5
2014.04.19 00:01:56.656 4: RCV L:0E N:B6 F:80 CMD:02 SRC:TCControler.SZ DST:Thermostat.SZ 0101540000 (ACK_STATUS CHANNEL:0x01 STATUS:0x54 UP:0 DOWN:0 LOWBAT:0 RSSI:0) (,RPTEN)
2014.04.19 00:02:04.907 4: CUL_Parse: cul868 A 14 03 845E 24AF1D 000000 800FEE00000000000964F717 -62.5
2014.04.19 00:02:04.917 0: HMLAN_Parse: HMLAN1 R:E24AF1D   stat:0000 t:000151E6 d:FF r:FFC2     m:03 845E 24AF1D 000000 800FEE00000000000964F7
2014.04.19 00:02:19.339 0: HMLAN_Send:  HMLAN1 I:K
2014.04.19 00:02:19.351 0: HMLAN_Parse: HMLAN1 V:03C1 sNo:JEQ0315335 d:1C671E O:123ABC t:00018A53 IDcnt:0003
2014.04.19 00:02:21.755 4: CUL_Parse: cul868 A 0C 50 8670 1BF81B 000000 00C33A1B -60.5
2014.04.19 00:02:21.769 4: RCV L:0C N:50 F:86 CMD:70 SRC:Thermostat.WZ DST:broadcast 00C33A (WeatherEvent TEMP:19.5 HUM:58) (,WAKEMEUP,CFG,RPTEN)
2014.04.19 00:02:21.838 0: HMLAN_Parse: HMLAN1 R:E1BF81B   stat:0000 t:000193BB d:FF r:FFD1     m:50 8670 1BF81B 000000 00C33A
2014.04.19 00:02:21.947 4: CUL_Parse: cul868 A 09 51 A03F 1BF81B 123ABC 1A -61
2014.04.19 00:02:21.957 4: RCV L:09 N:51 F:A0 CMD:3F SRC:Thermostat.WZ DST:123ABC  (TimeStamp) (,BIDI,RPTEN)
2014.04.19 00:02:22.011 0: HMLAN_Send:  HMLAN1 I:+1BF81B,00,00,
2014.04.19 00:02:22.049 0: HMLAN_Send:  HMLAN1 S:S76DCA302 stat:  00 t:00000000 d:01 r:76DCA302 m:51 803F 123ABC 1BF81B 02041AE45D6E
2014.04.19 00:02:22.130 0: HMLAN_Parse: HMLAN1 R:E1BF81B   stat:0000 t:0001947B d:FF r:FFD1     m:51 A03F 1BF81B 123ABC 
2014.04.19 00:02:22.143 0: HMLAN_Parse: HMLAN1 R:R76DCA302 stat:0002 t:00000000 d:FF r:7FFF     m:51 803F 123ABC 1BF81B 02041AE45D6E
2014.04.19 00:02:22.149 4: CUL_Parse: cul868 A 0F 51 803F 123ABC 1BF81B 02041AE45D6E4D -35.5
2014.04.19 00:02:30.571 4: CUL_Parse: cul868 A 0C 47 8670 1D252E 000000 00C03900 -74
2014.04.19 00:02:30.586 4: RCV L:0C N:47 F:86 CMD:70 SRC:Thermostat.Kueche DST:broadcast 00C039 (WeatherEvent TEMP:19.2 HUM:57) (,WAKEMEUP,CFG,RPTEN)
2014.04.19 00:02:30.772 0: HMLAN_Parse: HMLAN1 R:E1D252E   stat:0000 t:0001B62D d:FF r:FFC4     m:47 8670 1D252E 000000 00C039
2014.04.19 00:02:30.789 0: HMLAN_Parse: HMLAN1 R:E1D252E   stat:0000 t:0001B6F5 d:FF r:FFC4     m:48 A03F 1D252E 123ABC 
2014.04.19 00:02:30.857 0: HMLAN_Send:  HMLAN1 I:+1D252E,00,00,
2014.04.19 00:02:30.892 0: HMLAN_Send:  HMLAN1 S:+1D252E,00,01,1E
2014.04.19 00:02:30.894 0: HMLAN_Send:  HMLAN1 S:S76DCC590 stat:  00 t:00000000 d:01 r:76DCC590 m:48 803F 123ABC 1D252E 02041AE45D76
2014.04.19 00:02:30.965 4: CUL_Parse: cul868 A 09 48 A03F 1D252E 123ABC 01 -73.5
2014.04.19 00:02:30.979 0: HMLAN_Parse: HMLAN1 R:R76DCC590 stat:0002 t:00000000 d:FF r:7FFF     m:48 803F 123ABC 1D252E 02041AE45D76
2014.04.19 00:02:30.985 4: CUL_Parse: cul868 A 0F 48 803F 123ABC 1D252E 02041AE45D764D -35.5
2014.04.19 00:02:41.859 4: CUL_Parse: cul868 A 0B 50 A258 1BF81B D4D4D4 000E1A -61
2014.04.19 00:02:41.873 4: RCV L:0B N:50 F:A2 CMD:58 SRC:Thermostat.WZ DST:TCControler.WZ 000E (ClimateEvent CMD:0x00 ValvePos:14) (,WAKEMEUP,BIDI,RPTEN)
2014.04.19 00:02:42.197 0: HMLAN_Parse: HMLAN1 R:E1BF81B   stat:0000 t:0001E245 d:FF r:FFD1     m:50 A258 1BF81B D4D4D4 000E
2014.04.19 00:02:44.349 0: HMLAN_Send:  HMLAN1 I:K
2014.04.19 00:02:44.362 0: HMLAN_Parse: HMLAN1 V:03C1 sNo:JEQ0315335 d:1C671E O:123ABC t:0001EC09 IDcnt:0005
2014.04.19 00:02:50.704 4: CUL_Parse: cul868 A 0B 47 A258 1D252E D1D1D1 000E00 -74
2014.04.19 00:02:50.717 4: RCV L:0B N:47 F:A2 CMD:58 SRC:Thermostat.Kueche DST:TCControler.Kueche 000E (ClimateEvent CMD:0x00 ValvePos:14) (,WAKEMEUP,BIDI,RPTEN)
2014.04.19 00:02:51.111 0: HMLAN_Parse: HMLAN1 R:E1D252E   stat:0000 t:000204D4 d:FF r:FFC3     m:47 A258 1D252E D1D1D1 000E
2014.04.19 00:03:09.360 0: HMLAN_Send:  HMLAN1 I:K
2014.04.19 00:03:09.373 0: HMLAN_Parse: HMLAN1 V:03C1 sNo:JEQ0315335 d:1C671E O:123ABC t:00024DBF IDcnt:0005
2014.04.19 00:03:34.372 0: HMLAN_Send:  HMLAN1 I:K
2014.04.19 00:03:34.383 0: HMLAN_Parse: HMLAN1 V:03C1 sNo:JEQ0315335 d:1C671E O:123ABC t:0002AF76 IDcnt:0005

seltsam finde ich folgenden eintrag, da zu dieser zeit der hmlan disconnected sein soll.

Code Auswählen

2014.04.19 00:00:29.420 4: CUL_Parse: cul868 A 0F 47 803F 123ABC 1D252E 02041AE45CF94D -35.5

wie läuft der keepalive mechanismus denn technisch ab? ich stell mir das eigentlich so vor. irritieren stört mich dann allerdings der ausdruck "HMLAN_Send". fhem sendet über lan:

Code Auswählen

2014.04.19 00:02:19.339 0: HMLAN_Send: HMLAN1 I:K
wenn alles normal läuft, sendet der hmlan über lan zurück:

Code Auswählen

2014.04.19 00:02:19.351 0: HMLAN_Parse: HMLAN1 V:03C1 sNo:JEQ0315335 d:1C671E O:123ABC t:00018A53 IDcnt:0003

sollte die antwort nicht innerhalb einer sekunde erfolgen, wird bis zu 3 mal wiederholt. wenn immer noch keine antwort kommt, entscheidet fhem timeout. daraufhin veranlasst fhem ein disconnect? oder wird wegen den maximalen 30 sekunden zwischen den keepalive meldungen dann ein disconnect erwartet (antizipiert)?

wenn der disconnect antizipiert wird, kann der cul natürlich noch einen funkspruch vom hmlan empfangen, wenn der hmlan reel noch nicht disconnectet hat. dann stellt sich mir aber die frage, warum der funkspruch nicht mit hmlan_send im log auftaucht? das würde natürlich zum nichtbeantworten der keepalive meldung passen. also funkmodul funktioniert, aber lanmodul ist tot. besser lanverbindung zu fhem.

macht es sinn, wenn ich heute nacht den wdtimer auf 10 sekunden setze, so dass
noch eine 2. sequenz des keepalive algorithmus gesendet wird, bevor der hmlan disconnectet? oder verhindert bereits ein erstes timeout das senden eines weiteren keepalive?

hoffentlich fällt dir etwas entscheidendes auf!

gruss frank

martinp876 · 19 April 2014, 12:33:52

Code Auswählen

2014.04.19 00:00:18.201 0: HMLAN_Send:  HMLAN1 S:S76DABF36 stat:  00 t:00000000 d:01 r:76DABF36 m:50 803F 123ABC 1BF81B 02041AE45CF2
2014.04.19 00:00:18.271 0: HMLAN_Parse: HMLAN1 R:E1BF81B   stat:0000 t:0A4BCC6C d:FF r:FFD1     m:50 A03F 1BF81B 123ABC

seltsam, dass der Request des 1BF81B nach der Antwort kommt. Verstehe ich nicht, ist bei mir nicht so.

Zitatseltsam finde ich folgenden eintrag, da zu dieser zeit der hmlan disconnected sein soll.

das könnte von der angeschlossenen CUL kommen - das erklärt dann auch das obige Verhalten.

ZitatIch stell mir das eigentlich so vor. .....

korrekt

Zitatdaraufhin veranlasst fhem ein disconnect?

reconnect würde ich sagen

Zitatwenn der disconnect antizipiert wird, kann der cul natürlich noch einen funkspruch vom hmlan empfangen

klar. HMLAN ist nicht im reset - und hat ein eigenleben.

Zitatmacht es sinn, wenn ich heute nacht den wdtimer auf 10 sekunden setze, so dass
noch eine 2. sequenz des keepalive algorithmus gesendet wird, bevor der hmlan disconnectet? oder verhindert bereits ein erstes timeout das senden eines weiteren keepalive?

du kannst respTime auf 5 setzen. dann darf eine Antwort auf das keepAlive 5 sec dauern. Macht also 15 sec warten.
Generell halte ich den Mechanismus für korrekt. Der HMLAN antwortet 3 sec nicht, was ich schon einmal nicht für tragbar halte. Wenn man einen reconnect macht ist dies m.E. schneller, als zu warten. Möglich ist es schon, dass sich HMLAN selbst wieder meldet, dir Frage ist nur, was am schnellsten geht.
HMLAN sollte den Abbruch der TCP connection beim reconnect verarbeiten und einen neuen Aufbau starten.
So oder so - disconnect sieht schlecht aus. Fakt ist aber, dass das IO nicht erreichbar ist, auch wenn wir den Disconnect evtl vermeiden können.
Die Frage ist also, warum der HMLAN nicht antwortet - nicht ob es auch ohne disconnect zu schaffen ist. Den Disconnect halte ich im Gegenteil sogar für gut - er kehrt den Inoperablen Zustand nicht unter den Tisch.

Gruss Martin

frank · 19 April 2014, 15:07:08

ZitatDie Frage ist also, warum der HMLAN nicht antwortet - nicht ob es auch ohne disconnect zu schaffen ist.

ich denke nur darüber nach, den hmlan mit irgendwas zu "provozieren", um anhand der reaktionen eventuell einen hinweis auf dieses verhalten zu bekommen.

ansonsten habe ich im moment den verdacht, dass es mit den virtuellen vd zusammen hängen könnte. jeder tc ist bei mir mit einem vvd gepeert, damit die tc nicht zur vollen stunde beepen. zusätzlich versetze ich die vvd zeitweise in den dummy-zustand, um den traffic vom hmlan zu reduzieren. eventuell wird das dummy-verhalten seltsam umgesetzt. ich werde mal testen.

gruss frank

martinp876 · 19 April 2014, 15:45:01

Zitatich denke nur darüber nach, den hmlan mit irgendwas zu "provozieren", um anhand der reaktionen eventuell einen hinweis auf dieses verhalten zu bekommen.

guter Ansatz.

Zitatansonsten habe ich im moment den verdacht, dass es mit den virtuellen vd zusammen hängen könnte.

hm .... möglich. Möglich ist da viel. Der HMLAN kann viel mehr, und hat noch viele mir unbekannte Einstellungen.
Erstaunlich wäre, dass es um 0:00 zum Problem kommt. Und dass die Uhrzeit zufällig hier von TC abgefragt wird.
Vielleicht passiert auch sonst etwas in deinem Netzwerk? Vielleicht solltest du den Wireshark bemühen?

Dass HMLAN um Mitternacht alle seine devices kontrolliert ist seltsam - habe es noch nicht beobachtet.

frank · 20 April 2014, 12:12:00

nach abschaltung des setzens von attr dummy der virtuellen vd gab es heute kein timeout.

gruss frank