Autor Thema: Keine Event-Meldungen von ZWAVE-Sensoren  (Gelesen 480 mal)

Offline PNinBB

  • Full Member
  • ***
  • Beiträge: 135
Keine Event-Meldungen von ZWAVE-Sensoren
« am: 11 Juni 2018, 11:47:03 »
Ich hatte zum FHEM-Treffen in Karlsruhe im April 2018 von einem unerklärlichen Verhalten in meiner FHEM ZWave Installation berichtet und versprochen, im Forum darüber zu berichten.
Nach unfreiwilligen Wochen der FHEM-Ferne komme ich erst heute dazu.
Nun zur Sache selbst.
Ich betreibe seit mehreren Jahren FHEM ausschliesslich mit ZWave-Modulen, hauptsächlich FIBARO.
Seit mehreren Monaten (ich kann es nicht genauer angeben) bemerke ich, dass Sensoren, hauptsächlich der netzbetriebene (!!)
 Binärsensor wirkungslos wird; wohlbemerkt nicht nur einer, sondern eigentlich alle. Darüberhinaus auch zwei batteriebetriebene.
Nachfolgend die Gerätedetails des Fensterkontaktes.
Internals:
   CFGFN     
   DEF        ed70b42b 40
   IMAGE      /fhem/deviceimages/zwave/271.1281.4098_fgbs001.universal.binary.sensor.jpg
   IODev      ZWAVE
   NAME       AZ_DB_FV
   NR         34
   STATE      closed
   TYPE       ZWave
   ZWaveSubDevice no
   endpointChildren AZ_GZ_FV,AZ_FG_FV,AZ_TI_FV
   homeId     ed70b42b
   nodeIdHex  28
   .vclasses:
     ASSOCIATION 2
     BASIC      1
     CONFIGURATION 1
     FIRMWARE_UPDATE_MD 1
     MANUFACTURER_SPECIFIC 1
     MULTI_CHANNEL 2
     MULTI_CHANNEL_ASSOCIATION 2
     SCENE_ACTIVATION 1
     SENSOR_BINARY 1
     VERSION    1
   READINGS:
     2017-08-18 17:42:46   SEND_DATA       failed:00
     2017-12-28 09:01:47   assocGroup_1    Max 5 Nodes ZWAVE
     2017-12-28 09:01:47   assocGroup_2    Max 5 Nodes ZWAVE
     2017-12-28 09:01:47   assocGroup_3    Max 1 Nodes ZWAVE
     2017-12-28 09:01:46   assocGroups     3
     2017-12-28 09:16:45   basicReport     0
     2017-12-27 17:55:06   configDeactivateTransmissionOfFrame9 Groups1And2Sent
     2017-12-27 17:55:06   configForcedLevelOfDimmingGroup1 255
     2017-12-28 09:02:11   configForcedLevelOfDimmingGroup2 255
     2017-12-28 09:02:11   configIN1AlarmCancellationDelay 0
     2017-12-28 09:02:12   configIN2AlarmCancellationDelay 0
     2017-12-28 09:02:12   configInsensitivenessToTemperature12 8
     2017-12-27 17:55:12   configIntervalBetweenForcingToSend11 200
     2017-12-27 17:55:13   configIntervalBetweenSuccessive10 20
     2017-12-28 09:02:22   configSceneActivation ScenesDisabled
     2017-12-28 09:02:22   configTransmittingTheAlarmOrControl13 Sensor1And2BroadcastInactive
     2018-03-19 10:46:18   configTypeOfInputNo1 2180353
     2017-12-28 09:02:23   configTypeOfInputNo2 InputNONormalOpen
     2017-12-28 09:02:23   configTypeOfTransmittedControlFrameFor5 BASICSET
     2017-12-28 09:02:23   configTypeOfTransmittedControlFrameFor6 BASICSET
     2017-06-07 17:37:35   mcCapability_01 SENSOR_BINARY SENSOR_ALARM
     2017-06-07 17:37:41   mcCapability_02 SENSOR_BINARY SENSOR_ALARM
     2017-06-07 17:37:47   mcCapability_03 SENSOR_MULTILEVEL
     2017-06-07 17:39:14   mcEndpoints     total 3, different
     2017-06-07 17:40:48   mcaGroups       2
     2017-06-06 18:25:50   mca_1           Max 5 Nodes ZWAVE
     2017-06-06 18:25:50   mca_2           Max 5 Nodes ZWAVE
     2017-06-08 16:47:48   model           FIBARO System FGBS001 Universal Binary Sensor
     2017-06-08 16:47:48   modelConfig     fibaro/fgbs001.xml
     2017-06-08 16:47:48   modelId         010f-0501-1002
     2018-05-06 17:27:40   neighborList    ZWAVE FL_AB_RM AZ_AB_WV AZ_KZ_SF AZ_HZ_T1 BD_HZ_T1 BD_DB_FV SZ_RL_FL KU_DB_FS FL_DB_TV
     2017-08-23 17:19:23   neighborUpdate  done
     2018-01-29 10:31:19   reportedState   closed
     2018-01-29 10:31:19   state           closed
     2018-01-20 06:39:05   temperature     24.06 C
     2018-03-24 11:20:16   timeToAck       0.056
     2018-03-24 11:20:16   transmit        OK
     2017-06-21 12:06:09   version         Lib 3 Prot 3.52 App 2.1
