Fehler beim Speichern der fhem.cfg

Deudi · 11 Juli 2014, 20:22:47

Hallo Martin,

danke für deine ausführlichen Kommentare! Bin wieder etwas schlauer. Nun habe ich mein System mit drei HMLAN (mit gleicher ID) auch wieder am Laufen - allerdings derzeit ohne CCU. Für das "Unknown Code" habe ich Event und Log mal testweise lahmgelegt, damit ich die "richtigen" Fehler nicht so leicht übersehe.

So langsam kommt auch etwas Licht ins Dunkel bzgl. des Crashes.

Zunächst:

Zitatmehrere (unterschiedliche) HMIds machen nur selten sinn.

Die Kenntnisse bzgl. FHEM wachsen halt längs des Weges. Daher hatte ich ursprünglich bei Inbetriebnahme des zweiten LAN Adapters gedacht, daß zweimal die gleiche HMID nicht geht. Habe ich nun geändert und IODev sinnvoll den Geräten zugewiesen.
Zukünftig will ich dann wieder die CCU aktivieren, um das Redundanzkonzept zu nutzen. Dazu habe ich auch noch ein paar Fragen. Die stelle ich aber in dem entsprechenden Thread, dann haben alle was davon.

Zum wundersamen Peering:

Zitatdas peering steht im Attribut peerIDs und wird von dort in das Reading übertragen (lesbar). Was also stand im Attribut? Hast du ein getConfig gemacht oder kam dies aus deine .cfg?

Ich habe nie nie nie irgendwelche Devices gepeert, ich bin der der immer nur alles paired und die Steuerung über die Zentrale macht. Trotzdem habe ich nun das Phänomen, daß die folgenden zwei Lichtschalter miteinander kuscheln:

Code Auswählen

Internals: 
   DEF        1AE2C6 
   HMLAN0_MSGCNT 1 
   HMLAN0_RAWMSG E1AE2C6,0000,021A8E29,FF,FFB0,35A4101AE2C6444958060100003A 
   HMLAN0_RSSI -80 
   HMLAN0_TIME 2014-07-11 18:58:24 
   HMLAN1_MSGCNT 2 
   HMLAN1_RAWMSG R265C8AE2,0001,021D6571,FF,FFC6,35A4101AE2C6444958060100003A 
   HMLAN1_RSSI -58 
   HMLAN1_TIME 2014-07-11 18:58:24 
   HMLAN3_MSGCNT 1 
   HMLAN3_RAWMSG E1AE2C6,0000,020F9B9B,FF,FFAE,35A4101AE2C6444958060100003A 
   HMLAN3_RSSI -82 
   HMLAN3_TIME 2014-07-11 18:58:24 
   IODev      HMLAN1 
   LASTInputDev HMLAN1 
   MSGCNT     4 
   NAME       Licht_Eingang 
   NR         70 
   STATE      off 
   TYPE       CUL_HM 
   lastMsg    No:35 - t:10 s:1AE2C6 d:444958 060100003A 
   peerList   Licht_Fahrraeder, 
   protLastRcv 2014-07-11 18:58:24 
   protSnd    2 last_at:2014-07-11 18:58:24 
   protState  CMDs_done 
   rssi_HMLAN1 avg:-58 min:-58 max:-58 lst:-58 cnt:1 
   rssi_at_HMLAN0 avg:-80 min:-80 max:-80 lst:-80 cnt:1 
   rssi_at_HMLAN1 avg:-58 min:-58 max:-58 lst:-58 cnt:2 
   rssi_at_HMLAN3 avg:-82 min:-82 max:-82 lst:-82 cnt:1 
   Readings: 
     2014-07-11 07:10:45   D-firmware      2.1 
     2014-07-11 07:10:45   D-serialNr      JEQ0095248 
     2014-07-11 07:11:27   PairedTo        0x444958 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-lgActionType jmpToTarget 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-lgCtDlyOff geLo 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-lgCtDlyOn geLo 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-lgCtOff geLo 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-lgCtOn geLo 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-lgCtValHi 100 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-lgCtValLo 50 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-lgMultiExec on 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-lgOffDly 0 s 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-lgOffTime 111600 s 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-lgOffTimeMode absolut 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-lgOnDly 0 s 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-lgOnTime 111600 s 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-lgOnTimeMode absolut 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-lgSwJtDlyOff on 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-lgSwJtDlyOn on 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-lgSwJtOff dlyOn 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-lgSwJtOn on 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-shActionType jmpToTarget 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-shCtDlyOff geLo 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-shCtDlyOn geLo 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-shCtOff geLo 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-shCtOn geLo 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-shCtValHi 100 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-shCtValLo 50 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-shOffDly 0 s 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-shOffTime 111600 s 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-shOffTimeMode absolut 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-shOnDly 0 s 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-shOnTime 111600 s 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-shOnTimeMode absolut 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-shSwJtDlyOff on 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-shSwJtDlyOn on 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-shSwJtOff dlyOn 
     2014-07-11 07:11:29   R-Licht_Fahrraeder_chn-01-shSwJtOn on 
     2014-07-11 07:11:27   R-confBtnTime   255 min 
     2014-07-11 07:11:27   R-intKeyVisib   invisib 
     2014-07-11 07:11:27   R-pairCentral   0x444958 
     2014-07-11 07:11:28   R-sign          off 
     2014-07-11 07:11:27   RegL_00:        02:01 0A:44 0B:49 0C:58 15:FF 00:00 
     2014-07-11 07:11:28   RegL_01:        08:00 00:00 
     2014-07-11 07:11:29   RegL_03:Licht_Fahrraeder_chn:01 02:00 03:00 04:32 05:64 06:00 07:FF 08:00 09:FF 0A:01 0B:13 0C:33 82:00 83:00 84:32 85:64 86:00 87:FF 88:00 89:FF 8A:21 8B:13 8C:33 00:00 
     2014-07-11 18:58:24   deviceMsg       off (to HMLAN1) 
     2014-07-11 18:58:24   level           0 
     2014-07-11 18:58:24   pct             0 
     2014-07-11 18:58:02   peerList        Licht_Fahrraeder, 
     2014-07-11 18:58:24   recentStateType info 
     2014-07-11 18:58:24   state           off 
     2014-07-11 18:58:24   timedOn         off 
   Helper: 
     cSnd       014449581AE2C6010E 
     mId        0069 
     rxType     1 
     Io: 
       newChn     +1AE2C6,00,01,00 
       nextSend   1405097904.92124 
       prefIO     HMLAN1 
       rxt        0 
       vccu       CCU 
       p: 
         1AE2C6 
         00 
         01 
         00 
     Mrssi: 
       mNo        35 
       Io: 
         HMLAN0     -80 
         HMLAN1     -56 
         HMLAN3     -82 
     Prt: 
       bErr       0 
       sProc      0 
       Rspwait: 
     Q: 
       qReqConf 
       qReqStat 
     Role: 
       chn        1 
       dev        1 
       prs        1 
     Rpt: 
       IO         HMLAN1 
       flg        A 
       ts         1405097904.70603 
       ack: 
         HASH(0x2ad2520) 
         3580024449581AE2C600 
     Rssi: 
       Hmlan1: 
         avg        -58 
         cnt        1 
         lst        -58 
         max        -58 
         min        -58 
       At_hmlan0: 
         avg        -80 
         cnt        1 
         lst        -80 
         max        -80 
         min        -80 
       At_hmlan1: 
         avg        -58 
         cnt        2 
         lst        -58 
         max        -58 
         min        -58 
       At_hmlan3: 
         avg        -82 
         cnt        1 
         lst        -82 
         max        -82 
         min        -82 
