Neuerdings Probleme mit ZWave

[rudolfkoenig]

addNode is unsupported by this controller

Beim Initialisieren der ZWDongle holt FHEM die "caps" ab, das Ergebnis kann man in der ZWDongle caps Reading bewundern. Danach werden nur die Befehle erlaubt, die laut caps moeglich sind.
Ich habe auch mal gesehen, dass bei fehlerhafter Initialisierung "get caps" nicht beantwortet wurde, und danach nichts mehr ging. "set ZWDongle reopen" sollte das Problem loesen, noch besser waere es, wenn ich wuesste, woran ich kaputte caps erkennen kann. Kannst du bitte deine caps zeigen?

[dennis_n]

Also bei mir steht folgendes, aber ich denke Du hast alausm gemeint.

Code Auswählen Erweitern

ZWAVE1 caps => Vers:5 Rev:0 ManufID:0147 ProductType:0400 ProductID:0001 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 UNKNOWN_92 UNKNOWN_93 UNKNOWN_98 UNKNOWN_b4 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

Gruss
Dennis

[rudolfkoenig]

Ich meinte alle die, bei denen die erwaehnte Fehlermeldung kommt.
Da in deiner caps Eintrag ZW_ADD_NODE_TO_NETWORK vorkommt, duerfte die Meldung auch nicht kommen.

[Funnel]

Hallo,

ich möchte keinen fremden Thread kapern, aber ich denke meine Fehler passen ganz gut hier hin. Auch ich habe seit einem Update (am 31.07.2015 ) Probleme mit ZWave:

1. Ich habe 6 Jalousien in einer structure, welche vor dem Update einwandfrei funktionierte. Nach dem Update hatten einige Jalusien des öfteren "No Ack" Meldungen und bewegten sich dem entsprechend nicht (Einzeln kann ich alle Jalousien problemlos bedienen). Durch ein Einfügen von einem async_delay konnte dies behoben werden.

2. In meinem Log sehe ich seit dem Update regelmässig Fehlermeldungen:

ZWDongle_ProcessSendStack: no ACK, resending message 010a001308032501FF25081b
ZWDongle_ProcessSendStack: no ACK, resending message 010a001309032501FF25091b
ZWave: No ACK from JalousieErker_M after 10s for sent:1305032501FF2505

Gruß
Thomas

[Omega]

Ich hatte gehofft, durch das Firmwareupdate meines Sticks von 5.01 auf 5.03 meine Probleme zu lösen. Hat nicht geklappt, der Stick hängt wieder. Keine Meldung im Log. Einzige Auffälligkeit ist im Log meines Fibaro Motion Sensor FGMS-001

Code Auswählen Erweitern


2015-10-17_13:54:41 ZWave_SENSOR_BINARY_4 temperature: 20.3 C
2015-10-17_13:57:11 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:57:13 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:57:15 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:57:17 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:57:19 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:57:22 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:57:24 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:57:26 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:57:28 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:57:29 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:57:29 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:57:30 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:57:32 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:57:35 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:57:59 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:57:59 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:57:59 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:57:59 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:57:59 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:57:59 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:57:59 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:57:59 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:58:00 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:58:00 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:58:00 ZWave_SENSOR_BINARY_4 luminance: 64 Lux
2015-10-17_13:58:00 ZWave_SENSOR_BINARY_4 luminance: 64 Lux


Ein List meines Dongels (noch steht er)

Code Auswählen Erweitern


Internals:
   CallbackNr 20
   Clients    :ZWave:
   DEF        /dev/serial/by-id/usb-0658_0200_12345678-9012-3456-7890-123456789012-if00@115200
   DeviceName /dev/serial/by-id/usb-0658_0200_12345678-9012-3456-7890-123456789012-if00@115200
   FD         66
   MaxSendRetries 3
   NAME       ZWDongle_0
   NR         581
   PARTIAL
   RAWMSG     00040004063105030a0040
   ReadTime   1445083079.47589
   STATE      Initialized
   SendRetries 0
   SendTime   1445079615.70117
   TYPE       ZWDongle
   WaitForAck 0
   ZWDongle_0_MSGCNT 1509
   ZWDongle_0_TIME 2015-10-17 13:58:00
   homeId     e3839725
   nrNAck     0
   Matchlist:
     1:ZWave    .*
   Readings:
     2015-10-17 11:10:49   caps            Vers:5 Rev:3 ManufID:0115 ProductType:0400 ProductID:0001 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 UNKNOWN_92 UNKNOWN_93 UNKNOWN_98 UNKNOWN_b4 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 UNKNOWN_f5
     2015-10-17 11:10:49   homeId          HomeId:e3839725 CtrlNodeId:01
     2015-10-02 10:25:23   neighborList_1  2,5,6
     2015-10-15 18:01:37   nodeList        1
     2015-10-17 11:10:49   random          87501d6a743325987ae2d54fdbc19b0134d7418365df23d589fdeececd97c4da
     2015-10-17 11:10:49   state           Initialized
     2015-09-20 14:32:46   timeouts        0106640f
     2015-10-15 17:25:05   version         Z-Wave 4.05 STATIC_CONTROLLER
   SendStack:
Attributes:
   room       ZWave,Server


Ist eigentlich der Eintrag bei den timeouts normal?

[rudolfkoenig]

Kommen die Sensor-Meldungen auch nach dem Aufhängen?
Antwortet der Dongle auf sowas wie "get ZWDongle_0 random 10" ?

Ist eigentlich der Eintrag bei den timeouts normal?

Ja, und sollte Nicht-Entiwckler nicht interessieren, ist im Modul auch nicht dokumentiert.

[Omega]

Kommen die Sensor-Meldungen auch nach dem Aufhängen?

Nein, nach dem Aufhängen kommen keine Sensor-Meldungen mehr an.

Antwortet der Dongle auf sowas wie "get ZWDongle_0 random 10" ?

Das werde ich dann mal beim nächsten Hänger mal ausprobieren

[Omega]

So, diesmal hatte ich einen Hänger, bei dem zufällig noch verbose 5 aktiv war. Ich hoffe, dass unsere Spezialisten damit etwas anfangen können.

Neben dem Dongle habe ich 2 Sensoren, die regelmäßig Werte liefern:
- PSM02-1  Slim Multi-Sensor - liefert Daten spätestens alle 30 Min.
- FGMS001 Motion Sensor - liefert Daten spätestens alle 10 Min.

Zur Überwachung habe ich ein DOIF (di_zwaveControl), das aktiv wird, wenn der FGMS001 länger als 40 Min. keinen Wert liefert.
Zugeschlagen hat der DOIF um 00:14:24, da der letzte Wert von 23:38:11 war. Der 1. Befehl im DOIF ist der

Code Auswählen Erweitern


get ZWDongle_0 random 10

der anscheinend auch beantwortet wird. Danach läuft alles wieder, die Sensoren liefern regelmäßig ihre Daten.
Aufgefallen ist mir, dass die Hänger bisher immer in die Zeit um den Tageswechsel fielen.

Auszug aus dem Log, vor und nach dem Hänger:

Code Auswählen Erweitern