Attributes:
   IODev      ZWAVE
   alexaName  fenster
   alexaRoom  Arbeitszimmer
   classes    SENSOR_BINARY MULTI_CHANNEL ASSOCIATION MULTI_CHANNEL_ASSOCIATION MANUFACTURER_SPECIFIC CONFIGURATION VERSION FIRMWARE_UPDATE_MD MARK SCENE_ACTIVATION BASIC
   group      Basiseinheiten
   icon       rc_SETUP
   neighborListPos 24,15
   room       Detektoren
   sortby     20
   vclasses   ASSOCIATION:2 BASIC:1 CONFIGURATION:1 FIRMWARE_UPDATE_MD:1 MANUFACTURER_SPECIFIC:1 MULTI_CHANNEL:2 MULTI_CHANNEL_ASSOCIATION:2 SCENE_ACTIVATION:1 SENSOR_BINARY:1 VERSION:1
Ich habe nach einer ersten Analyse festgestellt, dass sofort nach einem FHEM-Befehl
get AZ_DB_FV neighborListalles wieder funktionierte.
Rudi schlug vor, es einmal mit dem FHEM-Befehl
get ZWAVE homeIdzu versuchen. Ergebnis: gleiche "positive" Wirkung.
Seither habe ich einen 'at-Befehl', der alle 5 Minuten die homeId liest. Damit verschwindet zwar nicht der kuriose Effekt, aber seine Auswirkungen sind stark begrenzt. Das Problem tritt immer noch auf, aber nach spätestens 5 Minuten ist es "geheilt" !
Über die Häufigkeit des Auftretens kann ich nichts Verwertbares berichten, bzw. habe bisher keine Gesetzmäßigkeit entdeckt.
Nun habe ich heute die Sache noch einmal genauer untersucht und kann folgende Details anführen.

Phase 1: der Effekt ist aufgetreten; ich öffne das Fenster.
Eventmonitor:
Event - Monitor:

2018-06-11 08:49:07.938 Global global ATTR ZWAVE verbose 5
.....
2018-06-11 08:49:15.680 FRITZBOX FritzBox WLAN: on gWLAN: off
2018-06-11 08:49:15.680 FRITZBOX FritzBox lastReadout: 328 values captured in 1.00 s
2018-06-11 08:49:29.286 DbLog myDbLog background_processing_time: 0.1171
2018-06-11 08:49:29.286 DbLog myDbLog sql_processing_time: 0.0266
2018-06-11 08:50:17.420 Twilight Helligkeit azimuth: 88.84
2018-06-11 08:50:17.420 Twilight Helligkeit elevation: 30.66
2018-06-11 08:50:17.420 Twilight Helligkeit twilight: 100
2018-06-11 08:50:17.420 Twilight Helligkeit twilight_weather: 100
2018-06-11 08:50:17.420 Twilight Helligkeit compasspoint: east-northeast
2018-06-11 08:50:29.279 DbLog myDbLog background_processing_time: 0.1239
2018-06-11 08:50:29.279 DbLog myDbLog sql_processing_time: 0.0362
Fazit: kein relevanter Event-Eintrag.
Logfile mit Verbose = 5:
überhaupt kein Eintrag in dieser Zeitspanne !