Attributes: 
   IODev      HMLAN1 
   IOgrp      CCU:HMLAN1 
   autoReadReg 4_reqStatus 
   devStateIcon on:on@orange 
   expert     2_full 
   firmware   2.1 
   group      Lichtschalter 
   model      HM-LC-Sw1PBU-FM 
   peerIDs    00000000,1B46AE01, 
   room       Aussen,Alles 
   serialNr   JEQ0095248 
   sortby     01 
   subType    switch 
   webCmd     toggle:on:off:statusRequest

Code Auswählen

Internals: 
   DEF        1B46AE 
   HMLAN0_MSGCNT 1 
   HMLAN0_RAWMSG E1B46AE,0000,021A8FC7,FF,FFB4,36A4101B46AE444958060100003F 
   HMLAN0_RSSI -76 
   HMLAN0_TIME 2014-07-11 18:58:25 
   HMLAN1_MSGCNT 2 
   HMLAN1_RAWMSG R265C8C7D,0001,021D670F,FF,FFBF,36A4101B46AE444958060100003F 
   HMLAN1_RSSI -65 
   HMLAN1_TIME 2014-07-11 18:58:25 
   HMLAN3_MSGCNT 1 
   HMLAN3_RAWMSG E1B46AE,0000,020F9D39,FF,FFA2,36A4101B46AE444958060100003F 
   HMLAN3_RSSI -94 
   HMLAN3_TIME 2014-07-11 18:58:25 
   IODev      HMLAN1 
   LASTInputDev HMLAN1 
   MSGCNT     4 
   NAME       Licht_Fahrraeder 
   NR         73 
   STATE      off 
   TYPE       CUL_HM 
   lastMsg    No:36 - t:10 s:1B46AE d:444958 060100003F 
   peerList   Licht_Eingang, 
   protLastRcv 2014-07-11 18:58:25 
   protSnd    2 last_at:2014-07-11 18:58:25 
   protState  CMDs_done 
   rssi_HMLAN1 avg:-63 min:-63 max:-63 lst:-63 cnt:1 
   rssi_at_HMLAN0 avg:-76 min:-76 max:-76 lst:-76 cnt:1 
   rssi_at_HMLAN1 avg:-65 min:-65 max:-65 lst:-65 cnt:2 
   rssi_at_HMLAN3 avg:-94 min:-94 max:-94 lst:-94 cnt:1 
   Readings: 
     2014-07-11 07:10:45   D-firmware      2.1 
     2014-07-11 07:10:45   D-serialNr      JEQ0096148 
     2014-07-11 07:11:31   PairedTo        0x444958 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-lgActionType jmpToTarget 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-lgCtDlyOff geLo 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-lgCtDlyOn geLo 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-lgCtOff geLo 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-lgCtOn geLo 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-lgCtValHi 100 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-lgCtValLo 50 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-lgMultiExec on 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-lgOffDly 0 s 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-lgOffTime 111600 s 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-lgOffTimeMode absolut 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-lgOnDly 0 s 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-lgOnTime 111600 s 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-lgOnTimeMode absolut 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-lgSwJtDlyOff on 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-lgSwJtDlyOn on 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-lgSwJtOff dlyOn 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-lgSwJtOn on 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-shActionType jmpToTarget 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-shCtDlyOff geLo 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-shCtDlyOn geLo 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-shCtOff geLo 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-shCtOn geLo 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-shCtValHi 100 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-shCtValLo 50 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-shOffDly 0 s 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-shOffTime 111600 s 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-shOffTimeMode absolut 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-shOnDly 0 s 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-shOnTime 111600 s 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-shOnTimeMode absolut 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-shSwJtDlyOff on 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-shSwJtDlyOn on 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-shSwJtOff dlyOn 
     2014-07-11 07:11:33   R-Licht_Eingang_chn-01-shSwJtOn on 
     2014-07-11 07:11:31   R-confBtnTime   255 min 
     2014-07-11 07:11:31   R-intKeyVisib   invisib 
     2014-07-11 07:11:31   R-pairCentral   0x444958 
     2014-07-11 07:11:32   R-sign          off 
     2014-07-11 07:11:31   RegL_00:        02:01 0A:44 0B:49 0C:58 15:FF 00:00 
     2014-07-11 07:11:32   RegL_01:        08:00 00:00 
     2014-07-11 07:11:33   RegL_03:Licht_Eingang_chn:01 02:00 03:00 04:32 05:64 06:00 07:FF 08:00 09:FF 0A:01 0B:13 0C:33 82:00 83:00 84:32 85:64 86:00 87:FF 88:00 89:FF 8A:21 8B:13 8C:33 00:00 
     2014-07-11 18:58:25   deviceMsg       off (to HMLAN1) 
     2014-07-11 18:58:25   level           0 
     2014-07-11 18:58:25   pct             0 
     2014-07-11 18:58:02   peerList        Licht_Eingang, 
     2014-07-11 18:58:25   recentStateType info 
     2014-07-11 18:58:25   state           off 
     2014-07-11 18:58:25   timedOn         off 
   Helper: 
     cSnd       014449581B46AE010E 
     mId        0069 
     rxType     1 
     Io: 
       newChn     +1B46AE,00,01,00 
       nextSend   1405097905.33417 
       prefIO     HMLAN1 
       rxt        0 
       vccu       CCU 
       p: 
         1B46AE 
         00 
         01 
         00 
     Mrssi: 
       mNo        36 
       Io: 
         HMLAN0     -76 
         HMLAN1     -63 
         HMLAN3     -94 
     Prt: 
       bErr       0 
       sProc      0 
       Rspwait: 
     Q: 
       qReqConf 
       qReqStat 
     Role: 
       chn        1 
       dev        1 
       prs        1 
     Rpt: 
       IO         HMLAN1 
       flg        A 
       ts         1405097905.119 
       ack: 
         HASH(0x2ad2b68) 
         3680024449581B46AE00 
     Rssi: 
       Hmlan1: 
         avg        -63 
         cnt        1 
         lst        -63 
         max        -63 
         min        -63 
       At_hmlan0: 
         avg        -76 
         cnt        1 
         lst        -76 
         max        -76 
         min        -76 
       At_hmlan1: 
         avg        -65 
         cnt        2 
         lst        -65 
         max        -65 
         min        -65 
       At_hmlan3: 
         avg        -94 
         cnt        1 
         lst        -94 
         max        -94 
         min        -94 