2015.11.03 23:24:23 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 00040004063105030a0036
2015.11.03 23:24:23 5: SW: 06
2015.11.03 23:24:23 5: ZWDongle_0 dispatch 00040004063105030a0036
2015.11.03 23:24:23 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:04 ARG:063105030a0036
2015.11.03 23:25:24 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 00040004033003ff
2015.11.03 23:25:24 5: SW: 06
2015.11.03 23:25:24 5: ZWDongle_0 dispatch 00040004033003ff
2015.11.03 23:25:24 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:04 ARG:033003ff
2015.11.03 23:25:24 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 00040004032001ff
2015.11.03 23:25:24 5: SW: 06
2015.11.03 23:25:24 5: ZWDongle_0 dispatch 00040004032001ff
2015.11.03 23:25:24 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:04 ARG:032001ff
2015.11.03 23:25:55 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 0004000403300300
2015.11.03 23:25:55 5: SW: 06
2015.11.03 23:25:55 5: ZWDongle_0 dispatch 0004000403300300
2015.11.03 23:25:55 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:04 ARG:03300300
2015.11.03 23:25:55 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 0004000403200100
2015.11.03 23:25:55 5: SW: 06
2015.11.03 23:25:55 5: ZWDongle_0 dispatch 0004000403200100
2015.11.03 23:25:55 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:04 ARG:03200100
2015.11.03 23:27:57 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 00040004033003ff
2015.11.03 23:27:57 5: SW: 06
2015.11.03 23:27:57 5: ZWDongle_0 dispatch 00040004033003ff
2015.11.03 23:27:57 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:04 ARG:033003ff
2015.11.03 23:27:58 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 00040004032001ff
2015.11.03 23:27:58 5: SW: 06
2015.11.03 23:27:58 5: ZWDongle_0 dispatch 00040004032001ff
2015.11.03 23:27:58 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:04 ARG:032001ff
2015.11.03 23:28:41 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 00040004063105012200d3
2015.11.03 23:28:41 5: SW: 06
2015.11.03 23:28:41 5: ZWDongle_0 dispatch 00040004063105012200d3
2015.11.03 23:28:41 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:04 ARG:063105012200d3
2015.11.03 23:29:24 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 0004000403300300
2015.11.03 23:29:24 5: SW: 06
2015.11.03 23:29:24 5: ZWDongle_0 dispatch 0004000403300300
2015.11.03 23:29:24 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:04 ARG:03300300
2015.11.03 23:29:24 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 0004000403200100
2015.11.03 23:29:24 5: SW: 06
2015.11.03 23:29:24 5: ZWDongle_0 dispatch 0004000403200100
2015.11.03 23:29:24 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:04 ARG:03200100
2015.11.03 23:30:09 3: CUL_HM set Zw_Stecker_LM_3_Sw off
2015.11.03 23:34:24 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 00040004063105030a0000
2015.11.03 23:34:24 5: SW: 06
2015.11.03 23:34:24 5: ZWDongle_0 dispatch 00040004063105030a0000
2015.11.03 23:34:24 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:04 ARG:063105030a0000
2015.11.03 23:35:00 2: Backup with command: tar -cf - fhem.cfg ./FHEM/99_ESXi.cfg ./FHEM/99_Watchdogs.cfg ./log/fhem.save ./CHANGED ./config ./configDB.pm ./contrib ./demolog ./docs ./FHEM ./fhem.cfg ./fhem.cfg.demo ./fhem.pl ./log ./README_DEMO.txt ./restoreDir ./tempList.cfg ./unused ./www |gzip > ./backup/FHEM-20151103_233500.tar.gz
2015.11.03 23:38:00 1: backup done: FHEM-20151103_233500.tar.gz (83446238 Bytes)
2015.11.03 23:38:00 3: SYS_BackupRun return value:
backup done: FHEM-20151103_233500.tar.gz (83446238 Bytes)
2015.11.03 23:38:11 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 0004000303800364
2015.11.03 23:38:11 5: SW: 06
2015.11.03 23:38:11 5: ZWDongle_0 dispatch 0004000303800364
2015.11.03 23:38:11 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:03 ARG:03800364
2015.11.03 23:38:11 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 0004000303800364
2015.11.03 23:38:11 5: SW: 06
2015.11.03 23:38:11 5: ZWDongle_0 dispatch 0004000303800364
2015.11.03 23:38:11 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:03 ARG:03800364
2015.11.03 23:38:11 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 0004000303800364
2015.11.03 23:38:11 5: SW: 06
2015.11.03 23:38:11 5: ZWDongle_0 dispatch 0004000303800364
2015.11.03 23:38:11 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:03 ARG:03800364
2015.11.03 23:38:11 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 0004000303800364
2015.11.03 23:38:11 5: SW: 06
2015.11.03 23:38:11 5: ZWDongle_0 dispatch 0004000303800364
2015.11.03 23:38:11 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:03 ARG:03800364
2015.11.03 23:38:11 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 00040003043003000a
2015.11.03 23:38:11 5: SW: 06
2015.11.03 23:38:11 5: ZWDongle_0 dispatch 00040003043003000a
2015.11.03 23:38:11 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:03 ARG:043003000a
2015.11.03 23:38:11 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 00040003043003000a
2015.11.03 23:38:11 5: SW: 06
2015.11.03 23:38:11 5: ZWDongle_0 dispatch 00040003043003000a
2015.11.03 23:38:11 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:03 ARG:043003000a
2015.11.03 23:38:11 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 00040003043003000a
2015.11.03 23:38:11 5: SW: 06
2015.11.03 23:38:11 5: ZWDongle_0 dispatch 00040003043003000a
2015.11.03 23:38:11 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:03 ARG:043003000a
2015.11.03 23:38:11 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 00040003043003000a
2015.11.03 23:38:11 5: SW: 06
2015.11.03 23:38:11 5: ZWDongle_0 dispatch 00040003043003000a
2015.11.03 23:38:11 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:03 ARG:043003000a
2015.11.03 23:38:11 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 00040003053105030101
2015.11.03 23:38:11 5: SW: 06
2015.11.03 23:38:11 5: ZWDongle_0 dispatch 00040003053105030101
2015.11.03 23:38:11 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:03 ARG:053105030101
2015.11.03 23:38:11 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 00040003053105030101
2015.11.03 23:38:11 5: SW: 06
2015.11.03 23:38:11 5: ZWDongle_0 dispatch 00040003053105030101
2015.11.03 23:38:11 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:03 ARG:053105030101
2015.11.03 23:38:11 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 00040003053105030101
2015.11.03 23:38:11 5: SW: 06
2015.11.03 23:38:11 5: ZWDongle_0 dispatch 00040003053105030101
2015.11.03 23:38:11 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:03 ARG:053105030101
2015.11.03 23:38:11 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 00040003053105030101
2015.11.03 23:38:11 5: SW: 06
2015.11.03 23:38:11 5: ZWDongle_0 dispatch 00040003053105030101
2015.11.03 23:38:11 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:03 ARG:053105030101
2015.11.04 00:14:24 4: ZWDongle get ZWDongle_0 random 10
2015.11.04 00:14:24 5: ZWDongle_Write 00 1c0a
2015.11.04 00:14:24 5: SW: 0104001c0aed
2015.11.04 00:14:24 4: ZWDongle_ReadAnswer arg:random regexp:^011c
2015.11.04 00:14:24 5: ACK received, removing 0104001c0aed from dongle sendstack
2015.11.04 00:14:24 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 011c010ad1feae6d32566e35e469
2015.11.04 00:14:24 5: SW: 06
2015.11.04 00:14:24 4: ZWDongle_ReadAnswer for random: 011c010ad1feae6d32566e35e469
2015.11.04 00:14:24 2: di_zwaveControl: get ZWDongle_0 random 10: ZWDongle_0 random => d1feae6d32566e35e469
2015.11.04 00:14:24 2: di_zwaveControl: set pushC6503 message ZWDongle_0 muss neu gestartet werden: OK! Message sent to C6503 (id: 1366)

