Denon Binding - Unresponsive after sitting idle

I’m running the latest snapshot with the denon binding and a denon AVR-S720W. The issue is, after the unit sits idle for a stretch of time (approx. 12 hrs) it will not power on from the binding. I have to turn it on with the remote and then it will respond to all binding commands, including power on/off.

I’m also having the second zone issue as described here, not sure if that is related or not.

Please let me know if I can provide any more information.

Thanks.

Hi,

If you happen to have a denon receiver with Zone2, could you help me out by trying the binding I’m working on?
I only have Denon N8, which doesn’t have Zone 2, so cannot verify if it works or not.

So I installed the package and after adding the thing in the inbox, I got the following logs:

2017-09-12 18:53:01.304 [home.event.InboxAddedEvent] - Discovery Result with UID 'avreceiver:denon:5f9ec1b3_ff59_19bb_8530_0005cd914723' has been added.
==> /var/log/openhab2/openhab.log <==
2017-09-12 18:53:30.280 [WARN ] [io.rest.core.thing.ThingTypeResource] - Cannot find channel type: avreceiver:zone2_input_denon
2017-09-12 18:53:46.938 [WARN ] [re.thing.internal.ThingFactoryHelper] - Could not create channel 'input_denon' for thing type 'avreceiver:denon:5f9ec1b3_ff59_19bb_8530_0005cd914723', because channel type 'avreceiver:zone2_input_denon' could not be found.
==> /var/log/openhab2/events.log <==
2017-09-12 18:53:46.964 [me.event.InboxRemovedEvent] - Discovery Result with UID 'avreceiver:denon:5f9ec1b3_ff59_19bb_8530_0005cd914723' has been removed.
2017-09-12 18:53:46.996 [hingStatusInfoChangedEvent] - 'avreceiver:denon:5f9ec1b3_ff59_19bb_8530_0005cd914723' changed from UNINITIALIZED to INITIALIZING
2017-09-12 18:53:47.014 [hingStatusInfoChangedEvent] - 'avreceiver:denon:5f9ec1b3_ff59_19bb_8530_0005cd914723' changed from INITIALIZING to ONLINE
==> /var/log/openhab2/openhab.log <==
2017-09-12 18:53:59.363 [WARN ] [io.rest.core.thing.ThingTypeResource] - Cannot find channel type: avreceiver:zone2_input_denon
2017-09-12 18:56:01.926 [WARN ] [io.rest.core.thing.ThingTypeResource] - Cannot find channel type: avreceiver:zone2_input_denon

It seemed to be continuing to output that last line until I updated my items. I don’t know if it fixed my power issue because I initially typoed the item. I’ll test that in the morning. After I refresh the items, I get the following logs:

2017-09-12 19:08:16.464 [INFO ] [avreceiver.handler.comm.SocketReader] - Reader stopped
==> /var/log/openhab2/events.log <==
2017-09-12 19:08:16.475 [hingStatusInfoChangedEvent] - 'avreceiver:denon:5f9ec1b3_ff59_19bb_8530_0005cd914723' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Connection Closed
2017-09-12 19:08:16.569 [hingStatusInfoChangedEvent] - 'avreceiver:denon:5f9ec1b3_ff59_19bb_8530_0005cd914723' changed from OFFLINE (COMMUNICATION_ERROR): Connection Closed to ONLINE
2017-09-12 19:08:17.628 [hingStatusInfoChangedEvent] - 'avreceiver:denon:5f9ec1b3_ff59_19bb_8530_0005cd914723' changed from ONLINE to ONLINE
2017-09-12 19:08:24.892 [ome.event.ItemCommandEvent] - Item 'DenonPower' received command ON
2017-09-12 19:08:24.900 [vent.ItemStateChangedEvent] - DenonPower changed from OFF to ON
2017-09-12 19:08:27.639 [ome.event.ItemCommandEvent] - Item 'Living_Hue_Dimmer' received command OFF
==> /var/log/openhab2/openhab.log <==
2017-09-12 19:08:30.210 [INFO ] [avreceiver.handler.AVReceiverHandler] - Received message: 'SSINFAISSIG 02', can't find suitable channel, you may need to implement override handleMessage() in your own AVReceiverHandler implementation to take care of this properly
==> /var/log/openhab2/events.log <==
2017-09-12 19:08:30.221 [hingStatusInfoChangedEvent] - 'avreceiver:denon:5f9ec1b3_ff59_19bb_8530_0005cd914723' changed from ONLINE to ONLINE: no handling for 'SSINFAISSIG 02'
==> /var/log/openhab2/openhab.log <==
2017-09-12 19:08:30.229 [INFO ] [avreceiver.handler.AVReceiverHandler] - Received message: 'SSINFAISFSV 48K', can't find suitable channel, you may need to implement override handleMessage() in your own AVReceiverHandler implementation to take care of this properly
==> /var/log/openhab2/events.log <==
2017-09-12 19:08:30.238 [hingStatusInfoChangedEvent] - 'avreceiver:denon:5f9ec1b3_ff59_19bb_8530_0005cd914723' changed from ONLINE: no handling for 'SSINFAISSIG 02' to ONLINE: no handling for 'SSINFAISFSV 48K'
2017-09-12 19:08:33.969 [hingStatusInfoChangedEvent] - 'avreceiver:denon:5f9ec1b3_ff59_19bb_8530_0005cd914723' changed from ONLINE: no handling for 'SSINFAISFSV 48K' to ONLINE