Attributes: 
   IODev      HMLAN1 
   IOgrp      CCU:HMLAN1 
   autoReadReg 4_reqStatus 
   devStateIcon on:on@orange 
   expert     2_full 
   firmware   2.1 
   group      Lichtschalter 
   model      HM-LC-Sw1PBU-FM 
   peerIDs    00000000,1AE2C601, 
   room       Aussen,Alles 
   serialNr   JEQ0096148 
   sortby     03 
   subType    switch 
   webCmd     toggle:on:off:statusRequest

Wie auch immer das zustande kam kann ich mir nicht erklären. Wie bekomme ich das sauber wieder weg?

Zum Crash:

Zitateine CCU macht kein connect/disconnect. habe die übrigen Parameter etwas ausgesagt? Gibt es irgendwelche logs?

Ich habe folgendes herausfinden können: FHEM lief gestern Abend scheinbar ohne Probleme. Dann steht plötzlich im Log:

Code Auswählen

2014.07.10 21:51:12 3: CUL_HM set Licht_Fahrraeder on
2014.07.10 21:59:31 3: CUL_HM set Rollladen_Kueche stop
2014.07.10 21:59:33 3: CUL_HM set Rollladen_Kueche off
2014.07.10 21:59:34 3: CUL_HM set Rollladen_Wohnzimmer_R stop
2014.07.10 21:59:36 3: CUL_HM set Rollladen_Wohnzimmer_R off
2014.07.10 21:59:50 3: CUL_HM set Rollladen_Wohnzimmer_L stop
2014.07.10 21:59:52 3: CUL_HM set Rollladen_Wohnzimmer_L off
2014.07.10 21:59:56 3: Watchdog Reset_BM_Zufahrt2 triggered
2014.07.10 22:00:00 3: CUL_HM set Rollladen_Flur_OG stop
2014.07.10 22:00:02 3: CUL_HM set Rollladen_Flur_OG off
2014.07.10 22:07:19 3: Watchdog Reset_BM_Zufahrt2 triggered
2014.07.10 22:24:11 3: Aenderung  Fenster_Dachterrasse contact: offen (to CCU)
2014.07.10 22:24:11 3: CUL_HM set LED10 led red
2014.07.10 22:24:13 3: CUL_HM set LED10 led red
2014.07.10 22:25:54 3: CUL_HM set Licht_Fahrraeder on-for-timer 20
2014.07.10 22:26:44 3: Watchdog Reset_BM_Zufahrt2 triggered
2014.07.10 22:39:04 3: Keep-Alive Fenster_Waschkeller contact: gekippt (to CCU)
2014.07.10 22:45:18 3: CUL_HM set Licht_Zufahrt on-for-timer 120
2014.07.10 22:45:25 3: CUL_HM set Licht_Fahrraeder on-for-timer 120
2014.07.10 22:45:34 3: CUL_HM set Licht_Fahrraeder on-for-timer 120
2014.07.10 22:45:35 3: CUL_HM set Licht_Zufahrt on-for-timer 120
2014.07.10 22:45:40 3: CUL_HM set Licht_Fahrraeder on-for-timer 120
2014.07.10 22:46:15 3: Watchdog Reset_BM_Zufahrt triggered
2014.07.10 22:46:20 3: Watchdog Reset_BM_Fahrraeder triggered
2014.07.10 22:53:12 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 22:53:38 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 22:54:13 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 22:54:20 1: HMLAN_Parse: HMLAN0 new condition timeout
2014.07.10 22:54:20 1: 192.168.211.72:1000 disconnected, waiting to reappear (HMLAN0)
2014.07.10 22:54:20 1: HMLAN_Parse: HMLAN0 new condition disconnected
2014.07.10 22:54:20 1: HMLAN_Parse: HMLAN2 new condition timeout
2014.07.10 22:54:20 1: 192.168.211.71:1000 disconnected, waiting to reappear (HMLAN2)
2014.07.10 22:54:20 1: HMLAN_Parse: HMLAN2 new condition disconnected
2014.07.10 22:54:30 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 22:54:32 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 22:54:43 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 22:54:43 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 22:54:57 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 22:55:00 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 22:55:00 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 22:55:09 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 22:55:10 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 22:55:12 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 22:55:14 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 22:55:40 1: HMLAN_Parse: HMLAN0 new condition timeout
2014.07.10 22:55:40 1: 192.168.211.72:1000 disconnected, waiting to reappear (HMLAN0)
2014.07.10 22:55:40 1: HMLAN_Parse: HMLAN0 new condition disconnected
2014.07.10 22:55:50 1: HMLAN_Parse: HMLAN2 new condition timeout
2014.07.10 22:55:50 1: 192.168.211.71:1000 disconnected, waiting to reappear (HMLAN2)
2014.07.10 22:55:50 1: HMLAN_Parse: HMLAN2 new condition disconnected
2014.07.10 22:55:57 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 22:56:10 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 22:56:16 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 22:56:17 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 22:56:17 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 22:56:23 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 22:56:23 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 22:56:34 1: HMLAN_Parse: HMLAN3 new condition timeout
2014.07.10 22:56:34 1: 192.168.211.73:1000 disconnected, waiting to reappear (HMLAN3)
2014.07.10 22:56:34 1: HMLAN_Parse: HMLAN3 new condition disconnected
2014.07.10 22:56:52 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 22:56:52 1: Accept failed (telnetPort: Resource temporarily unavailable)

