AEON Labs Z-Stick Series 2 factory reset - möglich?

wkarl · 22 Januar 2015, 16:05:41

Hallo,

habe zu dieser Frage hier und bei den Herren Google nichts gefunden. Ist dies möglich? Wenn nicht, vielleicht geplant?

Danke und ciao
walter

rudolfkoenig · 22 Januar 2015, 19:55:14

Ich finde dazu in der ZWave Serial-API keine Funktion.
Und ich plane es nicht, die Firmware fuer dieses Geraet zu aendern.

wkarl · 23 Januar 2015, 07:40:46

Hallo Rudolf,

ZitatUnd ich plane es nicht, die Firmware fuer dieses Geraet zu aendern.

Habe ich auch nicht gefordert

. Die Funktion habe ich in der SW auf meinem Mac gefunden und folgerte daraus das diese entsprechend auch zur Verfügung steht.

ciao walter

wkarl · 08 Februar 2015, 16:53:34

Hallo,

heute habe ich einen Ansatz gefunden den Z-Wave Stick zu reseten ohne jedesmal ihn in den Windows-Rechner stecken zu müssen.

Hier im groben die Vorgehensweise.

openzwave installieren
nodejs und npm installieren
node-openzwave über npm installieren
über ein kleines Programm basierend auf den Informationen von hier https://github.com/jperkin/node-openzwave den Stick rücksetzen

Das Programm benötigt noch etwas Feinschliff, zumindest funktioniert es schon mal.

ciao walter

wkarl · 09 Februar 2015, 12:03:42

Hallo,

mittlerweilen habe ich es geschafft openzwave-contro-panel zu compilieren:

