[Solved] Restore backup on UZB1 with z-way license

[sdh]

I upgraded my UZB stick with z-way license and that worked. Now I am trying to restore the most recent backup and it always throws an error. I have tried version 2.1.1 and 2.1.2-rc2 as well on ubuntu 14 and well as windows. Any idea which version is able to restore to gen5 controller.

Logs during restore (home ID masked with xxxxxxxx):

Code: Select all

[2015-11-12 10:08:27.046] [I] [zway] Restoring controller from config/zddx/xxxxxxxx-DevicesData.xml
[2015-11-12 10:08:28.811] [I] [zway] Adding job: Reset the controller
[2015-11-12 10:08:28.813] [D] [zway] SETDATA controller.data.controllerState = 20 (0x00000014)
[2015-11-12 10:08:28.814] [E] [zway] SDK is not supported. Upgrade your stick or contact Z-Way developers for more information
[2015-11-12 10:08:28.816] [D] [zway] SETDATA controller.data.controllerState = 0 (0x00000000)
[2015-11-12 10:08:28.909] [D] [zway] SENDING (cb 0x02): ( 01 04 00 42 02 BB )
[2015-11-12 10:08:28.912] [D] [zway] RECEIVED ACK
[2015-11-12 10:08:31.123] [D] [zway] RECEIVED: ( 01 04 00 42 02 BB )
[2015-11-12 10:08:31.125] [D] [zway] SENT ACK
[2015-11-12 10:08:31.127] [D] [zway] SETDATA controller.data.lastIncludedDevice = Empty
[2015-11-12 10:08:31.128] [D] [zway] SETDATA controller.data.lastExcludedDevice = Empty
[2015-11-12 10:08:31.130] [I] [zway] Job 0x42 (Reset the controller): Done
[2015-11-12 10:08:31.131] [D] [zway] Job 0x42 (Reset the controller): success
[2015-11-12 10:08:31.133] [I] [zway] Removing job: Reset the controller
[2015-11-12 10:08:51.394] [D] [zway] Job 0x42: deleted from queue

License key activation:

Code: Select all

[2015-11-12 10:12:23.682] [I] [zway] Adding job: Get or set Z-Wave.Me firmware capabilities
[2015-11-12 10:12:23.690] [D] [zway] SENDING (cb 0x03): ( ****** )
[2015-11-12 10:12:23.696] [D] [zway] RECEIVED ACK
[2015-11-12 10:12:23.698] [D] [zway] RECEIVED: ( 01 04 01 F5 CE C1 )
[2015-11-12 10:12:23.699] [D] [zway] SENT ACK
[2015-11-12 10:12:23.701] [I] [zway] Job 0xf5 (Get or set Z-Wave.Me firmware capabilities): In progress
[2015-11-12 10:12:32.766] [D] [zway] RECEIVED: ( ****** )
[2015-11-12 10:12:32.768] [D] [zway] SENT ACK
[2015-11-12 10:12:32.770] [D] [zway] SETDATA controller.data.uuid = "xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx"
[2015-11-12 10:12:32.771] [D] [zway] SETDATA controller.data.caps = byte[4]
[2015-11-12 10:12:32.772] [D] [zway]   ( 01 15 FF 05 )
[2015-11-12 10:12:32.782] [I] [zway] Job 0xf5 (Get or set Z-Wave.Me firmware capabilities): Done
[2015-11-12 10:12:32.783] [D] [zway] Job 0xf5 (Get or set Z-Wave.Me firmware capabilities): success
[2015-11-12 10:12:32.785] [I] [zway] Removing job: Get or set Z-Wave.Me firmware capabilities
[2015-11-12 10:12:53.026] [D] [zway] Job 0xf5: deleted from queue

[pofs]

And what is your stick's SDK version?
Z-way currently supports restoring topology on SDKs up to 6.51.06, did you happen to have a newer one?

[sdh]

For some reason my UZB has no SDK version listed (see below). The Backup is from RaZberry with SDK Version 6.51.03.

Role in Network

Node Id: 1
Home Id: 0xxxxxx
Primary Role: Yes
Primary Capability: Yes
SUC/SIS in network: 1 (SIS)
Hardware

Vendor: Z-Wave.Me
Vendors Product ID: 1024 / 1
Z-Wave Chip: ZW0500
Firmware