Das geht dann munter so weiter, später nach Betätigung eines Fenstergriffs kommt:

Code Auswählen

2014.07.10 23:10:50 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 23:10:52 3: Aenderung  Fenster_Dachterrasse contact: gekippt (to CCU)
2014.07.10 23:10:53 3: Aenderung  Fenster_Dachterrasse contact: gekippt (to CCU)
2014.07.10 23:10:53 3: CUL_HM set LED10 led orange
2014.07.10 23:10:53 3: CUL_HM set LED10 led orange
2014.07.10 23:10:53 2: CUL_HM LED_Anzeige attack:114449581B2599800A01:114449581B2599800A03.
2014.07.10 23:10:53 2: CUL_HM LED_Anzeige attack:114449581B2599800A01:114449581B2599800A03.
2014.07.10 23:10:55 3: CUL_HM set LED10 led orange
2014.07.10 23:10:58 3: CUL_HM set LED10 led orange
2014.07.10 23:10:58 2: CUL_HM LED_Anzeige attack:114449581B2599800A01:114449581B2599800A03.
2014.07.10 23:10:58 2: CUL_HM LED_Anzeige attack:114449581B2599800A01:114449581B2599800A03.
2014.07.10 23:11:10 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 23:11:13 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 23:11:41 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 23:11:43 1: HMLAN_Parse: HMLAN2 new condition timeout
2014.07.10 23:11:43 1: 192.168.211.71:1000 disconnected, waiting to reappear (HMLAN2)
2014.07.10 23:11:43 1: HMLAN_Parse: HMLAN2 new condition disconnected
2014.07.10 23:11:58 1: Accept failed (telnetPort: Resource temporarily unavailable)