ZWDongle_0 muss neu gestartet werden

2015.11.04 00:14:24 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 00040004063105012200d2
2015.11.04 00:14:24 5: SW: 06
2015.11.04 00:14:24 5: ZWDongle_0 dispatch 00040004063105012200d2
2015.11.04 00:14:24 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:04 ARG:063105012200d2
2015.11.04 00:14:24 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 00040004063105030a0000
2015.11.04 00:14:24 5: SW: 06
2015.11.04 00:14:24 5: ZWDongle_0 dispatch 00040004063105030a0000
2015.11.04 00:14:24 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:04 ARG:063105030a0000
2015.11.04 00:14:24 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 00040004063105012200d1
2015.11.04 00:14:24 5: SW: 06
2015.11.04 00:14:24 5: ZWDongle_0 dispatch 00040004063105012200d1
2015.11.04 00:14:24 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:04 ARG:063105012200d1
2015.11.04 00:14:28 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 00040004063105030a0000
2015.11.04 00:14:28 5: SW: 06
2015.11.04 00:14:28 5: ZWDongle_0 dispatch 00040004063105030a0000
2015.11.04 00:14:28 4: ZWDongle_0 CMD:APPLICATION_COMMAND_HANDLER ID:04 ARG:063105030a0000
2015.11.04 00:18:47 4: ZWDongle_Read ZWDongle_0: sending ACK, processing 00040004063105012200d0
2015.11.04 00:18:47 5: SW: 06



Auszug aus dem Log vom PSM02-1

Code Auswählen Erweitern