Library Type: Static Controller
SDK Version:
Serial API Version: 05.04
Capabillities:

UUID: xxxxxxxxx
Subvendor: 0x0115
Nodes limit: Unlimited
Capabillities: SlM
Z-Way Software Information

Version number: v2.1.2-rc14-internal
Compile-ID: cb501327220877f0601983d8e737033c5933463c
Compile-Date: 2015-11-11 16:19:05 +0300
UI

UI version 1.0.3

[pofs]

Can you please post a fragment of startup log, when version is requested (search for SDK/ZWlibMajor/ZWlibMinor values initialization)?. I'm interested in "RECEIVED" lines nearby.

[sdh]

Here is the requested part of the startup log. I hope we can resolve the issue soon.

Code: Select all

[2015-11-13 04:41:34.585] [D] [zway] Job 0x07 (Get controller info and supported function classes): success
[2015-11-13 04:41:34.586] [I] [zway] Adding job: Get or set Z-Wave.Me firmware capabilities
[2015-11-13 04:41:34.586] [D] [zway] SETDATA controller.data.controllerState = 0 (0x00000000)
[2015-11-13 04:41:34.586] [I] [zway] Adding job: Set Serial API timeouts
[2015-11-13 04:41:34.587] [I] [zway] Adding job: Set RF power level
[2015-11-13 04:41:34.587] [I] [zway] Adding job: WatchDog Start
[2015-11-13 04:41:34.587] [I] [zway] Adding job: Change/Get Z-Wave.Me firmware frequency
[2015-11-13 04:41:34.587] [I] [zway] Adding job: Get home id and controller node id
[2015-11-13 04:41:34.588] [I] [zway] Removing job: Get controller info and supported function classes
[2015-11-13 04:41:34.588] [D] [zway] SENDING (cb 0x01): ( 01 04 00 F5 01 0F )
[2015-11-13 04:41:34.589] [W] [zway] Received SOF, while awaiting ACK
[2015-11-13 04:41:34.589] [D] [zway] RECEIVED: ( 01 1E 00 0A 05 01 03 02 01 15 5E 86 60 8F 81 46 98 26 25 72 8A 2B 77 20 22 5B 56 73 85 59 5A CF )
[2015-11-13 04:41:34.590] [D] [zway] SENT ACK
[2015-11-13 04:41:34.590] [W] [zway] No job of class 0x0a is waiting for callback 0x05
[2015-11-13 04:41:34.591] [I] [zway] Unhandled callback for function 0x00
[2015-11-13 04:41:34.591] [I] [zway] Removing job: Unknown function class
[2015-11-13 04:41:34.599] [D] [zway] RECEIVED CAN
[2015-11-13 04:41:35.009] [I] [zway] Job 0xf5 (Get or set Z-Wave.Me firmware capabilities): No ACK received before timeout
[2015-11-13 04:41:35.009] [D] [zway] SENDING (cb 0x01): ( 01 04 00 F5 01 0F )
[2015-11-13 04:41:35.011] [D] [zway] RECEIVED ACK
[2015-11-13 04:41:35.012] [D] [zway] RECEIVED: ( 01 04 01 F5 CE C1 )
[2015-11-13 04:41:35.012] [D] [zway] SENT ACK
[2015-11-13 04:41:35.013] [I] [zway] Job 0xf5 (Get or set Z-Wave.Me firmware capabilities): In progress
[2015-11-13 04:41:35.021] [D] [zway] RECEIVED: ( 01 25 00 F5 01 DA DD 92 19 E0 FE 89 A7 F3 04 1E 53 98 20 67 60 D9 01 15 FF 05 00 00 00 00 00 00 00 00 00 00 00 00 A0 )
[2015-11-13 04:41:35.021] [D] [zway] SENT ACK
[2015-11-13 04:41:35.022] [D] [zway] SETDATA controller.data.uuid = "dd9219e0fe89a7f3041e5398206760d9"
[2015-11-13 04:41:35.022] [D] [zway] SETDATA controller.data.caps = byte[4]
[2015-11-13 04:41:35.022] [D] [zway]   ( 01 15 FF 05 )
[2015-11-13 04:41:35.023] [I] [zway] Job 0xf5 (Get or set Z-Wave.Me firmware capabilities): Done
[2015-11-13 04:41:35.023] [D] [zway] Job 0xf5 (Get or set Z-Wave.Me firmware capabilities): success
[2015-11-13 04:41:35.023] [I] [zway] Removing job: Get or set Z-Wave.Me firmware capabilities
[2015-11-13 04:41:35.023] [D] [zway] SENDING: ( 01 05 00 06 0A 0A FC )
[2015-11-13 04:41:35.030] [D] [zway] RECEIVED ACK
[2015-11-13 04:41:35.031] [D] [zway] RECEIVED: ( 01 05 01 06 96 0F 64 )
[2015-11-13 04:41:35.031] [D] [zway] SENT ACK
[2015-11-13 04:41:35.032] [I] [zway] Job 0x06 (Set Serial API timeouts): Old timeouts are: ACK timeout 1500 ms and Byte timeout 150 ms
[2015-11-13 04:41:35.032] [D] [zway] SETDATA controller.data.oldSerialAPIAckTimeout10ms = 150 (0x00000096)
[2015-11-13 04:41:35.032] [D] [zway] SETDATA controller.data.oldSerialAPIByteTimeout10ms = 15 (0x0000000f)
[2015-11-13 04:41:35.032] [D] [zway] SETDATA controller.data.curSerialAPIAckTimeout10ms = 10 (0x0000000a)
[2015-11-13 04:41:35.033] [D] [zway] SETDATA controller.data.curSerialAPIByteTimeout10ms = 10 (0x0000000a)
[2015-11-13 04:41:35.033] [D] [zway] Job 0x06 (Set Serial API timeouts): success
[2015-11-13 04:41:35.033] [I] [zway] Removing job: Set Serial API timeouts
[2015-11-13 04:41:35.034] [D] [zway] SENDING: ( 01 04 00 17 00 EC )
[2015-11-13 04:41:35.040] [D] [zway] RECEIVED ACK
[2015-11-13 04:41:35.041] [D] [zway] RECEIVED: ( 01 04 01 17 00 ED )
[2015-11-13 04:41:35.041] [D] [zway] SENT ACK
[2015-11-13 04:41:35.042] [I] [zway] Job 0x17 (Set RF power level): Done
[2015-11-13 04:41:35.042] [D] [zway] Job 0x17 (Set RF power level): success
[2015-11-13 04:41:35.043] [I] [zway] Removing job: Set RF power level
[2015-11-13 04:41:35.043] [D] [zway] SENDING: ( 01 03 00 D2 2E )
[2015-11-13 04:41:35.050] [D] [zway] RECEIVED ACK
[2015-11-13 04:41:35.051] [I] [zway] Removing job: WatchDog Start
[2015-11-13 04:41:35.061] [D] [zway] SENDING: ( 01 04 00 F2 FF F6 )
[2015-11-13 04:41:35.064] [D] [zway] RECEIVED ACK
[2015-11-13 04:41:35.064] [D] [zway] RECEIVED: ( 01 04 01 F2 00 08 )
[2015-11-13 04:41:35.065] [D] [zway] SENT ACK
[2015-11-13 04:41:35.066] [D] [zway] SETDATA controller.data.frequency = "EU"
[2015-11-13 04:41:35.066] [I] [zway] Job 0xf2 (Change/Get Z-Wave.Me firmware frequency): Done
[2015-11-13 04:41:35.067] [D] [zway] Job 0xf2 (Change/Get Z-Wave.Me firmware frequency): success
[2015-11-13 04:41:35.068] [I] [zway] Removing job: Change/Get Z-Wave.Me firmware frequency
[2015-11-13 04:41:35.068] [D] [zway] SENDING: ( 01 03 00 20 DC )
[2015-11-13 04:41:35.072] [D] [zway] RECEIVED ACK
[2015-11-13 04:41:35.073] [D] [zway] RECEIVED: ( 01 08 01 20 CA D8 72 0C 01 BB )
[2015-11-13 04:41:35.074] [D] [zway] SENT ACK
[2015-11-13 04:41:35.074] [D] [zway] SETDATA controller.data.nodeId = 1 (0x00000001)
[2015-11-13 04:41:35.074] [D] [zway] SETDATA controller.data.homeId = -891784692 (0xcad8720c)
[2015-11-13 04:41:35.075] [I] [zway] Job 0x20 (Get home id and controller node id): Home id: 0xcad8720c, controller node id: 1
[2015-11-13 04:41:35.075] [D] [zway] Job 0x20 (Get home id and controller node id): success
[2015-11-13 04:41:35.075] [I] [zway] Adding job: Get controller capabilities
[2015-11-13 04:41:35.076] [I] [zway] Adding job: Get controller version
[2015-11-13 04:41:35.076] [I] [zway] Adding job: Get SUC node id
[2015-11-13 04:41:35.076] [I] [zway] Adding job: Get initial data about network devices
[2015-11-13 04:41:35.076] [I] [zway] Removing job: Get home id and controller node id
[2015-11-13 04:41:35.077] [D] [zway] SENDING: ( 01 03 00 05 F9 )
[2015-11-13 04:41:35.082] [D] [zway] RECEIVED ACK
[2015-11-13 04:41:35.083] [D] [zway] RECEIVED: ( 01 04 01 05 28 D7 )
[2015-11-13 04:41:35.083] [D] [zway] SENT ACK
[2015-11-13 04:41:35.084] [D] [zway] SETDATA controller.data.isPrimary = True
[2015-11-13 04:41:35.084] [D] [zway] SETDATA controller.data.isInOthersNetwork = False
[2015-11-13 04:41:35.084] [D] [zway] SETDATA controller.data.SISPresent = False
[2015-11-13 04:41:35.084] [D] [zway] SETDATA controller.data.isRealPrimary = True
[2015-11-13 04:41:35.085] [D] [zway] SETDATA controller.data.isSUC = False
[2015-11-13 04:41:35.085] [D] [zway] Job 0x05 (Get controller capabilities): success
[2015-11-13 04:41:35.085] [I] [zway] Removing job: Get controller capabilities
[2015-11-13 04:41:35.086] [D] [zway] SENDING: ( 01 03 00 15 E9 )
[2015-11-13 04:41:35.092] [D] [zway] RECEIVED ACK
[2015-11-13 04:41:35.093] [D] [zway] RECEIVED: ( 01 10 01 15 5A 2D 57 61 76 65 20 34 2E 30 35 00 01 97 )
[2015-11-13 04:41:35.093] [D] [zway] SENT ACK
[2015-11-13 04:41:35.097] [D] [zway] SETDATA controller.data.SDK = ""
[2015-11-13 04:41:35.097] [D] [zway] SETDATA controller.data.ZWlibMajor = 4 (0x00000004)
[2015-11-13 04:41:35.097] [D] [zway] SETDATA controller.data.ZWlibMinor = 5 (0x00000005)
[2015-11-13 04:41:35.098] [D] [zway] SETDATA controller.data.ZWLib = 1 (0x00000001)
[2015-11-13 04:41:35.098] [D] [zway] SETDATA controller.data.libType = "Static Controller"
[2015-11-13 04:41:35.098] [D] [zway] Job 0x15 (Get controller version): success
[2015-11-13 04:41:35.099] [I] [zway] Removing job: Get controller version
[2015-11-13 04:41:35.099] [D] [zway] SENDING: ( 01 03 00 56 AA )
[2015-11-13 04:41:35.103] [D] [zway] RECEIVED ACK
[2015-11-13 04:41:35.103] [D] [zway] RECEIVED: ( 01 04 01 56 00 AC )
[2015-11-13 04:41:35.104] [D] [zway] SENT ACK
[2015-11-13 04:41:35.104] [I] [zway] Job 0x56 (Get SUC node id): No SUC
[2015-11-13 04:41:35.105] [D] [zway] SETDATA controller.data.SUCNodeId = 0 (0x00000000)
[2015-11-13 04:41:35.105] [D] [zway] Job 0x56 (Get SUC node id): success
[2015-11-13 04:41:35.105] [I] [zway] Removing job: Get SUC node id
[2015-11-13 04:41:35.105] [D] [zway] SENDING: ( 01 03 00 02 FE )
[2015-11-13 04:41:35.112] [D] [zway] RECEIVED ACK
[2015-11-13 04:41:35.200] [D] [zway] RECEIVED: ( 01 25 01 02 05 00 1D 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 05 00 C5 )
[2015-11-13 04:41:35.201] [D] [zway] SENT ACK
[2015-11-13 04:41:35.202] [D] [zway] SETDATA controller.data.ZWVersion = 5 (0x00000005)
[2015-11-13 04:41:35.202] [D] [zway] SETDATA controller.data.ZWaveChip = "ZW0500"
[2015-11-13 04:41:35.202] [D] [zway] Capabilities:
[2015-11-13 04:41:35.202] [D] [zway] - Controller API
[2015-11-13 04:41:35.203] [D] [zway] - Timer function not supported
[2015-11-13 04:41:35.203] [D] [zway] - Primary Controller
[2015-11-13 04:41:35.203] [D] [zway] Detected Devices:
[2015-11-13 04:41:35.203] [D] [zway] -----------------