Hier fällt auf, daß die Änderung zweimal im Log steht, da diese nicht gefiltert wurde. Jetzt könnte man meinen, vielleicht war das Delay zu groß und FHEM hat zwei Events draus gemacht. Aber es geht weiter:

Code Auswählen

2014.07.10 23:29:51 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 23:30:00 3: CUL_HM set Licht_Terrasse off
2014.07.10 23:30:00 3: CUL_HM set Licht_Terrasse off
2014.07.10 23:30:00 3: CUL_HM set Licht_Terrasse off
2014.07.10 23:30:00 3: CUL_HM set Licht_Terrasse off
2014.07.10 23:30:01 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 23:30:02 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 23:30:03 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 23:30:13 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.10 23:30:20 3: chk_set: Befehl set Licht_Terrasse off fehlgeschlagen (State: set_off)! Wiederhole...
2014.07.10 23:30:20 3: CUL_HM set Licht_Terrasse off
2014.07.10 23:30:20 3: chk_set: Befehl set Licht_Terrasse off fehlgeschlagen (State: set_off)! Wiederhole...
2014.07.10 23:30:20 3: CUL_HM set Licht_Terrasse off
2014.07.10 23:30:21 3: set Pushover1 msg 'Befehl fehlgeschlagen!' 'Licht_Terrasse off' '' 0 ''  : OK
2014.07.10 23:30:21 3: set Pushover1 msg 'Befehl fehlgeschlagen!' 'Licht_Terrasse off' '' 0 ''  : OK
2014.07.10 23:30:23 1: Accept failed (telnetPort: Resource temporarily unavailable)

Der Befehl zum Ausschalten von Licht_Terrasse kommt nun viermal. Meine Funktion "chk_set" setzt auf deine Resends nochmal einen drauf und bessert nach 20 Sekunden ggf. nochmal nach. Das greift normalerweise vielleicht einmal in 6 Monaten.

Morgens:

Code Auswählen

2014.07.11 06:14:56 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.11 06:15:00 3: CUL_HM set Rollladen_Kueche stop
2014.07.11 06:15:00 3: CUL_HM set Rollladen_Kueche stop
2014.07.11 06:15:00 3: CUL_HM set Rollladen_Flur_OG stop
2014.07.11 06:15:00 3: CUL_HM set Rollladen_Kueche stop
2014.07.11 06:15:00 3: CUL_HM set Rollladen_Kueche stop
2014.07.11 06:15:00 3: CUL_HM set Rollladen_Flur_OG stop
2014.07.11 06:15:00 3: CUL_HM set Rollladen_Flur_OG stop
2014.07.11 06:15:00 3: CUL_HM set Rollladen_Flur_OG stop
2014.07.11 06:15:00 2: CUL_HM Rollladen_Flur_OG attack:11444959234D530201000000:11444959234D530301.
2014.07.11 06:15:00 2: CUL_HM Rollladen_Kueche attack:1144495820B9630201000000:1144495820B9630301.
2014.07.11 06:15:00 2: CUL_HM Rollladen_Flur_OG attack:11444959234D530201000000:11444959234D530301.
2014.07.11 06:15:00 2: CUL_HM Rollladen_Flur_OG attack:11444959234D530201000000:11444959234D530301.
2014.07.11 06:15:02 3: CUL_HM set Rollladen_Kueche pct 60
2014.07.11 06:15:02 3: CUL_HM set Rollladen_Kueche pct 60
2014.07.11 06:15:02 3: CUL_HM set Rollladen_Kueche pct 60
2014.07.11 06:15:02 3: CUL_HM set Rollladen_Kueche pct 60
2014.07.11 06:15:02 2: CUL_HM Rollladen_Kueche attack:1144495820B9630201000000:1144495820B963020178.
2014.07.11 06:15:06 3: CUL_HM set Rollladen_Flur_OG pct 55
2014.07.11 06:15:06 3: CUL_HM set Rollladen_Flur_OG pct 55
2014.07.11 06:15:06 3: CUL_HM set Rollladen_Flur_OG pct 55
2014.07.11 06:15:06 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.11 06:15:14 3: CUL_HM set Rollladen_Flur_OG pct 55
2014.07.11 06:15:14 2: CUL_HM Rollladen_Flur_OG attack:11444959234D530201000000:11444959234D5302016E.
2014.07.11 06:15:14 2: CUL_HM Rollladen_Flur_OG attack:11444959234D530201000000:11444959234D5302016E.
2014.07.11 06:15:14 2: CUL_HM Rollladen_Flur_OG attack:11444959234D530201000000:11444959234D5302016E.
2014.07.11 06:15:17 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.11 06:15:20 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.11 06:15:41 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.11 06:15:42 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.11 06:15:48 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.11 06:15:56 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.11 06:15:59 3: CUL_HM set Licht_Treppenhaus off
2014.07.11 06:15:59 3: CUL_HM set Licht_Treppenhaus off
2014.07.11 06:15:59 3: CUL_HM set Licht_Treppenhaus off
2014.07.11 06:16:10 3: CUL_HM set Licht_Treppenhaus off
2014.07.11 06:16:10 2: CUL_HM Licht_Treppenhaus attack:1144495821D49A0201C80000:1144495821D49A0201000000.
2014.07.11 06:16:14 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.11 06:16:18 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.11 06:16:19 3: chk_set: Befehl set Licht_Treppenhaus off fehlgeschlagen (State: set_off)! Wiederhole...
2014.07.11 06:16:19 3: CUL_HM set Licht_Treppenhaus off
2014.07.11 06:16:19 3: set Pushover1 msg 'Befehl fehlgeschlagen!' 'Licht_Treppenhaus off' '' 0 ''  : OK
2014.07.11 06:16:22 3: chk_set: Befehl set Licht_Treppenhaus off fehlgeschlagen (State: set_off)! Wiederhole...
2014.07.11 06:16:22 3: CUL_HM set Licht_Treppenhaus off
2014.07.11 06:16:23 3: set Pushover1 msg 'Befehl fehlgeschlagen!' 'Licht_Treppenhaus off' '' 0 ''  : OK
2014.07.11 06:17:58 1: Accept failed (telnetPort: Resource temporarily unavailable)
2014.07.11 06:18:20 1: Accept failed (telnetPort: Resource temporarily unavailable)

