HMLAN Adapter wechselt permanent zwischen disconnected / connected

Ma_Bo · 16 Oktober 2016, 19:20:35

Schau mal hier : http://www.fhemwiki.de/wiki/HM-LGW-O-TW-W-EU_Funk-LAN_Gateway

Und hier : https://forum.fhem.de/index.php?topic=54511.0

Gesendet von iPhone mit Tapatalk

Tommy82 · 23 November 2016, 20:34:02

Hi, seit einigen Tage, ist mein Fhem wieso auch immer wieder sehr träge und auch im Log tauchen wieder vermehrt disconnects auf.

apptime max sagt dazu:

Code Auswählen

               name             function    max  count    total  average maxDly 
                        HmLanAdapter           HMLAN_Read  30097     74   580787  7848.47      0 HASH(HmLanAdapter) 
                         rd_Batterie readingsGroup_Notify   6569   1743   304970   174.97      0 HASH(rd_Batterie); HASH(Bewegungsmelder) 
                        rd_Batterie2 readingsGroup_Notify   6502   1743   277033   158.94      0 HASH(rd_Batterie2); HASH(Kinderzimmer_Laya) 
                              inTemp          FileLog_Log   6442   1743   563986   323.57      0 HASH(inTemp); HASH(rd_Batterie2) 
                   tmr-HttpUtils_Err      HASH(0x4a73ba0)   3182      1     3182  3182.00    224 HASH(0x4a73ba0) 
                         FireTV_Kodi           XBMC_Ready   3007   1103    48234    43.73      0 HASH(FireTV_Kodi) 
                   tmr-HttpUtils_Err      HASH(0x4a02cb8)   2630      1     2630  2630.00  27207 HASH(0x4a02cb8) 
                   tmr-HttpUtils_Err      HASH(0x49df9f8)   2587      1     2587  2587.00  29835 HASH(0x49df9f8) 
                        rg_VU_Ultimo readingsGroup_Notify   1676   1743     6317     3.62      0 HASH(rg_VU_Ultimo); HASH(VU_Ultimo) 
                 rg_Uno_Schlafzimmer readingsGroup_Notify   1484   1743     3135     1.80      0 HASH(rg_Uno_Schlafzimmer); HASH(Uno_Schlafzimmer) 
                         FBSmartHome           FBAHA_Read    996     12     6572   547.67      0 HASH(FBSmartHome) 
                         TV_Programm readingsGroup_Notify    977   1743     4998     2.87      0 HASH(TV_Programm); HASH(TV_Programme) 
                         tmr-at_Exec      HASH(0x2008c18)    474    106    13048   123.09  32975 HASH(at_fp_time) 
                         VU_UltimoRG readingsGroup_Notify    454   1743     2161     1.24      0 HASH(VU_UltimoRG); HASH(VU_Ultimo) 
                                 WEB              FW_Read    450     47    12441   264.70      0 HASH(WEB) 
                         tmr-at_Exec      HASH(0x3bb92e0)    363      1      363   363.00      4 HASH(Steckdose_Flur_Oben_Stehlampe_an) 
                 Steckdose_Flur_Oben               IT_Set    359      3      360   120.00      0 HASH(Steckdose_Flur_Oben); Steckdose_Flur_Oben; on 
                  Batterie_Status_nt          notify_Exec    351   1743     5111     2.93      0 HASH(Batterie_Status_nt); HASH(rd_Batterie) 
                             myDbLog            DbLog_Log    329   1743    18355    10.53      0 HASH(myDbLog); HASH(VU_Ultimo) 
               rg_Uno_Schlafzimmer_1 readingsGroup_Notify    302   1743     1530     0.88      0 HASH(rg_Uno_Schlafzimmer_1); HASH(Uno_Schlafzimmer) 
                              Cul433              CUL_Get    290      1      290   290.00      0 HASH(Cul433);  ; raw; is0000FF000FFF

Sieht da schon einer etwas auffälliges?

Perform lass ich aktuell im Hintergrund laufen, mal sehen was da aufschlägt

Tommy82 · 27 November 2016, 15:01:15

So, hier mal ein paar perform Meldungen, wenn ich mit verbose 3 Log, gibt es jede menge perform Meldungen, auf verbose 5 ist der Log sehr schnell sehr groß, obwohl ich nur nachts geloggt habe, wo eigentlich nichts groß laufen sollte

Code Auswählen

2016.11.26 00:00:47.011 1: Perfmon: possible freeze starting at 00:00:44, delay is 3.01
2016.11.26 00:00:47.016 5: SYSMON sysmon: updateReadings.1056 
2016.11.26 00:00:47.023 5: Triggering sysmon (1 changes)
2016.11.26 00:00:47.024 5: Starting notify loop for sysmon, first event cpu_freq: 480
2016.11.26 00:00:47.036 5: ABFALL_Notify(myAbfall) - Device: sysmon
2016.11.26 00:00:47.036 5: Notify from Device: sysmon recieved
2016.11.26 00:00:47.038 5: DbLog: logging of Device: sysmon , Type: SYSMON , Event: cpu_freq: 480 , Reading: cpu_freq , Value: 480 , Unit: 
2016.11.26 00:00:47.043 5: rd_Batterie: not on any display, ignoring notify
2016.11.26 00:00:47.044 5: rd_Batterie2: not on any display, ignoring notify
2016.11.26 00:00:47.045 5: rd_SysInfo: not on any display, ignoring notify
2016.11.26 00:00:47.064 4: BlockingCall (SYSMON_blockingCall): created child (15844), uses telnetPort to connect back
2016.11.26 00:00:47.078 5: HMLAN_Send:  HmLanAdapter I:K
2016.11.26 00:00:47.083 5: exec at command at_fp_time
2016.11.26 00:00:47.085 5: Cmd: >{ fhem 'set fp_time '.strftime('%H:%M Uhr', localtime) }<
2016.11.26 00:00:47.089 5: Cmd: >set fp_time 00:00 Uhr<
2016.11.26 00:00:47.092 4: dummy set fp_time 00:00 Uhr
2016.11.26 00:00:47.095 5: Triggering fp_time (1 changes)
2016.11.26 00:00:47.096 5: Starting notify loop for fp_time, first event 00:00 Uhr
2016.11.26 00:00:47.101 5: SYSMON sysmon: blockingCall.950 sysmon, 
2016.11.26 00:00:47.103 5: SYSMON sysmon: Exec_Local.4093 Execute 'cat /proc/uptime'
2016.11.26 00:00:47.116 5: ABFALL_Notify(myAbfall) - Device: fp_time
2016.11.26 00:00:47.117 5: Notify from Device: fp_time recieved
2016.11.26 00:00:47.121 5: DbLog: logging of Device: fp_time , Type: DUMMY , Event: 00:00 Uhr , Reading: state , Value: 00:00 Uhr , Unit: 
2016.11.26 00:00:47.132 5: rd_Batterie: not on any display, ignoring notify
2016.11.26 00:00:47.133 5: rd_Batterie2: not on any display, ignoring notify
2016.11.26 00:00:47.135 5: rd_Datum: not on any display, ignoring notify
2016.11.26 00:00:47.139 5: redefine at command at_fp_time as +*00:00:10 { fhem 'set fp_time '.strftime('%H:%M Uhr', localtime) }
2016.11.26 00:00:47.143 5: Triggering at_fp_time (1 changes)
2016.11.26 00:00:47.144 5: Starting notify loop for at_fp_time, first event Next: 00:00:55
2016.11.26 00:00:47.143 5: SYSMON sysmon: Exec_Local.4106 Result '152519.94 271314.29'
2016.11.26 00:00:47.147 5: SYSMON sysmon: Exec_Local.4093 Execute 'cat /sys/class/hwmon/hwmon0/device/temp1_input 2>&1'
2016.11.26 00:00:47.154 5: ABFALL_Notify(myAbfall) - Device: at_fp_time
2016.11.26 00:00:47.155 5: Notify from Device: at_fp_time recieved
2016.11.26 00:00:47.157 5: DbLog: logging of Device: at_fp_time , Type: AT , Event: Next: 00:00:55 , Reading: Next , Value: 00:00:55 , Unit: 
2016.11.26 00:00:47.164 5: rd_Batterie: not on any display, ignoring notify
2016.11.26 00:00:47.165 5: rd_Batterie2: not on any display, ignoring notify
2016.11.26 00:00:47.173 4: Connection accepted from AMADCommBridge_192.168.188.23_41388
2016.11.26 00:00:47.174 5: HMLAN/RAW: /HHM-LAN-IF,03C1,KEQ0852050,23A503,000041,5385CB90,0010

Code Auswählen

2016.11.26 00:01:50.105 1: Perfmon: possible freeze starting at 00:01:48, delay is 2.105
2016.11.26 00:01:50.110 5: HMLAN/RAW: /HHM-LAN-IF,03C1,KEQ0852050,23A503,000041,5386C1C8,0010

2016.11.26 00:01:50.112 5: HMLAN_Parse: HmLanAdapter V:03C1 sNo:KEQ0852050 d:23A503 O:000041 t:5386C1C8 IDcnt:0010 L:0 %
2016.11.26 00:01:50.115 5: Triggering HmLanAdapter (1 changes)
2016.11.26 00:01:50.116 5: Starting notify loop for HmLanAdapter, first event loadLvl: low
2016.11.26 00:01:50.125 5: SYSMON sysmon: blockingCall.950 sysmon, 
2016.11.26 00:01:50.128 5: SYSMON sysmon: Exec_Local.4093 Execute 'cat /proc/uptime'
2016.11.26 00:01:50.136 5: ABFALL_Notify(myAbfall) - Device: HmLanAdapter
2016.11.26 00:01:50.137 5: Notify from Device: HmLanAdapter recieved
2016.11.26 00:01:50.141 5: DbLog: logging of Device: HmLanAdapter , Type: HMLAN , Event: loadLvl: low , Reading: loadLvl , Value: low , Unit: 
2016.11.26 00:01:50.143 5: FRITZBOX FritzBox7490: Shell_Exec_Telnet.3930 Result '0'
2016.11.26 00:01:50.144 5: FRITZBOX FritzBox7490: Shell_Exec_Telnet.3922 Execute 'ctlmgr_ctl r tam settings/TAM0/NumOldMessages'
2016.11.26 00:01:50.152 5: rd_Batterie: not on any display, ignoring notify
2016.11.26 00:01:50.153 5: rd_Batterie2: not on any display, ignoring notify
2016.11.26 00:01:50.179 5: SYSMON sysmon: Exec_Local.4106 Result '152582.97 271432.59'
2016.11.26 00:01:50.182 5: SYSMON sysmon: Exec_Local.4093 Execute 'cat /sys/class/hwmon/hwmon0/device/temp1_input 2>&1'
2016.11.26 00:01:50.193 5: FRITZBOX FritzBox7490: Shell_Exec_Telnet.3930 Result '1'
2016.11.26 00:01:50.194 5: FRITZBOX FritzBox7490: Shell_Exec_Telnet.3922 Execute 'ctlmgr_ctl r user settings/user0/name'
2016.11.26 00:01:50.217 5: SYSMON sysmon: Exec_Local.4106 Result '34600'
2016.11.26 00:01:50.220 5: SYSMON sysmon: Exec_Local.4093 Execute 'cat /proc/loadavg'
2016.11.26 00:01:50.246 5: FRITZBOX FritzBox7490: Shell_Exec_Telnet.3930 Result '(guest)'
2016.11.26 00:01:50.247 5: FRITZBOX FritzBox7490: Shell_Exec_Telnet.3922 Execute 'ctlmgr_ctl r user settings/user0/this_month_time'
2016.11.26 00:01:50.256 5: SYSMON sysmon: Exec_Local.4106 Result '0.08 0.08 0.12 1/100 16097'
2016.11.26 00:01:50.258 5: SYSMON sysmon: Exec_Local.4093 Execute 'cat /proc/stat'
2016.11.26 00:01:50.296 5: SYSMON sysmon: Exec_Local.4098 Result '$VAR1 = 'cpu  2756609 2436 794471 26505921 36147 294 350088 0 0 0

