Page 1 of 1

Notification: critical (z-wave): Cannot start Z-Wave binding: Error: Bad data - communication failed

Posted: 15 May 2018 11:23
by tallackn
Got a Pi 3B+ with a new RaZberry2 board. Got bluetooth disabled in config.txt to free up /dev/ttyAMA0 for the z-wave-server. All looks great.

Power on the Pi, go see the brief little dual LED on the R2 board so that I know it draws power from the GPIO, system boots, web services come up on http://localhost:8083. Sweet.

But, one pretty big problem. The daemon is not getting any serial responses back from the R2 board. :(

The /var/log/z-way-server.log shows this at the end.

Code: Select all

[2018-05-15 20:10:54.939] [D] [zway] Opened device: /dev/ttyAMA0
[2018-05-15 20:10:54.939] [D] [zway] Worker thread successfully created
[2018-05-15 20:10:54.939] [D] [zway] Worker thread entry point
[2018-05-15 20:10:54.940] [I] [zway] Adding job: Get controller info and supported function classes
[2018-05-15 20:10:54.949] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2018-05-15 20:10:55.151] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2018-05-15 20:10:55.151] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2018-05-15 20:10:55.353] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2018-05-15 20:10:55.353] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2018-05-15 20:10:55.554] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2018-05-15 20:10:55.554] [W] [zway] Job 0x07 (Get controller info and supported function classes) dropped: too many resends
[2018-05-15 20:10:55.554] [D] [zway] Job 0x07 (Get controller info and supported function classes): fail
[2018-05-15 20:10:55.554] [C] [zway] Get Serial API Capabilities returned zero.
[2018-05-15 20:10:55.554] [I] [zway] Removing job: Get controller info and supported function classes
[2018-05-15 20:10:55.564] [D] [zway] Worker thread exit point
[2018-05-15 20:10:55.565] [D] [zway] Worker thread successfully finished
[2018-05-15 20:10:55.575] [E] [zway] SaveData will not save data since it wasn't loaded. This is to prevent data loss.
[2018-05-15 20:10:55.593] [I] [core] Error: Bad data - communication failed
[2018-05-15 20:10:55.594] [I] [core] Tried 5 times without success. Stopping tries.
[2018-05-15 20:10:55.596] [I] [core] Notification: critical (z-wave): Cannot start Z-Wave binding: Error: Bad data - communication failed
I'm hoping it is not a faulty RaZberry2 board. How could I tell?

Re: Notification: critical (z-wave): Cannot start Z-Wave binding: Error: Bad data - communication failed

Posted: 15 May 2018 12:19
by tallackn
Restarting the z-way-server service sometimes produces different results in the /var/log/z-way-server.log where it complains about unknown packet type. I'm really thinking my board is faulty now. :(

Code: Select all

[2018-05-15 21:16:31.440] [D] [zway] Opened device: /dev/ttyAMA0
[2018-05-15 21:16:31.440] [D] [zway] Worker thread successfully created
[2018-05-15 21:16:31.440] [D] [zway] Worker thread entry point
[2018-05-15 21:16:31.441] [I] [zway] Adding job: Get controller info and supported function classes
[2018-05-15 21:16:31.451] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2018-05-15 21:16:31.652] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2018-05-15 21:16:31.652] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2018-05-15 21:16:31.653] [E] [zway] RECEIVED UNKNOWN PACKET TYPE: 0x04
[2018-05-15 21:16:31.653] [E] [zway] RECEIVED UNKNOWN PACKET TYPE: 0x10
[2018-05-15 21:16:31.653] [W] [zway] Received SOF, while awaiting ACK
[2018-05-15 21:16:31.653] [E] [zway] RECEIVED UNKNOWN PACKET TYPE: 0x04
[2018-05-15 21:16:31.653] [E] [zway] RECEIVED UNKNOWN PACKET TYPE: 0x10
[2018-05-15 21:16:31.653] [W] [zway] Received SOF, while awaiting ACK
[2018-05-15 21:16:31.654] [E] [zway] RECEIVED UNKNOWN PACKET TYPE: 0x04
[2018-05-15 21:16:31.654] [E] [zway] RECEIVED UNKNOWN PACKET TYPE: 0x10
[2018-05-15 21:16:31.654] [W] [zway] Received SOF, while awaiting ACK
[2018-05-15 21:16:31.654] [E] [zway] RECEIVED UNKNOWN PACKET TYPE: 0x04
[2018-05-15 21:16:31.654] [E] [zway] RECEIVED UNKNOWN PACKET TYPE: 0x10
[2018-05-15 21:16:31.654] [W] [zway] Received SOF, while awaiting ACK
[2018-05-15 21:16:32.259] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2018-05-15 21:16:32.259] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2018-05-15 21:16:32.461] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2018-05-15 21:16:32.461] [W] [zway] Job 0x07 (Get controller info and supported function classes) dropped: too many resends
[2018-05-15 21:16:32.461] [D] [zway] Job 0x07 (Get controller info and supported function classes): fail
[2018-05-15 21:16:32.462] [C] [zway] Get Serial API Capabilities returned zero.
[2018-05-15 21:16:32.462] [I] [zway] Removing job: Get controller info and supported function classes
[2018-05-15 21:16:32.472] [D] [zway] Worker thread exit point
[2018-05-15 21:16:32.472] [D] [zway] Worker thread successfully finished
[2018-05-15 21:16:32.484] [E] [zway] SaveData will not save data since it wasn't loaded. This is to prevent data loss.
[2018-05-15 21:16:32.518] [I] [core] Error: Bad data - communication failed
[2018-05-15 21:16:32.520] [I] [core] Tried 5 times without success. Stopping tries.
[2018-05-15 21:16:32.525] [I] [core] Notification: critical (z-wave): Cannot start Z-Wave binding: Error: Bad data - communication failed

