Secure Include funktioniert nach Razberry Firmware Update nicht mehr

[charlie71]

Hallo

ich habe folgendes Problem ich habe die Firmware meines Razberry von 5.04 -> 5.22 aktualisiert.
Alle vor dem Upgrade inkludierten Devices haben sich mit addSec ohne Probleme inkludieren lassen.
Diese funktionieren auch nach dem Upgrade problemlos.

Wenn ich jedoch ein neues Device hinzufüge klappt das Secure include nicht mehr. (Getestet mit FIBARO System FGDW002 Door Opening Sensor 2 und FIBARO System FGSD002 Smoke Sensor)
(normler Include klappt ohne Probleme)

Logfile auszug (FIBARO System FGDW002 Door Opening Sensor 2)

Code Auswählen Erweitern

2017.09.23 14:58:57.023 4: ZWDongle_Read razberry: rcvd 004a01020000 (request ZW_ADD_NODE_TO_NETWORK), sending ACK
2017.09.23 14:58:57.025 5: SW: 06
2017.09.23 14:58:57.028 5: razberry: dispatch 004a01020000
2017.09.23 14:58:57.179 4: CMD:ZW_ADD_NODE_TO_NETWORK ID:02 ARG:0000 CB:01
2017.09.23 14:58:57.322 4: razberry ZW_ADD_NODE_TO_NETWORK nodeFound
2017.09.23 14:58:57.397 4: ZWDongle_Read razberry: rcvd 004a010342140407015e8559228070565a7a728e717398318684 (request ZW_ADD_NODE_TO_NETWORK), sending ACK
2017.09.23 14:58:57.424 5: SW: 06
2017.09.23 14:58:57.426 5: razberry: dispatch 004a010342140407015e8559228070565a7a728e717398318684
2017.09.23 14:58:57.513 4: CMD:ZW_ADD_NODE_TO_NETWORK ID:03 ARG:42140407015e8559228070565a7a728e717398318684 CB:01
2017.09.23 14:58:57.628 2: autocreate: define ZWave_SENSOR_NOTIFICATION_66 ZWave e7bb4d8d 66 5e8559228070565a7a728e717398318684
2017.09.23 14:58:57.657 2: autocreate: define FileLog_ZWave_SENSOR_NOTIFICATION_66 FileLog ./log/ZWave_SENSOR_NOTIFICATION_66-%Y.log ZWave_SENSOR_NOTIFICATION_66
2017.09.23 14:59:01.190 4: ZWDongle_Read razberry: rcvd 004a01054200 (request ZW_ADD_NODE_TO_NETWORK), sending ACK
2017.09.23 14:59:01.191 5: SW: 06
2017.09.23 14:59:01.193 5: razberry: dispatch 004a01054200
2017.09.23 14:59:01.311 4: CMD:ZW_ADD_NODE_TO_NETWORK ID:05 ARG:4200 CB:01
2017.09.23 14:59:01.313 4: ZWDongle *** set razberry addNode off
2017.09.23 14:59:01.395 5: ZWDongle_Write 004a0502 ()
2017.09.23 14:59:01.396 5: SW: 0105004a0502b7
2017.09.23 14:59:01.543 2: ZWAVE Starting secure init for ZWave_SENSOR_NOTIFICATION_66
2017.09.23 14:59:01.606 5: ZWDongle_Write 001342039804002514 (e7bb4d8d)
2017.09.23 14:59:01.629 5: ACK received, removing 0105004a0502b7 from dongle sendstack
2017.09.23 14:59:01.683 4: ZWDongle_Read razberry: rcvd 004a02064200 (request ZW_ADD_NODE_TO_NETWORK), sending ACK
2017.09.23 14:59:01.684 5: SW: 06
2017.09.23 14:59:01.706 5: razberry: dispatch 004a02064200
2017.09.23 14:59:01.751 4: CMD:ZW_ADD_NODE_TO_NETWORK ID:06 ARG:4200 CB:02
2017.09.23 14:59:01.792 4: razberry ZW_ADD_NODE_TO_NETWORK done
2017.09.23 14:59:01.881 5: SW: 010a0013420398040025140a
2017.09.23 14:59:02.124 5: ACK received, WaitForAck=>2 for 010a0013420398040025140a
2017.09.23 14:59:02.125 4: ZWDongle_Read razberry: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2017.09.23 14:59:02.125 5: SW: 06
2017.09.23 14:59:02.127 5: razberry: dispatch 011301
2017.09.23 14:59:02.185 4: ZWDongle_Read razberry: rcvd 00131400000100bf7f7f7f7f0000040000000003010000 (request ZW_SEND_DATA), sending ACK
2017.09.23 14:59:02.260 5: SW: 06
2017.09.23 14:59:02.261 5: device ack reveived, removing 010a0013420398040025140a from dongle sendstack
2017.09.23 14:59:02.305 5: razberry: dispatch 00131400000100bf7f7f7f7f0000040000000003010000
2017.09.23 14:59:02.307 4: CMD:ZW_SEND_DATA ID:00 ARG:000100bf7f7f7f7f0000040000000003010000 CB:14
2017.09.23 14:59:02.307 4: razberry transmit OK for CB 14, target ZWave_SENSOR_NOTIFICATION_66
2017.09.23 14:59:02.313 4: ZWDongle_Read razberry: rcvd 0004004203980500bf00 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.09.23 14:59:02.313 5: SW: 06
2017.09.23 14:59:02.315 5: razberry: dispatch 0004004203980500bf00
2017.09.23 14:59:02.316 4: CMD:APPLICATION_COMMAND_HANDLER ID:42 ARG:03980500bf00 CB:00
2017.09.23 14:59:02.317 5: ZWDongle_Write 0013420298402515 (e7bb4d8d)
2017.09.23 14:59:02.318 5: SW: 010900134202984025154d
2017.09.23 14:59:02.358 5: ACK received, WaitForAck=>2 for 010900134202984025154d
2017.09.23 14:59:02.362 4: ZWDongle_Read razberry: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2017.09.23 14:59:02.363 5: SW: 06
2017.09.23 14:59:02.365 5: razberry: dispatch 011301
2017.09.23 14:59:02.380 4: ZWDongle_Read razberry: rcvd 00131500000200bf7f7f7f7f0000030000000003010000 (request ZW_SEND_DATA), sending ACK
2017.09.23 14:59:02.381 5: SW: 06
2017.09.23 14:59:02.382 5: device ack reveived, removing 010900134202984025154d from dongle sendstack
2017.09.23 14:59:02.383 5: razberry: dispatch 00131500000200bf7f7f7f7f0000030000000003010000
2017.09.23 14:59:02.384 4: CMD:ZW_SEND_DATA ID:00 ARG:000200bf7f7f7f7f0000030000000003010000 CB:15
2017.09.23 14:59:02.385 4: razberry transmit OK for CB 15, target ZWave_SENSOR_NOTIFICATION_66
2017.09.23 14:59:02.395 4: ZWDongle_Read razberry: rcvd 000400420a988077c5b5561490a28cbf00 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.09.23 14:59:02.395 5: SW: 06
2017.09.23 14:59:02.397 5: razberry: dispatch 000400420a988077c5b5561490a28cbf00
2017.09.23 14:59:02.398 4: CMD:APPLICATION_COMMAND_HANDLER ID:42 ARG:0a988077c5b5561490a28cbf00 CB:00
2017.09.23 14:59:02.400 5: ZWDongle_Write 0013422698816572821c2ec4792dfeec27df8f36d7e25eeb98c3e80e2da55e895077497c7f914233c9f82516 (e7bb4d8d)
2017.09.23 14:59:02.402 5: SW: 012d0013422698816572821c2ec4792dfeec27df8f36d7e25eeb98c3e80e2da55e895077497c7f914233c9f8251635
2017.09.23 14:59:02.451 5: ACK received, WaitForAck=>2 for 012d0013422698816572821c2ec4792dfeec27df8f36d7e25eeb98c3e80e2da55e895077497c7f914233c9f8251635
2017.09.23 14:59:02.456 4: ZWDongle_Read razberry: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2017.09.23 14:59:02.456 5: SW: 06
2017.09.23 14:59:02.458 5: razberry: dispatch 011301
2017.09.23 14:59:02.477 4: ZWDongle_Read razberry: rcvd 00131600000200bf7f7f7f7f0000030000000003010000 (request ZW_SEND_DATA), sending ACK
2017.09.23 14:59:02.478 5: SW: 06
2017.09.23 14:59:02.479 5: device ack reveived, removing 012d0013422698816572821c2ec4792dfeec27df8f36d7e25eeb98c3e80e2da55e895077497c7f914233c9f8251635 from dongle sendstack
2017.09.23 14:59:02.480 5: razberry: dispatch 00131600000200bf7f7f7f7f0000030000000003010000
2017.09.23 14:59:02.481 4: CMD:ZW_SEND_DATA ID:00 ARG:000200bf7f7f7f7f0000030000000003010000 CB:16
2017.09.23 14:59:02.482 4: razberry transmit OK for CB 16, target ZWave_SENSOR_NOTIFICATION_66
2017.09.23 14:59:02.501 4: ZWDongle_Read razberry: rcvd 00040042029840bf00 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.09.23 14:59:02.501 5: SW: 06
2017.09.23 14:59:02.503 5: razberry: dispatch 00040042029840bf00
2017.09.23 14:59:02.504 4: CMD:APPLICATION_COMMAND_HANDLER ID:42 ARG:029840bf00 CB:00
2017.09.23 14:59:02.507 5: ZWDongle_Write 0013420a9880e51134fff70b56f02517 (e7bb4d8d)
2017.09.23 14:59:02.508 5: SW: 01110013420a9880e51134fff70b56f02517fa
2017.09.23 14:59:02.549 5: ACK received, WaitForAck=>2 for 01110013420a9880e51134fff70b56f02517fa
2017.09.23 14:59:02.553 4: ZWDongle_Read razberry: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2017.09.23 14:59:02.553 5: SW: 06
2017.09.23 14:59:02.555 5: razberry: dispatch 011301
2017.09.23 14:59:02.572 4: ZWDongle_Read razberry: rcvd 00131700000200be7f7f7f7f0000030000000003010000 (request ZW_SEND_DATA), sending ACK
2017.09.23 14:59:02.572 5: SW: 06
2017.09.23 14:59:02.574 5: device ack reveived, removing 01110013420a9880e51134fff70b56f02517fa from dongle sendstack
2017.09.23 14:59:02.575 5: razberry: dispatch 00131700000200be7f7f7f7f0000030000000003010000
2017.09.23 14:59:02.576 4: CMD:ZW_SEND_DATA ID:00 ARG:000200be7f7f7f7f0000030000000003010000 CB:17
2017.09.23 14:59:02.576 4: razberry transmit OK for CB 17, target ZWave_SENSOR_NOTIFICATION_66
2017.09.23 14:59:02.591 4: ZWDongle_Read razberry: rcvd 0004004216988124c4ba1eb5484c7453b712e597940d9704abd90bbe00 (request APPLICATION_COMMAND_HANDLER), sending ACK
2017.09.23 14:59:02.591 5: SW: 06
2017.09.23 14:59:02.593 5: razberry: dispatch 0004004216988124c4ba1eb5484c7453b712e597940d9704abd90bbe00
2017.09.23 14:59:02.594 4: CMD:APPLICATION_COMMAND_HANDLER ID:42 ARG:16988124c4ba1eb5484c7453b712e597940d9704abd90bbe00 CB:00
2017.09.23 14:59:02.596 1: ZWave_SENSOR_NOTIFICATION_66: Error, no send_nonce to decrypt message available
2017.09.23 14:59:04.576 5: ZWDongle_Write 0013420284082518 (e7bb4d8d)
2017.09.23 14:59:04.577 5: SW: 0109001342028408251814
2017.09.23 14:59:04.581 5: ACK received, WaitForAck=>2 for 0109001342028408251814
2017.09.23 14:59:04.585 4: ZWDongle_Read razberry: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2017.09.23 14:59:04.585 5: SW: 06
2017.09.23 14:59:04.587 5: razberry: dispatch 011301
2017.09.23 14:59:04.603 4: ZWDongle_Read razberry: rcvd 00131800000100be7f7f7f7f0000030000000003010000 (request ZW_SEND_DATA), sending ACK
2017.09.23 14:59:04.604 5: SW: 06
2017.09.23 14:59:04.605 5: device ack reveived, removing 0109001342028408251814 from dongle sendstack
2017.09.23 14:59:04.606 5: razberry: dispatch 00131800000100be7f7f7f7f0000030000000003010000
2017.09.23 14:59:04.607 4: CMD:ZW_SEND_DATA ID:00 ARG:000100be7f7f7f7f0000030000000003010000 CB:18
2017.09.23 14:59:04.607 4: razberry transmit OK for CB 18, target ZWave_SENSOR_NOTIFICATION_66