mkdir svn
cd svn
svn checkout http://open-zwave.googlecode.com/svn/trunk/ open-zwave
svn checkout http://openzwave-control-panel.googlecode.com/svn/trunk/ openzwave-control-panel
sudo yum install libudev-devel
wget ftp://ftp.gnu.org/gnu/libmicrohttpd/libmicrohttpd-0.9.19.tar.gz
tar zxvf libmicrohttpd-0.9.19.tar.gz
mv libmicrohttpd-0.9.19 libmicrohttpd
cd libmicrohttpd
./configure -without-gnutls (mit gnutls lässt sich openzwave-control-panel nicht compilieren)
make && make install
cd ../open-zwave
make
cd ../openzwave-control-panel
folgende Zeilen unkommentieren und evtl LIBZWAVE anpassen (bei mir liegt libopenzwave.a direkt im Verzeichnis von open-zwave)
# for Linux uncomment out next three lines
LIBZWAVE := $(wildcard $(OPENZWAVE)/*.a)
LIBUSB := -ludev
LIBS := $(LIBZWAVE) $(GNUTLS) $(LIBMICROHTTPD) -pthread $(LIBUSB)
make
ln -s ../open-zwave/config/ .
./ozwcp -d -p <port>

Sinn für mich ist es ein Vergleichsystem zu fhem zu haben und die Möglichkeit des fabric resets. Es gibt auch einen backup-button, muss ich nur noch untersuchen wie ein restore gehen soll.

ciao walter

rudolfkoenig · 09 Februar 2015, 17:54:26

Wenn du weisst, was zu tun ist, dann koennen wir das gerne fuer FHEM uebernehmen.

wkarl · 12 Februar 2015, 08:00:25

Hallo Rudolf,

da ich technisch dies nicht bewerten kann, bitte ich Dich mal einen Blick auf das folgende log zu werfen. Es ist von openzwave-control-panel.

Code Auswählen

2015-02-12 07:36:36.664 Detail, Node003, Changes to this value are not verified
2015-02-12 07:36:36.664 Info, Notification: Value Changed Home 0x0184e88b Node 3 Genre user Class SENSOR MULTILEVEL Instance 1 Index 4 Type decimal
2015-02-12 07:36:38.291 Detail, Node005, Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x05, 0x06, 0x31, 0x05, 0x04, 0x22, 0x00, 0x06, 0xe0
2015-02-12 07:36:38.292 Detail, 
2015-02-12 07:36:38.292 Info, Node005, Received SensorMultiLevel report from node 5, instance 1, Power: value=0.6W
2015-02-12 07:36:38.292 Detail, Node005, Refreshed Value: old value=0.5, new value=0.6, type=string
2015-02-12 07:36:38.292 Detail, Node005, Changes to this value are not verified
2015-02-12 07:36:38.293 Info, Notification: Value Changed Home 0x0184e88b Node 5 Genre user Class SENSOR MULTILEVEL Instance 1 Index 4 Type decimal
2015-02-12 07:36:41.216 Info, Soft-resetting the Z-Wave controller chip
2015-02-12 07:36:41.216 Detail, contrlr, Queuing (Command) Soft-resetting the Z-Wave controller chip: 0x01, 0x03, 0x00, 0x08, 0xf4
2015-02-12 07:36:41.217 Detail, 
2015-02-12 07:36:41.217 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x00) - Soft-resetting the Z-Wave controller chip: 0x01, 0x03, 0x00, 0x08, 0xf4
2015-02-12 07:36:41.219 Detail, contrlr, Removing current message
2015-02-12 07:36:42.380 Detail, Node005, Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x05, 0x06, 0x31, 0x05, 0x04, 0x22, 0x00, 0x05, 0xe3
2015-02-12 07:36:42.380 Detail, 
2015-02-12 07:36:42.381 Info, Node005, Received SensorMultiLevel report from node 5, instance 1, Power: value=0.5W
2015-02-12 07:36:42.381 Detail, Node005, Refreshed Value: old value=0.5, new value=0.5, type=string
2015-02-12 07:36:42.381 Detail, Node005, Changes to this value are not verified

Kannst Du daraus ableiten wie Abläufe in fhem umzusetzen sind?

Danke und ciao
walter

rudolfkoenig · 13 Februar 2015, 08:13:42

Code Auswählen


Received: 010c00 04 000506 3105 04 22 0006 e0
Sending:  010300 08 f4
Received: 010c00 04 000506 3105 04 22 0005 e3

Sending:  010300 08 f4
  01: SEND
  03: length
  00: ??
  08: SERIAL_API_SOFT_RESET
  f4 checksum

Received: 010c00 04 000506 3105 04 22 0005 e3
  01: SEND
  0c: length
  00: ??
  04: APPLICATION_COMMAND_HANDLER
  00: Callbackid
  05: id?
  06: length
  31: SENSOR_MULTILEVEL
  05: report
  22: power
  0005: value
  e3: checksum

Falls du SERIAL_API_SOFT_RESET in FHEM testen willst, dann geht das auch jetzt mit "set ZWDongle raw 08"
Was genau bewirkt es?

wkarl · 13 Februar 2015, 10:02:28

Hallo Rudolf,

bei mir gibt es für set kein raw (bei get schon). Gebe ich es in die command line ein gibt es die entsprechende Fehlermeldung.

Code Auswählen

Unknown argument raw, choose one of addNode:off,on createNode neighborUpdate removeFailedNode removeNode:off,on reopen replaceFailedNode sendNIF

ciao walter

rudolfkoenig · 13 Februar 2015, 10:22:56

Ok, dann probier es halt mit get.

Unterschied zw. set und get ist minimal: get wartet auf die Antwort, und gibt sie aus.

krikan · 13 Februar 2015, 20:42:02

SERIAL_API_SOFT_RESET (0x08) ist Firmware Restart des Controllers ohne Löschen von Controller-Inhalten bspw. wenn der Controller bei Inklusion/Exklusion nicht mehr reagiert. Mein Controller war nach Absetzen des Kommandos nicht mehr erreichbar und musste vom Stromnetz getrennt werden, damit er wieder (problemlos) funktionierte.

SERIAL_API_SET_DEFAULT (0x42) ist vermutlich eher das, was wkarl sucht. Damit wird laut openzwave der Controller komplett auf den Auslieferungsstand zurückgesetzt: Alle auf dem Controller gespeicherten Infos gehen verloren. NEIN, das habe und werde ich nicht testen. Auch gegen den Einbau als eigenständigen Befehl bin ich. Wer das -warum auch immer- nutzen will, kann das per "get DEVICE raw" absetzen. Interessanter fände ich schon, wie ein Controller-Backup und natürlich Restore funktioniert.

wkarl · 14 Februar 2015, 13:32:34

ZitatSERIAL_API_SET_DEFAULT (0x42) ist vermutlich eher das, was wkarl sucht. Damit wird laut openzwave der Controller komplett auf den Auslieferungsstand zurückgesetzt: Alle auf dem Controller gespeicherten Infos gehen verloren. NEIN, das habe und werde ich nicht testen. Auch gegen den Einbau als eigenständigen Befehl bin ich. Wer das -warum auch immer- nutzen will, kann das per "get DEVICE raw" absetzen. Interessanter fände ich schon, wie ein Controller-Backup und natürlich Restore funktioniert.

Funktioniert. Wäre jetzt toll ein restore zu haben $:-\$

Edit: meine Nachforschungen haben ergeben, dass der AEON Stick kein backup/restore unterstützt. Also warten bis der neue nächste Woche kommt.

ciao walter

wkarl · 14 Februar 2015, 17:10:46

Folgendes habe ich auf meine Anfrage bzgl backup/restore im openzwave Forum bekommen.

ZitatNo such option is available. The ZWave Serial API that we talk is pretty high level and doesn't offer such a option.

ciao walter

krikan · 14 Februar 2015, 17:51:57

Das ist schade.

Bleibt dann nur: 2. Controller (Sekundärcontroller) als Ersatzcontroller auf den man Primärcontroller clont und im Notfall zum Primärcontroller macht?

rudolfkoenig · 14 Februar 2015, 18:10:07

Das waere ein Hardware-Backup

Kann jemand beschreiben, wie man sowas macht?
Sowas ist bei einer groesseren Installation dringend angeraten.

wkarl · 14 Februar 2015, 18:25:04

Hallo Rudolf,

wie schon erwähnt bekomme ich nächste Woche meinen neuen Stick. Bei openzwave habe ich irgendwo gelesen, dass es eine Funktion gibt mit der der primary controller zum secondary geclont wird. Der secondary automatisch primary wird und vice versa.

ich geh mal auf die Suche und wenn der Stick da ist teste ich mal.

ciao walter

wkarl · 23 Februar 2015, 11:52:26

Hallo Rudolf,

anbei das log zum ersten Versuch ein Netzwerk mit zwei Controllern aufzubauen, plus die Primary Role zu transferieren. Sicherlich noch etwas chaotisch, da das openzwave-control-panel (ocp) ein paar mal abstürzte.

Primary: ZME, id01, /dev/ttyACM0@115200, ocp via port 8888
Primary: AEON, id01, /dev/ttyUSB0@115200, ocp via port 8889
ZME in Anlernmodus (add node)
AEON receive configuration aktivieren
ZME meldet Anlernen erfolgreich, ocp von AEON abgestürzt
nach reboot > AEON hat die homeid von ZME und ist slave, auch die devices sind da allerdings in einem inkonsistenten Zustand
von ZME aus transfer primary role initiert, ocp von ZME stürzt ab
nach reboot hat AEON die role primary und ZME secondary, aber der Zustand ist inkonsistent

Die Informationen stelle ich hier zu Deiner Information ein. Ich werde versuchen, dass ganze sauberer ablaufen zu lassen und dann die Daten nochmal einstellen. Solltest Du Anweisungen haben die ich berücksichtigen sollte, lass es mich wissen.

ciao walter

rudolfkoenig · 23 Februar 2015, 11:59:35

Bei so viel Inkonsistenzen werde ich lieber warten, bis man was sauberes hat.

Generell finde ich es beknackt, dass man einen zweiten Stick braucht, um backup machen zu koennen, ich haette das Backup lieber auf meiner Festplatte.

wkarl · 23 Februar 2015, 15:44:45

Hallo Rudolf,

da ocp/oz nicht stabil läuft - zumindest was diesen Test betrifft - habe ich den Vorgang mit den zensys-tools durchgespielt.

beide sticks factory reset
AEON an UZB als secondary angelernt
wall plug an UZB angelernt
AEON zum primary im Netzwerk gemacht

Erkenntnisse:

es kann anscheinend nur ein jungfräulicher controller in ein Netzewerk inkludiert werden
es gibt bei den zensys-tools für den controller einen 'add device', 'shift' und einen 'start learn mode' button
'add device': anlernen von wall plug, motion sensor, etc. Bei Anlernen eines neuen secondary muss dieser in den 'learn mode' versetzt werden.
'shift': den primary zum secondary und vice versa. Der aktuelle secondary wird in den 'learn mode' gebracht
secondary stimmt nicht ganz - der 'secondary' hat die Network Role realprimary, othernetwork
auch läst sich dies nicht umkehren, da bei beiden nun der 'learn mode' button ausgegraut ist.

Ich hoffe Du kannst mit den logs was anfangen.

ciao walter

rudolfkoenig · 27 Februar 2015, 17:32:59

Ich habe AEON_Sec2Prim jetzt 'ne Weile angestarrt, den mAn interessanten Teil extrahiert und etwas bearbeitet/kommentiert:

Code Auswählen


15:15:08.940   ZWaveGetRandom             noRandomBytes=20;   20   
15:15:08.994 A ZWaveGetRandom             rndomGenerationSuccess=01; noRandomGenerated=205A070F5D86C93FBFE43A4E826AA85C291FADC8D710C3043B4B3A74410EE253
15:15:09.055   MemoryGetId    
15:15:09.058 A MemoryGetId                HomeID=E1853D82; NodeID=002;   E1853D8202 
15:15:09.059   ZWaveSendData              nodeID=01; pData=980500 ; txOptions=25; funcID=1E                                                             9805:SecurityCmd_SchemeReport
15:15:09.120 A ApplicationCommandHandler  rxStatus=00; sourceNode=01; pCmd=9840                                                                         9840:SecurityCmd_NonceGet
15:15:09.128   ZWaveSendData              nodeID=01; pData=988062B64AD1A45D3528 ; txOptions=25; funcID=1F                                               9880:SecurityCmd_NonceReport
15:15:09.199 A ApplicationCommandHandler  rxStatus=00; sourceNode=01; pCmd=988118BDDFEBF57654194D4FC4E46A32A4D5EF29F229D95CC6511AC74A629D6E344DCD7C9281 9881:SecurityCmd_MessageEncap
15:15:09.234   ZWaveSendData              nodeID=01; pData=9840 ; txOptions=25; funcID=20;   010298402520                                               9840:SecurityCmd_NonceGet
15:15:09.276 A ApplicationCommandHandler  pCmd=9880BBAA6639F292B1FB                                                                                     9880:SecurityCmd_NonceReport
15:15:09.287   ZWaveSendData              nodeID=01; pData=98810DFE35DEE6CEE1B44E81C1BB53011C7EDCB91AA9 ; txOptions=25; funcID=21                       9881:SecurityCmd_MessageEncap
15:15:09.339 A ApplicationCommandHandler  pCmd=9840
15:15:09.341   ZWaveSendData              nodeID=01; pData=98807A0AF7AF7F16E02A ; txOptions=25; funcID=22
15:15:09.391 A ApplicationCommandHandler  pCmd=98815C6F03F65D6A8CB9966D3EC67AB60BC7C1541E26B1
15:15:09.394   ZWaveSendData              nodeID=01; pData=9840 ; txOptions=25; funcID=23
15:15:09.437 A ApplicationCommandHandler  pCmd=9880C3C8FFC12EA08C27 
15:15:09.439   ZWaveSendData              nodeID=01; pData=9881DFA1C3443D550BFFD595F055C3896AAAB56C8AC1B5 ; txOptions=25; funcID=24

Da wird zunaechst ne Zufallszahl vom Geraet geholt, und danach eine verschluesselte Kommunikation mit einem anderen Geraet durchgefuehrt. Das in FHEM nachzubauen veschiebe ich erstmal, da hier keine "leichten Fruechte" zu holen sind.

Falls natuerlich jemand einen Patch liefert, dann uebernehme ich ihn gerne.

krikan · 27 Februar 2015, 17:39:02

Ist eigentlich geklärt, ob der Sekundärcontroller auch zum Primärcontroller gemacht werden kann, wenn der Primärcontroller defekt ist? Nur dann würde das für Backupzwecke überhaupt Sinn machen.

wkarl · 28 Februar 2015, 08:36:27

im Netz gefunden

Code Auswählen

Driver::ControllerCommand_CreateNewPrimary - Create a new primary controller when old primary fails. Requires SUC.

ciao walter

wkarl · 02 März 2015, 14:37:21

Hallo Rudolf,

ich habe am Wochenende eine zusätzliches Szenario auf gebaut.

Fedora Server mit openzwave und domoticz als GUI (ZWave.Me Stick)
Windows Laptop mit zensys-tools (Aeon Stick)
es soll der AEON in das ZWave.Me Netzwerk als secondary aufgenommen werden
auf dem Fedora Server in domoticz 'Include Node' aktiviert
auf Windows in zensys-tools 'Start Learn Mode' aktiviert

Diesmal wird der AEON mit der Network Role: Secondary, OtherNetwork versehen. Scheint sauberere abgelaufen sein als beim ersten mal.

Hier die log Informationen von domoticz/openzwave:

Code Auswählen

[root@svr-fhem domoticz]# ./domoticz -www 9080 -verbose 1
2015-03-02 14:21:52.816  Domoticz V2.2293 (c)2012-2015 GizMoCuz
2015-03-02 14:21:52.817  Startup Path: /opt/domoticz/
2015-03-02 14:21:52.875  Webserver started on port: 9080
2015-03-02 14:21:55.380  OpenZWave: Starting...
2015-03-02 14:21:55.380  OpenZWave: using config in: /opt/domoticz/Config/
2015-03-02 14:21:55.383 Always, OpenZwave Version 1.3.1009 Starting Up
2015-03-02 14:21:55.383 Info, mgr,     Added driver for controller /dev/ttyACM0
2015-03-02 14:21:55.384 Info,   Opening controller /dev/ttyACM0
2015-03-02 14:21:55.384 Info, Trying to open serial port /dev/ttyACM0 (attempt 1)
2015-03-02 14:21:55.384 Info, Serial port /dev/ttyACM0 opened (attempt 1)
2015-03-02 14:21:55.385 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2015-03-02 14:21:55.385 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2015-03-02 14:21:55.386 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2015-03-02 14:21:55.386 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2015-03-02 14:21:55.386 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2015-03-02 14:21:55.386 Detail,
2015-03-02 14:21:55.386 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2015-03-02 14:21:55.389 Detail, contrlr,   Received: 0x01, 0x10, 0x01, 0x15, 0x5a, 0x2d, 0x57, 0x61, 0x76, 0x65, 0x20, 0x33, 0x2e, 0x39, 0x39, 0x00, 0x01, 0x95
2015-03-02 14:21:55.389 Detail,
2015-03-02 14:21:55.389 Info, contrlr, Received reply to FUNC_ID_ZW_GET_VERSION:
2015-03-02 14:21:55.390 Info, contrlr,     Static Controller library, version Z-Wave 3.99
2015-03-02 14:21:55.390 Detail, contrlr,   Expected reply was received
2015-03-02 14:21:55.390 Detail, contrlr,   Message transaction complete
2015-03-02 14:21:55.390 Detail,
2015-03-02 14:21:55.390 Detail, contrlr, Removing current message
2015-03-02 14:21:55.390 Detail,
2015-03-02 14:21:55.391 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2015-03-02 14:21:55.393 Detail, contrlr,   Received: 0x01, 0x08, 0x01, 0x20, 0xc2, 0xbc, 0x47, 0x0c, 0x01, 0xe2
2015-03-02 14:21:55.393 Detail,
2015-03-02 14:21:55.393 Info, contrlr, Received reply to FUNC_ID_ZW_MEMORY_GET_ID. Home ID = 0xc2bc470c.  Our node ID = 1
2015-03-02 14:21:55.394 Detail, contrlr,   Expected reply was received
2015-03-02 14:21:55.394 Detail, contrlr,   Message transaction complete
2015-03-02 14:21:55.394 Detail,
2015-03-02 14:21:55.394 Detail, contrlr, Removing current message
2015-03-02 14:21:55.394 Detail,
2015-03-02 14:21:55.395 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2015-03-02 14:21:55.396 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x05, 0x28, 0xd7
2015-03-02 14:21:55.396 Detail,
2015-03-02 14:21:55.397 Info, contrlr, Received reply to FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES:
2015-03-02 14:21:55.397 Info, contrlr,     There is no SUC ID Server (SIS) in this network.
2015-03-02 14:21:55.397 Info, contrlr,     The PC controller is a primary controller.
2015-03-02 14:21:55.397 Detail, contrlr,   Expected reply was received
2015-03-02 14:21:55.397 Detail, contrlr,   Message transaction complete
2015-03-02 14:21:55.397 Detail,
2015-03-02 14:21:55.398 Detail, contrlr, Removing current message
2015-03-02 14:21:55.398 Detail,
2015-03-02 14:21:55.398 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2015-03-02 14:21:55.900 Warning, WARNING: 500ms passed without reading the rest of the frame...aborting frame read
2015-03-02 14:21:55.900 Detail, contrlr,   Received: 0x01, 0x07, 0x05, 0x00, 0x01, 0x00, 0x01, 0xfe, 0x83
2015-03-02 14:21:55.901 Warning, contrlr, WARNING: Checksum incorrect - sending NAK
2015-03-02 14:21:55.904 Detail, contrlr,   Received: 0x01, 0x2b, 0x01, 0x07, 0x05, 0x00, 0x01, 0x15, 0x04, 0x00, 0x00, 0x01, 0xfe, 0x83, 0xff, 0x88, 0xcf, 0x1f, 0x00, 0x00, 0xfb, 0x9f, 0x7d, 0xa0, 0x67, 0x00, 0x80, 0x80, 0x00, 0x80, 0x86, 0x00, 0x00, 0x00, 0xe8, 0x73, 0x00, 0x00, 0x0e, 0x00, 0x00, 0x40, 0x0a, 0x00, 0x1b
2015-03-02 14:21:55.904 Detail,
2015-03-02 14:21:55.904 Info, contrlr,  Received reply to FUNC_ID_SERIAL_API_GET_CAPABILITIES
2015-03-02 14:21:55.905 Info, contrlr,     Serial API Version:   5.0
2015-03-02 14:21:55.905 Info, contrlr,     Manufacturer ID:      0x0115
2015-03-02 14:21:55.905 Info, contrlr,     Product Type:         0x0400
2015-03-02 14:21:55.905 Info, contrlr,     Product ID:           0x0001
2015-03-02 14:21:55.905 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2015-03-02 14:21:55.905 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2015-03-02 14:21:55.905 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
2015-03-02 14:21:55.905 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x07, 0x00, 0x03, 0x01, 0x02, 0x01, 0x00, 0xf9
2015-03-02 14:21:55.905 Detail, contrlr,   Expected reply was received
2015-03-02 14:21:55.906 Detail, contrlr,   Message transaction complete
2015-03-02 14:21:55.906 Detail,
2015-03-02 14:21:55.906 Detail, contrlr, Removing current message
2015-03-02 14:21:55.906 Detail,
2015-03-02 14:21:55.906 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x56) - FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2015-03-02 14:21:55.908 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x56, 0x00, 0xac
2015-03-02 14:21:55.908 Detail,
2015-03-02 14:21:55.908 Info, contrlr, Received reply to GET_SUC_NODE_ID.  Node ID = 0
2015-03-02 14:21:55.908 Info, Controller Does not Support SUC - Cannot Setup Controller as SUC Node
2015-03-02 14:21:55.908 Detail, contrlr,   Expected reply was received
2015-03-02 14:21:55.908 Detail, contrlr,   Message transaction complete
2015-03-02 14:21:55.908 Detail,
2015-03-02 14:21:55.909 Detail, contrlr, Removing current message
2015-03-02 14:21:55.909 Detail,
2015-03-02 14:21:55.909 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x1c) - FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2015-03-02 14:21:55.964 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x1c, 0x01, 0x20, 0x3c, 0xba, 0xfa, 0x0b, 0xb7, 0xd1, 0x24, 0xc0, 0x24, 0x2f, 0x54, 0xa8, 0x43, 0xc8, 0xc4, 0xcf, 0x22, 0xef, 0xe6, 0x84, 0xbb, 0x7d, 0xf0, 0x7a, 0x37, 0x09, 0x59, 0x36, 0x5a, 0x1f, 0xc0, 0xcf, 0x9c
2015-03-02 14:21:55.965 Detail,
2015-03-02 14:21:55.965 Info, contrlr, Received reply to FUNC_ID_ZW_GET_RANDOM: true
2015-03-02 14:21:55.965 Detail, contrlr,   Expected reply was received
2015-03-02 14:21:55.965 Detail, contrlr,   Message transaction complete
2015-03-02 14:21:55.965 Detail,
2015-03-02 14:21:55.966 Detail, contrlr, Removing current message
2015-03-02 14:21:55.966 Detail,
2015-03-02 14:21:55.966 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x02) - FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2015-03-02 14:21:56.063 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x02, 0x05, 0x00, 0x1d, 0x01, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x05, 0x00, 0xc5
2015-03-02 14:21:56.063 Detail,
2015-03-02 14:21:56.063 Info, mgr,     Driver with Home ID of 0xc2bc470c is now ready.
2015-03-02 14:21:56.063 Info,
2015-03-02 14:21:56.064 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_GET_INIT_DATA:
2015-03-02 14:21:56.064 Info, contrlr,     Node 001 - New
2015-03-02 14:21:56.064 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=None live=1
2015-03-02 14:21:56.064 Detail, Node001, QueryStage_ProtocolInfo
2015-03-02 14:21:56.064 Detail, Node001, Queuing (Query) Get Node Protocol Info (Node=1): 0x01, 0x04, 0x00, 0x41, 0x01, 0xbb
2015-03-02 14:21:56.064 Detail, Node001, Queuing (Query) Query Stage Complete (ProtocolInfo)
2015-03-02 14:21:56.064 Info, Initilizing Node. New Node: false (false)
2015-03-02 14:21:56.064 Detail, contrlr,   Expected reply was received
2015-03-02 14:21:56.065 Detail, contrlr,   Message transaction complete
2015-03-02 14:21:56.065 Detail,
2015-03-02 14:21:56.065 Detail, contrlr, Removing current message
2015-03-02 14:21:56.065  OpenZWave: Driver Ready
2015-03-02 14:21:56.065  OpenZWave: New Node added. HomeID: 3267118860, NodeID: 1 (0x01)
2015-03-02 14:21:56.066 Detail,
2015-03-02 14:21:56.066 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x06) - FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
2015-03-02 14:21:56.074 Detail, contrlr,   Received: 0x01, 0x05, 0x01, 0x06, 0x64, 0x0f, 0x96
2015-03-02 14:21:56.074 Detail,
2015-03-02 14:21:56.074 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_SET_TIMEOUTS
2015-03-02 14:21:56.074 Detail, contrlr,   Expected reply was received
2015-03-02 14:21:56.074 Detail, contrlr,   Message transaction complete
2015-03-02 14:21:56.075 Detail,
2015-03-02 14:21:56.075 Detail, contrlr, Removing current message
2015-03-02 14:21:56.075 Detail,
2015-03-02 14:21:56.075 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x00) - FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x07, 0x00, 0x03, 0x01, 0x02, 0x01, 0x00, 0xf9
2015-03-02 14:21:56.077 Detail, contrlr, Removing current message
2015-03-02 14:21:56.077 Detail,
2015-03-02 14:21:56.077 Info, Node001, Sending (Query) message (Callback ID=0x00, Expected Reply=0x41) - Get Node Protocol Info (Node=1): 0x01, 0x04, 0x00, 0x41, 0x01, 0xbb
2015-03-02 14:21:56.080 Detail, Node001,   Received: 0x01, 0x09, 0x01, 0x41, 0x93, 0x16, 0x01, 0x02, 0x02, 0x01, 0x33
2015-03-02 14:21:56.080 Detail,
2015-03-02 14:21:56.080 Info, Node001, Received reply to FUNC_ID_ZW_GET_NODE_PROTOCOL_INFO
2015-03-02 14:21:56.080 Info, Node001,   Protocol Info for Node 1:
2015-03-02 14:21:56.080 Info, Node001,     Listening     = true
2015-03-02 14:21:56.081 Info, Node001,     Beaming       = true
2015-03-02 14:21:56.081 Info, Node001,     Routing       = false
2015-03-02 14:21:56.081 Info, Node001,     Max Baud Rate = 40000
2015-03-02 14:21:56.081 Info, Node001,     Version       = 4
2015-03-02 14:21:56.081 Info, Node001,     Security      = false
2015-03-02 14:21:56.084 Info, Node001,   Basic device class    (0x02) - Static Controller
2015-03-02 14:21:56.085 Info, Node001,   Generic device Class  (0x02) - Static Controller
2015-03-02 14:21:56.085 Info, Node001,   Specific device class (0x01) - Static PC Controller
2015-03-02 14:21:56.085 Info, Node001,     COMMAND_CLASS_BASIC is not mapped
2015-03-02 14:21:56.085 Info, Node001,   Mandatory Command Classes for Node 1:
2015-03-02 14:21:56.085 Info, Node001,     None
2015-03-02 14:21:56.086 Info, Node001,   Mandatory Command Classes controlled by Node 1:
2015-03-02 14:21:56.086 Info, Node001,     COMMAND_CLASS_BASIC
2015-03-02 14:21:56.086 Detail, Node001,   Expected reply was received
2015-03-02 14:21:56.086 Detail, Node001,   Message transaction complete
2015-03-02 14:21:56.086 Detail,
2015-03-02 14:21:56.087 Detail, Node001, Removing current message
2015-03-02 14:21:56.087 Detail, Node001, Query Stage Complete (ProtocolInfo)
2015-03-02 14:21:56.087 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Probe live=1
2015-03-02 14:21:56.087 Detail, Node001, QueryStage_Probe
2015-03-02 14:21:56.087 Detail, Node001, QueryStage_WakeUp
2015-03-02 14:21:56.088 Detail, Node001, QueryStage_ManufacturerSpecific1
2015-03-02 14:21:56.103 Detail, Node001, QueryStage_NodeInfo
2015-03-02 14:21:56.104 Detail, Node001, Queuing (Query) Request Node Info (Node=1): 0x01, 0x04, 0x00, 0x60, 0x01, 0x9a
2015-03-02 14:21:56.104 Detail, Node001, Queuing (Query) Query Stage Complete (NodeInfo)
2015-03-02 14:21:56.104 Detail,
2015-03-02 14:21:56.104 Info, Node001, Sending (Query) message (Callback ID=0x00, Expected Reply=0x49) - Request Node Info (Node=1): 0x01, 0x04, 0x00, 0x60, 0x01, 0x9a
2015-03-02 14:21:56.113 Detail, Node001,   Received: 0x01, 0x04, 0x01, 0x60, 0x01, 0x9b
2015-03-02 14:21:56.114 Detail,
2015-03-02 14:21:56.114 Info, Node001, FUNC_ID_ZW_REQUEST_NODE_INFO Request successful.
2015-03-02 14:22:00.627 Detail, Node001,   Received: 0x01, 0x06, 0x00, 0x49, 0x81, 0x00, 0x00, 0x31
2015-03-02 14:22:00.627 Detail,
2015-03-02 14:22:00.627 Warning, WARNING: FUNC_ID_ZW_APPLICATION_UPDATE: UPDATE_STATE_NODE_INFO_REQ_FAILED received
2015-03-02 14:22:00.628 Info, Node001, QueryStageRetry stage NodeInfo requested stage NodeInfo max 2 retries 0 pending 1
2015-03-02 14:22:00.628 Detail, Node001,   Expected reply was received
2015-03-02 14:22:00.628 Detail, Node001,   Message transaction complete
2015-03-02 14:22:00.628 Detail,
2015-03-02 14:22:00.628 Detail, Node001, Removing current message
2015-03-02 14:22:00.628 Detail, Node001, Query Stage Complete (NodeInfo)
2015-03-02 14:22:00.628 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=1 queryStage=NodeInfo live=1
2015-03-02 14:22:00.628 Detail, Node001, QueryStage_NodeInfo
2015-03-02 14:22:00.628 Detail, Node001, Queuing (Query) Request Node Info (Node=1): 0x01, 0x04, 0x00, 0x60, 0x01, 0x9a
2015-03-02 14:22:00.628 Detail, Node001, Queuing (Query) Query Stage Complete (NodeInfo)
2015-03-02 14:22:00.629 Detail,
2015-03-02 14:22:00.629 Info, Node001, Sending (Query) message (Callback ID=0x00, Expected Reply=0x49) - Request Node Info (Node=1): 0x01, 0x04, 0x00, 0x60, 0x01, 0x9a
2015-03-02 14:22:00.638 Detail, Node001,   Received: 0x01, 0x04, 0x01, 0x60, 0x01, 0x9b
2015-03-02 14:22:00.638 Detail,
2015-03-02 14:22:00.638 Info, Node001, FUNC_ID_ZW_REQUEST_NODE_INFO Request successful.
2015-03-02 14:22:05.151 Detail, Node001,   Received: 0x01, 0x06, 0x00, 0x49, 0x81, 0x00, 0x00, 0x31
2015-03-02 14:22:05.151 Detail,
2015-03-02 14:22:05.151 Warning, WARNING: FUNC_ID_ZW_APPLICATION_UPDATE: UPDATE_STATE_NODE_INFO_REQ_FAILED received
2015-03-02 14:22:05.152 Info, Node001, QueryStageRetry stage NodeInfo requested stage NodeInfo max 2 retries 1 pending 1
2015-03-02 14:22:05.152 Detail, Node001,   Expected reply was received
2015-03-02 14:22:05.152 Detail, Node001,   Message transaction complete
2015-03-02 14:22:05.152 Detail,
2015-03-02 14:22:05.152 Detail, Node001, Removing current message
2015-03-02 14:22:05.152 Detail, Node001, Query Stage Complete (NodeInfo)
2015-03-02 14:22:05.152 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=SecurityReport live=1
2015-03-02 14:22:05.152 Detail, Node001, QueryStage_SecurityReport
2015-03-02 14:22:05.152 Detail, Node001, QueryStage_ManufacturerSpecific2
2015-03-02 14:22:05.152 Detail, Node001, QueryStage_Versions
2015-03-02 14:22:05.153 Detail, Node001, QueryStage_Instances
2015-03-02 14:22:05.153 Info, Node001, Essential node queries are complete
2015-03-02 14:22:05.153 Detail, Node001, QueryStage_Static
2015-03-02 14:22:05.153 Detail, Node001, QueryStage_Associations
2015-03-02 14:22:05.153 Detail, Node001, QueryStage_Neighbors
2015-03-02 14:22:05.153 Detail, Requesting routing info (neighbor list) for Node 1
2015-03-02 14:22:05.153 Detail, Node001, Queuing (Command) Get Routing Info (Node=1): 0x01, 0x07, 0x00, 0x80, 0x01, 0x00, 0x00, 0x03, 0x7a
2015-03-02 14:22:05.153 Detail, Node001, Queuing (Query) Query Stage Complete (Neighbors)
2015-03-02 14:22:05.154 Detail,
2015-03-02 14:22:05.154 Info, Node001, Sending (Command) message (Callback ID=0x00, Expected Reply=0x80) - Get Routing Info (Node=1): 0x01, 0x07, 0x00, 0x80, 0x01, 0x00, 0x00, 0x03, 0x7a
2015-03-02 14:22:05.158 Detail, Node001,   Received: 0x01, 0x20, 0x01, 0x80, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x5e
2015-03-02 14:22:05.160 Detail,
2015-03-02 14:22:05.160 Info, Node001, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
2015-03-02 14:22:05.160 Info, Node001,     Neighbors of this node are:
2015-03-02 14:22:05.160 Info, Node001,  (none reported)
2015-03-02 14:22:05.160 Detail, Node001,   Expected reply was received
2015-03-02 14:22:05.160 Detail, Node001,   Message transaction complete
2015-03-02 14:22:05.161 Detail,
2015-03-02 14:22:05.161 Detail, Node001, Removing current message
2015-03-02 14:22:05.161 Detail, Node001, Query Stage Complete (Neighbors)
2015-03-02 14:22:05.161 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1
2015-03-02 14:22:05.161 Detail, Node001, QueryStage_Session
2015-03-02 14:22:05.162 Detail, Node001, QueryStage_Dynamic
2015-03-02 14:22:05.162 Detail, Node001, QueryStage_Configuration
2015-03-02 14:22:05.162 Detail, Node001, QueryStage_Complete
2015-03-02 14:22:05.162 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2015-03-02 14:22:05.162 Warning, CheckCompletedNodeQueries all=1, deadFound=0 sleepingOnly=1
2015-03-02 14:22:05.162 Info,          Node query processing complete.
2015-03-02 14:22:05.163  OpenZWave: All Nodes queried
2015-03-02 14:22:07.077  Incoming connection from: 192.168.178.140
2015-03-02 14:22:37.196  New sensors allowed for 5 minutes...
2015-03-02 14:22:37.196 Detail, contrlr, Queuing (Controller) Add Device
2015-03-02 14:22:37.197  OpenZWave: Node Include command initiated...
2015-03-02 14:22:37.197 Info, Add Device
2015-03-02 14:22:37.197 Detail, contrlr, Queuing (Command) AddDevice: 0x01, 0x05, 0x00, 0x4a, 0x81, 0x01, 0x30
2015-03-02 14:22:37.197 Detail,
2015-03-02 14:22:37.197 Info, contrlr, Sending (Command) message (Callback ID=0x01, Expected Reply=0x4a) - AddDevice: 0x01, 0x05, 0x00, 0x4a, 0x81, 0x01, 0x30
2015-03-02 14:22:37.200 Detail, contrlr,   Received: 0x01, 0x07, 0x00, 0x4a, 0x01, 0x01, 0x00, 0x00, 0xb2
2015-03-02 14:22:37.200 Detail,
2015-03-02 14:22:37.201 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2015-03-02 14:22:37.201 Info, contrlr, ADD_NODE_STATUS_LEARN_READY
2015-03-02 14:22:37.201 Detail, contrlr,   Expected callbackId was received
2015-03-02 14:22:37.201 Detail, contrlr,   Expected reply was received
2015-03-02 14:22:37.201 Detail, contrlr,   Message transaction complete
2015-03-02 14:22:37.201 Detail,
2015-03-02 14:22:37.201 Detail, contrlr, Removing current message
2015-03-02 14:22:37.201  OpenZWave: Device Response: Controller is waiting for a user action
2015-03-02 14:22:37.201 Info, WriteNextMsg Controller nothing to do
2015-03-02 14:22:50.119 Detail,   Received: 0x01, 0x07, 0x00, 0x4a, 0x01, 0x02, 0x00, 0x00, 0xb1
2015-03-02 14:22:50.120 Detail,
2015-03-02 14:22:50.120 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2015-03-02 14:22:50.120 Info, ADD_NODE_STATUS_NODE_FOUND
2015-03-02 14:22:50.491 Detail,   Received: 0x01, 0x0e, 0x00, 0x4a, 0x01, 0x04, 0x02, 0x07, 0x02, 0x02, 0x01, 0x21, 0x20, 0x86, 0x98, 0xa5
2015-03-02 14:22:50.491 Detail,
2015-03-02 14:22:50.491 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2015-03-02 14:22:50.491 Info, ADD_NODE_STATUS_ADDING_CONTROLLER
2015-03-02 14:22:50.491 Info, Adding controller ID 2
2015-03-02 14:22:50.491 Detail, contrlr, Queuing (Command) Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2015-03-02 14:22:50.492 Detail,
2015-03-02 14:22:50.492 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x4a) - Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2015-03-02 14:22:51.008 Detail, contrlr,   Received: 0x01, 0x07, 0x00, 0x4a, 0x01, 0x05, 0x02, 0x00, 0xb4
2015-03-02 14:22:51.008 Detail,
2015-03-02 14:22:51.009 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2015-03-02 14:22:51.009 Info, contrlr, ADD_NODE_STATUS_PROTOCOL_DONE
2015-03-02 14:22:51.009 Detail, contrlr, Queuing (Command) Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2015-03-02 14:22:51.009 Detail, contrlr,   Expected reply was received
2015-03-02 14:22:51.009 Detail, contrlr,   Message transaction complete
2015-03-02 14:22:51.009 Detail,
2015-03-02 14:22:51.009 Detail, contrlr, Removing current message
2015-03-02 14:22:51.009 Detail,
2015-03-02 14:22:51.010 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x4a) - Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2015-03-02 14:22:51.096 Detail, contrlr,   Received: 0x01, 0x07, 0x00, 0x4a, 0x01, 0x06, 0x02, 0x00, 0xb7
2015-03-02 14:22:51.096 Detail,
2015-03-02 14:22:51.096 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2015-03-02 14:22:51.096 Info, contrlr, ADD_NODE_STATUS_DONE
2015-03-02 14:22:51.096 Detail, Node002, AdvanceQueries queryPending=0 queryRetries=0 queryStage=None live=1
2015-03-02 14:22:51.097 Detail, Node002, QueryStage_ProtocolInfo
2015-03-02 14:22:51.097 Detail, Node002, Queuing (Query) Get Node Protocol Info (Node=2): 0x01, 0x04, 0x00, 0x41, 0x02, 0xb8
2015-03-02 14:22:51.097 Detail, Node002, Queuing (Query) Query Stage Complete (ProtocolInfo)
2015-03-02 14:22:51.097 Info, Initilizing Node. New Node: true (true)
2015-03-02 14:22:51.097 Detail, contrlr,   Expected reply was received
2015-03-02 14:22:51.097 Detail, contrlr,   Message transaction complete
2015-03-02 14:22:51.097 Detail,
2015-03-02 14:22:51.097 Detail, contrlr, Removing current message
2015-03-02 14:22:51.098  OpenZWave: Device Response: The command has completed successfully
2015-03-02 14:22:51.098 Detail,
2015-03-02 14:22:51.099 Info, Node002, Sending (Query) message (Callback ID=0x00, Expected Reply=0x41) - Get Node Protocol Info (Node=2): 0x01, 0x04, 0x00, 0x41, 0x02, 0xb8
2015-03-02 14:22:51.101 Detail, Node002,   Received: 0x01, 0x09, 0x01, 0x41, 0x92, 0x16, 0x00, 0x02, 0x02, 0x01, 0x33
2015-03-02 14:22:51.101 Detail,
2015-03-02 14:22:51.101 Info, Node002, Received reply to FUNC_ID_ZW_GET_NODE_PROTOCOL_INFO
2015-03-02 14:22:51.102 Info, Node002,   Protocol Info for Node 2:
2015-03-02 14:22:51.102 Info, Node002,     Listening     = true
2015-03-02 14:22:51.102 Info, Node002,     Beaming       = true
2015-03-02 14:22:51.102 Info, Node002,     Routing       = false
2015-03-02 14:22:51.102 Info, Node002,     Max Baud Rate = 40000
2015-03-02 14:22:51.102 Info, Node002,     Version       = 3
2015-03-02 14:22:51.103 Info, Node002,     Security      = false
2015-03-02 14:22:51.103 Info, Node002,   Basic device class    (0x02) - Static Controller
2015-03-02 14:22:51.103 Info, Node002,   Generic device Class  (0x02) - Static Controller
2015-03-02 14:22:51.103 Info, Node002,   Specific device class (0x01) - Static PC Controller
2015-03-02 14:22:51.103 Info, Node002,     COMMAND_CLASS_BASIC is not mapped
2015-03-02 14:22:51.103 Info, Node002,   Mandatory Command Classes for Node 2:
2015-03-02 14:22:51.104 Info, Node002,     None
2015-03-02 14:22:51.104 Info, Node002,   Mandatory Command Classes controlled by Node 2:
2015-03-02 14:22:51.104 Info, Node002,     COMMAND_CLASS_BASIC
2015-03-02 14:22:51.104 Detail, Node002,   Expected reply was received
2015-03-02 14:22:51.104 Detail, Node002,   Message transaction complete
2015-03-02 14:22:51.104 Detail,
2015-03-02 14:22:51.105 Detail, Node002, Removing current message
2015-03-02 14:22:51.105 Detail, Node002, Query Stage Complete (ProtocolInfo)
2015-03-02 14:22:51.105 Detail, Node002, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Probe live=1
2015-03-02 14:22:51.105 Detail, Node002, QueryStage_Probe
2015-03-02 14:22:51.105 Info, Node002, NoOperation::Set - Routing=true
2015-03-02 14:22:51.106 Detail, Node002, Queuing (NoOp) NoOperation_Set (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x00, 0x00, 0x25, 0x02, 0xc2
2015-03-02 14:22:51.106 Detail, Node002, Queuing (Query) Query Stage Complete (Probe)
2015-03-02 14:22:51.106 Detail,
2015-03-02 14:22:51.106 Info, Node002, Sending (NoOp) message (Callback ID=0x02, Expected Reply=0x13) - NoOperation_Set (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x00, 0x00, 0x25, 0x02, 0xc2
2015-03-02 14:22:51.114 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2015-03-02 14:22:51.114 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2015-03-02 14:22:51.130 Detail, Node002,   Received: 0x01, 0x07, 0x00, 0x13, 0x02, 0x00, 0x00, 0x02, 0xeb
2015-03-02 14:22:51.130 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x02 received (expected 0x02)
2015-03-02 14:22:51.131 Info, Node002, Request RTT 24 Average Request RTT 24
2015-03-02 14:22:51.131 Detail, Node002,   Expected reply was received
2015-03-02 14:22:51.131 Detail, Node002,   Message transaction complete
2015-03-02 14:22:51.131 Detail,
2015-03-02 14:22:51.131 Detail, Node002, Removing current message
2015-03-02 14:22:51.131 Detail, Node002, Query Stage Complete (Probe)
2015-03-02 14:22:51.132 Detail, Node002, AdvanceQueries queryPending=0 queryRetries=0 queryStage=WakeUp live=1
2015-03-02 14:22:51.132 Detail, Node002, QueryStage_WakeUp
2015-03-02 14:22:51.132 Detail, Node002, QueryStage_ManufacturerSpecific1
2015-03-02 14:22:51.132 Detail, Node002, QueryStage_NodeInfo
2015-03-02 14:22:51.132 Detail, Node002, Queuing (Query) Request Node Info (Node=2): 0x01, 0x04, 0x00, 0x60, 0x02, 0x99
2015-03-02 14:22:51.132 Detail, Node002, Queuing (Query) Query Stage Complete (NodeInfo)
2015-03-02 14:22:51.133 Detail,
2015-03-02 14:22:51.133 Info, Node002, Sending (Query) message (Callback ID=0x00, Expected Reply=0x49) - Request Node Info (Node=2): 0x01, 0x04, 0x00, 0x60, 0x02, 0x99
2015-03-02 14:22:51.140 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x60, 0x01, 0x9b
2015-03-02 14:22:51.140 Detail,
2015-03-02 14:22:51.140 Info, Node002, FUNC_ID_ZW_REQUEST_NODE_INFO Request successful.
2015-03-02 14:22:51.170 Detail, Node002,   Received: 0x01, 0x0d, 0x00, 0x49, 0x84, 0x02, 0x07, 0x02, 0x02, 0x01, 0x21, 0x20, 0x86, 0x98, 0x24
2015-03-02 14:22:51.170 Detail,
2015-03-02 14:22:51.170 Info, Node002, UPDATE_STATE_NODE_INFO_RECEIVED from node 2
2015-03-02 14:22:51.170 Info, Node002,   Optional command classes for node 2:
2015-03-02 14:22:51.171 Info, Node002,     COMMAND_CLASS_CONTROLLER_REPLICATION
2015-03-02 14:22:51.171 Info, Node002,     COMMAND_CLASS_VERSION
2015-03-02 14:22:51.171 Info, Node002,   Using Scheme0 Network Key for Key Exchange (AddingNode: true KeySet: false)
2015-03-02 14:22:51.171 Info, Node002,     COMMAND_CLASS_SECURITY
2015-03-02 14:22:51.172 Detail, Node002, AdvanceQueries queryPending=1 queryRetries=0 queryStage=NodeInfo live=1
2015-03-02 14:22:51.172 Detail, Node002,   Expected reply was received
2015-03-02 14:22:51.172 Detail, Node002,   Message transaction complete
2015-03-02 14:22:51.172 Detail,
2015-03-02 14:22:51.172 Detail, Node002, Removing current message
2015-03-02 14:22:51.173 Detail, Node002, Query Stage Complete (NodeInfo)
2015-03-02 14:22:51.173 Detail, Node002, AdvanceQueries queryPending=0 queryRetries=0 queryStage=SecurityReport live=1
2015-03-02 14:22:51.173 Detail, Node002, QueryStage_SecurityReport
2015-03-02 14:22:51.173 Detail, Node002, Queuing (Security) SecurityCmd_SchemeGet (Node=2): 0x01, 0x0a, 0x00, 0x13, 0x02, 0x03, 0x98, 0x04, 0x00, 0x25, 0x03, 0x5d
2015-03-02 14:22:51.173 Detail,
2015-03-02 14:22:51.174 Info, Node002, Sending (Security) message (Callback ID=0x03, Expected Reply=0x04) - SecurityCmd_SchemeGet (Node=2): 0x01, 0x0a, 0x00, 0x13, 0x02, 0x03, 0x98, 0x04, 0x00, 0x25, 0x03, 0x5d
2015-03-02 14:22:51.181 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2015-03-02 14:22:51.181 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2015-03-02 14:22:51.198 Detail, Node002,   Received: 0x01, 0x07, 0x00, 0x13, 0x03, 0x00, 0x00, 0x02, 0xea
2015-03-02 14:22:51.198 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x03 received (expected 0x03)
2015-03-02 14:22:51.198 Info, Node002, Request RTT 24 Average Request RTT 24
2015-03-02 14:22:52.757 Detail, Node002,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x02, 0x03, 0x98, 0x05, 0x00, 0x6e
2015-03-02 14:22:52.757 Detail,
2015-03-02 14:22:52.757 Info, Node002, Response RTT 1583 Average Response RTT 1583
2015-03-02 14:22:52.757 Info, Node002, Received SecurityCmd_SchemeReport from node 2: 0
2015-03-02 14:22:52.757 Info, Node002,     Security scheme agreed.
2015-03-02 14:22:52.758 Detail, Node002, Queuing (Security) SecurityCmd_NonceGet (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x98, 0x40, 0x05, 0x05, 0x3d
2015-03-02 14:22:52.758 Detail, Node002,   Expected reply and command class was received
2015-03-02 14:22:52.758 Detail, Node002,   Message transaction complete
2015-03-02 14:22:52.758 Detail,
2015-03-02 14:22:52.758 Detail, Node002, Removing current message
2015-03-02 14:22:52.758 Detail,
2015-03-02 14:22:52.758 Info, Node002, Sending (Security) message (Callback ID=0x05, Expected Reply=0x04) - SecurityCmd_NonceGet (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x98, 0x40, 0x05, 0x05, 0x3d
2015-03-02 14:22:52.766 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2015-03-02 14:22:52.766 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2015-03-02 14:22:52.784 Detail, Node002,   Received: 0x01, 0x07, 0x00, 0x13, 0x05, 0x00, 0x00, 0x03, 0xed
2015-03-02 14:22:52.784 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x05 received (expected 0x05)
2015-03-02 14:22:52.785 Info, Node002, Request RTT 26 Average Request RTT 25
2015-03-02 14:22:52.902 Detail, Node002,   Received: 0x01, 0x10, 0x00, 0x04, 0x00, 0x02, 0x0a, 0x98, 0x80, 0x89, 0x3f, 0xfa, 0x6b, 0xac, 0xbc, 0x9b, 0x53, 0x04
2015-03-02 14:22:52.902 Detail,
2015-03-02 14:22:52.903 Info, Node002, Response RTT 144 Average Response RTT 863
2015-03-02 14:22:52.903 Info, Node002, Received SecurityCmd_NonceReport from node 2
2015-03-02 14:22:52.903 Info, Input Packet: Packet: 0x00, 0x98, 0x06, 0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08, 0x09, 0x0a, 0x0b, 0x0c, 0x0d, 0x0e, 0x0f, 0x10
2015-03-02 14:22:52.903 Detail, Node002, Queuing (Security) SecurityCmd_MessageEncap (SecurityCmd_NetworkKeySet) (Node=2): 0x01, 0x2d, 0x00, 0x13, 0x02, 0x26, 0x98, 0x81, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0x5c, 0xf3, 0x8b, 0x99, 0x76, 0x8b, 0xee, 0x3b, 0x9a, 0x5b, 0x81, 0xa9, 0x50, 0x9a, 0x0e, 0x94, 0xf2, 0x83, 0xa9, 0x89, 0xc2, 0xf7, 0x78, 0x19, 0x86, 0x23, 0x04, 0x9d, 0x25, 0x06, 0xca
2015-03-02 14:22:52.903 Info, Node002, Reseting Network Key after Inclusion
2015-03-02 14:22:52.904 Info, Node002,   Using Configured Network Key (AddingNode: true KeySet: true)
2015-03-02 14:22:52.904 Detail, Node002,   Expected reply and command class was received
2015-03-02 14:22:52.904 Detail, Node002,   Message transaction complete
2015-03-02 14:22:52.904 Detail,
2015-03-02 14:22:52.904 Detail, Node002, Removing current message
2015-03-02 14:22:52.905 Detail,
2015-03-02 14:22:52.905 Info, Node002, Sending (Security) message (Callback ID=0x06, Expected Reply=0x04) - SecurityCmd_MessageEncap (SecurityCmd_NetworkKeySet) (Node=2): 0x01, 0x2d, 0x00, 0x13, 0x02, 0x26, 0x98, 0x81, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0x5c, 0xf3, 0x8b, 0x99, 0x76, 0x8b, 0xee, 0x3b, 0x9a, 0x5b, 0x81, 0xa9, 0x50, 0x9a, 0x0e, 0x94, 0xf2, 0x83, 0xa9, 0x89, 0xc2, 0xf7, 0x78, 0x19, 0x86, 0x23, 0x04, 0x9d, 0x25, 0x06, 0xca
2015-03-02 14:22:52.917 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2015-03-02 14:22:52.917 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2015-03-02 14:22:52.942 Detail, Node002,   Received: 0x01, 0x07, 0x00, 0x13, 0x06, 0x00, 0x00, 0x04, 0xe9
2015-03-02 14:22:52.942 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x06 received (expected 0x06)
2015-03-02 14:22:52.942 Info, Node002, Request RTT 37 Average Request RTT 31
2015-03-02 14:22:53.035 Detail, Node002,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x02, 0x02, 0x98, 0x40, 0x2b
2015-03-02 14:22:53.035 Detail,
2015-03-02 14:22:53.035 Info, Node002, Response RTT 130 Average Response RTT 496
2015-03-02 14:22:53.035 Info, Node002, Received SecurityCmd_NonceGet from node 2
2015-03-02 14:22:53.036 Detail, Node002, Queuing (Security) SecurityCmd_NonceReport (Node=2): 0x01, 0x11, 0x00, 0x13, 0x02, 0x0a, 0x98, 0x80, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0x05, 0x07, 0xef
2015-03-02 14:22:53.036 Detail, Node002,   Expected reply and command class was received
2015-03-02 14:22:53.036 Detail, Node002,   Message transaction complete
2015-03-02 14:22:53.036 Detail,
2015-03-02 14:22:53.036 Detail, Node002, Removing current message
2015-03-02 14:22:53.037 Detail,
2015-03-02 14:22:53.037 Info, Node002, Sending (Security) message (Callback ID=0x07, Expected Reply=0x04) - SecurityCmd_NonceReport (Node=2): 0x01, 0x11, 0x00, 0x13, 0x02, 0x0a, 0x98, 0x80, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0x05, 0x07, 0xef
2015-03-02 14:22:53.045 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2015-03-02 14:22:53.046 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2015-03-02 14:22:53.064 Detail, Node002,   Received: 0x01, 0x07, 0x00, 0x13, 0x07, 0x00, 0x00, 0x03, 0xef
2015-03-02 14:22:53.064 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x07 received (expected 0x07)
2015-03-02 14:22:53.064 Info, Node002, Request RTT 27 Average Request RTT 29
2015-03-02 14:22:53.252 Detail, Node002,   Received: 0x01, 0x1c, 0x00, 0x04, 0x00, 0x02, 0x16, 0x98, 0x81, 0xb4, 0x61, 0x27, 0x59, 0x01, 0x5e, 0x84, 0x5a, 0x1c, 0x6c, 0xf7, 0xaa, 0x3d, 0x28, 0x94, 0x20, 0x02, 0xa9, 0x2d, 0xef, 0x25
2015-03-02 14:22:53.253 Detail,
2015-03-02 14:22:53.253 Info, Node002, Response RTT 215 Average Response RTT 355
2015-03-02 14:22:53.253 Info, Node002, Received SecurityCmd_MessageEncap from node 2
2015-03-02 14:22:53.253 Info, Decrypted Packet: 0x00, 0x98, 0x07
2015-03-02 14:22:53.254 Info, Node002, Received SecurityCmd_NetworkKeyVerify from node 2
2015-03-02 14:22:53.254 Detail, Node002, Queuing (Security) SecurityCmd_NonceGet (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x98, 0x40, 0x05, 0x09, 0x31
2015-03-02 14:22:53.254 Detail, Node002, Initial read of value
2015-03-02 14:22:53.254 Detail, Node002,   Expected reply and command class was received
2015-03-02 14:22:53.254 Detail, Node002,   Message transaction complete
2015-03-02 14:22:53.254 Detail,
2015-03-02 14:22:53.255 Detail, Node002, Removing current message
2015-03-02 14:22:53.255 Detail,
2015-03-02 14:22:53.255 Info, Node002, Sending (Security) message (Callback ID=0x09, Expected Reply=0x04) - SecurityCmd_NonceGet (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x98, 0x40, 0x05, 0x09, 0x31
2015-03-02 14:22:53.262 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2015-03-02 14:22:53.263 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2015-03-02 14:22:53.279 Detail, Node002,   Received: 0x01, 0x07, 0x00, 0x13, 0x09, 0x00, 0x00, 0x02, 0xe0
2015-03-02 14:22:53.279 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x09 received (expected 0x09)
2015-03-02 14:22:53.279 Info, Node002, Request RTT 24 Average Request RTT 26
2015-03-02 14:22:54.223 Detail, Node002,   Received: 0x01, 0x10, 0x00, 0x04, 0x00, 0x02, 0x0a, 0x98, 0x80, 0x22, 0xe7, 0x3c, 0xc7, 0x0c, 0xc8, 0x8f, 0x82, 0x0c
2015-03-02 14:22:54.223 Detail,
2015-03-02 14:22:54.223 Info, Node002, Response RTT 968 Average Response RTT 661
2015-03-02 14:22:54.223 Info, Node002, Received SecurityCmd_NonceReport from node 2
2015-03-02 14:22:54.223 Info, Input Packet: Packet: 0x00, 0x98, 0x02
2015-03-02 14:22:54.223 Detail, Node002, Queuing (Security) SecurityCmd_MessageEncap (SecurityCmd_SupportedGet) (Node=2): 0x01, 0x1d, 0x00, 0x13, 0x02, 0x16, 0x98, 0x81, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0x79, 0xe7, 0x3c, 0x22, 0x47, 0x61, 0x68, 0xaf, 0xb9, 0x95, 0xe1, 0xb2, 0x25, 0x0a, 0xcd
2015-03-02 14:22:54.223 Detail, Node002,   Expected reply and command class was received
2015-03-02 14:22:54.224 Detail, Node002,   Message transaction complete
2015-03-02 14:22:54.224 Detail,
2015-03-02 14:22:54.224 Detail, Node002, Removing current message
2015-03-02 14:22:54.224 Detail,
2015-03-02 14:22:54.224 Info, Node002, Sending (Security) message (Callback ID=0x0a, Expected Reply=0x04) - SecurityCmd_MessageEncap (SecurityCmd_SupportedGet) (Node=2): 0x01, 0x1d, 0x00, 0x13, 0x02, 0x16, 0x98, 0x81, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0x79, 0xe7, 0x3c, 0x22, 0x47, 0x61, 0x68, 0xaf, 0xb9, 0x95, 0xe1, 0xb2, 0x25, 0x0a, 0xcd
2015-03-02 14:22:54.234 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2015-03-02 14:22:54.234 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2015-03-02 14:22:54.255 Detail, Node002,   Received: 0x01, 0x07, 0x00, 0x13, 0x0a, 0x00, 0x00, 0x03, 0xe2
2015-03-02 14:22:54.255 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x0a received (expected 0x0a)
2015-03-02 14:22:54.255 Info, Node002, Request RTT 31 Average Request RTT 28
2015-03-02 14:22:54.999 Detail, Node002,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x02, 0x02, 0x98, 0x40, 0x2b
2015-03-02 14:22:54.999 Detail,
2015-03-02 14:22:54.999 Info, Node002, Response RTT 774 Average Response RTT 717
2015-03-02 14:22:54.999 Info, Node002, Received SecurityCmd_NonceGet from node 2
2015-03-02 14:22:54.999 Detail, Node002, Queuing (Security) SecurityCmd_NonceReport (Node=2): 0x01, 0x11, 0x00, 0x13, 0x02, 0x0a, 0x98, 0x80, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0x05, 0x0b, 0xe3
2015-03-02 14:22:55.000 Detail, Node002,   Expected reply and command class was received
2015-03-02 14:22:55.000 Detail, Node002,   Message transaction complete
2015-03-02 14:22:55.000 Detail,
2015-03-02 14:22:55.000 Detail, Node002, Removing current message
2015-03-02 14:22:55.000 Detail,
2015-03-02 14:22:55.001 Info, Node002, Sending (Security) message (Callback ID=0x0b, Expected Reply=0x04) - SecurityCmd_NonceReport (Node=2): 0x01, 0x11, 0x00, 0x13, 0x02, 0x0a, 0x98, 0x80, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0x05, 0x0b, 0xe3
2015-03-02 14:22:55.009 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2015-03-02 14:22:55.009 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2015-03-02 14:22:55.028 Detail, Node002,   Received: 0x01, 0x07, 0x00, 0x13, 0x0b, 0x00, 0x00, 0x02, 0xe2
2015-03-02 14:22:55.028 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x0b received (expected 0x0b)
2015-03-02 14:22:55.028 Info, Node002, Request RTT 26 Average Request RTT 27
2015-03-02 14:22:55.057 Detail, Node002,   Received: 0x01, 0x22, 0x00, 0x04, 0x00, 0x02, 0x1c, 0x98, 0x81, 0xad, 0x1f, 0x03, 0x5d, 0x46, 0x32, 0xab, 0x05, 0x84, 0x5a, 0xa6, 0x3d, 0xda, 0xb8, 0xfd, 0x36, 0xf6, 0xaa, 0x1e, 0x01, 0x1d, 0x48, 0x7d, 0x6e, 0x41, 0xd3, 0x93
2015-03-02 14:22:55.057 Detail,
2015-03-02 14:22:55.057 Info, Node002, Response RTT 56 Average Response RTT 386
2015-03-02 14:22:55.057 Info, Node002, Received SecurityCmd_MessageEncap from node 2
2015-03-02 14:22:55.057 Info, Decrypted Packet: 0x00, 0x98, 0x03, 0x00, 0x21, 0x20, 0x86, 0x98, 0xef
2015-03-02 14:22:55.058 Info, Node002, Received SecurityCmd_SupportedReport from node 2
2015-03-02 14:22:55.058 Info, Node002,   Secured command classes for node 2:
2015-03-02 14:22:55.058 Info, Node002,     COMMAND_CLASS_CONTROLLER_REPLICATION (Secured)
2015-03-02 14:22:55.058 Info, Node002,     COMMAND_CLASS_BASIC (Secured)
2015-03-02 14:22:55.058 Info, Node002,     COMMAND_CLASS_VERSION (Secured)
2015-03-02 14:22:55.058 Info, Node002,     COMMAND_CLASS_SECURITY (Downgraded)
2015-03-02 14:22:55.059 Info, Node002,   UnSecured command classes for node 2:
2015-03-02 14:22:55.059 Info, Node002,     COMMAND_CLASS_NO_OPERATION (Unsecured)
2015-03-02 14:22:55.059 Info, Node002,     COMMAND_CLASS_SECURITY (Unsecured)
2015-03-02 14:22:55.059 Detail, Node002, AdvanceQueries queryPending=0 queryRetries=0 queryStage=ManufacturerSpecific2 live=1
2015-03-02 14:22:55.059 Detail, Node002, QueryStage_ManufacturerSpecific2
2015-03-02 14:22:55.060 Detail, Node002, QueryStage_Versions
2015-03-02 14:22:55.060 Detail, Node002, QueryStage_Instances
2015-03-02 14:22:55.060 Info, Node002, Essential node queries are complete
2015-03-02 14:22:55.060 Detail, Node002, QueryStage_Static
2015-03-02 14:22:55.060 Detail, Node002, Encrypting Message For Command Class COMMAND_CLASS_VERSION
2015-03-02 14:22:55.060 Detail, Node002, Queuing (Security) SecurityCmd_NonceGet (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x98, 0x40, 0x05, 0x0d, 0x35
2015-03-02 14:22:55.061 Detail, Node002, Queuing (Query) Query Stage Complete (Static)
2015-03-02 14:22:55.061 Detail, Node002,   Expected reply and command class was received
2015-03-02 14:22:55.061 Detail, Node002,   Message transaction complete
2015-03-02 14:22:55.061 Detail,
2015-03-02 14:22:55.061 Detail, Node002, Removing current message
2015-03-02 14:22:55.062 Detail,
2015-03-02 14:22:55.062 Info, Node002, Sending (Security) message (Callback ID=0x0d, Expected Reply=0x04) - SecurityCmd_NonceGet (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x98, 0x40, 0x05, 0x0d, 0x35
2015-03-02 14:22:55.069 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2015-03-02 14:22:55.069 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2015-03-02 14:22:55.086 Detail, Node002,   Received: 0x01, 0x07, 0x00, 0x13, 0x0d, 0x00, 0x00, 0x02, 0xe4
2015-03-02 14:22:55.086 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x0d received (expected 0x0d)
2015-03-02 14:22:55.086 Info, Node002, Request RTT 23 Average Request RTT 25
2015-03-02 14:22:55.107 Detail, Node002,   Received: 0x01, 0x10, 0x00, 0x04, 0x00, 0x02, 0x0a, 0x98, 0x80, 0xfb, 0x9b, 0xa9, 0x7d, 0xbd, 0x02, 0xc9, 0x7a, 0x43
2015-03-02 14:22:55.107 Detail,
2015-03-02 14:22:55.107 Info, Node002, Response RTT 44 Average Response RTT 215
2015-03-02 14:22:55.107 Info, Node002, Received SecurityCmd_NonceReport from node 2
2015-03-02 14:22:55.108 Info, Input Packet: Packet: 0x00, 0x86, 0x11
2015-03-02 14:22:55.108 Detail, Node002, Queuing (Security) SecurityCmd_MessageEncap (VersionCmd_Get) (Node=2): 0x01, 0x1d, 0x00, 0x13, 0x02, 0x16, 0x98, 0x81, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0x3b, 0xb9, 0x45, 0xfb, 0xd3, 0x0e, 0x6f, 0x6f, 0x03, 0x40, 0x0e, 0x32, 0x25, 0x0e, 0x49
2015-03-02 14:22:55.108 Detail, Node002,   Expected reply and command class was received
2015-03-02 14:22:55.108 Detail, Node002,   Message transaction complete
2015-03-02 14:22:55.108 Detail,
2015-03-02 14:22:55.108 Detail, Node002, Removing current message
2015-03-02 14:22:55.109 Detail,
2015-03-02 14:22:55.109 Info, Node002, Sending (Security) message (Callback ID=0x0e, Expected Reply=0x04) - SecurityCmd_MessageEncap (VersionCmd_Get) (Node=2): 0x01, 0x1d, 0x00, 0x13, 0x02, 0x16, 0x98, 0x81, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0x3b, 0xb9, 0x45, 0xfb, 0xd3, 0x0e, 0x6f, 0x6f, 0x03, 0x40, 0x0e, 0x32, 0x25, 0x0e, 0x49
2015-03-02 14:22:55.119 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2015-03-02 14:22:55.119 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2015-03-02 14:22:55.140 Detail, Node002,   Received: 0x01, 0x07, 0x00, 0x13, 0x0e, 0x00, 0x00, 0x02, 0xe7
2015-03-02 14:22:55.140 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x0e received (expected 0x0e)
2015-03-02 14:22:55.140 Info, Node002, Request RTT 31 Average Request RTT 28
2015-03-02 14:22:55.164 Detail, Node002,   Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x02, 0x07, 0x86, 0x12, 0x01, 0x02, 0x4e, 0x03, 0x07, 0x2e
2015-03-02 14:22:55.164 Detail,
2015-03-02 14:22:55.164 Info, Node002, Response RTT 55 Average Response RTT 135
2015-03-02 14:22:55.165 Info, Node002, Received Version report from node 2: Library=1, Protocol=2.78, Application=3.07
2015-03-02 14:22:55.165 Detail, Node002, Initial read of value
2015-03-02 14:22:55.165 Detail, Node002, Initial read of value
2015-03-02 14:22:55.165 Detail, Node002, Initial read of value
2015-03-02 14:23:35.110 Detail,
2015-03-02 14:23:35.110 Info, Node002, Sending (Security) message (Attempt 2, Callback ID=0x0f, Expected Reply=0x04) - SecurityCmd_MessageEncap (VersionCmd_Get) (Node=2): 0x01, 0x1d, 0x00, 0x13, 0x02, 0x16, 0x98, 0x81, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0x3b, 0xb9, 0x45, 0xfb, 0xd3, 0x0e, 0x6f, 0x6f, 0x03, 0x40, 0x0e, 0x32, 0x25, 0x0f, 0x48
2015-03-02 14:23:35.113 Info, mgr,     Manager::WriteConfig completed for driver with home ID of 0xc2bc470c
2015-03-02 14:23:35.121 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2015-03-02 14:23:35.122 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2015-03-02 14:23:35.409 Detail, Node002,   Received: 0x01, 0x07, 0x00, 0x13, 0x0f, 0x01, 0x00, 0x1d, 0xf8
2015-03-02 14:23:35.409 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x0f received (expected 0x0f)
2015-03-02 14:23:35.409 Info, Node002, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2015-03-02 14:23:35.410 Warning, Node002, WARNING: Device is not a sleeping node.
2015-03-02 14:24:15.111 Detail,
2015-03-02 14:24:15.111 Info, Node002, Sending (Security) message (Attempt 3, Callback ID=0x10, Expected Reply=0x04) - SecurityCmd_MessageEncap (VersionCmd_Get) (Node=2): 0x01, 0x1d, 0x00, 0x13, 0x02, 0x16, 0x98, 0x81, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0xaa, 0x3b, 0xb9, 0x45, 0xfb, 0xd3, 0x0e, 0x6f, 0x6f, 0x03, 0x40, 0x0e, 0x32, 0x25, 0x10, 0x57
2015-03-02 14:24:15.123 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2015-03-02 14:24:15.124 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2015-03-02 14:24:15.304 Detail, Node002,   Received: 0x01, 0x07, 0x00, 0x13, 0x10, 0x01, 0x00, 0x13, 0xe9
2015-03-02 14:24:15.304 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x10 received (expected 0x10)
2015-03-02 14:24:15.304 Info, Node002, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2015-03-02 14:24:15.304 Warning, Node002, WARNING: Device is not a sleeping node.

Benötigst Du auch noch die Informationen von zensys-tools.

ciao walter

rudolfkoenig · 02 März 2015, 14:48:50

Dieser Log beweist auch, dass die Uebergabe mit verschluesselten Nachrichten ablaeuft, und das wollte ich mir noch nicht antun.

Ich benoetige keine weiteren Infos, sondern einen Patch oder ganz gute Argumente, wieso ich sehr viel Zeit damit verbringen soll, verschluesselte ZWave Nachrichten zu implementieren.

wkarl · 02 März 2015, 17:26:42

Hallo Rudolf,

lass mich das verstehen. Ohne verschlüsselte Nachrichten lässt sich kein secundary controler einbinden/erstellen, oder es funktioniert auch ohne Verschlüsselung. Es wird halt dann auf ein gewisses Mass an Security verzichtet.

ciao walter

rudolfkoenig · 02 März 2015, 17:36:33

Naja, beide Beispiele zeigen verschluesselte Nachrichten, ich habe keine Ahnung ob es ohne geht, die Beispiele waren ja dafuer gedacht, um zu lernen, wie es geht. In den Quellen von openzwave steht was von AES und zwei Schluesseln. Jetzt muesste man die Schluessel rausfinden, die genauen Betriebsmodi fuer AES, danach entschluesseln, verstehen, nachbauen, usw. Ziemlich viel Aufwand (Tage?) fuer ein Feature, was nicht sooo haeufig eingesetzt wird. Habs deswegen nach hinten geschoben.