Code Auswählen

2016.11.26 00:08:08.347 1: Perfmon: possible freeze starting at 00:08:06, delay is 2.347
2016.11.26 00:08:08.371 4: BlockingCall (WOL_Ping): created child (17487), uses telnetPort to connect back
2016.11.26 00:08:08.377 5: SYSMON sysmon: Exec_Local.4106 Result '0.10 0.08 0.12 3/99 17487'
2016.11.26 00:08:08.378 5: SYSMON sysmon: Exec_Local.4093 Execute 'cat /proc/stat'
2016.11.26 00:08:08.389 5: HMLAN/RAW: /HHM-LAN-IF,03C1,KEQ0852050,23A503,000041,538C871E,0010

2016.11.26 00:08:08.398 5: HMLAN_Parse: HmLanAdapter V:03C1 sNo:KEQ0852050 d:23A503 O:000041 t:538C871E IDcnt:0010 L:0 %
2016.11.26 00:08:08.405 5: Triggering HmLanAdapter (1 changes)
2016.11.26 00:08:08.407 5: Starting notify loop for HmLanAdapter, first event loadLvl: low
2016.11.26 00:08:08.420 5: SYSMON sysmon: Exec_Local.4098 Result '$VAR1 = 'cpu  2758495 2436 795962 26577048 36156 295 350917 0 0 0

Code Auswählen

2016.11.26 00:11:17.291 1: Perfmon: possible freeze starting at 00:11:15, delay is 2.29
2016.11.26 00:11:17.293 5: exec at command at_fp_time
2016.11.26 00:11:17.295 5: Cmd: >{ fhem 'set fp_time '.strftime('%H:%M Uhr', localtime) }<
2016.11.26 00:11:17.300 5: Cmd: >set fp_time 00:11 Uhr<
2016.11.26 00:11:17.303 4: dummy set fp_time 00:11 Uhr
2016.11.26 00:11:17.305 5: SYSMON sysmon: blockingCall.950 sysmon, 
2016.11.26 00:11:17.307 5: Triggering fp_time (1 changes)
2016.11.26 00:11:17.308 5: SYSMON sysmon: Exec_Local.4093 Execute 'cat /proc/uptime'
2016.11.26 00:11:17.308 5: Starting notify loop for fp_time, first event 00:11 Uhr
2016.11.26 00:11:17.330 5: ABFALL_Notify(myAbfall) - Device: fp_time
2016.11.26 00:11:17.332 5: Notify from Device: fp_time recieved
2016.11.26 00:11:17.336 5: DbLog: logging of Device: fp_time , Type: DUMMY , Event: 00:11 Uhr , Reading: state , Value: 00:11 Uhr , Unit: 
2016.11.26 00:11:17.344 5: SYSMON sysmon: Exec_Local.4106 Result '153150.14 272526.67'
2016.11.26 00:11:17.348 5: SYSMON sysmon: Exec_Local.4093 Execute 'cat /sys/class/hwmon/hwmon0/device/temp1_input 2>&1'
2016.11.26 00:11:17.353 5: rd_Batterie: not on any display, ignoring notify
2016.11.26 00:11:17.354 5: rd_Batterie2: not on any display, ignoring notify
2016.11.26 00:11:17.355 5: rd_Datum: not on any display, ignoring notify
2016.11.26 00:11:17.360 5: redefine at command at_fp_time as +*00:00:10 { fhem 'set fp_time '.strftime('%H:%M Uhr', localtime) }
2016.11.26 00:11:17.365 5: Triggering at_fp_time (1 changes)
2016.11.26 00:11:17.366 5: Starting notify loop for at_fp_time, first event Next: 00:11:25
2016.11.26 00:11:17.378 5: ABFALL_Notify(myAbfall) - Device: at_fp_time
2016.11.26 00:11:17.379 5: Notify from Device: at_fp_time recieved
2016.11.26 00:11:17.381 5: DbLog: logging of Device: at_fp_time , Type: AT , Event: Next: 00:11:25 , Reading: Next , Value: 00:11:25 , Unit: 
2016.11.26 00:11:17.384 5: SYSMON sysmon: Exec_Local.4106 Result '34600'
2016.11.26 00:11:17.387 5: SYSMON sysmon: Exec_Local.4093 Execute 'cat /proc/loadavg'
2016.11.26 00:11:17.389 5: rd_Batterie: not on any display, ignoring notify
2016.11.26 00:11:17.390 5: rd_Batterie2: not on any display, ignoring notify
2016.11.26 00:11:17.406 4: Connection accepted from AMADCommBridge_192.168.188.23_54413
2016.11.26 00:11:17.408 5: HMLAN/RAW: /HHM-LAN-IF,03C1,KEQ0852050,23A503,000041,538F69B5,0010

Code Auswählen

2016.11.26 02:32:01.364 1: Perfmon: possible freeze starting at 02:31:59, delay is 2.363
2016.11.26 02:32:01.368 5: HMLAN/RAW: /HHM-LAN-IF,03C1,KEQ0852050,23A503,000041,54104741,0010

2016.11.26 02:32:01.377 5: HMLAN_Parse: HmLanAdapter V:03C1 sNo:KEQ0852050 d:23A503 O:000041 t:54104741 IDcnt:0010 L:0 %
2016.11.26 02:32:01.380 5: Triggering HmLanAdapter (1 changes)
2016.11.26 02:32:01.381 5: Starting notify loop for HmLanAdapter, first event loadLvl: low
2016.11.26 02:32:01.397 5: SYSMON sysmon: blockingCall.950 sysmon, 
2016.11.26 02:32:01.401 5: SYSMON sysmon: Exec_Local.4093 Execute 'cat /proc/uptime'
2016.11.26 02:32:01.433 5: ABFALL_Notify(myAbfall) - Device: HmLanAdapter
2016.11.26 02:32:01.435 5: Notify from Device: HmLanAdapter recieved
2016.11.26 02:32:01.452 5: DbLog: logging of Device: HmLanAdapter , Type: HMLAN , Event: loadLvl: low , Reading: loadLvl , Value: low , Unit: 
2016.11.26 02:32:01.476 5: rd_Batterie: not on any display, ignoring notify
2016.11.26 02:32:01.477 5: rd_Batterie2: not on any display, ignoring notify
2016.11.26 02:32:01.498 5: SYSMON sysmon: Exec_Local.4106 Result '161594.32 288789.12'
2016.11.26 02:32:01.502 5: SYSMON sysmon: Exec_Local.4093 Execute 'cat /sys/class/hwmon/hwmon0/device/temp1_input 2>&1'
2016.11.26 02:32:01.560 5: SYSMON sysmon: Exec_Local.4106 Result '34600'
2016.11.26 02:32:01.564 5: SYSMON sysmon: Exec_Local.4093 Execute 'cat /proc/loadavg'
2016.11.26 02:32:01.603 5: SYSMON sysmon: Exec_Local.4106 Result '0.08 0.05 0.07 2/105 16274'
2016.11.26 02:32:01.604 5: SYSMON sysmon: Exec_Local.4093 Execute 'cat /proc/stat'
2016.11.26 02:32:01.648 5: SYSMON sysmon: Exec_Local.4098 Result '$VAR1 = 'cpu  2802074 2436 830168 28200740 36647 313 369883 0 0 0

Für mich ist auffällig das sysmon immer wieder auftritt!?

RadioJames · 02 März 2017, 11:44:10

Hallo allerseits,

leider bin auch ich seit einiger Zeit vom "HMLAN disconnected/connected" - Problem betroffen.

Aus verschiedenen Threads konnte ich folgende potentielle Fehlerursachen finden:
(1) Blockierende Threads mit einer Dauer > 10 Sekunden
(2) Multicast Messages
(3) Elkos im HMLAN, deren Kapazität sich wesentlich geändert hat

Da ich zwei HMLANs besitze und nur einer das Verhalten zeigt, habe ich beide getauscht. Nun trat das Problem beim vorher fehlerfrei arbeitenden HMLAN auf und der andere arbeite tadellos.
Damit möchte ich Problem (3) ausschließen.

Problem (1) versuche ich gerade mit AppTime auf die Spur zu kommen. Es trat glücklicherweise auch kurz nach dessen Start auf. Allerdings kann ich in der Zusamenfassung unten keine Probleme erkennen.

Könnte das bitte jemand bestätigen, der sich damit gut auskennt?

Code Auswählen

------------------------------------------------------------------------------------------------------------------------------------------------------------
name                          function                max    count   total   average    maxDly 
------------------------------------------------------------------------------------------------------------------------------------------------------------
HMLAN1                        HMLAN_Read              389    270     3573    13.23      0 HASH(HMLAN1) 
FileLog_iStatus               FileLog_Log             373    101      442     4.38      0 HASH(FileLog_iStatus); HASH(iStatus) 
tmr-CUL_HM_procQs             CUL_HM_procQs           254      4      362    90.50     23 CUL_HM_procQs 
tmr-at_Exec                   HASH(0x12d3150)         169      1      169   169.00      7 HASH(at.tmp.Warn_open_egTSensor_3) 
tmr-at_Exec                   HASH(0x3b6d048)         161      1      161   161.00      4 HASH(at.tmp.Warn_open_egTSensor_5) 
tmr-at_Exec                   HASH(0x335f0d8)         116      1      116   116.00      5 HASH(at.tmp.Warn_open_egTSensor_4) 
HMLAN2                        HMLAN_Read              107    225     5808    25.81      0 HASH(HMLAN2) 
tmr-HMLAN_KeepAliveCheck      keepAliveCk:HMLAN2       54     57       54     0.95     42 keepAliveCk:HMLAN2 
Batteriezustand               readingsGroup_Notify     51    574      267     0.47      0 HASH(Batteriezustand); HASH(global) 
tmr-Weather_GetUpdate         HASH(0x2cbcd70)          33      1       33    33.00      1 HASH(MeinWetter) 
HMLAN2                        HMLAN_Ready              28      2       28    14.00      0 HASH(HMLAN2) 
tmr-HourCounter_Run           Counter.azSteckdose1     28      1       28    28.00      3 Counter.azSteckdose1 
tmr-HourCounter_Run           Counter.azLichtSchalter  19      1       19    19.00      0 Counter.azLichtSchalter 
tmr-FRITZBOX_Readout_Start    FritzBox.Readout         15     22      308    14.00     31 FritzBox.Readout 
tmr-PRESENCE_StartLocalScan   HASH(0x2a06570)          14      4       56    14.00     12 HASH(Handy_1) 
tmr-PRESENCE_StartLocalScan   HASH(0x2dc5908)          14      4       56    14.00      2 HASH(Handy_2) 
tmr-at_Exec                   HASH(0x1ec9140)          14      1       14    14.00      1 HASH(at.check_system) 
HMLAN2                        HMLAN_Notify             13    574       13     0.02      0 HASH(HMLAN2); HASH(HMLAN2) 
at.tmp.Warn_open_egTSensor_6  at_Define                12      1       12    12.00      0 HASH(at.tmp.Warn_open_egTSensor_6);
                                                                                               at.tmp.Warn_open_egTSensor_6 at +00:10:00
                                                                                               {Send_open_warning("egTSensor")} 