Re: Notification: critical (z-wave): Cannot start Z-Wave binding: Error: Bad data - communication failed

Posted: 15 May 2018 14:36
by tallackn
Now I have a real suspicion it has something to do with the Pi 3's and the UART performance. Too much noise in google search results to be sure, but enough doubt to think maybe the board is not faulty.

I know for sure nothing else is hooking to /dev/ttyAMA0 because I can see the z-way-servers using it with with lsof as it is talking to the UART.

So I am sure of 2 things:
1: The software is configured correctly and successfully accessing the serial interface.
2: The hardware is put together correctly with a nice high amperage USB power supply and the R2 is plugged correctly into the 1-10 GPIO ports.

:(

Re: Notification: critical (z-wave): Cannot start Z-Wave binding: Error: Bad data - communication failed

Posted: 15 Jan 2019 17:36
by jonas.holmberg
Did you ever get this resolved? I got the same problem.

It was working fine at first, and after a reboot I couldn't get it to start.

Code: Select all

[2019-01-14 18:58:15.676] [D] [zway] Opened device: /dev/ttyAMA0
[2019-01-14 18:58:15.677] [D] [zway] Worker thread successfully created
[2019-01-14 18:58:15.677] [D] [zway] Worker thread entry point
[2019-01-14 18:58:15.684] [I] [zway] Adding job: Get controller info and supported function classes
[2019-01-14 18:58:15.687] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2019-01-14 18:58:15.888] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2019-01-14 18:58:15.888] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2019-01-14 18:58:16.093] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2019-01-14 18:58:16.093] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2019-01-14 18:58:16.094] [E] [zway] RECEIVED UNKNOWN PACKET TYPE: 0x04
[2019-01-14 18:58:16.094] [E] [zway] RECEIVED UNKNOWN PACKET TYPE: 0x0e
[2019-01-14 18:58:16.094] [E] [zway] RECEIVED UNKNOWN PACKET TYPE: 0x04
[2019-01-14 18:58:16.094] [W] [zway] Received SOF, while awaiting ACK
[2019-01-14 18:58:16.407] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2019-01-14 18:58:16.408] [W] [zway] Job 0x07 (Get controller info and supported function classes) dropped: too many resends
[2019-01-14 18:58:16.408] [D] [zway] Job 0x07 (Get controller info and supported function classes): fail
[2019-01-14 18:58:16.408] [C] [zway] Get Serial API Capabilities returned zero.
[2019-01-14 18:58:16.408] [I] [zway] Removing job: Get controller info and supported function classes
[2019-01-14 18:58:16.418] [D] [zway] Worker thread exit point
[2019-01-14 18:58:16.418] [D] [zway] Worker thread successfully finished
[2019-01-14 18:58:16.422] [I] [core] Terminating Z-Wave binding

Re: Notification: critical (z-wave): Cannot start Z-Wave binding: Error: Bad data - communication failed

Posted: 15 Jan 2019 23:12
by tallackn
Nope. :(

Re: Notification: critical (z-wave): Cannot start Z-Wave binding: Error: Bad data - communication failed

Posted: 16 Jan 2019 09:07
by PoltoS
May be the board is faulty? Does a full powercycle help?

Re: Notification: critical (z-wave): Cannot start Z-Wave binding: Error: Bad data - communication failed

Posted: 17 Jan 2019 22:00
by perf
Hey guys,
it seems I have quite a similar problem - same issue, but after firmware update.
Described all the situation in separate thread:
http://forum.z-wave.me/viewtopic.php?f=3422&t=31029