Cynical Denon - can't stay connected

Hi Perry,

I have an AVR-1913 and the plug-in is able to communicate nicely with it. However, some hours after the initial connection, it loses connectivity and gets stuck in the 'connecting' phase.

I turned on debugging and saw that it correctly interrogates all the usual values. Everything that the receiver sends back seems to match the protocol (at casual glance).

But I do see that the debug log gets flooded with this type of entry:

Code: Select all

2013-07-04 22:25:27   Cynical Denon   -> MV?
2013-07-04 22:25:27   Cynical Denon   <- <Ctx:<attr:volume>> 26
2013-07-04 22:25:27   Cynical Denon   <- <Ctx:<attr:max_volume>> 865
2013-07-04 22:25:27   Cynical Denon   <- <Ctx:<attr:max_volume>> 865
2013-07-04 22:25:27   Cynical Denon   <- <Ctx:<attr:max_volume>> 865
2013-07-04 22:25:27   Cynical Denon   <- <Ctx:<attr:max_volume>> 865
2013-07-04 22:25:27   Cynical Denon   <- <Ctx:<attr:max_volume>> 865
2013-07-04 22:25:27   Cynical Denon   <- <Ctx:<attr:max_volume>> 865
2013-07-04 22:25:27   Cynical Denon   <- <Ctx:<attr:max_volume>> 865
2013-07-04 22:25:27   Cynical Denon   <- <Ctx:<attr:max_volume>> 865
2013-07-04 22:25:27   Cynical Denon   <- <Ctx:<attr:max_volume>> 865
2013-07-04 22:25:27   Cynical Denon   <- <Ctx:<attr:max_volume>> 865
2013-07-04 22:25:27   Cynical Denon   <- <Ctx:<attr:max_volume>> 865
2013-07-04 22:25:27   Cynical Denon   <- <Ctx:<attr:max_volume>> 865
2013-07-04 22:25:27   Cynical Denon   <- <Ctx:<attr:max_volume>> 865
2013-07-04 22:25:27   Cynical Denon   <- <Ctx:<attr:max_volume>> 865
2013-07-04 22:25:27   Cynical Denon   <- <Ctx:<attr:max_volume>> 865
2013-07-04 22:25:27   Cynical Denon   <- <Ctx:<attr:max_volume>> 865
2013-07-04 22:25:28   Cynical Denon   <- <Ctx:<attr:max_volume>> 865


This goes on for 8769 lines, and stopped when I disabled the plug in.

Is the receiver really send back this property, or is there a bug in the debug output?

And, more importantly, does this provide any clue why the receiver can't stay connected for more than a couple hours? If I uncheck the 'Enable communication' on the indigo device, it is always able to immediately reconnect back to 'standby' status, for another few hours.

The max_volume attribute is something the Denons like to send quite frequently... but only once per query. I've never seen a receiver jam a reply like that. Does it happen again when you turn the plugin and device on?

What model receiver is this? Does it otherwise seem to function fine?

Obviously, if this happens, the plugin will not work with the receiver. It shouldn't drop the connection as such, but I can imagine it overloading the TCP channel eventually (or pop a gasket in your router), leading to a connection drop.

If you can reproduce this, I'd like to see a bit more context from the log - ideally from when you reload the plugin. (I understand that may be too much if it takes hours to manifest.)

Cheers
-- perry

My Denon has been losing connection pretty frequently lately too, but I haven't had the time to look into it. It always works fine after reloading the plugin so I'll check to see if it's the same issue later today.

Ok, I managed to capture the entire life cycle I hope.

Here is the startup sequence from the log. It is able to connect the first time, and then loses connectivity after 10 minutes. Or, probably more correctly, it notices that it has lost connectivity when the plugin does its keep-alive at 10 minutes. When trying to regain, it's not able to.

Code: Select all

2013-07-15 20:43:35   Application   Enabling plugin "Cynical Denon 1.5.1"
2013-07-15 20:43:35   Application   Starting plugin "Cynical Denon 1.5.1" (pid 63628)
2013-07-15 20:43:35   Cynical Denon   module denon.avr enabled debugging
2013-07-15 20:43:35   Application   Started plugin "Cynical Denon 1.5.1"
2013-07-15 20:43:35   Cynical Denon   mapping device "Denon AVR-1913" 472727163(avreceiver)
2013-07-15 20:43:35   Cynical Denon   Denon AVR-1913 starting
2013-07-15 20:43:35   Cynical Denon   Denon AVR-1913 connecting to denon-avr-1913.local
   // You'll notice that today I was using the Bonjour address, but that was just one of many attempts to get it to behave. Normally, I use its static IP address.