Phase 2: nach Ausführung des HomeID-Lesens: ich öffne das Fenster (Subdevice AZ_GZ_FV von AZ_DB_FV).
Eventmonitor:
Event - Monitor:
2018-06-11 08:52:57.326 at at_ContrHomeID Next: 08:56:39
2018-06-11 08:52:58.699 Global global SAVE
2018-06-11 08:53:02.628 DbLog myDbLog background_processing_time: 0.1242
2018-06-11 08:53:02.628 DbLog myDbLog sql_processing_time: 0.0271
2018-06-11 08:53:25.722 at at_ContrHomeID execNow
2018-06-11 08:53:25.839 ZWave WZ_DS_SM 0
2018-06-11 08:53:25.839 ZWave WZ_DS_SM reportedState: 0
2018-06-11 08:53:26.193 ZWave WZ_DS_SM basicSet: 0
2018-06-11 08:53:26.302 ZWave FL_DS_SM luminance: 2 Lux
2018-06-11 08:53:32.185 dummy du_StatusFenster 255
2018-06-11 08:53:32.195 ZWave AZ_GZ_FV basicSet: 255 AZ-Kontakt funkt !  --> alles OK
2018-06-11 08:53:32.438 ZWave WZ_DS_SM 1
2018-06-11 08:53:32.438 ZWave WZ_DS_SM reportedState: 1
2018-06-11 08:53:32.521 ZWave WZ_DS_SM basicSet: 255
2018-06-11 08:53:32.821 DbLog myDbLog background_processing_time: 0.1221
2018-06-11 08:53:32.821 DbLog myDbLog sql_processing_time: 0.0350
2018-06-11 08:53:34.212 dummy du_StatusFenster 255
2018-06-11 08:53:34.223 ZWave AZ_GZ_FV basicSet: 0 AZ-Kontakt funkt !  --> alles OK
2018-06-11 08:53:35.674 ZWave WZ_DS_SM luminance: 16 Lux
2018-06-11 08:53:54.612 ZWave WZ_DS_SM 0
2018-06-11 08:53:54.612 ZWave WZ_DS_SM reportedState: 0
2018-06-11 08:53:54.663 ZWave WZ_DS_SM basicSet: 0
2018-06-11 08:54:02.773 DbLog myDbLog background_processing_time: 0.1259
2018-06-11 08:54:02.773 DbLog myDbLog sql_processing_time: 0.0342
Der Kommentar 'AZ-Kontakt funkt !  --> alles OK' wurde von mir per Hand hinzugefügt !
Logfile mit Verbose = 5:
2018.06.11 08:36:37.603 2: ZWAVE transmit NO_ACK for CB a7, target FL_DS_SM
2018.06.11 08:53:25.629 4: ZWDongle *** get ZWAVE homeId
2018.06.11 08:53:25.630 5: ZWDongle_Write 0020 ()
2018.06.11 08:53:25.631 5: SW: 01030020dc
2018.06.11 08:53:25.633 4: ZWDongle_ReadAnswer arg:homeId regexp:^0120
2018.06.11 08:53:25.633 5: ACK received, removing 01030020dc from dongle sendstack
2018.06.11 08:53:25.634 4: ZWDongle_Read ZWAVE: rcvd 0120ed70b42b01 (answer MEMORY_GET_ID), sending ACK
2018.06.11 08:53:25.634 5: SW: 06
2018.06.11 08:53:25.637 4: ZWDongle_ReadAnswer for homeId: 0120ed70b42b01
2018.06.11 08:53:25.638 3: at_ContrHomeID: ZWAVE homeId => HomeId:ed70b42b CtrlNodeIdHex:01
2018.06.11 08:53:25.726 4: ZWDongle_Read ZWAVE: rcvd 0004002103300300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:53:25.726 5: SW: 06
2018.06.11 08:53:25.735 5: ZWAVE: dispatch 0004002103300300
2018.06.11 08:53:25.736 4: CMD:APPLICATION_COMMAND_HANDLER ID:21 ARG:03300300 CB:00
2018.06.11 08:53:26.096 4: ZWDongle_Read ZWAVE: rcvd 0004002103200100 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:53:26.097 5: SW: 06
2018.06.11 08:53:26.099 5: ZWAVE: dispatch 0004002103200100
2018.06.11 08:53:26.099 4: CMD:APPLICATION_COMMAND_HANDLER ID:21 ARG:03200100 CB:00
2018.06.11 08:53:26.199 4: ZWDongle_Read ZWAVE: rcvd 00040025063105030a0002 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:53:26.200 5: SW: 06
2018.06.11 08:53:26.202 5: ZWAVE: dispatch 00040025063105030a0002
2018.06.11 08:53:26.202 4: CMD:APPLICATION_COMMAND_HANDLER ID:25 ARG:063105030a0002 CB:00
2018.06.11 08:53:31.394 4: ZWDongle_Read ZWAVE: rcvd 0004002807600d01012001ff (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:53:31.394 5: SW: 06
2018.06.11 08:53:31.396 5: ZWAVE: dispatch 0004002807600d01012001ff
2018.06.11 08:53:31.397 4: CMD:APPLICATION_COMMAND_HANDLER ID:28 ARG:07600d01012001ff CB:00
2018.06.11 08:53:32.292 4: ZWDongle_Read ZWAVE: rcvd 00040021033003ff (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:53:32.293 5: SW: 06
2018.06.11 08:53:32.295 5: ZWAVE: dispatch 00040021033003ff
2018.06.11 08:53:32.295 4: CMD:APPLICATION_COMMAND_HANDLER ID:21 ARG:033003ff CB:00
2018.06.11 08:53:32.456 4: ZWDongle_Read ZWAVE: rcvd 00040021032001ff (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:53:32.456 5: SW: 06
2018.06.11 08:53:32.459 5: ZWAVE: dispatch 00040021032001ff
2018.06.11 08:53:32.460 4: CMD:APPLICATION_COMMAND_HANDLER ID:21 ARG:032001ff CB:00
2018.06.11 08:53:33.584 4: ZWDongle_Read ZWAVE: rcvd 0004002807600d0101200100 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:53:33.585 5: SW: 06
2018.06.11 08:53:33.587 5: ZWAVE: dispatch 0004002807600d0101200100
2018.06.11 08:53:33.587 4: CMD:APPLICATION_COMMAND_HANDLER ID:28 ARG:07600d0101200100 CB:00
2018.06.11 08:53:35.595 4: ZWDongle_Read ZWAVE: rcvd 00040021063105030a0010 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:53:35.596 5: SW: 06
2018.06.11 08:53:35.598 5: ZWAVE: dispatch 00040021063105030a0010
2018.06.11 08:53:35.598 4: CMD:APPLICATION_COMMAND_HANDLER ID:21 ARG:063105030a0010 CB:00
2018.06.11 08:53:54.492 4: ZWDongle_Read ZWAVE: rcvd 0004002103300300 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:53:54.493 5: SW: 06
2018.06.11 08:53:54.495 5: ZWAVE: dispatch 0004002103300300
2018.06.11 08:53:54.495 4: CMD:APPLICATION_COMMAND_HANDLER ID:21 ARG:03300300 CB:00
2018.06.11 08:53:54.613 4: ZWDongle_Read ZWAVE: rcvd 0004002103200100 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:53:54.613 5: SW: 06
2018.06.11 08:53:54.615 5: ZWAVE: dispatch 0004002103200100
2018.06.11 08:53:54.615 4: CMD:APPLICATION_COMMAND_HANDLER ID:21 ARG:03200100 CB:00
2018.06.11 08:54:15.461 4: ZWDongle_Read ZWAVE: rcvd 0004001303800333 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:54:15.462 5: SW: 06
2018.06.11 08:54:15.463 5: ZWAVE: dispatch 0004001303800333
2018.06.11 08:54:15.464 4: CMD:APPLICATION_COMMAND_HANDLER ID:13 ARG:03800333 CB:00
2018.06.11 08:54:15.592 4: ZWDongle_Read ZWAVE: rcvd 0004001303800333 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:54:15.592 5: SW: 06
2018.06.11 08:54:15.594 5: ZWAVE: dispatch 0004001303800333
2018.06.11 08:54:15.594 4: CMD:APPLICATION_COMMAND_HANDLER ID:13 ARG:03800333 CB:00
2018.06.11 08:54:15.720 4: ZWDongle_Read ZWAVE: rcvd 0004001303800333 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:54:15.721 5: SW: 06
2018.06.11 08:54:15.722 5: ZWAVE: dispatch 0004001303800333
2018.06.11 08:54:15.723 4: CMD:APPLICATION_COMMAND_HANDLER ID:13 ARG:03800333 CB:00
2018.06.11 08:54:15.849 4: ZWDongle_Read ZWAVE: rcvd 0004001303800333 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:54:15.850 5: SW: 06
2018.06.11 08:54:15.851 5: ZWAVE: dispatch 0004001303800333
2018.06.11 08:54:15.851 4: CMD:APPLICATION_COMMAND_HANDLER ID:13 ARG:03800333 CB:00
2018.06.11 08:54:16.053 4: ZWDongle_Read ZWAVE: rcvd 0004001306430301420190 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:54:16.054 5: SW: 06
2018.06.11 08:54:16.056 5: ZWAVE: dispatch 0004001306430301420190
2018.06.11 08:54:16.056 4: CMD:APPLICATION_COMMAND_HANDLER ID:13 ARG:06430301420190 CB:00
2018.06.11 08:54:16.155 4: ZWDongle_Read ZWAVE: rcvd 0004001306430301420190 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:54:16.155 5: SW: 06
2018.06.11 08:54:16.157 5: ZWAVE: dispatch 0004001306430301420190
2018.06.11 08:54:16.158 4: CMD:APPLICATION_COMMAND_HANDLER ID:13 ARG:06430301420190 CB:00
2018.06.11 08:54:16.262 4: ZWDongle_Read ZWAVE: rcvd 00040013044608007f (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:54:16.262 5: SW: 06
2018.06.11 08:54:16.264 5: ZWAVE: dispatch 00040013044608007f
2018.06.11 08:54:16.265 4: CMD:APPLICATION_COMMAND_HANDLER ID:13 ARG:044608007f CB:00
2018.06.11 08:54:18.470 4: ZWDongle_Read ZWAVE: rcvd 000400150c600d03033105014400000940 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:54:18.470 5: SW: 06
2018.06.11 08:54:18.472 5: ZWAVE: dispatch 000400150c600d03033105014400000940
2018.06.11 08:54:18.473 4: CMD:APPLICATION_COMMAND_HANDLER ID:15 ARG:0c600d03033105014400000940 CB:00
2018.06.11 08:54:25.330 4: ZWDongle_Read ZWAVE: rcvd 0004001b0c600d03033105014400000960 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:54:25.331 5: SW: 06
2018.06.11 08:54:25.333 5: ZWAVE: dispatch 0004001b0c600d03033105014400000960
2018.06.11 08:54:25.333 4: CMD:APPLICATION_COMMAND_HANDLER ID:1b ARG:0c600d03033105014400000960 CB:00
2018.06.11 08:54:26.366 4: ZWDongle_Read ZWAVE: rcvd 000400230c600d030331050144000009b1 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:54:26.366 5: SW: 06
2018.06.11 08:54:26.368 5: ZWAVE: dispatch 000400230c600d030331050144000009b1
2018.06.11 08:54:26.369 4: CMD:APPLICATION_COMMAND_HANDLER ID:23 ARG:0c600d030331050144000009b1 CB:00
2018.06.11 08:54:26.841 4: ZWDongle_Read ZWAVE: rcvd 000400230c600d04043105014400000a15 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:54:26.841 5: SW: 06
2018.06.11 08:54:26.843 5: ZWAVE: dispatch 000400230c600d04043105014400000a15
2018.06.11 08:54:26.843 4: CMD:APPLICATION_COMMAND_HANDLER ID:23 ARG:0c600d04043105014400000a15 CB:00
2018.06.11 08:54:36.771 4: ZWDongle_Read ZWAVE: rcvd 000400280c600d03033105014400000b22 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:54:36.771 5: SW: 06
2018.06.11 08:54:36.773 5: ZWAVE: dispatch 000400280c600d03033105014400000b22
2018.06.11 08:54:36.774 4: CMD:APPLICATION_COMMAND_HANDLER ID:28 ARG:0c600d03033105014400000b22 CB:00
2018.06.11 08:55:01.173 4: ZWDongle_Read ZWAVE: rcvd 0004001603800333 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:55:01.173 5: SW: 06
2018.06.11 08:55:01.175 5: ZWAVE: dispatch 0004001603800333
2018.06.11 08:55:01.176 4: CMD:APPLICATION_COMMAND_HANDLER ID:16 ARG:03800333 CB:00
2018.06.11 08:55:01.365 4: ZWDongle_Read ZWAVE: rcvd 0004001606430301420190 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:55:01.365 5: SW: 06
2018.06.11 08:55:01.367 5: ZWAVE: dispatch 0004001606430301420190
2018.06.11 08:55:01.367 4: CMD:APPLICATION_COMMAND_HANDLER ID:16 ARG:06430301420190 CB:00
2018.06.11 08:55:01.421 4: ZWDongle_Read ZWAVE: rcvd 00040016044608007f (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:55:01.421 5: SW: 06
2018.06.11 08:55:01.423 5: ZWAVE: dispatch 00040016044608007f
2018.06.11 08:55:01.423 4: CMD:APPLICATION_COMMAND_HANDLER ID:16 ARG:044608007f CB:00
2018.06.11 08:55:01.477 4: ZWDongle_Read ZWAVE: rcvd 00040016028407 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:55:01.478 5: SW: 06
2018.06.11 08:55:01.480 5: ZWAVE: dispatch 00040016028407
2018.06.11 08:55:01.480 4: CMD:APPLICATION_COMMAND_HANDLER ID:16 ARG:028407 CB:00
2018.06.11 08:55:02.433 4: ZWDongle_Read ZWAVE: rcvd 0004001d0c600d03033105014400000a53 (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:55:02.434 5: SW: 06
2018.06.11 08:55:02.436 5: ZWAVE: dispatch 0004001d0c600d03033105014400000a53
2018.06.11 08:55:02.436 4: CMD:APPLICATION_COMMAND_HANDLER ID:1d ARG:0c600d03033105014400000a53 CB:00
2018.06.11 08:55:03.503 5: ZWDongle_Write 00131602840825b1 (ed70b42b)
2018.06.11 08:55:03.504 5: SW: 010900131602840825b1e9
2018.06.11 08:55:03.507 5: ACK received, WaitForAck=>2 for 010900131602840825b1e9
2018.06.11 08:55:03.511 4: ZWDongle_Read ZWAVE: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2018.06.11 08:55:03.512 5: SW: 06
2018.06.11 08:55:03.514 5: ZWAVE: dispatch 011301
2018.06.11 08:55:03.527 4: ZWDongle_Read ZWAVE: rcvd 0013b1000002 (request ZW_SEND_DATA), sending ACK
2018.06.11 08:55:03.528 5: SW: 06
2018.06.11 08:55:03.529 5: device ack reveived, removing 010900131602840825b1e9 from dongle sendstack
2018.06.11 08:55:03.530 5: ZWAVE: dispatch 0013b1000002
2018.06.11 08:55:03.530 4: CMD:ZW_SEND_DATA ID:00 ARG:0002 CB:b1
2018.06.11 08:55:03.531 4: ZWAVE transmit OK for CB b1, target BD_HZ_T1
2018.06.11 08:55:05.450 4: ZWDongle_Read ZWAVE: rcvd 000400270c600d030331050144000009ef (request APPLICATION_COMMAND_HANDLER), sending ACK
2018.06.11 08:55:05.451 5: SW: 06
2018.06.11 08:55:05.453 5: ZWAVE: dispatch 000400270c600d030331050144000009ef
2018.06.11 08:55:05.453 4: CMD:APPLICATION_COMMAND_HANDLER ID:27 ARG:0c600d030331050144000009ef CB:00
2018.06.11 08:56:39.239 3: at_ContrHomeID: ZWAVE homeId => HomeId:ed70b42b CtrlNodeIdHex:01
2018.06.11 09:01:39.228 1: Systembereinigung bezüglich 'UNPARSED' gestartet
2018.06.11 09:01:39.278 1: Systembereinigung bezüglich 'UNKNOWN' gestartet
2018.06.11 09:01:39.370 3: at_ContrHomeID: ZWAVE homeId => HomeId:ed70b42b CtrlNodeIdHex:01
2018.06.11 09:05:17.840 3: UWZ Unwetter: Run.1043 Done fetching data
2018.06.11 09:06:24.108 2: ZWAVE transmit NO_ACK for CB b6, target FL_DS_SM
2018.06.11 09:06:39.241 3: at_ContrHomeID: ZWAVE homeId => HomeId:ed70b42b CtrlNodeIdHex:01
2018.06.11 09:11:39.402 3: at_ContrHomeID: ZWAVE homeId => HomeId:ed70b42b CtrlNodeIdHex:01
Noch ein paar Zusatzinformationen:
1. Die im Logfile sichtbaren Einträge
2018.06.11 09:01:39.228 1: Systembereinigung bezüglich 'UNPARSED' gestartet
2018.06.11 09:01:39.278 1: Systembereinigung bezüglich 'UNKNOWN' gestartet
kommen von mir geschriebenen Routinen, die Geräte nach 'UNPARSED' und 'UNKNOWN' Readings durchforsten und diese eliminieren.
2. Desweiteren habe ich immer wieder Gerätedefinitionen, die vermutlich von 'autocreate' stammen und durch fehlerhafte und nicht zuordenbare Pakete hervorgerufen werden. Sollte ich 'autocreate' abschalten, wenn ich keine Geräte inkludieren will ?
Was kann ich noch unternehmen ?
Da ich auch Vermutungen bezüglich Feldstärken- bzw. Reichweitenproblemen hatte, habe ich den RaZberry2 Modul über ein ca 30 cm langes Bandkabel an den RaspBerry3 angeschlossen, in der Hoffnung die Ausbreitungsverhältnisse zu verbessern; Ergebnis nach gut 3 Wochen: keine nennenswerten Veränderungen. Eine Ersatzantenne anzuschließen, schiebe ich noch vor mir her, da ich zögere, die eingebaute Antenne abzubrechen.
Nun bin ich etwas am Ende meines 'Lateins' und hoffe auf Ideen, vor allem von Rudi.
Auf jeden Fall, besten Dank im Voraus.
Peter
Raspberry Pi 3B mit RaZberry2 (Debian Jessie) als FHEM-Server, FritzBox 7490;
AEOTec: Key Fob Gen5: 1x;
Danfoss: Living Connect 2.51: 3x;
Fibaro: FGRM222: 1 x, FGRM223: 1 x, FGMS001: 2x, FGK-101: 3x, FGBS-001: 8x, FGRGBWM-441: 1x;
Philio: In Wall Dual Relay PAN06-1A: 3x.

Offline rudolfkoenig

  • Administrator
  • Hero Member
  • *****
  • Beiträge: 18639
Antw:Keine Event-Meldungen von ZWAVE-Sensoren
« Antwort #1 am: 11 Juni 2018, 12:19:40 »
Es klingt so, dass entweder die Kommunikation zum Controller oder der Controller selbst eingeschlafen ist.Habe leider keine Idee, wie man das reparieren kann, ich wuerde mit einem ander Controller (ZWave Stick?) versuchen.

Offline krikan

  • Global Moderator
  • Hero Member
  • ****
  • Beiträge: 5905
Antw:Keine Event-Meldungen von ZWAVE-Sensoren
« Antwort #2 am: 11 Juni 2018, 12:46:06 »
Aehnliches Problem mit dem "verwandten" UZB1 wurde in https://forum.fhem.de/index.php/topic,87812.0.html berichtet. Anderer Controller könnte einen Versuch wert sein. Ich selbst nutze produktiv einen Vision-Controller, der derzeit auch als Cyrus vermarktet wird, und habe das Problem noch nicht gehabt.

Nach meiner dunklen Erinnerung wird von zway beim Razberry/UZB eine watchdog-API-Funktion aufgerufen, die FHEM nicht nutzt. Eventuell hat das etwas mit dem Problem zu tuen.

Offline PNinBB

  • Full Member
  • ***
  • Beiträge: 135
Antw:Keine Event-Meldungen von ZWAVE-Sensoren
« Antwort #3 am: 18 Juni 2018, 19:50:15 »
Das Problem beschäftigt mich nach wie vor.
Ich habe im Thread
https://forum.fhem.de/index.php/topic,87812
gelesen, dass dort die gleichen Probleme mit einem 'UZB1 Dongel' auftreten.
Die Kommandos, um das 'schlafende FHEM' wieder aufzuwecken, sind wohl unterschiedlich, aber der Effekt und das Ergebnis sind identisch: danach 'hört' FHEM wieder richtig.
Ich habe in den letzten Tagen mein System 'entschlackt', da es da noch alte Routinen gab, den über WLAN angebundenen Raspi zu überwachen.
Jetzt läuft alles und im Logfile gibt es keine nennenswerten Fehlermeldungen; manchmal, aber eben selten, gibt es mal kein ACK.
Aber eben alles mit einem 'at-Befehl ...', der alle 3 Minuten die 'homeId' des Controllers ausliest und damit den Controller wieder 'aufweckt'.
Wenn ich diesen Befehl abstelle, dann dauert es nur wenige Minuten, bis der - eben auch von anderen - beschriebene Effekt wieder auftritt.
Nun habe ich wieder getestet: den 'at...-Befehl' abgeschaltet und versucht, die Zeitspanne zu ermitteln nach der der Controller 'einschläft'.
Ergebnis: nach ca. 3 Minuten ist 'Ruhe'. Das ist insofern nicht eindeutig zu ermitteln, da jede 'Fensteröffnung' natürlich wieder eine FHEM-Reaktion auslöst oder auch ein anderer Befehl 'dazwischen funkt'.
Die Geräteeigenschaften des Controllers, wenn es funktioniert, sind wie folgt:
Internals:
   CFGFN     
   CallbackNr 0
   Clients    :ZWave:
   DEF        /dev/ttyAMA0@115200
   DeviceName /dev/ttyAMA0@115200
   FD         9
   MaxSendRetries 3
   NAME       ZWAVE
   NR         14
   PARTIAL   
   RAWMSG     001352000001
   ReadTime   1529333413.71991
   STATE      Initialized
   SendRetries 0
   SendTime   1529333413.69794
   TYPE       ZWDongle
   WaitForAck 0
   ZWAVE_MSGCNT 13026
   ZWAVE_TIME 2018-06-18 16:50:13
   homeId     ????????
   nodeIdHex  01
   nrNAck     0
   .attraggr:
   .attrminint:
   .clientArray:
     ZWave
   MatchList:
     1:ZWave    .*
   READINGS:
     2018-06-17 19:41:39   caps            Vers:5 Rev:4 ManufID:0147 ProductType:0400 ProductID:0002 SERIAL_API_GET_INIT_DATA SERIAL_API_APPL_NODE_INFORMATION APPLICATION_COMMAND_HANDLER ZW_GET_CONTROLLER_CAPABILITIES SERIAL_API_SET_TIMEOUTS SERIAL_API_GET_CAPABILITIES SERIAL_API_SOFT_RESET UNKNOWN_09 UNKNOWN_0a ZW_SET_R_F_RECEIVE_MODE ZW_SET_SLEEP_MODE ZW_SEND_NODE_INFORMATION ZW_SEND_DATA ZW_SEND_DATA_MULTI ZW_GET_VERSION ZW_SEND_DATA_ABORT ZW_R_F_POWER_LEVEL_SET ZW_SEND_DATA_META ZW_GET_RANDOM MEMORY_GET_ID MEMORY_GET_BYTE MEMORY_PUT_BYTE MEMORY_GET_BUFFER MEMORY_PUT_BUFFER FLASH_AUTO_PROG_SET UNKNOWN_28 NVM_GET_ID NVM_EXT_READ_LONG_BUFFER NVM_EXT_WRITE_LONG_BUFFER NVM_EXT_READ_LONG_BYTE NVM_EXT_WRITE_LONG_BYTE ZW_GET_NODE_PROTOCOL_INFO ZW_SET_DEFAULT ZW_REPLICATION_COMMAND_COMPLETE ZW_REPLICATION_SEND_DATA ZW_ASSIGN_RETURN_ROUTE ZW_DELETE_RETURN_ROUTE ZW_REQUEST_NODE_NEIGHBOR_UPDATE ZW_APPLICATION_UPDATE ZW_ADD_NODE_TO_NETWORK ZW_REMOVE_NODE_FROM_NETWORK ZW_CREATE_NEW_PRIMARY ZW_CONTROLLER_CHANGE ZW_SET_LEARN_MODE ZW_ASSIGN_SUC_RETURN_ROUTE ZW_REQUEST_NETWORK_UPDATE ZW_SET_SUC_NODE_ID ZW_DELETE_SUC_RETURN_ROUTE ZW_GET_SUC_NODE_ID ZW_SEND_SUC_ID ZW_EXPLORE_REQUEST_INCLUSION ZW_REQUEST_NODE_INFO ZW_REMOVE_FAILED_NODE_ID ZW_IS_FAILED_NODE ZW_REPLACE_FAILED_NODE UNKNOWN_66 UNKNOWN_67 UNKNOWN_78 GET_ROUTING_TABLE_LINE LOCK_ROUTE_RESPONSE ZW_GET_PRIORITY_ROUTE ZW_SET_PRIORITY_ROUTE UNKNOWN_98 ZW_SET_WUT_TIMEOUT ZW_WATCHDOG_ENABLE ZW_WATCHDOG_DISABLE ZW_WATCHDOG_CHECK ZW_SET_EXT_INT_LEVEL ZW_RF_POWERLEVEL_GET ZW_TYPE_LIBRARY ZW_SEND_TEST_FRAME ZW_GET_PROTOCOL_STATUS WATCHDOG_START WATCHDOG_STOP UNKNOWN_d4 UNKNOWN_ef ZME_FREQ_CHANGE ZME_BOOTLOADER_FLASH ZME_CAPABILITIES
     2018-06-17 19:41:39   ctrlCaps        MEMBER PRIMARY SUC
     2018-06-18 16:47:41   homeId          HomeId:???????? CtrlNodeIdHex:01
     2017-12-03 08:09:19   isFailedNode_3  no
     2017-12-28 14:58:24   isFailedNode_34 no
     2017-12-08 10:22:27   isFailedNode_53 no
     2017-10-25 07:20:57   isFailedNode_75 no
     2017-08-23 17:04:28   neighborList_1  ZWAVE WZ_RL_TT WZ_DS_SM FL_AB_RM AZ_KZ_SF AZ_HZ_T1 FL_DS_SM WZ_HZ_T1 BD_HZ_T1 WZ_AB_LS AZ_DB_FV KU_DB_FS
     2017-06-06 19:38:45   neighborList_2  WZ_RL_FT WZ_RL_TT WZ_DS_SM TR_SK_D1 WZ_HZ_T1 WZ_GZ_FS WZ_AB_TV
     2017-06-07 11:05:45   neighborList_23 ZWAVE WZ_RL_FS WZ_RL_FT WZ_RL_TT FL_AB_RM GA_DB_TO WZ_AB_TV
     2017-12-05 16:47:33   neighborList_3  empty
     2017-12-28 14:58:48   neighborList_34 empty
     2017-12-28 14:58:41   neighborList_35 TR_AB_WA FL_AB_RM AZ_AB_WV WZ_AB_LS SZ_DB_FS BD_DB_FV SZ_RL_FR SZ_RL_FL FL_DB_TV AZ_DB_FV GA_DB_TO
     2017-06-06 19:39:21   neighborList_4  ZWAVE WZ_RL_FS WZ_RL_FT WZ_DS_SM GA_AB_TA TR_SK_D1 FL_AB_RM AZ_HZ_T1 WZ_HZ_T1 WZ_GZ_FS WZ_DB_TT BD_HZ_T1 KL_GZ_TK WZ_AB_TV UNKNOWN_75
     2017-12-08 10:21:14   neighborList_53 empty
     2017-10-25 07:21:20   neighborList_75 WZ_RL_FT WZ_RL_TT WZ_DS_SM TR_AB_WA WZ_HZ_T1 WZ_GZ_FS
     2017-12-03 08:09:32   nodeInfo_3      node 3 is not present
     2018-03-07 17:21:17   nodeInfo_33     ProtocolVers:SDK4.5x+6.0x sleeping routing maxBaud:40kbps SpecificDev RoutingSlave BeamCap OptFunc RoleType:N/A BasicDevClass:ROUTING_SLAVE GenericDevClass:SENSOR_BINARY SpecificDevClass:01
     2017-12-08 10:23:50   nodeInfo_53     ProtocolVers:SDK4.5x+6.0x sleeping maxBaud:40kbps Controller SpecificDev BeamCap OptFunc SpeedExt:100kbps RoleType:N/A BasicDevClass:CONTROLLER GenericDevClass:GENERIC_CONTROLLER SpecificDevClass:01
     2018-03-07 17:21:54   nodeList        ZWAVE WZ_RL_FS WZ_RL_TT GA_AB_TA TR_AB_WA FL_AB_RM AZ_AB_WV AZ_KZ_SF AZ_HZ_T1 WZ_HZ_T1 WZ_GZ_FS WZ_DB_TT BD_HZ_T1 WZ_AB_LS SZ_DB_FS BD_DB_FV SZ_RL_FR SZ_RL_FL WZ_RL_FT WZ_DS_SM KU_DB_FS FL_DB_TV AZ_DB_FV UNKNOWN_53 GA_DB_TO KL_GZ_TK MO_FB_P1 WZ_AB_TV
     2018-06-17 19:41:39   random          841e282dca57048f1e4fa575f747e379321f079291855ef8ae9fd082a0ac623c
     2017-06-20 17:35:02   routeFor_25     ZWAVE FL_AB_RM at 40kbps
     2017-06-17 16:18:28   routeFor_53     N/A
     2018-06-17 19:41:39   state           Initialized
     2018-06-17 19:41:39   sucNodeId       1
     2018-05-06 19:22:44   version         Z-Wave 4.05 STATIC_CONTROLLER
   SendStack:
Attributes:
   group      MasterController
   helpSites  pepper,alliance
   homeId     ????????
   icon       scc_868
   model      ZWDongle
   neighborListPos 486,626
   room       Controller
   verbose    3
   
Wenn der Fehler auftritt, liefert 'list ZWAVE' das gleiche Ergebnis (die Zeiten sind natürlich andere).
Mittels 'htop' sieht man in beiden Fällen keine Unterschiede des fhem-Prozesses.
Das einzigste, was ich festgestellt habe, sind neue Zeitstempel im Geräterekord unter '/dev/...' (siehe Bild).
Wenn der Fehler auftritt, gibt es dort keinen Neueintrag; ansonsten wird mit jedem FHEM-Befehl ein neuer Zeitstempel sichtbar. Das scheint mir aber normal; andererseits ist eben an dieser Stelle Ruhe, wenn der Effekt auftritt.
Mein Fazit ist (zumindest momentan): es ist kein FHEM-Problem, sondern ein Debian- oder Raspi-Problem. Möglicherweise greift hier nach einigen Minuten eine Powersave-Funktion an der GPIO-Schnittstelle. Noch habe ich dazu nichts gefunden, aber ich werde in dieser Richtung weiter suchen.
Aber vielleicht habt ihr eine bessere Idee; ich greife sie gern auf.
Auf jeden Fall: Danke im Voraus.
Peter

Nachtrag, bzw. -frage:
Gibt es noch Parameter beim Definieren des Controllers:
define ZWAVE ZWDongle /dev/ttyAMA0@115200
die zu berücksichtigen wären, bzw. Einstellmöglichkeiten böten.
« Letzte Änderung: 18 Juni 2018, 19:59:16 von PNinBB »
Raspberry Pi 3B mit RaZberry2 (Debian Jessie) als FHEM-Server, FritzBox 7490;
AEOTec: Key Fob Gen5: 1x;
Danfoss: Living Connect 2.51: 3x;
Fibaro: FGRM222: 1 x, FGRM223: 1 x, FGMS001: 2x, FGK-101: 3x, FGBS-001: 8x, FGRGBWM-441: 1x;
Philio: In Wall Dual Relay PAN06-1A: 3x.

Offline krikan

  • Global Moderator
  • Hero Member
  • ****
  • Beiträge: 5905
Antw:Keine Event-Meldungen von ZWAVE-Sensoren
« Antwort #4 am: 18 Juni 2018, 20:33:13 »
Nachtrag, bzw. -frage:
Gibt es noch Parameter beim Definieren des Controllers:
define ZWAVE ZWDongle /dev/ttyAMA0@115200
die zu berücksichtigen wären, bzw. Einstellmöglichkeiten böten.
Nein. (Ansonsten wird Rudi verbessern, der jetzt auch mit Sicherheit den Nachtrag mitbekommt.)

Mich wundert immer noch, dass ich das Problem am Raspi 2 mit einem Vision-Controller nicht habe.

Offline PNinBB

  • Full Member
  • ***
  • Beiträge: 135
Antw:Keine Event-Meldungen von ZWAVE-Sensoren
« Antwort #5 am: 18 Juni 2018, 20:51:06 »
Danke für deine Bemerkung. Ich bin noch immer am Lesen und Suchen.
Deine BemerkungMich wundert immer noch, dass ich das Problem am Raspi 2 mit einem Vision-Controller nicht habeschürrt meinen Verdacht, dass es vielleicht mit dem WLAN-Interface des Raspi3 zusammen hängt, welches der Raspi 2 doch wohl nicht hat.
Ich betreibe meinen Raspi 3 am Ethernetkabel und habe das WLAN-Interface deaktiviert. Aber vielleicht ist da noch irgendwas aktiv ! Ich gebe zu: momentan nur eine Spekulation.
Peter
« Letzte Änderung: 18 Juni 2018, 20:59:33 von PNinBB »
Raspberry Pi 3B mit RaZberry2 (Debian Jessie) als FHEM-Server, FritzBox 7490;
AEOTec: Key Fob Gen5: 1x;
Danfoss: Living Connect 2.51: 3x;
Fibaro: FGRM222: 1 x, FGRM223: 1 x, FGMS001: 2x, FGK-101: 3x, FGBS-001: 8x, FGRGBWM-441: 1x;
Philio: In Wall Dual Relay PAN06-1A: 3x.

Offline laserrichi

  • Jr. Member
  • **
  • Beiträge: 71
Antw:Keine Event-Meldungen von ZWAVE-Sensoren
« Antwort #6 am: 24 Juni 2018, 22:36:59 »
ups, jetzt lese ich doch leider erst jetzt den Thread hier :-)  Ist ja auch schon auf meinen verlinkt.

Ich habe auch einen Raspi 3 über LAN angebunden und Bluetooth deaktiviert. Ein Homematik dongle steckt auf dem gpio, dafür auch UART gedreht und die Taktfrequenz fix eingestellt.


enable_uart=1
dtoverlay=pi3-disable-bt
# force_turbo=1
core_freq=250
max_usb_current=1

vieleicht sollte ich die SD Karte mal in einen PI2 stecken ? Aber warum hat davor auch schon in der konstellation funktionert...
« Letzte Änderung: 24 Juni 2018, 22:38:35 von laserrichi »

 

decade-submarginal