ZWave blockiert FHEM-Start

[Omega]

Momentan häufen sich meine FHEM Baustellen...
Eine davon betrifft ZWave.
Seit einigen Tagen bleibt der Start von FHEM – meistens – hängen. Als letzte Aktivität finde ich dann im Log immer Hinweise auf ZWave als Spielverderber. Mein derzeitiger funktionierender Notbehelf: Ausstecken des Dongles, kurz warten und dann ZWave-Dongle (AEOTEC Z-Stick GEN5) wieder anstecken. Dann wird der Start von FHEM wieder fortgesetzt. Um 10:32:43 ist der Start hängengeblieben, um 10:36:29 habe ich den Stick aus- und wieder eingesteckt.
Auszug aus dem Log:

Code Auswählen Erweitern


...
2016.09.20 10:32:42 3: CUL_HM set RM_Arbeitszimmer statusRequest
2016.09.20 10:32:42 3: ZWave got config for greenwave/powernode1.xml from ./FHEM/lib/fhem_zwave_deviceconfig.xml.gz
2016.09.20 10:32:42 3: ZWave set ZW_SWITCH_9 off
2016.09.20 10:32:43 3: Opening ZWDongle_1 device /dev/serial/by-id/usb-0658_0200-if00
2016.09.20 10:32:43 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a001309032501002501ec
2016.09.20 10:36:29 1: /dev/serial/by-id/usb-0658_0200-if00 disconnected, waiting to reappear (ZWDongle_1)
2016.09.20 10:36:29 1: ZWDongle_ReadAnswer: no data read
2016.09.20 10:36:29 3: ZWDongle_1 device opened
2016.09.20 10:37:09 3: CUL_HM set RM_Dachboden statusRequest
2016.09.20 10:37:09 2: ZWave: No ACK from ZW_SWITCH_9 after 5s for sentset:1309032501002501
2016.09.20 10:37:16 3: CUL_HM set RM_Flur_Keller statusRequest

und ein List vom Stick:

Code Auswählen Erweitern


Internals:
   CallbackNr 0
   Clients    :ZWave:
   DEF        /dev/serial/by-id/usb-0658_0200-if00
   DevIoJustClosed 1
   DeviceName /dev/serial/by-id/usb-0658_0200-if00
   GotCAN     1
   MaxSendRetries 4
   NAME       ZWDongle_1
   NR         830
   PARTIAL
   RAWMSG     00040003063105012200c3
   ReadTime   1474360581.78296
   STATE      Initialized
   SendRetries 0
   SendTime   1474360363.29418
   TYPE       ZWDongle
   WaitForAck 0
   ZWDongle_1_MSGCNT 23
   ZWDongle_1_TIME 2016-09-20 10:32:41
   homeId     cb799c01
   nodeIdHex  01
   nrNAck     0
   Matchlist:
     1:ZWave    .*
   Readings:
     2016-09-20 10:31:57   caps            Vers:1 Rev:0 ManufID:0086 ProductType:0001 ProductID:005a 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 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 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 GET_ROUTING_TABLE_LINE LOCK_ROUTE_RESPONSE UNKNOWN_92 UNKNOWN_93 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_ee UNKNOWN_ef
     2016-09-20 10:31:57   ctrlCaps        PRIMARY
     2016-09-20 10:31:57   homeId          HomeId:cb799c01 CtrlNodeIdHex:01
     2016-08-23 09:43:59   neighborList_1  ZW_Schuppen ZW_MS_Arbeitszimmer ZW_SWITCH_5 ZW_SWITCH_6 ZW_Bodentreppe ZW_SWITCH_9 ZW_MS_EG_Flur ZW_MS_Werkzeugkeller ZW_SWITCH_13
     2016-08-20 02:02:07   nodeList        ZWDongle_1 ZWave_SWITCH_BINARY_2 ZWave_Schuppentuer ZWave_MS_Arbeitszimmer ZWave_SWITCH_BINARY_5 ZWave_SWITCH_BINARY_6 ZWave_Garagentor ZWave_Bodentreppe ZWave_SWITCH_BINARY_9 ZWave_SENSOR_BINARY_10 ZWave_MS_Werkzeugkeller
     2016-09-20 10:31:57   random          e8c64e03feb074acc93f3ddf8f5a4d74c5d9eca259cb311940ab2636886d8f75
     2016-09-20 10:36:29   state           Initialized
     2016-09-20 10:31:57   sucNodeId       no
   SendStack:
     01030007fb
     01030005f9
     01030020dc
     01030056aa
     0104001c20c7
     01050006640f97
     0107000301020100f9
Attributes:
   comment    AEOTEC Z-Stick GEN5
   neighborListPos 267.6875,141
   room       ZWave


FHEM ist aktuell (der letzte Start(versuch) war aufgrund eines Updates).
Ist der Fehler bekannt? Wie eingrenzen?

LG
Holger

[rudolfkoenig]

Ist der Fehler bekannt?

Mir nicht.

Wie eingrenzen?

attr global verbose 5

[Omega]

Ich bin etwas weiter. in StartINIT hatte ich folgende Definition:

Code Auswählen Erweitern


global:INITIALIZED
sleep 20;
        set Schalter_Zirkulationspumpe_Betrieb off;
set ZW_SWITCH_9 off;
set ZWDongle_1 reopen

Nachdem ich die beiden ZWave-Anweisungen gelöscht hatte, lief ein Neustart erfolgreich durch.
Der reopen hing noch mit meinem alten Stick (ZME_UZB1) zusammen. Der brauchte das manchmal. Inzwischen habe ich ja den AEOTEC Z-Stick GEN5. Mal beobachten.

LG
Holger