2013-07-15 20:43:35   Cynical Denon   plugin starting asyn operation
2013-07-15 20:43:35   Cynical Denon   Denon AVR-1913 is now exploring
2013-07-15 20:43:35   Cynical Denon   -> TFHD?
2013-07-15 20:43:35   Cynical Denon   Denon AVR-1913 enabling idle probes
2013-07-15 20:43:35   Cynical Denon   -> TFAN?
2013-07-15 20:43:35   Cynical Denon   -> Z2?
2013-07-15 20:43:36   Cynical Denon   -> Z3?
2013-07-15 20:43:36   Cynical Denon   -> Z4?
2013-07-15 20:43:36   Cynical Denon   -> Z5?
2013-07-15 20:43:36   Cynical Denon   -> Z6?
2013-07-15 20:43:36   Cynical Denon   -> Z7?
2013-07-15 20:43:36   Cynical Denon   -> Z8?
2013-07-15 20:43:36   Cynical Denon   -> Z9?
2013-07-15 20:43:36   Cynical Denon   -> ZM?
2013-07-15 20:43:36   Cynical Denon   -> MU?
2013-07-15 20:43:36   Cynical Denon   -> MV?
2013-07-15 20:43:37   Cynical Denon   -> SI?
2013-07-15 20:43:37   Cynical Denon   -> SV?
2013-07-15 20:43:37   Cynical Denon   -> TMHD?
2013-07-15 20:43:37   Cynical Denon   -> SR?
2013-07-15 20:43:37   Cynical Denon   -> PSFRONT?
2013-07-15 20:43:37   Cynical Denon   -> HD?
2013-07-15 20:43:37   Cynical Denon   -> PSTRE ?
2013-07-15 20:43:37   Cynical Denon   -> VSAUDIO ?
2013-07-15 20:43:37   Cynical Denon   -> TFHD?
2013-07-15 20:43:37   Cynical Denon   -> VSMONI ?
2013-07-15 20:43:37   Cynical Denon   -> TFHD?
2013-07-15 20:43:37   Cynical Denon   -> PSDYNEQ ?
2013-07-15 20:43:37   Cynical Denon   -> TPHD?
2013-07-15 20:43:37   Cynical Denon   -> PSCINEMA EQ. ?
2013-07-15 20:43:37   Cynical Denon   -> PSMODE: ?
2013-07-15 20:43:37   Cynical Denon   -> PSTONE CTRL ?
2013-07-15 20:43:37   Cynical Denon   -> PSDCO ?
2013-07-15 20:43:37   Cynical Denon   -> PSDYNVOL ?
2013-07-15 20:43:37   Cynical Denon   -> PSREFLEV ?
2013-07-15 20:43:37   Cynical Denon   -> PSRSTR ?
2013-07-15 20:43:37   Cynical Denon   -> PSBAS ?
2013-07-15 20:43:37   Cynical Denon   -> SD?
2013-07-15 20:43:37   Cynical Denon   -> VSVPM ?
2013-07-15 20:43:37   Cynical Denon   -> VSASP ?
2013-07-15 20:43:37   Cynical Denon   -> TMHD?
2013-07-15 20:43:37   Cynical Denon   -> VSSCH ?
2013-07-15 20:43:37   Cynical Denon   -> PW?
2013-07-15 20:43:37   Cynical Denon   -> VSSC ?
2013-07-15 20:43:37   Cynical Denon   -> MS?
2013-07-15 20:43:37   Cynical Denon   -> PSMULTEQ: ?
2013-07-15 20:43:37   Cynical Denon   -> PSDRC ?
2013-07-15 20:43:37   Cynical Denon   -> MSQUICK ?
2013-07-15 20:43:37   Cynical Denon   -> DC?
2013-07-15 20:43:37   Cynical Denon   -> CV?
2013-07-15 20:43:37   Cynical Denon   zones M
2013-07-15 20:43:37   Cynical Denon   Denon AVR-1913 is now standby

2013-07-15 20:46:35   Z-Wave   received "Bedroom Thermostat" temperature update to 27 °C
2013-07-15 20:50:04   Z-Wave   received "006 - Stairs Motion Sensor" status update battery level 91%