2015-11-03_23:06:35 ZWave_SENSOR_BINARY_3 luminance: 1 %
2015-11-03_23:06:35 ZWave_SENSOR_BINARY_3 temperature: 6.0 C
2015-11-03_23:38:11 ZWave_SENSOR_BINARY_3 battery: 100 %
2015-11-03_23:38:11 ZWave_SENSOR_BINARY_3 battery: 100 %
2015-11-03_23:38:11 ZWave_SENSOR_BINARY_3 battery: 100 %
2015-11-03_23:38:11 ZWave_SENSOR_BINARY_3 battery: 100 %
2015-11-03_23:38:11 ZWave_SENSOR_BINARY_3 doorWindow: 00
2015-11-03_23:38:11 ZWave_SENSOR_BINARY_3 doorWindow: 00
2015-11-03_23:38:11 ZWave_SENSOR_BINARY_3 doorWindow: 00
2015-11-03_23:38:11 ZWave_SENSOR_BINARY_3 doorWindow: 00
2015-11-03_23:38:11 ZWave_SENSOR_BINARY_3 luminance: 1 %
2015-11-03_23:38:11 ZWave_SENSOR_BINARY_3 luminance: 1 %
2015-11-03_23:38:11 ZWave_SENSOR_BINARY_3 luminance: 1 %
2015-11-03_23:38:11 ZWave_SENSOR_BINARY_3 luminance: 1 %
2015-11-04_00:32:31 ZWave_SENSOR_BINARY_3 luminance: 1 %
2015-11-04_00:32:31 ZWave_SENSOR_BINARY_3 temperature: 5.5 C
2015-11-04_00:32:31 ZWave_SENSOR_BINARY_3 wakeup: notification
2015-11-04_01:01:08 ZWave_SENSOR_BINARY_3 luminance: 1 %
2015-11-04_01:01:08 ZWave_SENSOR_BINARY_3 temperature: 5.5 C
2015-11-04_01:29:44 ZWave_SENSOR_BINARY_3 luminance: 1 %
2015-11-04_01:29:44 ZWave_SENSOR_BINARY_3 temperature: 5.0 C
2015-11-04_01:58:19 ZWave_SENSOR_BINARY_3 luminance: 1 %
2015-11-04_01:58:19 ZWave_SENSOR_BINARY_3 temperature: 5.0 C
2015-11-04_02:26:54 ZWave_SENSOR_BINARY_3 luminance: 1 %



Auszug aus dem Log vom FGMS001

Code Auswählen Erweitern


2015-11-03_23:22:04 ZWave_SENSOR_BINARY_4 basicSet: 00
2015-11-03_23:24:23 ZWave_SENSOR_BINARY_4 luminance: 54 Lux
2015-11-03_23:25:24 ZWave_SENSOR_BINARY_4 open
2015-11-03_23:25:24 ZWave_SENSOR_BINARY_4 reportedState: open
2015-11-03_23:25:24 ZWave_SENSOR_BINARY_4 basicSet: ff
2015-11-03_23:25:55 ZWave_SENSOR_BINARY_4 closed
2015-11-03_23:25:55 ZWave_SENSOR_BINARY_4 reportedState: closed
2015-11-03_23:25:55 ZWave_SENSOR_BINARY_4 basicSet: 00
2015-11-03_23:27:57 ZWave_SENSOR_BINARY_4 open
2015-11-03_23:27:57 ZWave_SENSOR_BINARY_4 reportedState: open
2015-11-03_23:27:58 ZWave_SENSOR_BINARY_4 basicSet: ff
2015-11-03_23:28:41 ZWave_SENSOR_BINARY_4 temperature: 21.1 C
2015-11-03_23:29:24 ZWave_SENSOR_BINARY_4 closed
2015-11-03_23:29:24 ZWave_SENSOR_BINARY_4 reportedState: closed
2015-11-03_23:29:24 ZWave_SENSOR_BINARY_4 basicSet: 00
2015-11-03_23:34:24 ZWave_SENSOR_BINARY_4 luminance: 0 Lux
2015-11-04_00:14:24 ZWave_SENSOR_BINARY_4 temperature: 21.0 C
2015-11-04_00:14:24 ZWave_SENSOR_BINARY_4 luminance: 0 Lux
2015-11-04_00:14:24 ZWave_SENSOR_BINARY_4 temperature: 20.9 C
2015-11-04_00:14:28 ZWave_SENSOR_BINARY_4 luminance: 0 Lux
2015-11-04_00:18:47 ZWave_SENSOR_BINARY_4 temperature: 20.8 C
2015-11-04_00:24:30 ZWave_SENSOR_BINARY_4 luminance: 0 Lux
2015-11-04_00:28:48 ZWave_SENSOR_BINARY_4 temperature: 20.7 C
2015-11-04_00:34:31 ZWave_SENSOR_BINARY_4 luminance: 0 Lux
2015-11-04_00:38:49 ZWave_SENSOR_BINARY_4 temperature: 20.7 C


[Chlorex]