[sdh]

I upgraded to RC15 Internal and the SDK Version popped up now, see log.

Code: Select all

2015-11-13 05:33:27.388] [I] [zway] Adding job: Get SUC node id
[2015-11-13 05:33:27.388] [I] [zway] Adding job: Get initial data about network devices
[2015-11-13 05:33:27.388] [I] [zway] Removing job: Get home id and controller node id
[2015-11-13 05:33:27.389] [D] [zway] SENDING: ( 01 03 00 05 F9 )
[2015-11-13 05:33:27.394] [D] [zway] RECEIVED ACK
[2015-11-13 05:33:27.395] [D] [zway] RECEIVED: ( 01 04 01 05 08 F7 )
[2015-11-13 05:33:27.395] [D] [zway] SENT ACK
[2015-11-13 05:33:27.396] [D] [zway] SETDATA controller.data.isPrimary = True
[2015-11-13 05:33:27.396] [D] [zway] SETDATA controller.data.isInOthersNetwork = False
[2015-11-13 05:33:27.396] [D] [zway] SETDATA controller.data.SISPresent = False
[2015-11-13 05:33:27.397] [D] [zway] SETDATA controller.data.isRealPrimary = True
[2015-11-13 05:33:27.397] [D] [zway] SETDATA controller.data.isSUC = False
[2015-11-13 05:33:27.397] [D] [zway] Job 0x05 (Get controller capabilities): success
[2015-11-13 05:33:27.397] [I] [zway] Removing job: Get controller capabilities
[2015-11-13 05:33:27.398] [D] [zway] SENDING: ( 01 03 00 15 E9 )
[2015-11-13 05:33:27.405] [D] [zway] RECEIVED ACK
[2015-11-13 05:33:27.405] [D] [zway] RECEIVED: ( 01 10 01 15 5A 2D 57 61 76 65 20 34 2E 30 35 00 01 97 )
[2015-11-13 05:33:27.405] [D] [zway] SENT ACK
[2015-11-13 05:33:27.409] [D] [zway] SETDATA controller.data.SDK = "6.51.06"
[2015-11-13 05:33:27.409] [D] [zway] SETDATA controller.data.ZWlibMajor = 4 (0x00000004)
[2015-11-13 05:33:27.410] [D] [zway] SETDATA controller.data.ZWlibMinor = 5 (0x00000005)
[2015-11-13 05:33:27.410] [D] [zway] SETDATA controller.data.ZWLib = 1 (0x00000001)
[2015-11-13 05:33:27.410] [D] [zway] SETDATA controller.data.libType = "Static Controller"
[2015-11-13 05:33:27.410] [D] [zway] Job 0x15 (Get controller version): success
[2015-11-13 05:33:27.410] [I] [zway] Removing job: Get controller version
[2015-11-13 05:33:27.411] [D] [zway] SENDING: ( 01 03 00 56 AA )
[2015-11-13 05:33:27.415] [D] [zway] RECEIVED ACK
[2015-11-13 05:33:27.415] [D] [zway] RECEIVED: ( 01 04 01 56 00 AC )
[2015-11-13 05:33:27.415] [D] [zway] SENT ACK
[2015-11-13 05:33:27.416] [I] [zway] Job 0x56 (Get SUC node id): No SUC

The restore worked after this.

[PoltoS]

Youp, with 05.04 and 2.1.2-rc15 and upper restore function is finally fixed.