ZWDongle abgestürzt/eingefroren?

Begonnen von A.Harrenberg, 02 Mai 2017, 21:57:40

Vorheriges Thema - Nächstes Thema

A.Harrenberg

Hallo Rudi,

ich mache zur Zeit ja recht intensive Tests mit der 4x CUL Platine und dem einzelnen CUL (und dem ZWDongle)

Unter anderem habe ich einen Z-UNO im Netzwerk der mit 9 subdevices arbeitet und auf 7 davon alle 30sec. Statusmeldungen sendet. Zuletzt hat er das aus noch nicht bekannten Gründen nur mit 40K gemacht, obwohl er eigentlich 100K unterstützt. Das Geschwindigkeitsproblem hatte ich schon mal wegbekommen nachdem ich die Routen / NeighborList upgedatet habe, aber das ist jetzt nicht das eigentlich Problem...

Jetzt ist mir anscheinend der original Dongle (UZB1) abgestürzt/eingefroren...
Im Log ist gestern abend noch ein Block mit Nachrichten um 23:46:07, dann ein nicht mehr ganz kompletter Block um 23:46:34, danach gibt es es dann einen SOF Fehler der vom System mit einer 0x15/NACK quitiert wird, danach ist Ruhe, es kommen nur noch Nachrichten von ZWCUL der momentan auf 9600 eingestellt ist und von dem auf 9600 eingestellten Transceiver auf meiner Experimentierplatform. Die anderen Transceiver sind gerade so eingestellt das sie keine Pakete melden.