Kann das gleiche Problem seit einiger Zeit ebenfalls bestätigen.
System:
-Raspberry Pi B+
-Vision Z-Wave USB Stick ZU 1401 EU (VIS_ZU1401) (version Z-Wave 3.41 STATIC_CONTROLLER)
-Fhem: stets aktuell $Id: fhem.pl 9755 2015-11-02 19:34:14Z rudolfkoenig $ (mal 2-3 Tage Verzögerung)
-FIBARO System FGSS101 Smoke Sensor
-FIBARO System FGWPE Wall Plug
-FIBARO System FGMS001 Motion Sensor
-2x Popp / Duwi ZW ZS 3500 Plugin Switch
Dazu ein HomeMatic-USB-Stick und 4x Thermostate HM-CC-RT-DN

Seit einiger Zeit bekommen die Z-Wave-Produkte immer wieder NO_ACK
Beispiel vom FIBARO System FGWPE Wall Plug

Code Auswählen Erweitern

2015.11.08 14:58:34.012 5: Cmd: >set Zwischenstecker_1 on<
2015.11.08 14:58:34.019 2: ZWave set Zwischenstecker_1 on
2015.11.08 14:58:34.021 5: ZWDongle_Write 00 1309032501FF2509
2015.11.08 14:58:34.024 5: SW: 010a001309032501FF25091b
2015.11.08 14:58:34.028 5: Triggering Zwischenstecker_1 (1 changes)
2015.11.08 14:58:34.029 5: Notify loop for Zwischenstecker_1 on
2015.11.08 14:58:34.080 4: name: /fhem?cmd.Zwischenstecker_1=set%20Zwischenstecker_1%20on&room=Licht&XHR=1 / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2015.11.08 14:58:34.083 5: ACK received, removing 010a001309032501FF25091b from dongle sendstack
2015.11.08 14:58:34.084 4: ZWDongle_Read ZWave_USB_Stick: sending ACK, processing 011301
2015.11.08 14:58:34.085 5: SW: 06
2015.11.08 14:58:34.088 5: ZWave_USB_Stick dispatch 011301
2015.11.08 14:58:34.095 4: ZWDongle_Read ZWave_USB_Stick: sending ACK, processing 00130900
2015.11.08 14:58:34.096 5: SW: 06
2015.11.08 14:58:34.098 5: ZWave_USB_Stick dispatch 00130900
2015.11.08 14:58:34.100 4: ZWave_USB_Stick CMD:ZW_SEND_DATA ID:00 ARG:
2015.11.08 14:58:34.101 4: ZWave_USB_Stick transmit OK for 09
2015.11.08 14:58:43.185 4: FHEMWEB:192.168.2.101:55573 POST /fhem?cmd.Zwischenstecker_1=set%20Zwischenstecker_1%20off&room=Licht&XHR=1; BUFLEN:0
2015.11.08 14:58:43.188 5: Cmd: >set Zwischenstecker_1 off<
2015.11.08 14:58:43.195 2: ZWave set Zwischenstecker_1 off
2015.11.08 14:58:43.197 5: ZWDongle_Write 00 1309032501002509
2015.11.08 14:58:43.199 5: SW: 010a001309032501002509e4
2015.11.08 14:58:43.203 5: Triggering Zwischenstecker_1 (1 changes)
2015.11.08 14:58:43.205 5: Notify loop for Zwischenstecker_1 off
2015.11.08 14:58:43.268 4: name: /fhem?cmd.Zwischenstecker_1=set%20Zwischenstecker_1%20off&room=Licht&XHR=1 / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2015.11.08 14:58:43.271 5: ACK received, removing 010a001309032501002509e4 from dongle sendstack
2015.11.08 14:58:43.273 4: ZWDongle_Read ZWave_USB_Stick: sending ACK, processing 011301
2015.11.08 14:58:43.273 5: SW: 06
2015.11.08 14:58:43.276 5: ZWave_USB_Stick dispatch 011301
2015.11.08 14:58:47.597 4: ZWDongle_Read ZWave_USB_Stick: sending ACK, processing 00130901
2015.11.08 14:58:47.598 5: SW: 06
2015.11.08 14:58:47.601 5: ZWave_USB_Stick dispatch 00130901
2015.11.08 14:58:47.603 4: ZWave_USB_Stick CMD:ZW_SEND_DATA ID:01 ARG:
2015.11.08 14:58:47.604 2: ZWave_USB_Stick transmit NO_ACK for 09
2015.11.08 14:58:47.609 5: Triggering Zwischenstecker_1 (2 changes)
2015.11.08 14:58:47.610 5: Notify loop for Zwischenstecker_1 TRANSMIT_NO_ACK