tmr-CUL_HM_readStateTo        sUpdt:iStatus            12      1       12    12.00     36 sUpdt:iStatus 
tmr-CUL_HM_ActCheck           ActionDetector            7      3       21     7.00      5 ActionDetector

Zu Problem (3) mache ich mich gerade schlau.
Mein erster Ansatz ist, Geräte vom Netz zu nehmen, die einen UPnP Server besitzen.
Eventuell hat jemand noch Tipps, wie hier am Besten vorzugehen ist.

Ciao James

Ergänzung:
In der Zwischenzeit habe ich nun auch de Bedeutung des Internals "msgKeepAlive" und des Readings "wdTimer" verstanden.

Code Auswählen

msgKeepAlive dlyMax:28.055 bufferMin:-23
wdTimer     25

Das sollte also bedeuten, ich habe ein Problem im Netzwerk.
Der HMLAN erwartet alle 30 Sekunden eine Keep-Alive-Message von der Zentrale. Er fordert sie alle 25 Sekunden an (Inhalt von "wdTimer"), hat damit einen Puffer von 5 Sekunden.
Wie "msgKeepAlive" zeigt, kam es im Fehlerfall zu einer Verzögerung von ~28 Sekunden ("dlyMax"), was den Puffer von 5 Sekunde um 23 Sekunden ("bufferMin") überschritt.
Damit dürfte Problem (1) auch ausgeschlossen sein und ich fokussiere auf Problem (2), die Multicasts oder generell Blockierungen im LAN.

Kommentare sind natürlich weiterhin äußerst erwünscht

Wuppi68 · 02 März 2017, 13:27:39

Zitat von: RadioJames am 02 März 2017, 11:44:10
Hallo allerseits,

leider bin auch ich seit einiger Zeit vom "HMLAN disconnected/connected" - Problem betroffen.

Aus verschiedenen Threads konnte ich folgende potentielle Fehlerursachen finden:
(1) Blockierende Threads mit einer Dauer > 10 Sekunden
(2) Multicast Messages
(3) Elkos im HMLAN, deren Kapazität sich wesentlich geändert hat

Da ich zwei HMLANs besitze und nur einer das Verhalten zeigt, habe ich beide getauscht. Nun trat das Problem beim vorher fehlerfrei arbeitenden HMLAN auf und der andere arbeite tadellos.
Damit möchte ich Problem (3) ausschließen.

Problem (1) versuche ich gerade mit AppTime auf die Spur zu kommen. Es trat glücklicherweise auch kurz nach dessen Start auf. Allerdings kann ich in der Zusamenfassung unten keine Probleme erkennen.

Könnte das bitte jemand bestätigen, der sich damit gut auskennt?
Code Auswählen Erweitern
------------------------------------------------------------------------------------------------------------------------------------------------------------ name function max count total average maxDly ------------------------------------------------------------------------------------------------------------------------------------------------------------ HMLAN1 HMLAN_Read 389 270 3573 13.23 0 HASH(HMLAN1) FileLog_iStatus FileLog_Log 373 101 442 4.38 0 HASH(FileLog_iStatus); HASH(iStatus) tmr-CUL_HM_procQs CUL_HM_procQs 254 4 362 90.50 23 CUL_HM_procQs tmr-at_Exec HASH(0x12d3150) 169 1 169 169.00 7 HASH(at.tmp.Warn_open_egTSensor_3) tmr-at_Exec HASH(0x3b6d048) 161 1 161 161.00 4 HASH(at.tmp.Warn_open_egTSensor_5) tmr-at_Exec HASH(0x335f0d8) 116 1 116 116.00 5 HASH(at.tmp.Warn_open_egTSensor_4) HMLAN2 HMLAN_Read 107 225 5808 25.81 0 HASH(HMLAN2) tmr-HMLAN_KeepAliveCheck keepAliveCk:HMLAN2 54 57 54 0.95 42 keepAliveCk:HMLAN2 Batteriezustand readingsGroup_Notify 51 574 267 0.47 0 HASH(Batteriezustand); HASH(global) tmr-Weather_GetUpdate HASH(0x2cbcd70) 33 1 33 33.00 1 HASH(MeinWetter) HMLAN2 HMLAN_Ready 28 2 28 14.00 0 HASH(HMLAN2) tmr-HourCounter_Run Counter.azSteckdose1 28 1 28 28.00 3 Counter.azSteckdose1 tmr-HourCounter_Run Counter.azLichtSchalter 19 1 19 19.00 0 Counter.azLichtSchalter tmr-FRITZBOX_Readout_Start FritzBox.Readout 15 22 308 14.00 31 FritzBox.Readout tmr-PRESENCE_StartLocalScan HASH(0x2a06570) 14 4 56 14.00 12 HASH(Handy_1) tmr-PRESENCE_StartLocalScan HASH(0x2dc5908) 14 4 56 14.00 2 HASH(Handy_2) tmr-at_Exec HASH(0x1ec9140) 14 1 14 14.00 1 HASH(at.check_system) HMLAN2 HMLAN_Notify 13 574 13 0.02 0 HASH(HMLAN2); HASH(HMLAN2) at.tmp.Warn_open_egTSensor_6 at_Define 12 1 12 12.00 0 HASH(at.tmp.Warn_open_egTSensor_6); at.tmp.Warn_open_egTSensor_6 at +00:10:00 {Send_open_warning("egTSensor")} tmr-CUL_HM_readStateTo sUpdt:iStatus 12 1 12 12.00 36 sUpdt:iStatus tmr-CUL_HM_ActCheck ActionDetector 7 3 21 7.00 5 ActionDetector

Zu Problem (3) mache ich mich gerade schlau.
Mein erster Ansatz ist, Geräte vom Netz zu nehmen, die einen UPnP Server besitzen.
Eventuell hat jemand noch Tipps, wie hier am Besten vorzugehen ist.

Ciao James

Ergänzung:
In der Zwischenzeit habe ich nun auch de Bedeutung des Internals "msgKeepAlive" und des Readings "wdTimer" verstanden.

Code Auswählen Erweitern
msgKeepAlive dlyMax:28.055 bufferMin:-23 wdTimer 25

Das sollte also bedeuten, ich habe ein Problem im Netzwerk.
Der HMLAN erwartet alle 30 Sekunden eine Keep-Alive-Message von der Zentrale. Er fordert sie alle 25 Sekunden an (Inhalt von "wdTimer"), hat damit einen Puffer von 5 Sekunden.
Wie "msgKeepAlive" zeigt, kam es im Fehlerfall zu einer Verzögerung von ~28 Sekunden ("dlyMax"), was den Puffer von 5 Sekunde um 23 Sekunden ("bufferMin") überschritt.
Damit dürfte Problem (1) auch ausgeschlossen sein und ich fokussiere auf Problem (2), die Multicasts oder generell Blockierungen im LAN.

Kommentare sind natürlich weiterhin äußerst erwünscht

ist doch schon einmal gut dokumentiert

Ich hätte da noch eine Idee um dem Multicast Problem ein wenig auf die schliche zu kommen.

Wenn Du Dein FHEM NICHT auf der Fritzbox betreibst, dann versuche doch mal folgendes:

Weise Deiner Netzwerkarte von FHEM eine 2. IP Adresse in einem anderen IP Netzwerk zu. In dieses Netzwerk kommt dann der HMLAN der Probleme macht