All of my previous functionality seems to be working (power, volume, mute). After one of the first commands I got this message repeated ~6 times:

==> /var/log/openhab2/openhab.log <==
2017-09-12 19:20:57.139 [INFO ] [avreceiver.handler.comm.SocketReader] - Reader stopped
==> /var/log/openhab2/events.log <==
2017-09-12 19:20:57.147 [hingStatusInfoChangedEvent] - 'avreceiver:denon:5f9ec1b3_ff59_19bb_8530_0005cd914723' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Connection Closed
2017-09-12 19:20:57.244 [hingStatusInfoChangedEvent] - 'avreceiver:denon:5f9ec1b3_ff59_19bb_8530_0005cd914723' changed from OFFLINE (COMMUNICATION_ERROR): Connection Closed to ONLINE

I added a switch for the Zone 2 Power and these are the logs:

2017-09-12 19:39:22.387 [vent.ItemStateChangedEvent] - DenonPower changed from ON to OFF
2017-09-12 19:39:22.393 [vent.ItemStateChangedEvent] - Zone2Power changed from ON to OFF
2017-09-12 19:39:22.401 [vent.ItemStateChangedEvent] - DenonPower changed from OFF to ON
2017-09-12 19:39:22.414 [vent.ItemStateChangedEvent] - Zone2Power changed from OFF to ON
==> /var/log/openhab2/openhab.log <==
2017-09-12 19:39:22.830 [INFO ] [avreceiver.handler.AVReceiverHandler] - Received message: 'SSINFAISSIG 02', can't find suitable channel, you may need to implement override handleMessage() in your own AVReceiverHandler implementation to take care of this properly
2017-09-12 19:39:22.834 [INFO ] [avreceiver.handler.AVReceiverHandler] - Received message: 'SSINFAISFSV 48K', can't find suitable channel, you may need to implement override handleMessage() in your own AVReceiverHandler implementation to take care of this properly
==> /var/log/openhab2/events.log <==
2017-09-12 19:39:22.836 [hingStatusInfoChangedEvent] - 'avreceiver:denon:5f9ec1b3_ff59_19bb_8530_0005cd914723' changed from ONLINE to ONLINE: no handling for 'SSINFAISSIG 02'
2017-09-12 19:39:22.841 [hingStatusInfoChangedEvent] - 'avreceiver:denon:5f9ec1b3_ff59_19bb_8530_0005cd914723' changed from ONLINE: no handling for 'SSINFAISSIG 02' to ONLINE: no handling for 'SSINFAISFSV 48K'
2017-09-12 19:39:44.530 [ome.event.ItemCommandEvent] - Item 'Zone2Power' received command OFF
2017-09-12 19:39:44.536 [vent.ItemStateChangedEvent] - Zone2Power changed from ON to OFF
2017-09-12 19:39:44.633 [hingStatusInfoChangedEvent] - 'avreceiver:denon:5f9ec1b3_ff59_19bb_8530_0005cd914723' changed from ONLINE: no handling for 'SSINFAISFSV 48K' to ONLINE
2017-09-12 19:39:50.071 [ome.event.ItemCommandEvent] - Item 'Zone2Power' received command ON
2017-09-12 19:39:50.075 [vent.ItemStateChangedEvent] - Zone2Power changed from OFF to ON

Again this appears to work correctly on the unit.

Let me know if I can provide any other info.

Thanks!

Thanks a bunch! There was an error in the thing definition regarding the zone2 input. Didn’t see any other really problematic messages in the logs.

The following means your receiver is sending these messages but there are no channels defined in xml or implemented handling. Every time the receiver sends something the binding doesn’t know how to intepret,it’s going to print these verbose messages, it’s a bit loud I know.
‘SSINFAISSIG 02’, can’t find suitable channel, you may need to implement override
’SSINFAISFSV 48K’, can’t find suitable channel, you may need to implement override

Couldn’t find these from the denon documents I was able to find online, the latter looks like a frequency info, but no idea what :slight_smile:

I did push another version of the binding to dropbox, feel free to give it a try

Works great! No more zone 2 errors. New unknown response though:

2017-09-13 19:32:44.116 [INFO ] [avreceiver.handler.AVReceiverHandler] - Received message: 'SVOFF'

Thanks for the fix!!

Nice to hear, looks like ‘SV’ (OFF) is a video select command, should have values like SVDVD/SVBD/SVTV…

Could be added if needed… There is a whole list of commands that haven’t been added to the definition yet, but if you feel like adding, feel free to key them in to the xml file and I can compile the new version for you. Maybe worth mentioning that some of the basic channel-type definitions are in a global channel.xml (power/master volume/mute/…) Only the very denon specific channel-types are in the denon.xml, like input selection values

One Denon AVR protocol spec
http://openrb.com/wp-content/uploads/2012/02/AVR3312CI_AVR3312_PROTOCOL_V7.6.0.pdf