Der FIBARO System FGMS001 Motion Sensor sendet oder der Vision Z-Wave USB Stick empfängt sehr unzuverlässig ein open/closed bei Bewegungen oder stellt einfach für x Stunden zufällig auch die Temperatur-Meldungen ein, leider nur als einfaches Log

Code Auswählen Erweitern

2015-11-04_08:11:16 FlurMotion temperature: 21.3 C
2015-11-04_08:12:03 FlurMotion closed
2015-11-04_08:12:03 FlurMotion reportedState: closed
2015-11-04_08:12:03 FlurMotion basicSet: 00
2015-11-04_08:12:06 FlurMotion open
2015-11-04_08:12:06 FlurMotion reportedState: open
2015-11-04_08:12:06 FlurMotion basicSet: ff
2015-11-04_08:15:04 FlurMotion closed
2015-11-04_08:15:04 FlurMotion reportedState: closed
2015-11-04_08:15:04 FlurMotion basicSet: 00
2015-11-04_08:16:08 FlurMotion open
2015-11-04_08:16:08 FlurMotion reportedState: open
2015-11-04_08:16:08 FlurMotion basicSet: ff
2015-11-04_08:18:31 FlurMotion closed
2015-11-04_08:18:31 FlurMotion reportedState: closed
2015-11-04_08:18:31 FlurMotion basicSet: 00
..
..
2015-11-04_16:13:20 FlurMotion temperature: 21.1 C
2015-11-04_17:19:13 FlurMotion closed
2015-11-04_17:19:13 FlurMotion reportedState: closed
2015-11-04_17:19:13 FlurMotion basicSet: 00
Angeblich hat er also von 8:16 - 17:19 durchgehend bewegung registriert, bzw "closed" vergessen... komisch ,denn genau in dem Zeitraum war niemand zuhause.. erst als wieder tatsächliche Bewegung wurde "close" gemeldet, dabei soll in der Zeit alle 20 Minuten die Temp melden.
Dieses Verhalten ist häufiger in den letzten Wochen aufgetreten und scheint hier im Post die richtige Heimat zu haben.

NO_ACK passiert auf allen Z-Wave Aktoren, bei dem Homematic habe ich absolut keine Probleme.

Vielleicht/hoffentlich helfen die Logs weiter

[krikan]

Bitte http://forum.fhem.de/index.php/topic,43735.msg356420.html#msg356420 beachten.
Und mit geänderten Modulen noch mal testen.

[Chlorex]

Okay, werde ich morgen testen... habe an dem Post, mit Störungen und Pause knapp 2 Stunden geschrieben daher hat es sich zeitlich überschnitten...

[krikan]

Bin mir nicht sicher, ob die neue Version bei Deinem Problem hilft. Aber jetzt alte Versionen zu analysieren ist wenig zielführend.
Wenn es weiterhin Probleme gibt oder Erfolg vorliegt, bitte melden.
Gruß, Christian

[Chlorex]

Heute Vormittag habe ich ein Update durchgeführt und die 00_ZWDongle.pm & 10_ZWave.pm sind mit aktuellen Zeitstempeln von gestern.
Nach dem Umschalten eines Popp / Duwi ZW ZS 3500 Plugin Switch

Code Auswählen Erweitern