Also wenn dein Router im Netz 192.168.178.0/24 ist mit der IP 192.168.178.1 (Default von AVM)
Dann füge ein Virtuelles Interface mit z.B. der Adresse 192.168.42.1 hinzu (Abschnitt Virtuelle Schnittstellen in https://wiki.ubuntuusers.de/interfaces/ ) und gibst dem HMLAN dann die Adresse 192.168.42.55 und änderst entsprechend in FHEM die Adresse

Wenn jetzt Besserung eintritt solltest Du entweder mit diesem Workaround leben oder Dir einen Managed Switch kaufen

Nobby1805 · 02 März 2017, 13:50:23

Ich würde als erstes immer nach der uptime des HMLAN schauen ... wenn diese beim disconnect auf 0 gesetzt wurde war es ein Reboot des HMLAN, und das weist auf (2) ... wenn es kein Reboot war dann ist die Ursache eher in (1) zu suchen

LuckyDay · 02 März 2017, 13:52:22

firmware 0.965 drauf?

RadioJames · 02 März 2017, 14:06:27

Hallo,

danke für euer Feedback.

Code Auswählen

... wenn diese beim disconnect auf 0 gesetzt wurde war es ein Reboot des HMLAN, und das weist auf (2)
"uptime" wird zurückgesetzt.

Code Auswählen

firmware 0.965 drauf
Die Firmware auf meinen beiden HMLANs ist die aktuelle V0.965.

@Wuppi68
Danke, interessanter Vorschlag, muss ich geistig aber noch durchdringen.

Mein FHEM läuft auf einem Raspberry Pi im Keller. Die HMLANs sind über das Haus verteilt (Keller, Wohnzimmer). Der Problem-HMLAN ist der im Wohnzimmer.
Das Stichwort Managed Switch hatte ich auch schon mal im Hinterkopf, muss aber zugeben, dass ich das Ganze noch nicht verstanden habe.

Ich denke, es ist jetzt ziemlich eindeutig ein Problem mit meinem Netzwerk und ich werde an Wuppis Vorschlag weiterforschen.

Grüße
James

blade-of-fire · 15 Januar 2018, 21:21:19

Hallo Zusammen,

sorry, dass ich das hier mal wieder aufrolle, aber ich habe letztens angefangen, mein fhem neu aufzusetzen und ziehe zur Zeit nach und nach die Komponenten um. Da ist mir das hier beschriebene verhalten aufgefallen.
@RadioJames: hast du den von Wuppi68 vorgeschlagenen Weg mal ausprobiert? Da ich sowieso nur mit fhem auf den hmlan zugreife, wäre das für mich ein akzeptabler Workaround.

Viele Grüße,

Blade

mrmist · 20 Januar 2018, 19:52:27

Hallo,

ich habe immer dann ständige disconnects des HMALN, wenn ich den CUL aktivere. Beide gleichzeitig kann ich nicht nutzen. Den Grund dafür habe ich noch nicht gefunden. Sobald ich dem CUL "none" als Device gebe, gibt es keine Disconnects des HMLAN mehr.

Kann das jemand bestätigen? Laufen beide parallel bei irgend jemandem ohne Disconnects des HMLAN?

Ach ja: Den CUL habe ich per ser2net angebunden.

Gruß,
Mirko.

Amenophis86 · 20 Januar 2018, 20:10:35

Vccu eingerichtet? Haben beide verschiedene HM ID? Beide auf Verbose 5 stellen und im Log schauen.

mrmist · 22 Januar 2018, 18:16:48

Ja, vccu ist eingerichtet. Allerdings haben beide dieselbe HM ID. Ist das mein Problem?

Amenophis86 · 22 Januar 2018, 18:35:19

Wenn du meinst HMLAN und CUL haben die selbe, dann wird das vermutlich dein Problem sein.

mrmist · 22 Januar 2018, 20:52:13

Da hatte ich wohl etwas misverstanden. Für mich waren beide (CUL und HMLAN) IO-Device für dieselbe vccu und hatten deshalb auch beide die gleiche HM-Id.

Das habe ich jetzt geändert, indem ich dem CUL eine eigene HM-Id zugewiesen und ihn dann der vccu erneut als IO-Device hinzugefügt habe. Das hat auch erst ganz gut funktioniert, aber nun bekomme ich doch wieder die disconnects des HMLAN. $:-\$
Ich habe bei HMLAN und CUL einmal verbose=5 gesetzt. Anbei das Log rund um ein disconnect des HMLAN. Ich kann da leider nicht herauslesen, warum das HMLAN nicht rechtzeitig antwortet.

Code Auswählen


2018.01.22 20:34:03 5: HMLAN_Parse: HMLAN1 V:03C5 sNo:MEQ0313652 d:37A0AD O:F12305 t:0000936B IDcnt:0006 L:1 %

2018.01.22 20:34:03 5: HMLAN/RAW: /HHM-LAN-IF,03C5,MEQ0313652,37A0AD,F12305,0000936B,0006,01
2018.01.22 20:34:03 5: HMLAN_Send:  HMLAN1 I:K
2018.01.22 20:34:01 5: CUL1: dispatch A0CB8847058800600000000BF2C::-88:CUL1
2018.01.22 20:34:01 4: CUL_Parse: CUL1 A 0C B8 8470 588006 000000 00BF2CE4 -88

2018.01.22 20:34:01 5: CUL/RAW: /A0CB8847058800600000000BF2CE4
2018.01.22 20:34:01 5: HMLAN1: dispatch A0AEA8002F123055AF44A00::-73:HMLAN1
2018.01.22 20:34:01 5: HMLAN_Parse: HMLAN1 R:EF12305   stat:0000 t:00008B36 d:FF r:FFB7     m:EA 8002 F12305 5AF44A 00
2018.01.22 20:34:01 5: HMLAN_Send:  HMLAN1 I:+588006,00,01,00
2018.01.22 20:34:01 5: HMLAN1: dispatch A0CB8847058800600000000BF2C::-62:HMLAN1
2018.01.22 20:34:01 5: HMLAN_Parse: HMLAN1 R:E588006   stat:0000 t:00008ADD d:FF r:FFC2     m:B8 8470 588006 000000 00BF2C
2018.01.22 20:34:01 5: HMLAN1: dispatch A14EAA45F5AF44AF1230580B4B20016F201920936FF::-57:HMLAN1
2018.01.22 20:34:01 5: HMLAN_Parse: HMLAN1 R:E5AF44A   stat:0000 t:00008AB3 d:FF r:FFC7     m:EA A45F 5AF44A F12305 80B4B20016F201920936FF

EF12305,0000,00008B36,FF,FFB7,EA8002F123055AF44A00
E588006,0000,00008ADD,FF,FFC2,B8847058800600000000BF2C
2018.01.22 20:34:01 5: HMLAN/RAW: /E5AF44A,0000,00008AB3,FF,FFC7,EAA45F5AF44AF1230580B4B20016F201920936FF
2018.01.22 20:34:01 5: SW: As0AEA8002F123055AF44A00
2018.01.22 20:34:01 5: CUL 5AF44A dly:93ms
2018.01.22 20:34:01 5: CUL1 sending As0AEA8002F123055AF44A00
2018.01.22 20:34:01 5: HMLAN_Send:  HMLAN1 I:-5AF44A
2018.01.22 20:34:01 5: HMLAN_Send:  HMLAN1 I:+5AF44A,00,01,00
2018.01.22 20:34:01 5: CUL1: dispatch A14EAA45F5AF44AF1230580B4B20016F201920936FF::-66:CUL1
2018.01.22 20:34:01 4: CUL_Parse: CUL1 A 14 EA A45F 5AF44A F12305 80B4B20016F201920936FF10 -66

2018.01.22 20:34:01 5: CUL/RAW: /A14EAA45F5AF44AF1230580B4B20016F201920936FF10
2018.01.22 20:33:57 5: HMLAN1: dispatch A0F4586103094FE0000000AB8FB0C2400::-84:HMLAN1
2018.01.22 20:33:57 5: HMLAN_Parse: HMLAN1 R:E3094FE   stat:0000 t:00006E7D d:FF r:FFAC     m:45 8610 3094FE 000000 0AB8FB0C2400

2018.01.22 20:33:57 5: HMLAN/RAW: /E3094FE,0000,00006E7D,FF,FFAC,4586103094FE0000000AB8FB0C2400
2018.01.22 20:33:54 5: CUL1: dispatch A0F4586103094FE0000000AB8FB0C2400::-61.5:CUL1
2018.01.22 20:33:54 4: CUL_Parse: CUL1 A 0F 45 8610 3094FE 000000 0AB8FB0C240019 -61.5

2018.01.22 20:33:54 5: CUL/RAW: /A0F4586103094FE0000000AB8FB0C240019
2018.01.22 20:33:53 5: HMLAN1: dispatch A0AE98002F123055AF44A00::-74:HMLAN1
2018.01.22 20:33:53 5: HMLAN_Parse: HMLAN1 R:EF12305   stat:0000 t:00006BFE d:FF r:FFB6     m:E9 8002 F12305 5AF44A 00

2018.01.22 20:33:53 5: HMLAN/RAW: /EF12305,0000,00006BFE,FF,FFB6,E98002F123055AF44A00
2018.01.22 20:33:53 5: HMLAN1: dispatch A14E9A45F5AF44AF1230580B4B1000DF900FD0936FE::-57:HMLAN1
2018.01.22 20:33:53 5: HMLAN_Parse: HMLAN1 R:E5AF44A   stat:0000 t:00006B71 d:FF r:FFC7     m:E9 A45F 5AF44A F12305 80B4B1000DF900FD0936FE

2018.01.22 20:33:53 5: HMLAN/RAW: /E5AF44A,0000,00006B71,FF,FFC7,E9A45F5AF44AF1230580B4B1000DF900FD0936FE
2018.01.22 20:33:53 5: CUL1: dispatch A0AE98002F123055AF44A00::-69:CUL1
2018.01.22 20:33:53 4: CUL_Parse: CUL1 A 0A E9 8002 F12305 5AF44A 000A -69

2018.01.22 20:33:53 5: CUL/RAW: /A0AE98002F123055AF44A000A
2018.01.22 20:33:53 5: SW: As0AE98002F123055AF44A00
2018.01.22 20:33:53 5: CUL 5AF44A dly:96ms
2018.01.22 20:33:53 5: CUL1 sending As0AE98002F123055AF44A00
2018.01.22 20:33:53 5: HMLAN_Send:  HMLAN1 I:-5AF44A
2018.01.22 20:33:53 5: CUL1: dispatch A14E9A45F5AF44AF1230580B4B1000DF900FD0936FE::-66:CUL1
2018.01.22 20:33:53 4: CUL_Parse: CUL1 A 14 E9 A45F 5AF44A F12305 80B4B1000DF900FD0936FE10 -66

2018.01.22 20:33:53 5: CUL/RAW: /A14E9A45F5AF44AF1230580B4B1000DF900FD0936FE10
2018.01.22 20:33:52 5: HMLAN_Send:  HMLAN1 I:-4513D1
2018.01.22 20:33:52 5: HMLAN1: dispatch A0AE380024513D14B4D1C00::-55:HMLAN1
2018.01.22 20:33:52 5: HMLAN_Parse: HMLAN1 R:E4513D1   stat:0000 t:0000696F d:FF r:FFC9     m:E3 8002 4513D1 4B4D1C 00

2018.01.22 20:33:52 5: HMLAN/RAW: /E4513D1,0000,0000696F,FF,FFC9,E380024513D14B4D1C00
2018.01.22 20:33:52 5: HMLAN_Send:  HMLAN1 I:+4513D1,00,01,00
2018.01.22 20:33:52 5: CUL1: dispatch A0AE380024513D14B4D1C00::-75.5:CUL1
2018.01.22 20:33:52 4: CUL_Parse: CUL1 A 0A E3 8002 4513D1 4B4D1C 00FD -75.5

2018.01.22 20:33:52 5: CUL/RAW: /A0AE380024513D14B4D1C00FD
2018.01.22 20:33:52 5: HMLAN_Send:  HMLAN1 I:-4B4D1C
2018.01.22 20:33:52 5: HMLAN1: dispatch A0CE3A0414B4D1C4513D1017300::-54:HMLAN1
2018.01.22 20:33:52 5: HMLAN_Parse: HMLAN1 R:E4B4D1C   stat:0000 t:000068F0 d:FF r:FFCA     m:E3 A041 4B4D1C 4513D1 017300

2018.01.22 20:33:52 5: HMLAN/RAW: /E4B4D1C,0000,000068F0,FF,FFCA,E3A0414B4D1C4513D1017300
2018.01.22 20:33:52 5: HMLAN_Send:  HMLAN1 I:+4B4D1C,00,01,00
2018.01.22 20:33:52 5: CUL1: dispatch A0CE3A0414B4D1C4513D1017300::-81:CUL1
2018.01.22 20:33:52 4: CUL_Parse: CUL1 A 0C E3 A041 4B4D1C 4513D1 017300F2 -81

2018.01.22 20:33:52 5: CUL/RAW: /A0CE3A0414B4D1C4513D1017300F2
2018.01.22 20:33:52 5: HMLAN_Send:  HMLAN1 I:-45139E
2018.01.22 20:33:52 5: HMLAN1: dispatch A0AE2800245139E4B4D1C00::-51:HMLAN1
2018.01.22 20:33:52 5: HMLAN_Parse: HMLAN1 R:E45139E   stat:0000 t:00006871 d:FF r:FFCD     m:E2 8002 45139E 4B4D1C 00

2018.01.22 20:33:52 5: HMLAN/RAW: /E45139E,0000,00006871,FF,FFCD,E2800245139E4B4D1C00
2018.01.22 20:33:52 5: HMLAN_Send:  HMLAN1 I:+45139E,00,01,00
2018.01.22 20:33:52 5: CUL1: dispatch A0AE2800245139E4B4D1C00::-76.5:CUL1
2018.01.22 20:33:52 4: CUL_Parse: CUL1 A 0A E2 8002 45139E 4B4D1C 00FB -76.5

2018.01.22 20:33:52 5: CUL/RAW: /A0AE2800245139E4B4D1C00FB
2018.01.22 20:33:52 5: HMLAN_Send:  HMLAN1 I:-4B4D1C
2018.01.22 20:33:52 5: HMLAN1: dispatch A0CE2B0414B4D1C45139E017300::-54:HMLAN1
2018.01.22 20:33:52 5: HMLAN_Parse: HMLAN1 R:E4B4D1C   stat:0000 t:000067F2 d:FF r:FFCA     m:E2 B041 4B4D1C 45139E 017300

2018.01.22 20:33:52 5: HMLAN/RAW: /E4B4D1C,0000,000067F2,FF,FFCA,E2B0414B4D1C45139E017300
2018.01.22 20:33:52 5: HMLAN_Send:  HMLAN1 I:+4B4D1C,00,01,00
2018.01.22 20:33:52 5: CUL1: dispatch A0CE2B0414B4D1C45139E017300::-80.5:CUL1
2018.01.22 20:33:52 4: CUL_Parse: CUL1 A 0C E2 B041 4B4D1C 45139E 017300F3 -80.5

2018.01.22 20:33:52 5: CUL/RAW: /A0CE2B0414B4D1C45139E017300F3
2018.01.22 20:33:51 5: HMLAN1: dispatch A0AE18002F123054B4D1C00::-73:HMLAN1
2018.01.22 20:33:51 5: HMLAN_Parse: HMLAN1 R:EF12305   stat:0000 t:00006614 d:FF r:FFB7     m:E1 8002 F12305 4B4D1C 00
2018.01.22 20:33:51 5: HMLAN1: dispatch A0CE1A4414B4D1CF12305017300::-54:HMLAN1
2018.01.22 20:33:51 5: HMLAN_Parse: HMLAN1 R:E4B4D1C   stat:0000 t:00006592 d:FF r:FFCA     m:E1 A441 4B4D1C F12305 017300

EF12305,0000,00006614,FF,FFB7,E18002F123054B4D1C00
2018.01.22 20:33:51 5: HMLAN/RAW: /E4B4D1C,0000,00006592,FF,FFCA,E1A4414B4D1CF12305017300
2018.01.22 20:33:51 5: SW: As0AE18002F123054B4D1C00
2018.01.22 20:33:51 5: CUL 4B4D1C dly:96ms
2018.01.22 20:33:51 5: CUL1 sending As0AE18002F123054B4D1C00
2018.01.22 20:33:51 5: HMLAN_Send:  HMLAN1 I:-4B4D1C
2018.01.22 20:33:51 5: HMLAN_Send:  HMLAN1 I:+4B4D1C,00,01,00
2018.01.22 20:33:51 5: CUL1: dispatch A0CE1A4414B4D1CF12305017300::-81:CUL1
2018.01.22 20:33:51 4: CUL_Parse: CUL1 A 0C E1 A441 4B4D1C F12305 017300F2 -81

2018.01.22 20:33:51 5: CUL/RAW: /A0CE1A4414B4D1CF12305017300F2
2018.01.22 20:33:51 5: HMLAN_Send:  HMLAN1 I:-588006
2018.01.22 20:33:51 5: HMLAN1: dispatch A0EB984105880060000000B80BF2B00::-62:HMLAN1
2018.01.22 20:33:51 5: HMLAN_Parse: HMLAN1 R:E588006   stat:0000 t:000063CD d:FF r:FFC2     m:B9 8410 588006 000000 0B80BF2B00

2018.01.22 20:33:51 5: HMLAN/RAW: /E588006,0000,000063CD,FF,FFC2,B984105880060000000B80BF2B00
2018.01.22 20:33:51 5: HMLAN_Send:  HMLAN1 I:+588006,00,01,00
2018.01.22 20:33:51 5: CUL1: dispatch A0EB984105880060000000B80BF2B00::-90.5:CUL1
2018.01.22 20:33:51 4: CUL_Parse: CUL1 A 0E B9 8410 588006 000000 0B80BF2B00DF -90.5

2018.01.22 20:33:51 5: CUL/RAW: /A0EB984105880060000000B80BF2B00DF
2018.01.22 20:33:50 5: HMLAN_Send:  HMLAN1 I:-4513D1
2018.01.22 20:33:50 5: HMLAN1: dispatch A0AE080024513D14B4D1C00::-56:HMLAN1
2018.01.22 20:33:50 5: HMLAN_Parse: HMLAN1 R:E4513D1   stat:0000 t:000060A6 d:FF r:FFC8     m:E0 8002 4513D1 4B4D1C 00

2018.01.22 20:33:50 5: HMLAN/RAW: /E4513D1,0000,000060A6,FF,FFC8,E080024513D14B4D1C00
2018.01.22 20:33:50 5: HMLAN_Send:  HMLAN1 I:+4513D1,00,01,00
2018.01.22 20:33:50 5: CUL1: dispatch A0AE080024513D14B4D1C00::-74.5:CUL1
2018.01.22 20:33:50 4: CUL_Parse: CUL1 A 0A E0 8002 4513D1 4B4D1C 00FF -74.5

2018.01.22 20:33:50 5: CUL/RAW: /A0AE080024513D14B4D1C00FF
2018.01.22 20:33:50 5: HMLAN_Send:  HMLAN1 I:-4B4D1C
2018.01.22 20:33:50 5: HMLAN1: dispatch A0CE0A0414B4D1C4513D10172C8::-53:HMLAN1
2018.01.22 20:33:50 5: HMLAN_Parse: HMLAN1 R:E4B4D1C   stat:0000 t:00006027 d:FF r:FFCB     m:E0 A041 4B4D1C 4513D1 0172C8

2018.01.22 20:33:50 5: HMLAN/RAW: /E4B4D1C,0000,00006027,FF,FFCB,E0A0414B4D1C4513D10172C8
2018.01.22 20:33:50 5: HMLAN_Send:  HMLAN1 I:+4B4D1C,00,01,00
2018.01.22 20:33:50 5: CUL1: dispatch A0CE0A0414B4D1C4513D10172C8::-80:CUL1
2018.01.22 20:33:50 4: CUL_Parse: CUL1 A 0C E0 A041 4B4D1C 4513D1 0172C8F4 -80

2018.01.22 20:33:50 5: CUL/RAW: /A0CE0A0414B4D1C4513D10172C8F4
2018.01.22 20:33:50 5: HMLAN_Send:  HMLAN1 I:-45139E
2018.01.22 20:33:50 5: HMLAN1: dispatch A0ADF800245139E4B4D1C00::-50:HMLAN1
2018.01.22 20:33:50 5: HMLAN_Parse: HMLAN1 R:E45139E   stat:0000 t:00005FA7 d:FF r:FFCE     m:DF 8002 45139E 4B4D1C 00

2018.01.22 20:33:50 5: HMLAN/RAW: /E45139E,0000,00005FA7,FF,FFCE,DF800245139E4B4D1C00
2018.01.22 20:33:50 5: HMLAN_Send:  HMLAN1 I:+45139E,00,01,00
2018.01.22 20:33:50 5: CUL1: dispatch A0ADF800245139E4B4D1C00::-76:CUL1
2018.01.22 20:33:50 4: CUL_Parse: CUL1 A 0A DF 8002 45139E 4B4D1C 00FC -76

2018.01.22 20:33:50 5: CUL/RAW: /A0ADF800245139E4B4D1C00FC
2018.01.22 20:33:50 5: HMLAN_Send:  HMLAN1 I:-4B4D1C
2018.01.22 20:33:50 5: HMLAN1: dispatch A0CDFB0414B4D1C45139E0172C8::-53:HMLAN1
2018.01.22 20:33:50 5: HMLAN_Parse: HMLAN1 R:E4B4D1C   stat:0000 t:00005F28 d:FF r:FFCB     m:DF B041 4B4D1C 45139E 0172C8

2018.01.22 20:33:50 5: HMLAN/RAW: /E4B4D1C,0000,00005F28,FF,FFCB,DFB0414B4D1C45139E0172C8
2018.01.22 20:33:50 5: HMLAN_Send:  HMLAN1 I:+4B4D1C,00,01,00
2018.01.22 20:33:50 5: CUL1: dispatch A0CDFB0414B4D1C45139E0172C8::-80.5:CUL1
2018.01.22 20:33:50 4: CUL_Parse: CUL1 A 0C DF B041 4B4D1C 45139E 0172C8F3 -80.5

2018.01.22 20:33:50 5: CUL/RAW: /A0CDFB0414B4D1C45139E0172C8F3
2018.01.22 20:33:49 5: HMLAN1: dispatch A0ADE8002F123054B4D1C00::-73:HMLAN1
2018.01.22 20:33:49 5: HMLAN_Parse: HMLAN1 R:EF12305   stat:0000 t:00005D62 d:FF r:FFB7     m:DE 8002 F12305 4B4D1C 00

2018.01.22 20:33:49 5: HMLAN/RAW: /EF12305,0000,00005D62,FF,FFB7,DE8002F123054B4D1C00
2018.01.22 20:33:49 5: HMLAN1: dispatch A0ADE8002F123054B4D1C00::-73:HMLAN1
2018.01.22 20:33:49 5: HMLAN_Parse: HMLAN1 R:EF12305   stat:0000 t:00005D49 d:FF r:FFB7     m:DE 8002 F12305 4B4D1C 00
2018.01.22 20:33:49 5: HMLAN1: dispatch A0CDEA4414B4D1CF123050172C8::-54:HMLAN1
2018.01.22 20:33:49 5: HMLAN_Parse: HMLAN1 R:E4B4D1C   stat:0000 t:00005CC8 d:FF r:FFCA     m:DE A441 4B4D1C F12305 0172C8

EF12305,0000,00005D49,FF,FFB7,DE8002F123054B4D1C00
2018.01.22 20:33:49 5: HMLAN/RAW: /E4B4D1C,0000,00005CC8,FF,FFCA,DEA4414B4D1CF123050172C8
2018.01.22 20:33:49 5: SW: As0ADE8002F123054B4D1C00
2018.01.22 20:33:49 5: CUL1 sending As0ADE8002F123054B4D1C00
2018.01.22 20:33:49 5: SW: As0ADE8002F123054B4D1C00
2018.01.22 20:33:49 5: CUL 4B4D1C dly:96ms
2018.01.22 20:33:49 5: CUL1 sending As0ADE8002F123054B4D1C00
2018.01.22 20:33:49 5: CUL1: dispatch A0CDEA4414B4D1CF123050172C8::-78:CUL1
2018.01.22 20:33:49 4: CUL_Parse: CUL1 A 0C DE A441 4B4D1C F12305 0172C8F8 -78

2018.01.22 20:33:49 5: CUL/RAW: /A0CDEA4414B4D1CF123050172C8F8
2018.01.22 20:33:45 5: HMLAN_Send:  HMLAN1 I:-569404
2018.01.22 20:33:45 5: HMLAN1: dispatch A0F3586105694040000000A80AE0B0000::-59:HMLAN1
2018.01.22 20:33:45 5: HMLAN_Parse: HMLAN1 R:E569404   stat:0000 t:00004DAD d:FF r:FFC5     m:35 8610 569404 000000 0A80AE0B0000

2018.01.22 20:33:45 5: HMLAN/RAW: /E569404,0000,00004DAD,FF,FFC5,3586105694040000000A80AE0B0000
2018.01.22 20:33:45 5: HMLAN_Send:  HMLAN1 I:+569404,00,01,00
2018.01.22 20:33:45 5: CUL1: dispatch A0F3586105694040000000A80AE0B0000::-71.5:CUL1
2018.01.22 20:33:45 4: CUL_Parse: CUL1 A 0F 35 8610 569404 000000 0A80AE0B000005 -71.5

2018.01.22 20:33:45 5: CUL/RAW: /A0F3586105694040000000A80AE0B000005
2018.01.22 20:33:44 5: HMLAN1: dispatch A0FE686105697370000000A80B60D0000::-75:HMLAN1
2018.01.22 20:33:44 5: HMLAN_Parse: HMLAN1 R:E569737   stat:0000 t:000049E5 d:FF r:FFB5     m:E6 8610 569737 000000 0A80B60D0000

2018.01.22 20:33:44 5: HMLAN/RAW: /E569737,0000,000049E5,FF,FFB5,E686105697370000000A80B60D0000
2018.01.22 20:33:44 5: CUL1: dispatch A0FE686105697370000000A80B60D0000::-70.5:CUL1
2018.01.22 20:33:44 4: CUL_Parse: CUL1 A 0F E6 8610 569737 000000 0A80B60D000007 -70.5

2018.01.22 20:33:44 5: CUL/RAW: /A0FE686105697370000000A80B60D000007
2018.01.22 20:33:41 5: HMLAN_Send:  HMLAN1 I:-588006
2018.01.22 20:33:41 5: HMLAN1: dispatch A0CB8865A58800600000080BF2C::-62:HMLAN1
2018.01.22 20:33:41 5: HMLAN_Parse: HMLAN1 R:E588006   stat:0000 t:00003CBA d:FF r:FFC2     m:B8 865A 588006 000000 80BF2C

2018.01.22 20:33:41 5: HMLAN/RAW: /E588006,0000,00003CBA,FF,FFC2,B8865A58800600000080BF2C
2018.01.22 20:33:41 5: HMLAN_Send:  HMLAN1 I:+588006,00,01,00
2018.01.22 20:33:41 5: CUL1: dispatch A0CB8865A58800600000080BF2C::-89:CUL1
2018.01.22 20:33:41 4: CUL_Parse: CUL1 A 0C B8 865A 588006 000000 80BF2CE2 -89

2018.01.22 20:33:41 5: CUL/RAW: /A0CB8865A58800600000080BF2CE2
2018.01.22 20:33:39 5: HMLAN1: dispatch A0D4D8002F12305580A440101C800::-74:HMLAN1
2018.01.22 20:33:39 5: HMLAN_Parse: HMLAN1 R:EF12305   stat:0000 t:00003421 d:FF r:FFB6     m:4D 8002 F12305 580A44 0101C800

2018.01.22 20:33:39 5: HMLAN/RAW: /EF12305,0000,00003421,FF,FFB6,4D8002F12305580A440101C800
2018.01.22 20:33:39 5: HMLAN1: dispatch A0A4D8002F12305580A4400::-73:HMLAN1
2018.01.22 20:33:39 5: HMLAN_Parse: HMLAN1 R:EF12305   stat:0000 t:00003406 d:FF r:FFB7     m:4D 8002 F12305 580A44 00

2018.01.22 20:33:39 5: HMLAN/RAW: /EF12305,0000,00003406,FF,FFB7,4D8002F12305580A4400
2018.01.22 20:33:39 5: HMLAN1: dispatch A0A628002F12305580A8D00::-74:HMLAN1
2018.01.22 20:33:39 5: HMLAN_Parse: HMLAN1 R:EF12305   stat:0000 t:00003395 d:FF r:FFB6     m:62 8002 F12305 580A8D 00
2018.01.22 20:33:39 5: HMLAN1: dispatch A0A618002F12305580A8D00::-73:HMLAN1
2018.01.22 20:33:39 5: HMLAN_Parse: HMLAN1 R:EF12305   stat:0000 t:00003329 d:FF r:FFB7     m:61 8002 F12305 580A8D 00
2018.01.22 20:33:39 5: HMLAN1: dispatch A0A608002F12305580A8D00::-73:HMLAN1
2018.01.22 20:33:39 5: HMLAN_Parse: HMLAN1 R:EF12305   stat:0000 t:000032BE d:FF r:FFB7     m:60 8002 F12305 580A8D 00
2018.01.22 20:33:39 5: HMLAN1: dispatch A0A5F8002F12305580A8D00::-73:HMLAN1
2018.01.22 20:33:39 5: HMLAN_Parse: HMLAN1 R:EF12305   stat:0000 t:00003252 d:FF r:FFB7     m:5F 8002 F12305 580A8D 00
2018.01.22 20:33:39 1: HMLAN_Parse: HMLAN1 new condition ok
2018.01.22 20:33:39 5: HMLAN_Parse: HMLAN1 R:R1F5C9D7B stat:0002 t:00000000 d:FF r:7FFF     m:99 8112 F12305 000000 

EF12305,0000,00003395,FF,FFB6,628002F12305580A8D00
EF12305,0000,00003329,FF,FFB7,618002F12305580A8D00
EF12305,0000,000032BE,FF,FFB7,608002F12305580A8D00
EF12305,0000,00003252,FF,FFB7,5F8002F12305580A8D00
2018.01.22 20:33:39 5: HMLAN/RAW: /R1F5C9D7B,0002,00000000,FF,7FFF,998112F12305000000
2018.01.22 20:33:39 5: CUL1: dispatch A09998112F12305000000::-69.5:CUL1
2018.01.22 20:33:39 4: CUL_Parse: CUL1 A 09 99 8112 F12305 000000 09 -69.5

2018.01.22 20:33:39 5: CUL/RAW: /A09998112F1230500000009
2018.01.22 20:33:39 5: HMLAN1: dispatch A0D4D8002F12305580A440101C800::-74:HMLAN1
2018.01.22 20:33:39 5: HMLAN_Parse: HMLAN1 R:EF12305   stat:0000 t:00002618 d:FF r:FFB6     m:4D 8002 F12305 580A44 0101C800
2018.01.22 20:33:39 5: HMLAN1: dispatch A0A4D8002F12305580A4400::-74:HMLAN1
2018.01.22 20:33:39 5: HMLAN_Parse: HMLAN1 R:EF12305   stat:0000 t:000025FD d:FF r:FFB6     m:4D 8002 F12305 580A44 00
2018.01.22 20:33:39 5: SW: As0D4D8002F12305580A440101C800
2018.01.22 20:33:39 5: CUL1 sending As0D4D8002F12305580A440101C800
2018.01.22 20:33:39 5: SW: As0A4D8002F12305580A4400
2018.01.22 20:33:38 5: CUL 580A44 dly:98ms
2018.01.22 20:33:38 5: CUL1 sending As0A4D8002F12305580A4400
2018.01.22 20:33:38 5: HMLAN1: dispatch A0C4DA641580A44F123050186C8::-75:HMLAN1
2018.01.22 20:33:38 5: HMLAN_Parse: HMLAN1 R:E580A44   stat:0000 t:0000257B d:FF r:FFB5     m:4D A641 580A44 F12305 0186C8
2018.01.22 20:33:38 5: HMLAN_Send:  HMLAN1 I:-569404
2018.01.22 20:33:38 5: HMLAN1: dispatch A0A4C8002569404580A4400::-57:HMLAN1
2018.01.22 20:33:38 5: HMLAN_Parse: HMLAN1 R:E569404   stat:0000 t:000024ED d:FF r:FFC7     m:4C 8002 569404 580A44 00
2018.01.22 20:33:38 5: HMLAN1: dispatch A0C4CB641580A445694040186C8::-73:HMLAN1
2018.01.22 20:33:38 5: HMLAN_Parse: HMLAN1 R:E580A44   stat:0000 t:0000246E d:FF r:FFB7     m:4C B641 580A44 569404 0186C8
2018.01.22 20:33:38 5: HMLAN1: dispatch A0A628002F12305580A8D00::-74:HMLAN1
2018.01.22 20:33:38 5: HMLAN_Parse: HMLAN1 R:EF12305   stat:0000 t:0000172E d:FF r:FFB6     m:62 8002 F12305 580A8D 00
2018.01.22 20:33:38 5: SW: As0A628002F12305580A8D00
2018.01.22 20:33:38 5: CUL 580A8D dly:97ms
2018.01.22 20:33:38 5: CUL1 sending As0A628002F12305580A8D00
2018.01.22 20:33:38 5: HMLAN1: dispatch A0D62A610580A8DF1230506010000::-56:HMLAN1
2018.01.22 20:33:38 5: HMLAN_Parse: HMLAN1 R:E580A8D   stat:0000 t:000016AB d:FF r:FFC8     m:62 A610 580A8D F12305 06010000
2018.01.22 20:33:38 5: HMLAN1: dispatch A0A618002F12305580A8D00::-73:HMLAN1
2018.01.22 20:33:38 5: HMLAN_Parse: HMLAN1 R:EF12305   stat:0000 t:000013F1 d:FF r:FFB7     m:61 8002 F12305 580A8D 00
2018.01.22 20:33:38 5: SW: As0A618002F12305580A8D00
2018.01.22 20:33:38 5: CUL 580A8D dly:97ms
2018.01.22 20:33:38 5: CUL1 sending As0A618002F12305580A8D00
2018.01.22 20:33:38 5: HMLAN1: dispatch A0D61A610580A8DF1230506010000::-56:HMLAN1
2018.01.22 20:33:38 5: HMLAN_Parse: HMLAN1 R:E580A8D   stat:0000 t:0000136F d:FF r:FFC8     m:61 A610 580A8D F12305 06010000
2018.01.22 20:33:38 5: HMLAN1: dispatch A0A608002F12305580A8D00::-73:HMLAN1
2018.01.22 20:33:38 5: HMLAN_Parse: HMLAN1 R:EF12305   stat:0000 t:000011BC d:FF r:FFB7     m:60 8002 F12305 580A8D 00
2018.01.22 20:33:38 5: SW: As0A608002F12305580A8D00
2018.01.22 20:33:38 5: CUL 580A8D dly:96ms
2018.01.22 20:33:38 5: CUL1 sending As0A608002F12305580A8D00
2018.01.22 20:33:38 5: HMLAN1: dispatch A0D60A610580A8DF1230506010000::-56:HMLAN1
2018.01.22 20:33:38 5: HMLAN_Parse: HMLAN1 R:E580A8D   stat:0000 t:0000113A d:FF r:FFC8     m:60 A610 580A8D F12305 06010000
2018.01.22 20:33:38 5: HMLAN1: dispatch A0A5F8002F12305580A8D00::-73:HMLAN1
2018.01.22 20:33:38 5: HMLAN_Parse: HMLAN1 R:EF12305   stat:0000 t:000010AC d:FF r:FFB7     m:5F 8002 F12305 580A8D 00
2018.01.22 20:33:38 5: SW: As0A5F8002F12305580A8D00
2018.01.22 20:33:38 5: CUL 580A8D dly:98ms
2018.01.22 20:33:38 5: CUL1 sending As0A5F8002F12305580A8D00
2018.01.22 20:33:38 5: HMLAN1: dispatch A0D5FA610580A8DF1230506010000::-56:HMLAN1
2018.01.22 20:33:38 5: HMLAN_Parse: HMLAN1 R:E580A8D   stat:0000 t:0000102A d:FF r:FFC8     m:5F A610 580A8D F12305 06010000
2018.01.22 20:33:38 5: HMLAN_Parse: HMLAN1 V:03C5 sNo:MEQ0313652 d:37A0AD O:F12305 t:000031A6 IDcnt:0000 L:0 %

I00,01,00,00
I00,01,00,00
I00,01,00,00
EF12305,0000,00002618,FF,FFB6,4D8002F12305580A440101C800
EF12305,0000,000025FD,FF,FFB6,4D8002F12305580A4400
E580A44,0000,0000257B,FF,FFB5,4DA641580A44F123050186C8
E569404,0000,000024ED,FF,FFC7,4C8002569404580A4400
E580A44,0000,0000246E,FF,FFB7,4CB641580A445694040186C8
EF12305,0000,0000172E,FF,FFB6,628002F12305580A8D00
E580A8D,0000,000016AB,FF,FFC8,62A610580A8DF1230506010000
EF12305,0000,000013F1,FF,FFB7,618002F12305580A8D00
E580A8D,0000,0000136F,FF,FFC8,61A610580A8DF1230506010000
EF12305,0000,000011BC,FF,FFB7,608002F12305580A8D00
E580A8D,0000,0000113A,FF,FFC8,60A610580A8DF1230506010000
EF12305,0000,000010AC,FF,FFB7,5F8002F12305580A8D00
E580A8D,0000,0000102A,FF,FFC8,5FA610580A8DF1230506010000
2018.01.22 20:33:38 5: HMLAN/RAW: /HHM-LAN-IF,03C5,MEQ0313652,37A0AD,F12305,000031A6,0000,00
2018.01.22 20:33:38 1: 192.168.178.26:1000 reappeared (HMLAN1)
2018.01.22 20:33:38 5: HMLAN_Send:  HMLAN1 S:S1F5C9D7B stat:  00 t:00000000 d:01 r:1F5C9D7B m:99 8112 F12305 000000 
2018.01.22 20:33:38 1: HMLAN_Parse: HMLAN1 new condition init
2018.01.22 20:33:38 5: HMLAN_Send:  HMLAN1 I:T21F8EB02,04,00,00000000
2018.01.22 20:33:38 5: HMLAN_Send:  HMLAN1 I:Y03,00,
2018.01.22 20:33:38 5: HMLAN_Send:  HMLAN1 I:Y02,00,
2018.01.22 20:33:38 5: HMLAN_Send:  HMLAN1 I:Y01,01,c4683d74356c9b8d02930caddf1096b3
2018.01.22 20:33:38 5: HMLAN_Send:  HMLAN1 I:+36E81A,00,00,00
2018.01.22 20:33:38 5: HMLAN_Send:  HMLAN1 I:+4A0FCD,01,01,02
2018.01.22 20:33:38 5: HMLAN_Send:  HMLAN1 I:+580A44,00,01,00
2018.01.22 20:33:38 5: HMLAN_Send:  HMLAN1 I:+36E97A,00,00,00
2018.01.22 20:33:38 5: HMLAN_Send:  HMLAN1 I:+365CEE,00,01,00
2018.01.22 20:33:38 5: HMLAN_Send:  HMLAN1 I:+569404,00,01,00
2018.01.22 20:33:38 5: HMLAN_Send:  HMLAN1 I:+5AF44A,00,01,00
2018.01.22 20:33:38 5: HMLAN_Send:  HMLAN1 I:C
2018.01.22 20:33:38 5: HMLAN_Send:  HMLAN1 I:AF12305
2018.01.22 20:33:35 5: SW: As0D4D8002F12305580A440101C800
2018.01.22 20:33:35 5: CUL1 sending As0D4D8002F12305580A440101C800
2018.01.22 20:33:35 5: SW: As0A4D8002F12305580A4400
2018.01.22 20:33:35 5: CUL 580A44 dly:97ms
2018.01.22 20:33:35 5: CUL1 sending As0A4D8002F12305580A4400
2018.01.22 20:33:35 5: CUL1: dispatch A0C4DA641580A44F123050186C8::-77.5:CUL1
2018.01.22 20:33:35 4: CUL_Parse: CUL1 A 0C 4D A641 580A44 F12305 0186C8F9 -77.5

2018.01.22 20:33:35 5: CUL/RAW: /A0C4DA641580A44F123050186C8F9
2018.01.22 20:33:35 1: HMLAN_Parse: HMLAN1 new condition disconnected
2018.01.22 20:33:35 1: 192.168.178.26:1000 disconnected, waiting to reappear (HMLAN1)
2018.01.22 20:33:35 1: HMLAN_Parse: HMLAN1 new condition disconnected
2018.01.22 20:33:35 5: HMLAN_Send:  HMLAN1 I:+569404,00,01,00
2018.01.22 20:33:35 5: CUL1: dispatch A0A4C8002569404580A4400::-68.5:CUL1
2018.01.22 20:33:35 4: CUL_Parse: CUL1 A 0A 4C 8002 569404 580A44 000B -68.5

2018.01.22 20:33:35 5: CUL/RAW: /A0A4C8002569404580A44000B
2018.01.22 20:33:35 5: HMLAN_Send:  HMLAN1 I:+580A44,00,01,00
2018.01.22 20:33:35 5: CUL1: dispatch A0C4CB641580A445694040186C8::-77:CUL1
2018.01.22 20:33:35 4: CUL_Parse: CUL1 A 0C 4C B641 580A44 569404 0186C8FA -77

2018.01.22 20:33:35 5: CUL/RAW: /A0C4CB641580A445694040186C8FA
2018.01.22 20:33:34 5: HMLAN_Send:  HMLAN1 I:K
2018.01.22 20:33:33 5: HMLAN_Send:  HMLAN1 I:K
2018.01.22 20:33:32 5: HMLAN_Send:  HMLAN1 I:K
2018.01.22 20:33:31 5: SW: As0A628002F12305580A8D00
2018.01.22 20:33:31 5: CUL 580A8D dly:95ms
2018.01.22 20:33:31 5: CUL1 sending As0A628002F12305580A8D00
2018.01.22 20:33:31 5: CUL1: dispatch A0D62A610580A8DF1230506010000::-90:CUL1
2018.01.22 20:33:31 4: CUL_Parse: CUL1 A 0D 62 A610 580A8D F12305 06010000E0 -90

2018.01.22 20:33:31 5: CUL/RAW: /A0D62A610580A8DF1230506010000E0
2018.01.22 20:33:31 5: HMLAN_Send:  HMLAN1 I:K
2018.01.22 20:33:30 5: SW: As0A618002F12305580A8D00
2018.01.22 20:33:30 5: CUL 580A8D dly:96ms
2018.01.22 20:33:30 5: CUL1 sending As0A618002F12305580A8D00
2018.01.22 20:33:30 5: CUL1: dispatch A0D61A610580A8DF1230506010000::-89:CUL1
2018.01.22 20:33:30 4: CUL_Parse: CUL1 A 0D 61 A610 580A8D F12305 06010000E2 -89

2018.01.22 20:33:30 5: CUL/RAW: /A0D61A610580A8DF1230506010000E2
2018.01.22 20:33:30 5: SW: As0A608002F12305580A8D00
2018.01.22 20:33:30 5: CUL 580A8D dly:95ms
2018.01.22 20:33:30 5: CUL1 sending As0A608002F12305580A8D00
2018.01.22 20:33:30 5: CUL1: dispatch A0D60A610580A8DF1230506010000::-89.5:CUL1
2018.01.22 20:33:30 4: CUL_Parse: CUL1 A 0D 60 A610 580A8D F12305 06010000E1 -89.5

2018.01.22 20:33:30 5: CUL/RAW: /A0D60A610580A8DF1230506010000E1
2018.01.22 20:33:29 5: SW: As0A5F8002F12305580A8D00
2018.01.22 20:33:29 5: CUL 580A8D dly:93ms
2018.01.22 20:33:29 5: CUL1 sending As0A5F8002F12305580A8D00
2018.01.22 20:33:29 5: HMLAN_Send:  HMLAN1 I:-580A8D
2018.01.22 20:33:29 5: HMLAN_Send:  HMLAN1 I:+580A8D,00,01,00
2018.01.22 20:33:29 5: CUL1: dispatch A0D5FA610580A8DF1230506010000::-88.5:CUL1
2018.01.22 20:33:29 4: CUL_Parse: CUL1 A 0D 5F A610 580A8D F12305 06010000E3 -88.5

2018.01.22 20:33:29 5: CUL/RAW: /A0D5FA610580A8DF1230506010000E3
2018.01.22 20:33:27 5: CUL1: dispatch A1413845E5AF47D0000008094AB00000A002D0943FD::-60.5:CUL1
2018.01.22 20:33:27 4: CUL_Parse: CUL1 A 14 13 845E 5AF47D 000000 8094AB00000A002D0943FD1B -60.5

2018.01.22 20:33:27 5: CUL/RAW: /A1413845E5AF47D0000008094AB00000A002D0943FD1B
2018.01.22 20:33:24 5: CUL1: dispatch A0FA486102B1D3A0000000AACED0B0E00::-56:CUL1
2018.01.22 20:33:24 4: CUL_Parse: CUL1 A 0F A4 8610 2B1D3A 000000 0AACED0B0E0024 -56

2018.01.22 20:33:24 5: CUL/RAW: /A0FA486102B1D3A0000000AACED0B0E0024
2018.01.22 20:33:20 5: HMLAN_Send:  HMLAN1 I:+5AF44A,00,01,00
2018.01.22 20:33:20 5: CUL1: dispatch A14C8845E5AF44A00000080B4B00004D3006D0938FD::-66:CUL1
2018.01.22 20:33:20 4: CUL_Parse: CUL1 A 14 C8 845E 5AF44A 000000 80B4B00004D3006D0938FD10 -66

2018.01.22 20:33:20 5: CUL/RAW: /A14C8845E5AF44A00000080B4B00004D3006D0938FD10
2018.01.22 20:33:13 5: CUL1: dispatch A0C978670323DEF00000000395A::-67:CUL1
2018.01.22 20:33:13 4: CUL_Parse: CUL1 A 0C 97 8670 323DEF 000000 00395A0E -67

2018.01.22 20:33:13 5: CUL/RAW: /A0C978670323DEF00000000395A0E
2018.01.22 20:33:06 5: SW: As0DF88002F123053FC66E0101C800
2018.01.22 20:33:06 5: CUL 3FC66E dly:95ms
2018.01.22 20:33:06 5: CUL1 sending As0DF88002F123053FC66E0101C800
2018.01.22 20:33:06 5: HMLAN_Send:  HMLAN1 I:-3FC66E
2018.01.22 20:33:06 5: HMLAN_Send:  HMLAN1 I:+3FC66E,00,01,00
2018.01.22 20:33:06 5: CUL1: dispatch A0CF8A6413FC66EF1230501A100::-77:CUL1
2018.01.22 20:33:06 4: CUL_Parse: CUL1 A 0C F8 A641 3FC66E F12305 01A100FA -77

2018.01.22 20:33:06 5: CUL/RAW: /A0CF8A6413FC66EF1230501A100FA
2018.01.22 20:33:06 5: HMLAN_Parse: HMLAN1 V:03C5 sNo:MEQ0313652 d:37A0AD O:F12305 t:000C0E97 IDcnt:0004 L:1 %

2018.01.22 20:33:06 5: HMLAN/RAW: /HHM-LAN-IF,03C5,MEQ0313652,37A0AD,F12305,000C0E97,0004,01
2018.01.22 20:33:06 5: HMLAN_Send:  HMLAN1 I:K
2018.01.22 20:33:00 5: HMLAN1: dispatch A0DF78002F123053FC66E0101C800::-73:HMLAN1
2018.01.22 20:33:00 5: HMLAN_Parse: HMLAN1 R:EF12305   stat:0000 t:000BF901 d:FF r:FFB7     m:F7 8002 F12305 3FC66E 0101C800
2018.01.22 20:33:00 5: HMLAN1: dispatch A0CF7A6413FC66EF1230501A0C8::-66:HMLAN1
2018.01.22 20:33:00 5: HMLAN_Parse: HMLAN1 R:E3FC66E   stat:0000 t:000BF87C d:FF r:FFBE     m:F7 A641 3FC66E F12305 01A0C8

EF12305,0000,000BF901,FF,FFB7,F78002F123053FC66E0101C800
2018.01.22 20:33:00 5: HMLAN/RAW: /E3FC66E,0000,000BF87C,FF,FFBE,F7A6413FC66EF1230501A0C8
2018.01.22 20:33:00 5: SW: As0DF78002F123053FC66E0101C800
2018.01.22 20:33:00 5: CUL 3FC66E dly:95ms
2018.01.22 20:33:00 5: CUL1 sending As0DF78002F123053FC66E0101C800
2018.01.22 20:33:00 5: HMLAN_Send:  HMLAN1 I:-3FC66E
2018.01.22 20:33:00 5: HMLAN_Send:  HMLAN1 I:+3FC66E,00,01,00
2018.01.22 20:33:00 5: CUL1: dispatch A0CF7A6413FC66EF1230501A0C8::-74:CUL1
2018.01.22 20:33:00 4: CUL_Parse: CUL1 A 0C F7 A641 3FC66E F12305 01A0C800 -74

2018.01.22 20:33:00 5: CUL/RAW: /A0CF7A6413FC66EF1230501A0C800

Achtung: Ich habe reverseLog aktiviert, also bitte rückwärts lesen.

Xguide · 23 Januar 2018, 17:22:17

Hallo zusammen,

ich kann zum aktuellen Thema nichts beisteuern, habe aber ebenfalls Probleme mit Disconnects, wenn auch relativ unregelmäßig.
Die sehen dann ungefähr so aus (Disconnect|Init|OK):

Code Auswählen


2018.01.21 23:50:32 1: Notify_HMLAN_Connection: Event received for HMLAN1 - (msg: cond: init)
2018.01.21 23:50:32 1: Notify_HMLAN_Connection: HMLAN1: Reading assignedIDsCnt: 56 report:55
2018.01.21 23:50:32 1: Notify_HMLAN_Connection: HMLAN1: Reading msgLoadCurrent: 14
2018.01.21 23:50:32 1: Notify_HMLAN_Connection: HMLAN1: Reading msgKeepAlive: dlyMax:71.303 bufferMin:-66
2018.01.21 23:50:32 1: Notify_HMLAN_Connection: HMLAN1: Reading msgLoadHistoryAbs: 5min steps: 21/21/22/21/21/21/21/21/21/21/21/22
2018.01.21 23:50:32 1: Notify_HMLAN_Connection: HMLAN1: Reading uptime: 006 144:27:24.709
2018.01.21 23:50:32 1: Notify_HMLAN_Connection: HMLAN1: Reading msgParseDly: min:-8267 max:73819 last:125 cnt:46949
2018.01.21 23:50:32 1: Notify_HMLAN_Connection: HMLAN1: Reading condition: init
2018.01.21 23:50:32 1: Notify_HMLAN_Connection: HMLAN1: Reading Xmit-Events: init:3 disconnected:3 ok:2 
2018.01.21 23:50:32 1: Notify_HMLAN_Connection: HMLAN1: Reading load: n/a
2018.01.21 23:50:32 1: Notify_HMLAN_Connection: HMLAN1: Reading loadLvl: low
2018.01.21 23:50:32 1: Notify_HMLAN_Connection: Event received for HMLAN1 - (msg: cond: ok)
2018.01.21 23:50:32 1: Notify_HMLAN_Connection: HMLAN1: Reading assignedIDsCnt: 56 report:0
2018.01.21 23:50:32 1: Notify_HMLAN_Connection: HMLAN1: Reading msgLoadCurrent: 0
2018.01.21 23:50:32 1: Notify_HMLAN_Connection: HMLAN1: Reading msgKeepAlive: dlyMax:71.303 bufferMin:-66
2018.01.21 23:50:32 1: Notify_HMLAN_Connection: HMLAN1: Reading msgLoadHistoryAbs: 5min steps: 21/21/22/21/21/21/21/21/21/21/21/22
2018.01.21 23:50:32 1: Notify_HMLAN_Connection: HMLAN1: Reading uptime: 000 00:00:07.907
2018.01.21 23:50:32 1: Notify_HMLAN_Connection: HMLAN1: Reading msgParseDly: min:-8267 max:73819 last:125 cnt:46949
2018.01.21 23:50:32 1: Notify_HMLAN_Connection: HMLAN1: Reading condition: ok
2018.01.21 23:50:32 1: Notify_HMLAN_Connection: HMLAN1: Reading Xmit-Events: init:3 disconnected:3 ok:3 
2018.01.21 23:50:32 1: Notify_HMLAN_Connection: HMLAN1: Reading load: n/a
2018.01.21 23:50:32 1: Notify_HMLAN_Connection: HMLAN1: Reading loadLvl: low

2018.01.22 03:05:32 1: Notify_HMLAN_Connection: Event received for HMLAN1 - (msg: disconnected)
2018.01.22 03:05:32 1: Notify_HMLAN_Connection: HMLAN1: Reading assignedIDsCnt: 59
2018.01.22 03:05:32 1: Notify_HMLAN_Connection: HMLAN1: Reading msgLoadCurrent: 21
2018.01.22 03:05:32 1: Notify_HMLAN_Connection: HMLAN1: Reading msgKeepAlive: dlyMax:71.303 bufferMin:-66
2018.01.22 03:05:32 1: Notify_HMLAN_Connection: HMLAN1: Reading msgLoadHistoryAbs: 5min steps: 21/21/22/23/23/23/23/23/23/23/22/22
2018.01.22 03:05:32 1: Notify_HMLAN_Connection: HMLAN1: Reading uptime: 000 03:14:55.629
2018.01.22 03:05:32 1: Notify_HMLAN_Connection: HMLAN1: Reading msgParseDly: min:-8267 max:73819 last:11 cnt:49191
2018.01.22 03:05:32 1: Notify_HMLAN_Connection: HMLAN1: Reading condition: disconnected
2018.01.22 03:05:32 1: Notify_HMLAN_Connection: HMLAN1: Reading Xmit-Events: init:3 disconnected:4 ok:3 
2018.01.22 03:05:32 1: Notify_HMLAN_Connection: HMLAN1: Reading load: n/a
2018.01.22 03:05:32 1: Notify_HMLAN_Connection: HMLAN1: Reading loadLvl: low
2018.01.22 03:06:33 1: Notify_HMLAN_Connection: Event received for HMLAN1 - (msg: cond: init)
2018.01.22 03:06:33 1: Notify_HMLAN_Connection: HMLAN1: Reading assignedIDsCnt: 59
2018.01.22 03:06:33 1: Notify_HMLAN_Connection: HMLAN1: Reading msgLoadCurrent: 21
2018.01.22 03:06:33 1: Notify_HMLAN_Connection: HMLAN1: Reading msgKeepAlive: dlyMax:71.303 bufferMin:-66
2018.01.22 03:06:33 1: Notify_HMLAN_Connection: HMLAN1: Reading msgLoadHistoryAbs: 5min steps: 21/21/22/23/23/23/23/23/23/23/22/22
2018.01.22 03:06:33 1: Notify_HMLAN_Connection: HMLAN1: Reading uptime: 000 03:14:55.629
2018.01.22 03:06:33 1: Notify_HMLAN_Connection: HMLAN1: Reading msgParseDly: min:-8267 max:73819 last:11 cnt:49191
2018.01.22 03:06:33 1: Notify_HMLAN_Connection: HMLAN1: Reading condition: init
2018.01.22 03:06:33 1: Notify_HMLAN_Connection: HMLAN1: Reading Xmit-Events: init:4 disconnected:5 ok:3 
2018.01.22 03:06:33 1: Notify_HMLAN_Connection: HMLAN1: Reading load: n/a
2018.01.22 03:06:33 1: Notify_HMLAN_Connection: HMLAN1: Reading loadLvl: low
2018.01.22 03:06:34 1: Notify_HMLAN_Connection: Event received for HMLAN1 - (msg: cond: ok)
2018.01.22 03:06:34 1: Notify_HMLAN_Connection: HMLAN1: Reading assignedIDsCnt: 54 report:59
2018.01.22 03:06:34 1: Notify_HMLAN_Connection: HMLAN1: Reading msgLoadCurrent: 21
2018.01.22 03:06:34 1: Notify_HMLAN_Connection: HMLAN1: Reading msgKeepAlive: dlyMax:71.303 bufferMin:-66
2018.01.22 03:06:34 1: Notify_HMLAN_Connection: HMLAN1: Reading msgLoadHistoryAbs: 5min steps: 21/21/22/23/23/23/23/23/23/23/22/22
2018.01.22 03:06:34 1: Notify_HMLAN_Connection: HMLAN1: Reading uptime: 000 03:16:08.682
2018.01.22 03:06:34 1: Notify_HMLAN_Connection: HMLAN1: Reading msgParseDly: min:-8267 max:73819 last:11 cnt:49191
2018.01.22 03:06:34 1: Notify_HMLAN_Connection: HMLAN1: Reading condition: ok
2018.01.22 03:06:34 1: Notify_HMLAN_Connection: HMLAN1: Reading Xmit-Events: init:4 disconnected:5 ok:4 
2018.01.22 03:06:34 1: Notify_HMLAN_Connection: HMLAN1: Reading load: n/a
2018.01.22 03:06:34 1: Notify_HMLAN_Connection: HMLAN1: Reading loadLvl: lo

Die Disconnects beziehen sich nur auf die beiden HM-LAN-CFG Adapter.
Ich betreibe ebenfalls eine VCCU mit 2 HM-LAN-CFG und einem HM-UART-PI Module.
Alle drei HM-Interfaces haben als D-HMIdAssigned die HM-ID der VCCU eingetragen.

Nun wollte ich ein VLAN aufbauen um dort die beiden HM-LAN-CFGs reinzuhängen um Fehler durch Multicasts (bspw. meine PV-Wechselrichter) oder SONOS als Störenfried im Netzwerk auszuschließen.
Dort habe ich mich an gängige, im Internet verfügbare, Tutorials angelehnt, bekomme aber kein VLAN zum Laufen. Nutzen wolle ich das vlan Paket (apt-get install vlan).
Konfiguriert habe ich in /etc/network/interfaces folgendes:

Code Auswählen



# The primary network interface
iface eth0 inet static
address 192.168.0.2
netmask 255.255.255.0
gateway 192.168.0.1

#adding vlan segment for HMLAN
auto eth0.1
iface eth0.1 inet static
address 192.168.10.1
netmask 255.255.255.0
#vlan-raw-device eth0

Mit und ohne Zusatz "vlan-raw-device eth0" kann ich den Rapi (Jessie) unter 192.168.10.1 (natürlich habe ich meinen Rechner in das gleiche Netz gelegt) nicht pingen.
Der RaPi selbst kann sich unter der IP pingen.
Hat jemand diesbezüglich schon Erfahrungen? Kann mein Switch mir diesbezüglich einen Strich durch die Rechnung machen (ich dachte eigentlich nicht).

Danke für Eure Kommentare und viele Grüße,

Marcel