2017.09.23 14:59:27.408 1: ZWave_SENSOR_NOTIFICATION_66: SECURITY disabled, networkkey was not verified and timer expired

woran könnte das liegen
lG
Charlie71

[rudolfkoenig]

Hatten beide Geraete das gleiche Problem (networkkey was no verified)?
Falls man "setreading ZWave_SENSOR_NOTIFICATION_66 SECURITY ENABLED" setzt, kann man mit dem Geraet noch kommunizieren?

[krikan]

Die Aussage vom z-wave.me-Entwickler zur 5.22: https://forum.z-wave.me/viewtopic.php?t=25253#p69890
Danach bin ich mir unsicher, ob es an FHEM oder der "problematic" Firmeware liegt.

edit: Typo

[charlie71]

Hallo

ein wenig bin ich nun weiter, der FIBARO System FGSD002 Smoke Sensor hat sich nach einem lokalem Reset inkludieren lassen.
Ich habe trotz lokalem Reset beim FIBARO System FGDW002 Door Opening Sensor 2 noch immer das selbe Problem

lG
Charlie71

[A.Harrenberg]

Hi,

das scheint mir ein Problem in (meinem) Code zu sein... 

Die Kommunikation läuft bis zum Sendes des Netzwerkschlüssel, dann wird eine NONCE-GET Nachricht vom Gerät empfangen und eine NONCE zurückgeschickt. Die Verschlüsselte Nachricht mit der Bestätigung des Schlüssels wird dann aber mit Fehlermeldung es sei keine NONCE da abgebrochen...

Verstehen/Nachvollziehen kann ich das momentan nicht, die Nonce wird beim Senden in eine Liste eingetragen (mit dem ersten Byte als Index), wenn dann was vom Gerät kommt wird dieses erste Byte noch mal mit Zurückgeschickt und damit dann die original versendete Node wieder aus der Liste ausgelesen. Wenn die Antwort zu lange dauert wird die NONCE verworfen, das sind aber soweit ich mich erinnere 10 oder 20 sekunden, in diesem Fall kommt die Antwort innerhalb weniger Millisekunden.

Leider habe ich momentan keine Zeit mich darum zu kümmern und bin dann auch noch mal für 2 Wochen weg... 

Ich müsste mir die Nachrichten noch mal genau anschauen und per Hand dekodieren, wäre nicht das erste mal das da Nachrichten mit falschen Längen ankommen die dann auch an den falschen Offsets interpretiert werden...
Sobald ich etwas Zeit finde werde ich mal versuchen das nachvollziehen.

Gruß,
Andreas.