2013-07-15 20:53:35   Cynical Denon   -> MV?
2013-07-15 20:53:55   Cynical Denon Error   Denon AVR-1913 unavailable: no response from device
2013-07-15 20:53:55   Cynical Denon   Denon AVR-1913 connecting to denon-avr-1913.local
2013-07-15 21:00:00   Schedule   Evening off


Now, I toggled the Comm Enable, and it was able to reconnect without an issue.

Code: Select all

2013-07-15 21:24:22   Cynical Denon   Denon AVR-1913 stopping
2013-07-15 21:24:23   Cynical Denon   Denon AVR-1913 starting
2013-07-15 21:24:23   Cynical Denon   Denon AVR-1913 reset
2013-07-15 21:24:24   Cynical Denon   Denon AVR-1913 connecting to denon-avr-1913.local
2013-07-15 21:24:24   Cynical Denon   Denon AVR-1913 is now exploring
2013-07-15 21:24:24   Cynical Denon   -> TFHD?
2013-07-15 21:24:24   Cynical Denon   Denon AVR-1913 enabling idle probes
2013-07-15 21:24:24   Cynical Denon   -> TFAN?
2013-07-15 21:24:24   Cynical Denon   -> Z2?
2013-07-15 21:24:24   Cynical Denon   -> Z3?
2013-07-15 21:24:24   Cynical Denon   -> Z4?
2013-07-15 21:24:24   Cynical Denon   -> Z5?
2013-07-15 21:24:25   Cynical Denon   -> Z6?
2013-07-15 21:24:25   Cynical Denon   -> Z7?
2013-07-15 21:24:25   Cynical Denon   -> Z8?
2013-07-15 21:24:25   Cynical Denon   -> Z9?
2013-07-15 21:24:25   Cynical Denon   -> ZM?
2013-07-15 21:24:25   Cynical Denon   -> MU?
2013-07-15 21:24:25   Cynical Denon   -> MV?
2013-07-15 21:24:25   Cynical Denon   -> SI?
2013-07-15 21:24:25   Cynical Denon   -> SV?
2013-07-15 21:24:25   Cynical Denon   -> TMHD?
2013-07-15 21:24:25   Cynical Denon   -> SR?
2013-07-15 21:24:25   Cynical Denon   -> PSFRONT?
2013-07-15 21:24:25   Cynical Denon   -> HD?
2013-07-15 21:24:25   Cynical Denon   -> PSTRE ?
2013-07-15 21:24:25   Cynical Denon   -> VSAUDIO ?
2013-07-15 21:24:25   Cynical Denon   -> TFHD?
2013-07-15 21:24:25   Cynical Denon   -> VSMONI ?
2013-07-15 21:24:25   Cynical Denon   -> TFHD?
2013-07-15 21:24:25   Cynical Denon   -> PSDYNEQ ?
2013-07-15 21:24:25   Cynical Denon   -> TPHD?
2013-07-15 21:24:25   Cynical Denon   -> PSCINEMA EQ. ?
2013-07-15 21:24:25   Cynical Denon   -> PSMODE: ?
2013-07-15 21:24:25   Cynical Denon   -> PSTONE CTRL ?
2013-07-15 21:24:25   Cynical Denon   -> PSDCO ?
2013-07-15 21:24:25   Cynical Denon   -> PSDYNVOL ?
2013-07-15 21:24:25   Cynical Denon   -> PSREFLEV ?
2013-07-15 21:24:26   Cynical Denon   -> PSRSTR ?
2013-07-15 21:24:26   Cynical Denon   -> PSBAS ?
2013-07-15 21:24:26   Cynical Denon   -> SD?
2013-07-15 21:24:26   Cynical Denon   -> VSVPM ?
2013-07-15 21:24:26   Cynical Denon   -> VSASP ?
2013-07-15 21:24:26   Cynical Denon   -> TMHD?
2013-07-15 21:24:26   Cynical Denon   -> VSSCH ?
2013-07-15 21:24:26   Cynical Denon   -> PW?
2013-07-15 21:24:26   Cynical Denon   -> VSSC ?
2013-07-15 21:24:26   Cynical Denon   -> MS?
2013-07-15 21:24:26   Cynical Denon   -> PSMULTEQ: ?
2013-07-15 21:24:26   Cynical Denon   -> PSDRC ?
2013-07-15 21:24:26   Cynical Denon   -> MSQUICK ?
2013-07-15 21:24:26   Cynical Denon   -> DC?
2013-07-15 21:24:26   Cynical Denon   -> CV?
2013-07-15 21:24:26   Cynical Denon   zones M
2013-07-15 21:24:26   Cynical Denon   Denon AVR-1913 is now standby


