Starting a few weeks ago, I would occasionally see one of my Denon receivers (I have four controlled by BLDenon) stop responding to events and device status changes. When I dig deeper, I see that the TCP connection between HS3 and the receiver was no longer ESTABLISHED to port 23. After several attempts to capture this in debug log (because it takes time to occur the logs would be many gigabytes), I have had to create a scheduled event which tests each receiver and then restarts the BLDenon plugin. (attached as denon-validate.vb.txt) Most of the time it is one receiver which is disconnected but sometimes several are disconnected.
In the BLDenon-Detailed.log I see a whole lot of 0x00 (ASCII null) characters. Typically 8164 nulls after every I/O operation.
4/16/2016 5:21:32 PM : v2.0.29.0 - Ping_Using_NetworkInformationPing(): pingOk --> True
4/16/2016 5:21:32 PM : v2.0.29.0 - Ping_Using_NetworkInformationPing() time finished --> 4/16/2016 5:21:32 PM
4/16/2016 5:21:32 PM : v2.0.29.0 - Ping_Using_NetworkInformationPing() finished
4/16/2016 5:21:32 PM : v2.0.29.0 - Check_Receiver_Connection(): pingOk --> True
4/16/2016 5:21:32 PM : v2.0.29.0 - Check_Receiver_Connection(): doConnect --> False
4/16/2016 5:21:32 PM : v2.0.29.0 - Check_Receiver_Connection(): doDisconnect --> False
4/16/2016 5:21:32 PM : v2.0.29.0 - Check_Receiver_Connection() finished
4/16/2016 5:21:32 PM : v2.0.29.0 - Get_Denon_Response() started
4/16/2016 5:21:32 PM : v2.0.29.0 - Get_Denon_Response(): aReceiver.ToString -->
Receiver:
---------
Number: 2
Name: Party Room & Creek Deck Denon
IpAddress: 192.168.1.211
Port: 23
PingFailuresForDisconnect: 3
PingFailureCount: 0
Connected: True
4/16/2016 5:21:32 PM : v2.0.29.0 - Get_Denon_Response(): isStatusCheck --> True
4/16/2016 5:21:32 PM : v2.0.29.0 - Get_Denon_Response(): denonResponseString --> Z2OFFZ2SOURCEZ240SVSOURCE<null><null><null><null>about 8164 of them
From the perspective of my events, when the receiver becomes disconnected, all calls to SendRawCommand start returning the same value (what appears to be the last response received before the disconnect). ie theResponse = hs.PluginFunction("BLDenon", "", "SendRawCommand", {denonID,"MV?"})
I have set the # ping for failure at one and three with no effect. Once the receiver gets disconnected, I have to restart the BLDenon plugin. I have rolled back to 2.0.28 and 2.0.27 with no effect. I did install windows updates as well as upgrade HS3 Pro to 3.0.0.280 recently.
The Denon models are 3312CI and X3100W's (all hardwired to same switch as HS3 host). I also use mostly the SendRawCommand in my events via the denon-raw.vb script also attached. ie Main("livingroomdenon|PSFRONT SPB,MSSTEREO") or Main("partyroomdenon|SSPAAMOD FRB,PSFRONT A+B") or Main("partyroomdenon|PWON,SITV,MV50,MSDOLBY DIGITAL,PSDSX ONW,VSVPMMOVI")
Has anyone else seen this problem? Any suggestions Blade? I can send you the large logs if you wish.
In the BLDenon-Detailed.log I see a whole lot of 0x00 (ASCII null) characters. Typically 8164 nulls after every I/O operation.
4/16/2016 5:21:32 PM : v2.0.29.0 - Ping_Using_NetworkInformationPing(): pingOk --> True
4/16/2016 5:21:32 PM : v2.0.29.0 - Ping_Using_NetworkInformationPing() time finished --> 4/16/2016 5:21:32 PM
4/16/2016 5:21:32 PM : v2.0.29.0 - Ping_Using_NetworkInformationPing() finished
4/16/2016 5:21:32 PM : v2.0.29.0 - Check_Receiver_Connection(): pingOk --> True
4/16/2016 5:21:32 PM : v2.0.29.0 - Check_Receiver_Connection(): doConnect --> False
4/16/2016 5:21:32 PM : v2.0.29.0 - Check_Receiver_Connection(): doDisconnect --> False
4/16/2016 5:21:32 PM : v2.0.29.0 - Check_Receiver_Connection() finished
4/16/2016 5:21:32 PM : v2.0.29.0 - Get_Denon_Response() started
4/16/2016 5:21:32 PM : v2.0.29.0 - Get_Denon_Response(): aReceiver.ToString -->
Receiver:
---------
Number: 2
Name: Party Room & Creek Deck Denon
IpAddress: 192.168.1.211
Port: 23
PingFailuresForDisconnect: 3
PingFailureCount: 0
Connected: True
4/16/2016 5:21:32 PM : v2.0.29.0 - Get_Denon_Response(): isStatusCheck --> True
4/16/2016 5:21:32 PM : v2.0.29.0 - Get_Denon_Response(): denonResponseString --> Z2OFFZ2SOURCEZ240SVSOURCE<null><null><null><null>about 8164 of them
From the perspective of my events, when the receiver becomes disconnected, all calls to SendRawCommand start returning the same value (what appears to be the last response received before the disconnect). ie theResponse = hs.PluginFunction("BLDenon", "", "SendRawCommand", {denonID,"MV?"})
I have set the # ping for failure at one and three with no effect. Once the receiver gets disconnected, I have to restart the BLDenon plugin. I have rolled back to 2.0.28 and 2.0.27 with no effect. I did install windows updates as well as upgrade HS3 Pro to 3.0.0.280 recently.
The Denon models are 3312CI and X3100W's (all hardwired to same switch as HS3 host). I also use mostly the SendRawCommand in my events via the denon-raw.vb script also attached. ie Main("livingroomdenon|PSFRONT SPB,MSSTEREO") or Main("partyroomdenon|SSPAAMOD FRB,PSFRONT A+B") or Main("partyroomdenon|PWON,SITV,MV50,MSDOLBY DIGITAL,PSDSX ONW,VSVPMMOVI")
Has anyone else seen this problem? Any suggestions Blade? I can send you the large logs if you wish.
Comment