... usw.
Heute Morgen habe ich dann festgestellt, daß fhem.pl viermal läuft! Da sich die Prozesse "um die Wette" mit den HMLAN verbinden wollten, waren diese nahezu ständig disconnected. Ich weiß nicht wer oder was das ausgelöst hat. Ich habe plotfork aktiviert und auch z.B. ein gutes Dutzend PRESENCE laufen, um die Netzwerkgeräte zu überwachen. Pingabstände liegen im Minutenbereich.
Beim ersten Neustart:

Code Auswählen

2014.07.11 06:55:05 0: Server shutdown
2014.07.11 06:55:05 1: 192.168.211.254:2002 disconnected, waiting to reappear (FRITZBOX)
2014.07.11 06:55:05 1: 192.168.211.254:2002 disconnected, waiting to reappear (FRITZBOX)
2014.07.11 06:55:05 1: 192.168.211.254:2002 reappeared (FRITZBOX)
2014.07.11 06:55:05 1: FBAHA FRITZBOX registered with handle: 00000021
2014.07.11 06:55:05 1: 192.168.211.254:2002 reappeared (FRITZBOX)
2014.07.11 06:55:05 1: FBAHA FRITZBOX registered with handle: 00000022
2014.07.11 06:55:09 1: 192.168.211.73:1000 reappeared (HMLAN3)
2014.07.11 06:55:09 1: HMLAN_Parse: HMLAN3 new condition init
2014.07.11 06:55:09 1: 192.168.211.72:1000 reappeared (HMLAN0)
2014.07.11 06:55:09 1: HMLAN_Parse: HMLAN0 new condition init
2014.07.11 06:55:09 1: 192.168.211.70:1000 reappeared (HMLAN1)
2014.07.11 06:55:09 1: HMLAN_Parse: HMLAN1 new condition init
2014.07.11 06:55:09 1: 192.168.211.71:1000 reappeared (HMLAN2)
2014.07.11 06:55:09 1: HMLAN_Parse: HMLAN2 new condition init
2014.07.11 06:55:09 1: HMLAN_Parse: HMLAN3 new condition ok
2014.07.11 06:55:09 1: HMLAN_Parse: HMLAN0 new condition ok
2014.07.11 06:55:09 1: HMLAN_Parse: HMLAN2 new condition ok
2014.07.11 06:55:09 1: HMLAN_Parse: HMLAN1 new condition ok
2014.07.11 06:55:09 0: Server shutdown
2014.07.11 06:55:09 0: Server shutdown
2014.07.11 06:56:06 1: Including fhem.cfg
2014.07.11 06:56:06 3: telnetPort: port 7072 opened
2014.07.11 06:56:07 3: WEB: port 8083 opened
2014.07.11 06:56:07 3: WEBphone: port 8084 opened
2014.07.11 06:56:07 3: WEBtablet: port 8085 opened
2014.07.11 06:56:08 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.07.11 06:56:08 3: Opening HMLAN1 device 192.168.211.70:1000
2014.07.11 06:56:09 3: HMLAN1 device opened
2014.07.11 06:56:09 1: HMLAN_Parse: HMLAN1 new condition init
2014.07.11 06:56:09 1: HMLAN_Parse: HMLAN2 new condition disconnected
2014.07.11 06:56:09 3: Opening HMLAN2 device 192.168.211.71:1000
2014.07.11 06:56:09 3: HMLAN2 device opened
2014.07.11 06:56:09 1: HMLAN_Parse: HMLAN2 new condition init
2014.07.11 06:56:09 1: HMLAN_Parse: HMLAN0 new condition disconnected
2014.07.11 06:56:09 3: Opening HMLAN0 device 192.168.211.72:1000
2014.07.11 06:56:09 3: HMLAN0 device opened
2014.07.11 06:56:09 1: HMLAN_Parse: HMLAN0 new condition init
2014.07.11 06:56:09 3: Opening FRITZBOX device 192.168.211.254:2002
2014.07.11 06:56:09 3: FRITZBOX device opened
2014.07.11 06:56:09 1: FBAHA FRITZBOX registered with handle: 00000023
2014.07.11 06:56:09 3: FHEM2FHEM opening FH2FH at 192.168.211.254:7072
2014.07.11 06:56:09 3: FHEM2FHEM device opened (FH2FH)
2014.07.11 06:56:20 1: HMLAN_Parse: HMLAN3 new condition disconnected
2014.07.11 06:56:20 3: Opening HMLAN3 device 192.168.211.73:1000
2014.07.11 06:56:20 3: HMLAN3 device opened
2014.07.11 06:56:20 1: HMLAN_Parse: HMLAN3 new condition init
2014.07.11 06:56:20 1: Including ./log/fhem.save
2014.07.11 06:56:21 1: statefile: Unknown command 2014-03-18, try help.
2014.07.11 06:56:21 3: No I/O device found for L_Garten_auto
2014.07.11 06:56:21 3: No I/O device found for L_Garten_man
2014.07.11 06:56:21 3: No I/O device found for L_Fahrraeder_auto
2014.07.11 06:56:21 3: No I/O device found for L_Zufahrt_auto
2014.07.11 06:56:21 3: No I/O device found for L_Zufahrt_man
2014.07.11 06:56:21 3: No I/O device found for L_Fahrraeder_man
2014.07.11 06:56:22 3: FHEM (Re-)Start
2014.07.11 06:56:22 3: CUL_HM set LED_Anzeige ilum 6 0
2014.07.11 06:56:22 3: FHEM_Init return value: Can't use an undefined value as an ARRAY reference at ./FHEM/10_CUL_HM.pm line 6553.