And then I manually turned it on, adjusted volume, mute on/off, power off, just so you can see the traffic.

Code: Select all

2013-07-15 21:27:47   Cynical Denon   <- <Ctx:<attr:channel_volume>> FL 44
2013-07-15 21:27:47   Cynical Denon   <- <Ctx:<attr:channel_volume>> FR 45
2013-07-15 21:27:47   Cynical Denon   <- <Ctx:<attr:channel_volume>> C 485
2013-07-15 21:27:47   Cynical Denon   <- <Ctx:<attr:channel_volume>> SW 48
2013-07-15 21:27:47   Cynical Denon   <- <Ctx:<attr:channel_volume>> SL 435
2013-07-15 21:27:47   Cynical Denon   <- <Ctx:<attr:channel_volume>> SR 435
2013-07-15 21:27:47   Cynical Denon   <- <Ctx:<attr:channel_volume>> SBL 50
2013-07-15 21:27:47   Cynical Denon   <- <Ctx:<attr:channel_volume>> SBR 50
2013-07-15 21:27:47   Cynical Denon   <- <Ctx:<attr:channel_volume>> SB 50
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:<attr:channel_volume>> FHL 50
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:<attr:channel_volume>> FHR 50
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:<attr:surround>> DOLBY PL2 C
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:<attr:audio_restorer>> OFF
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:<attr:max_volume>> 98
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:<attr:surround_mode>> CINEMA
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:<attr:digital_input_mode>> AUTO
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:<attr:resolution>> AUTO
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:<attr:hdmi_resolution>> AUTO
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:<attr:video_aspect>> FUL
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:<attr:video_processing_mode>> AUTO
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:<attr:mult_eq>> AUDYSSEY
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:<attr:dynamic_eq>> ON
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:unknown> PSDYNVOL MED
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:<attr:reference_level>> 0
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:<attr:dcomp>> OFF
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:<attr:drc>> AUTO
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:unknown> PSLFE 00
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:<attr:base>> 50
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:<attr:treble>> 50
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:<attr:cinema_eq>> OFF
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:<attr:tone_control>> OFF
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:<attr:tuner_frequency>> AN 008810
2013-07-15 21:27:48   Cynical Denon   <- <Ctx:<attr:power>> ON
2013-07-15 21:27:49   Cynical Denon   <- <Ctx:<attr:main_power>> ON
2013-07-15 21:27:49   Cynical Denon   Denon AVR-1913 is now powerup
2013-07-15 21:27:49   Cynical Denon   <- <Ctx:<attr:audio_output>> TV
2013-07-15 21:27:49   Cynical Denon   <- <Ctx:unknown> SSFRSDST SPA
2013-07-15 21:27:49   Cynical Denon   <- <Ctx:unknown> SSFRSMUL SPA
2013-07-15 21:27:49   Cynical Denon   <- <Ctx:<attr:front_speakers>> SPA
2013-07-15 21:27:49   Cynical Denon   <- <Ctx:<attr:quick_select>> 0
2013-07-15 21:27:56   Cynical Denon   <- <Ctx:<attr:main_power>> ON
2013-07-15 21:27:56   Cynical Denon   Denon AVR-1913 is now on
2013-07-15 21:28:03   Cynical Denon   <- <Ctx:<attr:volume>> 31
2013-07-15 21:28:03   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:28:05   Cynical Denon   <- <Ctx:<attr:volume>> 305
2013-07-15 21:28:05   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:28:05   Cynical Denon   <- <Ctx:<attr:volume>> 30
2013-07-15 21:28:05   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:28:10   Cynical Denon   <- <Ctx:<attr:volume>> 295
2013-07-15 21:28:10   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:28:10   Cynical Denon   <- <Ctx:<attr:volume>> 29
2013-07-15 21:28:10   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:28:11   Cynical Denon   <- <Ctx:<attr:volume>> 285
2013-07-15 21:28:11   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:28:16   Cynical Denon   <- <Ctx:<attr:muted>> ON
2013-07-15 21:28:19   Cynical Denon   <- <Ctx:<attr:muted>> OFF
2013-07-15 21:28:23   Cynical Denon   <- <Ctx:<attr:main_power>> STANDBY
2013-07-15 21:28:23   Cynical Denon   Denon AVR-1913 is now standby
2013-07-15 21:28:23   Cynical Denon   <- <Ctx:<attr:power>> OFF