2015.11.09 12:51:37.193 2: ZWave set Zwischenstecker_2 on
2015.11.09 12:51:37.197 5: ZWDongle_Write 00 130b032501FF250b
2015.11.09 12:51:37.199 5: SW: 010a00130b032501FF250b1b
2015.11.09 12:51:37.284 5: ACK received, WaitForAck=>2 for 010a00130b032501FF250b1b
2015.11.09 12:51:37.285 4: ZWDongle_Read ZWave_USB_Stick: sending ACK, processing 011301
2015.11.09 12:51:37.286 5: SW: 06
2015.11.09 12:51:37.289 5: ZWave_USB_Stick dispatch 011301
2015.11.09 12:51:39.305 4: no response from device, removing 010a00130b032501FF250b1b from dongle sendstack
2015.11.09 12:51:41.598 4: ZWDongle_Read ZWave_USB_Stick: sending ACK, processing 00130b01
2015.11.09 12:51:41.599 5: SW: 06
2015.11.09 12:51:41.605 5: ZWave_USB_Stick dispatch 00130b01
2015.11.09 12:51:41.608 4: ZWave_USB_Stick CMD:ZW_SEND_DATA ID:01 ARG:
2015.11.09 12:51:41.609 2: ZWave_USB_Stick transmit NO_ACK for 0b
2015.11.09 12:51:47.206 2: ZWave: No ACK from Zwischenstecker_2 after 10s for sent:130b032501FF250b
2015.11.09 12:54:04.342 4: ZWDongle_Read ZWave_USB_Stick: sending ACK, processing 00040008063105012200e4
2015.11.09 12:54:04.343 5: SW: 06
2015.11.09 12:54:04.348 5: ZWave_USB_Stick dispatch 00040008063105012200e4
2015.11.09 12:54:04.351 4: ZWave_USB_Stick CMD:APPLICATION_COMMAND_HANDLER ID:08 ARG:063105012200e4
2015.11.09 12:56:18.334 4: ZWDongle_Read ZWave_USB_Stick: sending ACK, processing 00040010028407
2015.11.09 12:56:18.335 5: SW: 06
2015.11.09 12:56:18.338 5: ZWave_USB_Stick dispatch 00040010028407
2015.11.09 12:56:18.340 4: ZWave_USB_Stick CMD:APPLICATION_COMMAND_HANDLER ID:10 ARG:028407
2015.11.09 12:56:20.409 5: ZWDongle_Write 00 13100284082510
2015.11.09 12:56:20.411 5: SW: 010900131002840825104e
2015.11.09 12:56:20.417 5: ACK received, WaitForAck=>2 for 010900131002840825104e
2015.11.09 12:56:20.418 4: ZWDongle_Read ZWave_USB_Stick: sending ACK, processing 011301
2015.11.09 12:56:20.419 5: SW: 06
2015.11.09 12:56:20.421 5: ZWave_USB_Stick dispatch 011301
2015.11.09 12:56:20.431 4: ZWDongle_Read ZWave_USB_Stick: sending ACK, processing 00131000
2015.11.09 12:56:20.432 5: SW: 06
2015.11.09 12:56:20.434 5: device ack reveived, removing 010900131002840825104e from dongle sendstack
2015.11.09 12:56:20.436 5: ZWave_USB_Stick dispatch 00131000
2015.11.09 12:56:20.437 4: ZWave_USB_Stick CMD:ZW_SEND_DATA ID:00 ARG:
2015.11.09 12:56:20.438 4: ZWave_USB_Stick transmit OK for 10
2015.11.09 12:56:28.374 4: ZWDongle_Read ZWave_USB_Stick: sending ACK, processing 00040010063105012200e3
2015.11.09 12:56:28.375 5: SW: 06
2015.11.09 12:56:28.379 5: ZWave_USB_Stick dispatch 00040010063105012200e3
2015.11.09 12:56:28.382 4: ZWave_USB_Stick CMD:APPLICATION_COMMAND_HANDLER ID:10 ARG:063105012200e3
Ansonsten scheint es das gleich wie in dem Post zu sein: http://forum.fhem.de/index.php/topic,43413.0.html

[rudolfkoenig]

Ich sehe hier ein set um 12:51:37, was vom Geraet nicht bestaetigt wurde (00130b01). Da parallel keine weitere Kommunikation stattfand, liegt das nicht an den Modifkiationen von gestern, ich vermute, Zwischenstecker_2 ist entweder nicht im Betrieb, nicht erreichbar oder nicht inkludiert. Danach kommen irgendwelche Nachrichten rein, ohne Probleme.

[Chlorex]

...ich vermute, Zwischenstecker_2 ist entweder nicht im Betrieb, nicht erreichbar oder nicht inkludiert....

Das betrifft ALLE Aktoren(3 Zwischenstecker), schalten tun sie alle trotzdem.
Innerhalb der nächsten 10 Sekunden reagieren die aber auf keine weiteren set- oder get-Befehle und/oder es kommt zum Timeout.

Sie liefen ja Monatelang (1x Fibaro seit 2'2015 + 2x POPP/DÜWI seit 5'2015)problemlos, bloß seit einigen Wochen ständig "NO_ACK".. was Schaltungen mit "toggle" ziemlich träge oder unmöglich macht, sowie Notify auch nicht zuverlässig funktioniert, weil der State ja schließlich bei on und off auf NO_ACK steht.