2017.05.01 23:46:07.504 4: ZWDongle_Read ZWDongle_0: rcvd 000400400a600d02003105012200f0 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.05.01 23:46:07.504 5: SW: 06
2017.05.01 23:46:07.505 4: CMD:APPLICATION_COMMAND_HANDLER ID:40 ARG:0a600d02003105012200f0 CB:00
2017.05.01 23:46:07.519 4: ZWDongle_Read ZWDongle_0: rcvd 00040040063105012200f0 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.05.01 23:46:07.519 5: SW: 06
2017.05.01 23:46:07.521 4: CMD:APPLICATION_COMMAND_HANDLER ID:40 ARG:063105012200f0 CB:00
2017.05.01 23:46:07.543 4: ZWDongle_Read ZWDongle_0: rcvd 00040040063105012200f0 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.05.01 23:46:07.543 5: SW: 06
2017.05.01 23:46:07.544 4: CMD:APPLICATION_COMMAND_HANDLER ID:40 ARG:063105012200f0 CB:00
2017.05.01 23:46:07.575 4: ZWDongle_Read ZWDongle_0: rcvd 000400400a600d030031050522015e (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.05.01 23:46:07.575 5: SW: 06
2017.05.01 23:46:07.576 4: CMD:APPLICATION_COMMAND_HANDLER ID:40 ARG:0a600d030031050522015e CB:00
2017.05.01 23:46:07.601 4: ZWDongle_Read ZWDongle_0: rcvd 0004004009600d04003105030102 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.05.01 23:46:07.601 5: SW: 06
2017.05.01 23:46:07.602 4: CMD:APPLICATION_COMMAND_HANDLER ID:40 ARG:09600d04003105030102 CB:00
2017.05.01 23:46:07.635 4: ZWDongle_Read ZWDongle_0: rcvd 000400400a600d07003105012200e6 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.05.01 23:46:07.635 5: SW: 06
2017.05.01 23:46:07.636 4: CMD:APPLICATION_COMMAND_HANDLER ID:40 ARG:0a600d07003105012200e6 CB:00
2017.05.01 23:46:07.668 4: ZWDongle_Read ZWDongle_0: rcvd 000400400c600d080031050844000026e9 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.05.01 23:46:07.668 5: SW: 06
2017.05.01 23:46:07.669 4: CMD:APPLICATION_COMMAND_HANDLER ID:40 ARG:0c600d080031050844000026e9 CB:00
2017.05.01 23:46:07.709 4: ZWDongle_Read ZWDongle_0: rcvd 000400400a600d0900310501420947 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.05.01 23:46:07.709 5: SW: 06
2017.05.01 23:46:07.710 4: CMD:APPLICATION_COMMAND_HANDLER ID:40 ARG:0a600d0900310501420947 CB:00
2017.05.01 23:46:07.735 4: ZWDongle_Read ZWDongle_0: rcvd 000400400a600d0900310501420947 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.05.01 23:46:07.735 5: SW: 06
2017.05.01 23:46:07.736 4: CMD:APPLICATION_COMMAND_HANDLER ID:40 ARG:0a600d0900310501420947 CB:00
2017.05.01 23:46:34.723 4: ZWDongle_Read ZWDongle_0: rcvd 000400400a600d02003105012200f0 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.05.01 23:46:34.723 5: SW: 06
2017.05.01 23:46:34.724 4: CMD:APPLICATION_COMMAND_HANDLER ID:40 ARG:0a600d02003105012200f0 CB:00
2017.05.01 23:46:34.747 4: ZWDongle_Read ZWDongle_0: rcvd 00040040063105012200f0 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.05.01 23:46:34.747 5: SW: 06
2017.05.01 23:46:34.748 4: CMD:APPLICATION_COMMAND_HANDLER ID:40 ARG:063105012200f0 CB:00
2017.05.01 23:46:34.787 4: ZWDongle_Read ZWDongle_0: rcvd 00040040063105012200f0 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.05.01 23:46:34.787 5: SW: 06
2017.05.01 23:46:34.788 4: CMD:APPLICATION_COMMAND_HANDLER ID:40 ARG:063105012200f0 CB:00
2017.05.01 23:46:34.827 4: ZWDongle_Read ZWDongle_0: rcvd 000400400a600d030031050522015e (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.05.01 23:46:34.827 5: SW: 06
2017.05.01 23:46:34.828 4: CMD:APPLICATION_COMMAND_HANDLER ID:40 ARG:0a600d030031050522015e CB:00
2017.05.01 23:46:38.402 1: ZWDongle_0: SOF missing (got 00 instead of 01)
2017.05.01 23:46:38.402 5: ZWDongle_Read SOF Error -> sending NACK
2017.05.01 23:46:38.402 5: SW: 15
2017.05.01 23:46:38.406 1: ZWDongle_0: SOF missing (got 31 instead of 01)
2017.05.01 23:46:38.406 5: ZWDongle_Read SOF Error -> sending NACK
2017.05.01 23:46:38.406 5: SW: 15
2017.05.01 23:46:38.409 1: ZWDongle_0: SOF missing (got 03 instead of 01)
2017.05.01 23:46:38.409 5: ZWDongle_Read SOF Error -> sending NACK
2017.05.01 23:46:38.409 5: SW: 15


Ein Reset des Z-UNO hat noch keine Änderung gebracht, es blieb ruhig. Ich habe jetzt den CUL mal auf 40k umgestellt, merkwürdigerweise kommen jetzt Pakte mit 9600 von dem Z-Uno bei meiner Experimentierplatine an...

Der Dongle meldet aber weiterhin nichts.

Im list vom Dongle ist ein relativ langes "PARTIAL":


fhem> list ZWDongle_0
Internals:
   CallbackNr 0
   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         10
   MaxSendRetries 3
   NAME       ZWDongle_0
   NR         20
   PARTIAL    0100e2010f0004004009600d04003105030100e2010f0004004009600d04003105030100e2010f0004004009600d04003105030100e20110000400400a600d07003105012200e63a0110000400400a600d07003105012200e63a0110000400400a600d07003105012200e63a0110000400400a600d07003105012200e63a0112000400400c600d080031050844000026ea740112000400400c600d080031050844000026ea740112000400400c600d080031050844000026ea740112000400400c600d080031050844000026ea74
   RAWMSG     000400400a600d030031050522015e
   ReadTime   1493675205.63286
   STATE      Initialized
   SendRetries 0
   SendTime   1493673354.67624
   TYPE       ZWDongle
   WaitForAck 0
   ZWDongle_0_MSGCNT 10006
   ZWDongle_0_TIME 2017-05-01 23:46:34
   homeId     e015dfed
   nodeIdHex  01
   nrNAck     3
   Matchlist:
     1:ZWave    .*
   Readings:
     2017-05-01 15:47:58   caps            Vers:5 Rev:1 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 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 UNKNOWN_f5
     2017-05-01 15:47:59   ctrlCaps        PRIMARY
     2017-05-01 15:47:59   homeId          HomeId:e015dfed CtrlNodeIdHex:01
     2017-04-13 22:50:57   nodeList        ZWDongle_0 ZWave_SENSOR_MULTILEVEL_8 UNKNOWN_9 ZWave_SWITCH_BINARY_23 ZWave_SWITCH_MULTILEVEL_64 ZWave_SENSOR_MULTILEVEL_65 ZWave_WALL_CONTROLLER_81 ZWave_ENTRY_CONTROL_89 ZWave_SWITCH_BINARY_103 ZWave_SWITCH_BINARY_224 ZWave_ENTRY_CONTROL_232
     2017-05-01 15:47:59   random          1fbf4b931e898c4f064ab340ee3fbf39d34ec03a04c1365a250d6b137a3cf5ac
     2017-05-01 15:47:59   state           Initialized
     2017-05-01 15:47:59   sucNodeId       no
   SendStack:
Attributes:
   neighborListPos 476,165
   networkKey 0102030405060708090a0b0c0d0e0f10
   room       Maple,ZWave
   verbose    5


Ansonsten ist da für mich erst mal nichts sooo auffälliges zu erkennen.

Irgendeine Idee was da passiert ist? Irgendetwas das ich spezifisch machen sollte? Da das ja immer wieder mal gemeldet wird bietet es sich ja an da mal genauer nach zu schauen.

Zum weiter experimentieren müsste ich das System aber demnächst dann doch wieder starten...

Gruß,
Andreas.
FB 7360, Homematic und ZWave
Support for ZWave-SECURITY

krikan

#1
Am Rande:
Das "PARTIAL" quasi unendlich vollläuft und damit keine Nachrichten mehr durchkommen, gibt es bei EnOcean auch immer wieder. Gibt dazu mehrere Berichte hier im Forum und ich selbst habe das schon mehrfach beobachtet. Lösung existiert bei EnOcean afaik bisher nicht.

rudolfkoenig

PARTIAL ist wohl so aufzuteilen
0100e2
010f0004004009600d04003105030100e2
010f0004004009600d04003105030100e2
010f0004004009600d04003105030100e2
0110000400400a600d07003105012200e63a
0110000400400a600d07003105012200e63a
0110000400400a600d07003105012200e63a
0110000400400a600d07003105012200e63a
0112000400400c600d080031050844000026ea74
0112000400400c600d080031050844000026ea74
0112000400400c600d080031050844000026ea74
0112000400400c600d080031050844000026ea74

und die erste Zeile hat den Parser in ZWDongle_Read ausser Kraft gesetzt: length ist 00, und damit wartet ZWDongle_Read auf mehr Daten (?!).

FHEM hat die erste Zeile nicht bestaetigt, aber sie wurde vom Dongle nicht wiederholt, deswegen gehe ich von Muell aus.

Da die naechsten 3 Nachrichten von FHEM auch nicht bestaetigt wurden, hat vermutlich das ZWDongle die Kommunikation eingestellt. Die Nachrichten kamen vmtl. innerhalb von 1s an, deswegen konnte das in ZWDongle_Read eingebaute "ReadTime-Reset" nicht helfen.

Ich habe ZWDongle angepasst, dieser Art von Fehler sollte nicht mehr auftreten.

A.Harrenberg

Hi Rudi,

super, d.h. ich kann meinen Dongle jetzt wiederbeleben ,-)

Ich hatte gerade überlegt ob ich diese "kaputten" Nachrichten bei Testen erzeugt und mit den CUL gesendet habe, aber zu der Zeit habe ich nicht mehr gearbeitet, müssen also "reale" Nachrichten gewesen sein.

Danke für's reparieren,
Andreas.
FB 7360, Homematic und ZWave
Support for ZWave-SECURITY

A.Harrenberg

Hi,

kleiner Nachtrag, ein einfaches "get ZWDongle_0 timeouts" hat gereicht um den Stick wieder zu beleben. Update mache ich aber erst später, beim Neustart werden die Transceiver initialisiert und ich habe mir die gerade in bestimmte Modi "hingefummelt"...

Gruß,
Andreas.
FB 7360, Homematic und ZWave
Support for ZWave-SECURITY

krikan

Zitat von: A.Harrenberg am 03 Mai 2017, 07:51:23
kleiner Nachtrag, ein einfaches "get ZWDongle_0 timeouts" hat gereicht um den Stick wieder zu beleben.
Damit hast Du dann auch eventuell die Timeouts auf einen Zufallswert verstellt (https://forum.fhem.de/index.php/topic,71333.0.html)
Bei mir ist das mit allen Controllern reproduzierbar.

A.Harrenberg

Hi Christian,

ok, stimmt, da war was in dem anderen Thread. Hatte einfach einen Befehl genommen der erst mal keine Funknachrichten auslöst um auszuprobieren ob der Stack dann wieder funktioniert.

Werde dann morgen wohl doch mal FHEM updaten und neu starten, beim Init sollten ja wieder die richtigen Werte geschrieben werden.

Gruß,
Andreas.
FB 7360, Homematic und ZWave
Support for ZWave-SECURITY