2014.07.11 06:56:22 2: Error messages while initializing FHEM: statefile: Unknown command 2014-03-18, try help.
2014.07.11 06:56:22 0: Server started with 316 defined entities (version $Id: fhem.pl 6080 2014-06-07 16:12:09Z rudolfkoenig $, os linux, user fhem, pid 2464)

Danach ist FHEM wieder abgestürzt. Den Rest hatte ich schon geschrieben. Irgendeine Idee?

Ach, fast vergessen. Das war meine definierte CCU:

Code Auswählen

define CCU CUL_HM 444958
attr CCU IODev HMLAN1
attr CCU IOList HMLAN0,HMLAN1,HMLAN3
attr CCU autoReadReg 4_reqStatus
attr CCU expert 2_full
attr CCU group HMLAN
attr CCU model CCU-FHEM
attr CCU room Status
attr CCU subType virtual
attr CCU webCmd virtual:update
attr Fenster_Arbeitszimmer IOgrp CCU:HMLAN3

IOgrp war bei allen Devices gesetzt, ggf. natürlich mit einem anderen LAN Adapter.
HMLAN2 hatte noch die andere HMID.

Viele Grüße
Jürgen

baumeister · 11 Juli 2014, 20:31:06

So, habe fhem wie gewünscht heute aktualisiert, das set vccu update ausgeführt und dann noch einmal das list vccu erstellt.

Internals:
DEF F11234
IODev CUL_0
NAME vccu
NR 32
STATE CUL_0:ok,
TYPE CUL_HM
assignedIOs CUL_0
Readings:
Helper:
mId FFF0
rxType 1
Io:
prefIO
vccu
ioList:
CUL_0
Mrssi:
mNo
Prt:
bErr 0
sProc 0
Rspwait:
Q:
qReqConf
qReqStat
Role:
chn 1
dev 1
vrt 1
Attributes:
IODev CUL_0
IOList CUL_0
model CCU-FHEM
subType virtual
webCmd virtual:update

Scheint so, alls hätte ich oben noch weiter rumgeklickt und nicht direkt die Konsolenausgabe beobachtet. Jetzt ist es wie zuerst berichtet:
# Can't use an undefined value as an ARRAY reference at ./FHEM/10_CUL_HM.pm line 6582.
fhem Prozess stürzt ab und muss neu gestartet werden.

Ich habe zum Spass mal den vccu Teil auf der fhem.cfg auskommentiert.
#virtual ccu
#define vccu CUL_HM F11234
#attr vccu IODev CUL_0
#attr vccu IOList CUL_0
#attr vccu model CCU-FHEM
#attr vccu subType virtual
#attr vccu webCmd virtual:update

Nun kann ich die fhem.cfg speichern. Fehlerausgabe in der Konsole ist weg und der fhem Prozess läuft normal weiter.

Hilft das weiter?

martinp876 · 11 Juli 2014, 21:00:22

Die Änderung ist in SVN - morgen im update.

Seltsam, dass beim save kommt - beim reload könnte es mehr sinn machen.
wird die vccu "spät" angelegt? Das könnte es sein - schiebt die einmal an erste Stelle im fhem.cfg.
Morgen kommt es dann nicht mehr vor, denke ich. Evtl liegt es an eurer Perl version... werde noch einmal testen - aber wohl nicht nächste Woche