And then, 10 minutes later... Instead of just not getting a response, the plugin says it's being swamped with responses.

Code: Select all

2013-07-15 21:38:23   Cynical Denon   -> MV?
2013-07-15 21:38:23   Cynical Denon   <- <Ctx:<attr:volume>> 285
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:24   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
2013-07-15 21:38:25   Cynical Denon   <- <Ctx:<attr:max_volume>> 765
...
// until i kill comm with the device


I didn't throw an analyzer on the line, so I don't know if the packets are really being communicated, or perhaps the plugin is just re-reading the last line of the buffer?

Regardless, I know you added the keep-alive to prevent the Denon from losing touch with reality, perhaps the 10 minute interval is too long?

When properly communicating, within that window, the plugin has done everything I've asked it to do, without any issues.

Something is not right here. In the first log batch, notice how there's this flurry of -> (outgoing) messages with no answers? That's not normal; there should be a flurry of <- replies containing answers. And sure enough, after the 10 minute timeout, Indigo sends an "are you there?" that gets no answer, leading to a reset. Note that this is not a network problem; it's simply the Denon not saying anything in response to commands.

Yet when you later frob the front controls, your Denon is happy to spout off about what's happening, so there's no problem with the actual data flow. It just wasn't anwering commands earlier.

Do Indigo-initiated actions actually work? If you execute an action to turn the receiver on or off, or change the volume, does it?

Have you tried a processor reset? (The procedure's in the manual.) If that doesn't help, consider a reset to factory settings.

Of course, I don't have an AVR-1913 here. I'm just going by the generic Denon documentation...

Cheers
-- perry

You're right, the comm is flaky. I was able to make it turn on and change source from Indigo, but it stopped accepting commands.

At this moment, I'm more interested in just determining whether the thing is on, and to which source it's set. So, to meet my immediate objectives, I just curl'd the built-in web interface and scraped the zone source and power status. Not elegant, but it will work for now.

I'll spend some more time at a later date and see if I can reset it and coerce it into behaving nicely. At the moment, I have to move on to phase 26 of my home automation, which involves calendar integration, using your iCal class to read all of the info on tomorrow's calendar.

Thanks for your help!

I'm not sure if the OP ever resolved this, but I finally had time to work on it last night and noticed that you had mentioned a microprocessor reset. I went ahead and did it and my problem seems to have been fixed! Thanks again Perry!

Well it seems I've spoken too soon - the plugin is still failing to connect to the Denon. Perry, have you gained any new insight on what might be going on here?

Has anyone solved this issue?
After I send a command, it doesn't respond anymore, or sometimes it responds 10 minutes later.

I've got the same problem with a Denon AVR X3000. Sometimes it stays connected but drops.

Keep alive enabled. Stay connected in Standby enabled on Denon AVR. Fixed IP.

Device state changes to 'Unavailable', but can be enabled by disabling and enabling Indigo communication.

I have made a trigger that works if the Device State changes to 'Unavailable', but does anyone know aw ay to script a action to disable and enable Indigo Communication on a Denon plugin device?

I've got the same problem with a Denon AVR X3000. Sometimes it stays connected but drops.

Keep alive enabled. Stay connected in Standby enabled on Denon AVR. Fixed IP.

Device state changes to 'Unavailable', but can be enabled by disabling and enabling Indigo communication.

Sorry to hear that. Care to turn on logging and include your Indigo log? Maybe I can figure it out. (Enter "denon.avr" into the "debug modules" field of the plugin configuration dialog, reload the plugin. Wait until the problem appears, and then send the entire Indigo log from when you reloaded to when it failed.)

I have made a trigger that works if the Device State changes to 'Unavailable', but does anyone know aw ay to script a action to disable and enable Indigo Communication on a Denon plugin device?

Server Actions, Enable/Disable Actions. Disable, then Enable the device.

Cheers
-- perry

Thanks so much Perry for the response. Really helpful. Have enabled logging.

Have also set a disconnect / reconnect action when it drops but added an iMessage script so I will get an alert to check the logs. Will report back.

Also, as others have said, thanks for making this plugin; and also particularly your network plugin - our system revolves around it.