baumeister · 12 Juli 2014, 09:53:43

Hallo,

auch nach dem update heute leider keine Änderung. Mit in der fhem.cfg aktiviertem vccu stürzt alles beim speichern ab. Ist die vccu deaktiviert läuft es.

frank · 12 Juli 2014, 10:14:01

Zitatwird die vccu "spät" angelegt? Das könnte es sein - schiebt die einmal an erste Stelle im fhem.cfg.

die reihenfolge der definitionen in fhem.cfg hast du geprüft?
ein shutdown restart nach update hast du sicher gemacht.
ein update force bewirkt manchmal wunder.

baumeister · 12 Juli 2014, 14:17:06

Hallo,

so noch eiunmal alles probiert
- update force mit restart natürlich
=> fhem stürtzt ab wenn ich vccu in fhem.cfg aktiviere und speicher

- vccu als erstes in config eingetragen
=> macht keinen unterschied, fhem stürtzt beim speichern ab

Also alles wie vorher.

frank · 14 Juli 2014, 21:57:41

ehrlich gesagt finde ich es logischer, wenn erst das io-device definiert wird und anschliessend die vccu. da diese doch auf daten des io aufbaut. so habe ich es in meiner fhem.cfg auch angelegt.

sind in deiner vccu definition eventuell irgendwo versteckte leerzeichen? aus deinen geposteten lists kann man das schlecht erkennen, da der text nicht mit code-tags markiert ist.

Deudi · 14 Juli 2014, 22:12:44

Habe heute neues Update gemacht. Erst die drei HMLAN eingetragen, direkt danach die CCU, dann den Rest. FHEM beendet sich mit besagter Fehlermeldung. Ergo: CCU musste wieder raus

baumeister · 15 Juli 2014, 20:57:29

Hallo,

nein auch wenn ich das io-device vor dem vccu definiere geht es nicht. Leerzeichen sind auch definitiv nicht vorhanden. Bleibt alles beim alten, wenn eine vccu definiert ist kann ich die fhem.cfg nicht speichern ohne das der Prozess abstürtzt.

betateilchen · 16 Juli 2014, 14:49:03

Zitat von: martinp876 am 11 Juli 2014, 21:00:22

Seltsam, dass beim save kommt - beim reload könnte es mehr sinn machen.

Hallo Martin,

das ist überhaupt nicht seltsam, sondern "by design" und die Fehlermeldung tritt auch nicht beim save auf, sondern im ersten Schritt danach.

Ein "save" in FHEMWEB -> edit files löst immer automatisch ein rereadcfg aus, wenn es sich um die Konfigurationsdatei handelt.

Code Auswählen

my $ret = FW_fC("rereadcfg") if($filePath eq $attr{global}{configfile});

Der Fehler passiert also - wie von Dir vermutet - beim Einlesen der zuvor abgespeicherten Konfiguration.
Für den Anwender ist das allerdings kaum zu unterscheiden - ich wollte Dir diese Info nur aus technischer Sicht geben.

Viele Grüße
Udo

martinp876 · 19 Juli 2014, 08:49:28

@Udo - danke
@baumeister

Zitatnein auch wenn ich das io-device vor dem vccu definiere geht es nicht.

die vccu sollte vor den anderen Devices (nicht vor den IOs) definiert werden.
Auch wenn die reihenfolgen sinn macht sollte es jetzt auch bei anderer Reihenfolge keinen Fehler mehr geben (seit einer Woche)
Tritt es immer noch auf?

Deudi · 20 Juli 2014, 13:39:13

Zitat von: martinp876 am 19 Juli 2014, 08:49:28
Die vccu sollte vor den anderen Devices (nicht vor den IOs) definiert werden.
Auch wenn die reihenfolgen sinn macht sollte es jetzt auch bei anderer Reihenfolge keinen Fehler mehr geben (seit einer Woche)
Tritt es immer noch auf?

Gerade update gemacht, Fehler immer noch da. => CCU wieder raus.

martinp876 · 20 Juli 2014, 17:35:58

hm - immer noch die gleiche Zeile? Es wird jetzt explizit abgefragt.
Hat der update auch funktioniert? Welche Version hattest du?

baumeister · 20 Juli 2014, 18:31:50

Hallo,

ja das Problem tritt immer noch auf, auch wenn die vccu jetzt vor anderen Geräten definiert ist. Wie oben schon erwähnt scheint nicht das speichern der config, sondern das erneute neu laden das Problem zu sein. Beides ein speichern und ein rereadconfig bringen fhem zum Absturz.

martinp876 · 20 Juli 2014, 20:27:34

ich habe noch einen Versuch eingestellt. Leider kann ich es nicht nachstellen, auch wenn ich die vccu identisch definieren.
Das definieren der vccu kann schon garkein Problem sein - es kommt sicher mit dem attribut IOGrp eines der devices. Da wird es genutzt.

evtl. doch eine andere Perl version? wenn es mit der aktuellen Version (von morgen, Version 6288) immer noch auftritt bitte noch einmal die Meldung mit Zeile