Sharp Aquos TV Driver

[albertsmark]

This is what I see every 5 min, and then I hit volume up a couple times but things still don't work...

Summary

dev:9932019-12-16 01:20:47.448 am errorSharpAquos - request - ERROR. Request [VOLM] [?] dropped. Plugin initializing.

dev:9932019-12-16 01:20:47.444 am errorSharpAquos - request - ERROR. Request [RCKY] [33] dropped. Plugin initializing.

dev:9932019-12-16 01:20:47.442 am debugSharpAquos - volumeUp()

dev:9932019-12-16 01:20:44.693 am errorSharpAquos - request - ERROR. Request [VOLM] [?] dropped. Plugin initializing.

dev:9932019-12-16 01:20:44.688 am errorSharpAquos - request - ERROR. Request [RCKY] [33] dropped. Plugin initializing.

dev:9932019-12-16 01:20:44.678 am debugSharpAquos - volumeUp()

dev:9932019-12-16 01:20:38.703 am errorSharpAquos - request - ERROR. Request [VOLM] [?] dropped. Plugin initializing.

dev:9932019-12-16 01:20:38.700 am errorSharpAquos - request - ERROR. Request [RCKY] [33] dropped. Plugin initializing.

dev:9932019-12-16 01:20:38.697 am debugSharpAquos - volumeUp()

dev:9932019-12-16 01:19:28.108 am debugSharpAquos - getDeviceState() Completed.

dev:9932019-12-16 01:19:28.105 am debugSharpAquos - getChannelStatus() Completed.

dev:9932019-12-16 01:19:28.103 am errorSharpAquos - request - ERROR. Request [DC11] [?] dropped. Plugin initializing.

dev:9932019-12-16 01:19:28.100 am errorSharpAquos - request - ERROR. Request [DC10] [?] dropped. Plugin initializing.

dev:9932019-12-16 01:19:28.097 am errorSharpAquos - request - ERROR. Request [DC2L] [?] dropped. Plugin initializing.

dev:9932019-12-16 01:19:28.094 am errorSharpAquos - request - ERROR. Request [DC2U] [?] dropped. Plugin initializing.

dev:9932019-12-16 01:19:28.092 am errorSharpAquos - request - ERROR. Request [DA2P] [?] dropped. Plugin initializing.

dev:9932019-12-16 01:19:28.089 am errorSharpAquos - request - ERROR. Request [DCCH] [?] dropped. Plugin initializing.

dev:9932019-12-16 01:19:28.086 am debugSharpAquos - getChannelStatus() Starting

dev:9932019-12-16 01:19:28.084 am errorSharpAquos - request - ERROR. Request [OFTM] [?] dropped. Plugin initializing.

dev:9932019-12-16 01:19:28.081 am errorSharpAquos - request - ERROR. Request [TDCH] [?] dropped. Plugin initializing.

dev:9932019-12-16 01:19:28.078 am errorSharpAquos - request - ERROR. Request [AVMD] [?] dropped. Plugin initializing.

dev:9932019-12-16 01:19:28.076 am errorSharpAquos - request - ERROR. Request [ACSU] [?] dropped. Plugin initializing.

dev:9932019-12-16 01:19:28.073 am errorSharpAquos - request - ERROR. Request [WIDE] [?] dropped. Plugin initializing.

dev:9932019-12-16 01:19:28.070 am errorSharpAquos - request - ERROR. Request [IAVD] [?] dropped. Plugin initializing.

dev:9932019-12-16 01:19:28.067 am errorSharpAquos - request - ERROR. Request [VOLM] [?] dropped. Plugin initializing.

dev:9932019-12-16 01:19:28.064 am errorSharpAquos - request - ERROR. Request [MUTE] [?] dropped. Plugin initializing.

dev:9932019-12-16 01:19:28.062 am errorSharpAquos - request - ERROR. Request [POWR] [?] dropped. Plugin initializing.

dev:9932019-12-16 01:19:28.059 am debugSharpAquos - getDeviceState() Starting

[anon61068208]

@cybrmage

Wait for the logs entries to stop (you should see "getDeviceState() Completed." and "initialize - COMPLETED")... then click on initialize

Did you follow his directions and see the getDeviceState() Completed in the logs ?

[albertsmark]

Yep, I see it every 5 minutes, but I don't see initialize completed, and if i hit initialize again I get ERROR - initialize() already in progress. Aborting.
None of my control work either.

[anon61068208]

shutdown the hub from settings and wait at least 10 minutes and power it back up to let everything settle down. I would shut the TV off at the power source and and plug it back in when you power up the hub.

[albertsmark]

I will give it a try...

[cybrmage]

@anon61068208

I would shut the TV off at the power source and and plug it back in when you power up the hub.

Not needed.... I'm seeing the same thing with the emulator... It's the HE IO model... Something is getting it deadlocked... and it takes time to clear....

I'm working on it...

[anon61068208]

Ok sorry. It sucks debugging code sometimes.

[albertsmark]

Thanks guys....

[cybrmage]

Ok... Backed out a few changes...

1. connection mode is "telnet" only
2. removed auto disconnect/reconnect of mutex failures

You will need to upload and save the new driver... Then, without clicking initialize, give the HE a few minutes to settle. Once it has settled... reboot the HE.... The driver should come up properly during the reboot...

[Old version removed]

[albertsmark]

OK....sorry for the delay....long work day.......did exactly as you stated.

here are a few logs right after the hub reboot.

Summary

dev:10252019-12-16 05:42:37.638 pm debugSharpAquos - getMutex 6 - Acquire mutex request [0]

dev:10252019-12-16 05:42:37.632 pm debugSharpAquos - releaseMutex ?4? - Released mutex permit [0]

dev:10252019-12-16 05:42:37.610 pm debugSharpAquos - sendSocketCommand - telnet - sending [POWR? ]

dev:10252019-12-16 05:42:37.587 pm debugSharpAquos - request 5 - Interface [telnet] Socket [open] CMD [POWR] Params [?]

dev:10252019-12-16 05:42:37.564 pm debugSharpAquos - getMutex 5 - Acquired mutex permit [0]

dev:10252019-12-16 05:42:37.533 pm debugSharpAquos - releaseMutex ?4? - Release mutex request [0]

dev:10252019-12-16 05:42:37.524 pm debugSharpAquos - parseIPPV - response 'ERR'

dev:10252019-12-16 05:42:37.495 pm debugSharpAquos - parse - 1 - COMMAND [IPPV1 ] RESPONSE [ERR]

dev:10252019-12-16 05:42:37.473 pm debugSharpAquos - IOBUFFER: 2 entries [[IPPV1 , ERR]]

dev:10252019-12-16 05:42:37.442 pm debugSharpAquos - getMutex 5 - Acquire mutex request [0]

dev:10252019-12-16 05:42:37.385 pm debugSharpAquos - getDeviceState() Starting

dev:10252019-12-16 05:42:37.362 pm debugSharpAquos - releaseMutex ?3? - Released mutex permit [0]

dev:10252019-12-16 05:42:37.276 pm debugSharpAquos - sendSocketCommand - telnet - sending [IPPV1 ]

dev:10252019-12-16 05:42:37.263 pm debugSharpAquos - request 4 - Interface [telnet] Socket [open] CMD [IPPV] Params [1]

dev:10252019-12-16 05:42:37.245 pm debugSharpAquos - getMutex 4 - Acquired mutex permit [0]

dev:10252019-12-16 05:42:37.238 pm debugSharpAquos - releaseMutex ?3? - Release mutex request [0]

dev:10252019-12-16 05:42:37.223 pm debugSharpAquos - parseSWVN - response 'AQUOS LE844U'

dev:10252019-12-16 05:42:37.217 pm debugSharpAquos - parse - 1 - COMMAND [SWVN1 ] RESPONSE [AQUOS LE844U]

dev:10252019-12-16 05:42:37.194 pm debugSharpAquos - IOBUFFER: 2 entries [[SWVN1 , AQUOS LE844U]]

dev:10252019-12-16 05:42:37.185 pm debugSharpAquos - releaseMutex ?2? - Released mutex permit [1]

dev:10252019-12-16 05:42:37.176 pm debugSharpAquos - getMutex 4 - Acquire mutex request [0]

dev:10252019-12-16 05:42:37.146 pm debugSharpAquos - sendSocketCommand - telnet - sending [SWVN1 ]

dev:10252019-12-16 05:42:37.134 pm debugSharpAquos - request 3 - Interface [telnet] Socket [open] CMD [SWVN] Params [1]

dev:10252019-12-16 05:42:37.127 pm debugSharpAquos - getMutex 3 - Acquired mutex permit [0]

dev:10252019-12-16 05:42:37.100 pm debugSharpAquos - releaseMutex ?2? - Release mutex request [0]

dev:10252019-12-16 05:42:37.084 pm debugSharpAquos - parseMNRD - response 'ERR'

dev:10252019-12-16 05:42:37.077 pm debugSharpAquos - parse - 1 - COMMAND [MNRD1 ] RESPONSE [ERR]

dev:10252019-12-16 05:42:37.051 pm debugSharpAquos - IOBUFFER: 2 entries [[MNRD1 , ERR]]

dev:10252019-12-16 05:42:37.045 pm debugSharpAquos - getMutex 3 - Acquire mutex request [0]

dev:10252019-12-16 05:42:37.029 pm debugSharpAquos - releaseMutex ?1? - Released mutex permit [0]

dev:10252019-12-16 05:42:37.023 pm debugSharpAquos - sendSocketCommand - telnet - sending [MNRD1 ]

dev:10252019-12-16 05:42:37.006 pm debugSharpAquos - request 2 - Interface [telnet] Socket [open] CMD [MNRD] Params [1]

dev:10252019-12-16 05:42:37.000 pm debugSharpAquos - getMutex 2 - Acquired mutex permit [0]

dev:10252019-12-16 05:42:36.982 pm debugSharpAquos - releaseMutex ?1? - Release mutex request [0]

dev:10252019-12-16 05:42:36.977 pm debugSharpAquos - parse - 1 - COMMAND [TVNM1 ] RESPONSE [OK]

dev:10252019-12-16 05:42:36.950 pm debugSharpAquos - IOBUFFER: 2 entries [[TVNM1 , OK]]

dev:10252019-12-16 05:42:36.944 pm debugSharpAquos - getMutex 2 - Acquire mutex request [0]

dev:10252019-12-16 05:42:36.926 pm debugSharpAquos - releaseMutex ?0? - Released mutex permit [0]

dev:10252019-12-16 05:42:36.920 pm debugSharpAquos - sendSocketCommand - telnet - sending [TVNM1 ]

dev:10252019-12-16 05:42:36.904 pm debugSharpAquos - request 1 - Interface [telnet] Socket [open] CMD [TVNM] Params [1]

dev:10252019-12-16 05:42:36.898 pm debugSharpAquos - getMutex 1 - Acquired mutex permit [0]

dev:10252019-12-16 05:42:36.881 pm debugSharpAquos - releaseMutex ?0? - Release mutex request [0]

dev:10252019-12-16 05:42:36.875 pm debugSharpAquos - parse - 1 - COMMAND [RSPW2 ] RESPONSE [ERR]

dev:10252019-12-16 05:42:36.851 pm debugSharpAquos - IOBUFFER: 2 entries [[RSPW2 , ERR]]

dev:10252019-12-16 05:42:36.820 pm debugSharpAquos - getMutex 1 - Acquire mutex request [0]

dev:10252019-12-16 05:42:36.782 pm debugSharpAquos - sendSocketCommand - telnet - sending [RSPW2 ]

dev:10252019-12-16 05:42:36.749 pm debugSharpAquos - request 0 - Interface [telnet] Socket [open] CMD [RSPW] Params [2]

dev:10252019-12-16 05:42:36.743 pm debugSharpAquos - getMutex 0 - Acquired mutex permit [0]

dev:10252019-12-16 05:42:36.730 pm debugSharpAquos - getMutex 0 - Acquire mutex request [1]

dev:10252019-12-16 05:42:36.721 pm debugSharpAquos - initialize - Poll Rate set to every 5 minutes

dev:10252019-12-16 05:42:36.549 pm debugSharpAquos - openSocket: Socket opened.

dev:10252019-12-16 05:42:35.451 pm debugSharpAquos - openSocket: Connecting to 192.168.254.205:10002

dev:10252019-12-16 05:42:35.445 pm debugSharpAquos - initialize - Set DNI = [C0A8FECD]

dev:10252019-12-16 05:42:34.383 pm debugSharpAquos - initialize - TV mac address not yet available. Retry in 1 second.

dev:10252019-12-16 05:42:33.367 pm debugSharpAquos - initialize - TV mac address not yet available. Retry in 1 second.

dev:10252019-12-16 05:42:32.357 pm debugSharpAquos - initialize - TV mac address not yet available. Retry in 1 second.

dev:10252019-12-16 05:42:31.348 pm debugSharpAquos - initialize - TV mac address not yet available. Retry in 1 second.

dev:10252019-12-16 05:42:30.329 pm debugSharpAquos - initialize - TV mac address not yet available. Retry in 1 second.

dev:10252019-12-16 05:42:30.309 pm debugSharpAquos - initialize - IP: 192.168.254.205 PORT: 10002 USER: null PASS: null Poll Rate: 5

dev:10252019-12-16 05:42:29.804 pm debugSharpAquos - closeSocket: Socket closed. [1]

dev:10252019-12-16 05:42:29.793 pm warnFLUSHMUTEX - FLUSHED - Queued [false] Waiting [0] permits [1]

dev:10252019-12-16 05:42:28.674 pm warnFLUSHMUTEX - flushing mutex queue. Queued [false] Waiting [0] permits [1]

dev:10252019-12-16 05:42:27.653 pm debugSharpAquos - closeSocket: Socket close requested.

dev:10252019-12-16 05:42:27.631 pm debugSharpAquos - releaseMutex ?-2? - Released mutex permit [1]

dev:10252019-12-16 05:42:27.617 pm debugSharpAquos - releaseMutex ?-2? - ERROR - Permit release not required [1]

dev:10252019-12-16 05:42:27.592 pm debugSharpAquos - releaseMutex ?-2? - Release mutex request [1]

dev:10252019-12-16 05:42:27.522 pm warninitialize - Driver v0.36 debug [true] verbose [true] events [true] INTERFACE [telnet] permits [1] Init [null] Queue [0]

[albertsmark]

And more logs after it has run for a little bit....

Summary

dev:10252019-12-16 05:49:57.733 pm debugSharpAquos - releaseMutex ?36? - Released mutex permit [1]

dev:10252019-12-16 05:49:57.729 pm debugSharpAquos - releaseMutex ?36? - ERROR - Permit release not required [1]

dev:10252019-12-16 05:49:57.725 pm debugSharpAquos - releaseMutex ?36? - Release mutex request [1]

dev:10252019-12-16 05:49:57.721 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 05:49:57.717 pm debugSharpAquos - releaseMutex ?36? - Released mutex permit [1]

dev:10252019-12-16 05:49:57.712 pm debugSharpAquos - releaseMutex ?36? - ERROR - Permit release not required [1]

dev:10252019-12-16 05:49:57.708 pm debugSharpAquos - releaseMutex ?36? - Release mutex request [1]

dev:10252019-12-16 05:49:57.703 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 05:49:57.699 pm debugSharpAquos - releaseMutex ?36? - Released mutex permit [1]

dev:10252019-12-16 05:49:57.695 pm debugSharpAquos - releaseMutex ?36? - ERROR - Permit release not required [1]

dev:10252019-12-16 05:49:57.692 pm debugSharpAquos - releaseMutex ?36? - Release mutex request [1]

dev:10252019-12-16 05:49:57.688 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 05:49:57.684 pm debugSharpAquos - releaseMutex ?36? - Released mutex permit [1]

dev:10252019-12-16 05:49:57.680 pm debugSharpAquos - releaseMutex ?36? - ERROR - Permit release not required [1]

dev:10252019-12-16 05:49:57.675 pm debugSharpAquos - releaseMutex ?36? - Release mutex request [1]

dev:10252019-12-16 05:49:57.671 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 05:49:57.666 pm debugSharpAquos - releaseMutex ?36? - Released mutex permit [1]

dev:10252019-12-16 05:49:57.662 pm debugSharpAquos - releaseMutex ?36? - ERROR - Permit release not required [1]

dev:10252019-12-16 05:49:57.658 pm debugSharpAquos - releaseMutex ?36? - Release mutex request [1]

dev:10252019-12-16 05:49:57.654 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 05:49:57.650 pm debugSharpAquos - releaseMutex ?36? - Released mutex permit [1]

dev:10252019-12-16 05:49:57.647 pm debugSharpAquos - releaseMutex ?36? - ERROR - Permit release not required [1]

dev:10252019-12-16 05:49:57.643 pm debugSharpAquos - releaseMutex ?36? - Release mutex request [1]

dev:10252019-12-16 05:49:57.639 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 05:49:57.635 pm debugSharpAquos - releaseMutex ?36? - Released mutex permit [1]

dev:10252019-12-16 05:49:57.631 pm debugSharpAquos - releaseMutex ?36? - ERROR - Permit release not required [1]

dev:10252019-12-16 05:49:57.627 pm debugSharpAquos - releaseMutex ?36? - Release mutex request [1]

dev:10252019-12-16 05:49:57.622 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 05:49:57.618 pm debugSharpAquos - releaseMutex ?36? - Released mutex permit [1]

dev:10252019-12-16 05:49:57.614 pm debugSharpAquos - releaseMutex ?36? - ERROR - Permit release not required [1]

dev:10252019-12-16 05:49:57.610 pm debugSharpAquos - releaseMutex ?36? - Release mutex request [1]

dev:10252019-12-16 05:49:57.606 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 05:49:57.601 pm debugSharpAquos - releaseMutex ?36? - Released mutex permit [1]

dev:10252019-12-16 05:49:57.597 pm debugSharpAquos - releaseMutex ?36? - ERROR - Permit release not required [1]

dev:10252019-12-16 05:49:57.593 pm debugSharpAquos - releaseMutex ?36? - Release mutex request [1]

dev:10252019-12-16 05:49:57.587 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 05:49:57.582 pm debugSharpAquos - releaseMutex ?36? - Released mutex permit [1]

dev:10252019-12-16 05:49:57.578 pm debugSharpAquos - releaseMutex ?36? - Release mutex request [0]

dev:10252019-12-16 05:49:57.573 pm debugSharpAquos - parseSleepTimer - ERROR - Invalid SleepTimer [8]

dev:10252019-12-16 05:49:57.567 pm debugSharpAquos - parseSleepTimer - response '8'

dev:10252019-12-16 05:49:57.563 pm debugSharpAquos - parse - 1 - COMMAND [OFTM? ] RESPONSE [8]

dev:10252019-12-16 05:49:57.557 pm debugSharpAquos - IOBUFFER: 2 entries [[OFTM? , 8]]

dev:10252019-12-16 05:49:57.553 pm debugSharpAquos - getDeviceState() Completed.

dev:10252019-12-16 05:49:57.548 pm debugSharpAquos - releaseMutex ?36? - Released mutex permit [0]

dev:10252019-12-16 05:49:57.543 pm debugSharpAquos - sendSocketCommand - telnet - sending [OFTM? ]

dev:10252019-12-16 05:49:57.539 pm debugSharpAquos - request 37 - Interface [telnet] Socket [open] CMD [OFTM] Params [?]

dev:10252019-12-16 05:49:57.533 pm debugSharpAquos - getMutex 37 - Acquired mutex permit [0]

dev:10252019-12-16 05:49:57.528 pm debugSharpAquos - releaseMutex ?36? - Release mutex request [0]

dev:10252019-12-16 05:49:57.523 pm debugSharpAquos - parse3DMode - SET - 3DMode [1] 3DModeName [2D to 3D]

dev:10252019-12-16 05:49:57.517 pm infoSharpAquos - EVENT - [[name:3DModeName, value:2D to 3D]]

dev:10252019-12-16 05:49:57.512 pm infoSharpAquos - EVENT - [[name:3DMode, value:1]]

dev:10252019-12-16 05:49:57.505 pm debugSharpAquos - parse3DMode - response '1'

dev:10252019-12-16 05:49:57.501 pm debugSharpAquos - parse - 1 - COMMAND [TDCH? ] RESPONSE [1]

dev:10252019-12-16 05:49:57.496 pm debugSharpAquos - IOBUFFER: 2 entries [[TDCH? , 1]]

dev:10252019-12-16 05:49:57.479 pm debugSharpAquos - releaseMutex ?35? - Released mutex permit [1]

dev:10252019-12-16 05:49:57.474 pm debugSharpAquos - getMutex 37 - Acquire mutex request [0]

dev:10252019-12-16 05:49:57.463 pm debugSharpAquos - sendSocketCommand - telnet - sending [TDCH? ]

dev:10252019-12-16 05:49:57.443 pm debugSharpAquos - request 36 - Interface [telnet] Socket [open] CMD [TDCH] Params [?]

dev:10252019-12-16 05:49:57.439 pm debugSharpAquos - getMutex 36 - Acquired mutex permit [0]

dev:10252019-12-16 05:49:57.434 pm debugSharpAquos - releaseMutex ?35? - Release mutex request [0]

dev:10252019-12-16 05:49:57.430 pm debugSharpAquos - parseVideoMode - ERROR - Invalid VideoMode [ERR]

dev:10252019-12-16 05:49:57.425 pm debugSharpAquos - parseVideoMode - response 'ERR'

dev:10252019-12-16 05:49:57.420 pm debugSharpAquos - parse - 1 - COMMAND [AVMD? ] RESPONSE [ERR]

dev:10252019-12-16 05:49:57.415 pm debugSharpAquos - IOBUFFER: 2 entries [[AVMD? , ERR]]

dev:10252019-12-16 05:49:57.410 pm debugSharpAquos - getMutex 36 - Acquire mutex request [0]

dev:10252019-12-16 05:49:57.405 pm debugSharpAquos - sendSocketCommand - telnet - sending [AVMD? ]

dev:10252019-12-16 05:49:57.400 pm debugSharpAquos - request 35 - Interface [telnet] Socket [open] CMD [AVMD] Params [?]

dev:10252019-12-16 05:49:57.395 pm debugSharpAquos - getMutex 35 - Acquired mutex permit [0]

dev:10252019-12-16 05:49:57.390 pm debugSharpAquos - releaseMutex ?34? - Released mutex permit [1]

dev:10252019-12-16 05:49:57.382 pm debugSharpAquos - releaseMutex ?34? - Release mutex request [0]

dev:10252019-12-16 05:49:57.377 pm debugSharpAquos - parseSurroundMode - SET - SurroundMode [4] SurroundModeName [3D Hall]

dev:10252019-12-16 05:49:57.372 pm infoSharpAquos - EVENT - [[name:SurroundModeName, value:3D Hall]]

dev:10252019-12-16 05:49:57.367 pm infoSharpAquos - EVENT - [[name:SurroundMode, value:4]]

dev:10252019-12-16 05:49:57.362 pm debugSharpAquos - parseSurround - response '4'

dev:10252019-12-16 05:49:57.358 pm debugSharpAquos - parse - 1 - COMMAND [ACSU? ] RESPONSE [4]

dev:10252019-12-16 05:49:57.352 pm debugSharpAquos - IOBUFFER: 2 entries [[ACSU? , 4]]

dev:10252019-12-16 05:49:57.349 pm debugSharpAquos - getMutex 35 - Acquire mutex request [0]

dev:10252019-12-16 05:49:57.344 pm debugSharpAquos - releaseMutex ?33? - Released mutex permit [1]

dev:10252019-12-16 05:49:57.340 pm debugSharpAquos - sendSocketCommand - telnet - sending [ACSU? ]

dev:10252019-12-16 05:49:57.320 pm debugSharpAquos - request 34 - Interface [telnet] Socket [open] CMD [ACSU] Params [?]

dev:10252019-12-16 05:49:57.314 pm debugSharpAquos - getMutex 34 - Acquired mutex permit [0]

dev:10252019-12-16 05:49:57.309 pm debugSharpAquos - releaseMutex ?33? - Release mutex request [0]

dev:10252019-12-16 05:49:57.304 pm debugSharpAquos - parseViewMode - ERROR - Invalid ViewMode [ERR]

dev:10252019-12-16 05:49:57.298 pm debugSharpAquos - parseViewMode - response 'ERR'

dev:10252019-12-16 05:49:57.291 pm debugSharpAquos - parse - 1 - COMMAND [WIDE? ] RESPONSE [ERR]

dev:10252019-12-16 05:49:57.286 pm debugSharpAquos - IOBUFFER: 2 entries [[WIDE? , ERR]]

dev:10252019-12-16 05:49:57.282 pm debugSharpAquos - getMutex 34 - Acquire mutex request [0]

dev:10252019-12-16 05:49:57.278 pm debugSharpAquos - releaseMutex ?32? - Released mutex permit [0]

dev:10252019-12-16 05:49:57.273 pm debugSharpAquos - sendSocketCommand - telnet - sending [WIDE? ]

dev:10252019-12-16 05:49:57.269 pm debugSharpAquos - request 33 - Interface [telnet] Socket [open] CMD [WIDE] Params [?]

dev:10252019-12-16 05:49:57.265 pm debugSharpAquos - getMutex 33 - Acquired mutex permit [0]

dev:10252019-12-16 05:49:57.260 pm debugSharpAquos - releaseMutex ?32? - Release mutex request [0]

dev:10252019-12-16 05:49:57.255 pm debugSharpAquos - parseInput - SET - input [2] InputName [HDMI IN 2]

dev:10252019-12-16 05:49:57.250 pm infoSharpAquos - EVENT - [[name:inputName, value:HDMI IN 2]]

dev:10252019-12-16 05:49:57.245 pm infoSharpAquos - EVENT - [[name:input, value:2]]

dev:10252019-12-16 05:49:57.240 pm debugSharpAquos - parseInput - response '2'

dev:10252019-12-16 05:49:57.235 pm debugSharpAquos - parse - 1 - COMMAND [IAVD? ] RESPONSE [2]

dev:10252019-12-16 05:49:57.231 pm debugSharpAquos - IOBUFFER: 2 entries [[IAVD? , 2]]

dev:10252019-12-16 05:49:57.225 pm debugSharpAquos - getMutex 33 - Acquire mutex request [0]

dev:10252019-12-16 05:49:57.221 pm debugSharpAquos - sendSocketCommand - telnet - sending [IAVD? ]

dev:10252019-12-16 05:49:57.216 pm debugSharpAquos - request 32 - Interface [telnet] Socket [open] CMD [IAVD] Params [?]

dev:10252019-12-16 05:49:57.212 pm debugSharpAquos - releaseMutex ?31? - Released mutex permit [1]

dev:10252019-12-16 05:49:57.207 pm debugSharpAquos - getMutex 32 - Acquired mutex permit [0]

dev:10252019-12-16 05:49:57.203 pm debugSharpAquos - releaseMutex ?31? - Release mutex request [0]

dev:10252019-12-16 05:49:57.198 pm debugSharpAquos - parseVolume - ERROR - invalid volume [null]

dev:10252019-12-16 05:49:57.193 pm debugSharpAquos - parseVolume - response 'ERR'

dev:10252019-12-16 05:49:57.189 pm debugSharpAquos - parse - 1 - COMMAND [VOLM? ] RESPONSE [ERR]

dev:10252019-12-16 05:49:57.184 pm debugSharpAquos - IOBUFFER: 2 entries [[VOLM? , ERR]]

dev:10252019-12-16 05:49:57.180 pm debugSharpAquos - getMutex 32 - Acquire mutex request [0]

dev:10252019-12-16 05:49:57.174 pm debugSharpAquos - sendSocketCommand - telnet - sending [VOLM? ]

dev:10252019-12-16 05:49:57.169 pm debugSharpAquos - request 31 - Interface [telnet] Socket [open] CMD [VOLM] Params [?]

dev:10252019-12-16 05:49:57.164 pm debugSharpAquos - releaseMutex ?30? - Released mutex permit [1]

dev:10252019-12-16 05:49:57.160 pm debugSharpAquos - getMutex 31 - Acquired mutex permit [0]

dev:10252019-12-16 05:49:57.155 pm debugSharpAquos - releaseMutex ?30? - Release mutex request [0]

dev:10252019-12-16 05:49:57.151 pm debugSharpAquos - parseMute - SET - mute [muted]

dev:10252019-12-16 05:49:57.147 pm infoSharpAquos - EVENT - [[name:mute, value:muted]]

dev:10252019-12-16 05:49:57.142 pm debugSharpAquos - parseMute - response '1'

dev:10252019-12-16 05:49:57.138 pm debugSharpAquos - parse - 1 - COMMAND [MUTE? ] RESPONSE [1]

dev:10252019-12-16 05:49:57.134 pm debugSharpAquos - IOBUFFER: 2 entries [[MUTE? , 1]]

dev:10252019-12-16 05:49:57.129 pm debugSharpAquos - getMutex 31 - Acquire mutex request [0]

dev:10252019-12-16 05:49:57.123 pm debugSharpAquos - sendSocketCommand - telnet - sending [MUTE? ]

dev:10252019-12-16 05:49:57.119 pm debugSharpAquos - request 30 - Interface [telnet] Socket [open] CMD [MUTE] Params [?]

dev:10252019-12-16 05:49:57.114 pm debugSharpAquos - releaseMutex ?29? - Released mutex permit [1]

dev:10252019-12-16 05:49:57.111 pm debugSharpAquos - getMutex 30 - Acquired mutex permit [0]

dev:10252019-12-16 05:49:57.107 pm debugSharpAquos - releaseMutex ?29? - Release mutex request [0]

dev:10252019-12-16 05:49:57.102 pm debugSharpAquos - parsePower - ERROR - invalid power [ERR]

dev:10252019-12-16 05:49:57.096 pm debugSharpAquos - parsePower - response 'ERR'

dev:10252019-12-16 05:49:57.091 pm debugSharpAquos - parse - 1 - COMMAND [POWR? ] RESPONSE [ERR]

dev:10252019-12-16 05:49:57.087 pm debugSharpAquos - IOBUFFER: 2 entries [[POWR? , ERR]]

dev:10252019-12-16 05:49:57.078 pm debugSharpAquos - getMutex 30 - Acquire mutex request [0]

dev:10252019-12-16 05:49:57.073 pm debugSharpAquos - sendSocketCommand - telnet - sending [POWR? ]

dev:10252019-12-16 05:49:57.068 pm debugSharpAquos - request 29 - Interface [telnet] Socket [open] CMD [POWR] Params [?]

dev:10252019-12-16 05:49:57.051 pm debugSharpAquos - getMutex 29 - Acquired mutex permit [0]

dev:10252019-12-16 05:49:57.046 pm debugSharpAquos - getMutex 29 - Acquire mutex request [1]

dev:10252019-12-16 05:49:57.042 pm debugSharpAquos - getDeviceState() Starting

dev:10252019-12-16 05:48:06.022 pm debugSharpAquos - openSocket: Socket opened.

dev:10252019-12-16 05:48:04.961 pm debugSharpAquos - openSocket: Connecting to 192.168.254.205:10002

dev:10252019-12-16 05:47:59.964 pm debugSharpAquos - socketStatus - Stream Closed - Attempting reconnect [1]

dev:10252019-12-16 05:47:59.960 pm debugSharpAquos - releaseMutex ?27? - Released mutex permit [1]

dev:10252019-12-16 05:47:59.957 pm debugSharpAquos - releaseMutex ?27? - ERROR - Permit release not required [1]

dev:10252019-12-16 05:47:59.953 pm debugSharpAquos - releaseMutex ?27? - Release mutex request [1]

dev:10252019-12-16 05:47:59.949 pm warnsocketStatus - Socket [open] Message [receive error: Stream is closed]

dev:10252019-12-16 05:47:59.945 pm debugSharpAquos - telnetStatus - Socket [open] Message [receive error: Stream is closed] - Passing to socketStatus...

[albertsmark]

volumes, toggle mute and powers all work.
Here is a volume up log....

Summary

dev:10252019-12-16 05:57:37.749 pm debugSharpAquos - releaseMutex ?95? - Released mutex permit [1]

dev:10252019-12-16 05:57:37.745 pm debugSharpAquos - releaseMutex ?95? - ERROR - Permit release not required [1]

dev:10252019-12-16 05:57:37.741 pm debugSharpAquos - releaseMutex ?95? - Release mutex request [1]

dev:10252019-12-16 05:57:37.737 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 05:57:37.713 pm debugSharpAquos - releaseMutex ?95? - Released mutex permit [1]

dev:10252019-12-16 05:57:37.701 pm debugSharpAquos - releaseMutex ?95? - ERROR - Permit release not required [1]

dev:10252019-12-16 05:57:37.697 pm debugSharpAquos - releaseMutex ?95? - Release mutex request [1]

dev:10252019-12-16 05:57:37.684 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 05:57:37.516 pm debugSharpAquos - releaseMutex ?95? - Released mutex permit [1]

dev:10252019-12-16 05:57:37.512 pm debugSharpAquos - releaseMutex ?95? - ERROR - Permit release not required [1]

dev:10252019-12-16 05:57:37.507 pm debugSharpAquos - releaseMutex ?95? - Release mutex request [1]

dev:10252019-12-16 05:57:37.504 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 05:57:37.499 pm debugSharpAquos - releaseMutex ?95? - Released mutex permit [1]

dev:10252019-12-16 05:57:37.495 pm debugSharpAquos - releaseMutex ?95? - Release mutex request [0]

dev:10252019-12-16 05:57:37.491 pm debugSharpAquos - parseVolume - ERROR - invalid volume [null]

dev:10252019-12-16 05:57:37.487 pm debugSharpAquos - parseVolume - response 'ERR'

dev:10252019-12-16 05:57:37.483 pm debugSharpAquos - parse - 1 - COMMAND [VOLM? ] RESPONSE [ERR]

dev:10252019-12-16 05:57:37.479 pm debugSharpAquos - IOBUFFER: 2 entries [[VOLM? , ERR]]

dev:10252019-12-16 05:57:37.474 pm debugSharpAquos - sendSocketCommand - telnet - sending [VOLM? ]

dev:10252019-12-16 05:57:37.471 pm debugSharpAquos - request 96 - Interface [telnet] Socket [open] CMD [VOLM] Params [?]

dev:10252019-12-16 05:57:37.467 pm debugSharpAquos - getMutex 96 - Acquired mutex permit [0]

dev:10252019-12-16 05:57:37.463 pm debugSharpAquos - releaseMutex ?95? - Released mutex permit [1]

dev:10252019-12-16 05:57:37.460 pm debugSharpAquos - releaseMutex ?95? - Release mutex request [0]

dev:10252019-12-16 05:57:37.456 pm debugSharpAquos - parse - 1 - COMMAND [RCKY33 ] RESPONSE [12]

dev:10252019-12-16 05:57:37.453 pm debugSharpAquos - IOBUFFER: 2 entries [[RCKY33 , 12]]

dev:10252019-12-16 05:57:37.442 pm debugSharpAquos - getMutex 96 - Acquire mutex request [0]

dev:10252019-12-16 05:57:37.438 pm debugSharpAquos - sendSocketCommand - telnet - sending [RCKY33 ]

dev:10252019-12-16 05:57:37.434 pm debugSharpAquos - request 95 - Interface [telnet] Socket [open] CMD [RCKY] Params [33]

dev:10252019-12-16 05:57:37.426 pm debugSharpAquos - getMutex 95 - Acquired mutex permit [0]

dev:10252019-12-16 05:57:37.423 pm debugSharpAquos - getMutex 95 - Acquire mutex request [1]

dev:10252019-12-16 05:57:37.419 pm debugSharpAquos - releaseMutex ?94? - Released mutex permit [1]

dev:10252019-12-16 05:57:37.415 pm debugSharpAquos - releaseMutex ?94? - ERROR - Permit release not required [1]

dev:10252019-12-16 05:57:37.411 pm debugSharpAquos - releaseMutex ?94? - Release mutex request [1]

dev:10252019-12-16 05:57:37.408 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 05:57:37.404 pm debugSharpAquos - volumeUp()

dev:10252019-12-16 05:57:37.218 pm debugSharpAquos - releaseMutex ?93? - Released mutex permit [1]

dev:10252019-12-16 05:57:37.213 pm debugSharpAquos - releaseMutex ?93? - Release mutex request [0]

dev:10252019-12-16 05:57:37.197 pm debugSharpAquos - parse - 1 - COMMAND [VOLM? ] RESPONSE [OK]

dev:10252019-12-16 05:57:37.192 pm debugSharpAquos - IOBUFFER: 2 entries [[VOLM? , OK]]

dev:10252019-12-16 05:57:37.169 pm debugSharpAquos - sendSocketCommand - telnet - sending [VOLM? ]

dev:10252019-12-16 05:57:37.166 pm debugSharpAquos - request 94 - Interface [telnet] Socket [open] CMD [VOLM] Params [?]

dev:10252019-12-16 05:57:37.163 pm debugSharpAquos - releaseMutex ?93? - Released mutex permit [1]

dev:10252019-12-16 05:57:37.149 pm debugSharpAquos - getMutex 94 - Acquired mutex permit [0]

dev:10252019-12-16 05:57:37.145 pm debugSharpAquos - releaseMutex ?93? - Release mutex request [0]

dev:10252019-12-16 05:57:37.141 pm debugSharpAquos - parse - 1 - COMMAND [RCKY33 ] RESPONSE [ERR]

dev:10252019-12-16 05:57:37.138 pm debugSharpAquos - IOBUFFER: 2 entries [[RCKY33 , ERR]]

dev:10252019-12-16 05:57:37.134 pm debugSharpAquos - getMutex 94 - Acquire mutex request [0]

dev:10252019-12-16 05:57:37.130 pm debugSharpAquos - sendSocketCommand - telnet - sending [RCKY33 ]

dev:10252019-12-16 05:57:37.126 pm debugSharpAquos - request 93 - Interface [telnet] Socket [open] CMD [RCKY] Params [33]

dev:10252019-12-16 05:57:37.117 pm debugSharpAquos - getMutex 93 - Acquired mutex permit [0]

dev:10252019-12-16 05:57:37.114 pm debugSharpAquos - getMutex 93 - Acquire mutex request [1]

dev:10252019-12-16 05:57:37.110 pm debugSharpAquos - volumeUp()

dev:10252019-12-16 05:57:36.327 pm debugSharpAquos - releaseMutex ?91? - Released mutex permit [1]

dev:10252019-12-16 05:57:36.322 pm debugSharpAquos - releaseMutex ?91? - ERROR - Permit release not required [1]

dev:10252019-12-16 05:57:36.316 pm debugSharpAquos - releaseMutex ?91? - Release mutex request [1]

dev:10252019-12-16 05:57:36.311 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 05:57:36.297 pm debugSharpAquos - releaseMutex ?91? - Released mutex permit [1]

dev:10252019-12-16 05:57:36.292 pm debugSharpAquos - releaseMutex ?91? - ERROR - Permit release not required [1]

dev:10252019-12-16 05:57:36.287 pm debugSharpAquos - releaseMutex ?91? - Release mutex request [1]

dev:10252019-12-16 05:57:36.283 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 05:57:36.269 pm debugSharpAquos - releaseMutex ?91? - Released mutex permit [1]

dev:10252019-12-16 05:57:36.265 pm debugSharpAquos - releaseMutex ?91? - Release mutex request [0]

dev:10252019-12-16 05:57:36.261 pm debugSharpAquos - parse - 1 - COMMAND [VOLM? ] RESPONSE [OK]

dev:10252019-12-16 05:57:36.257 pm debugSharpAquos - IOBUFFER: 2 entries [[VOLM? , OK]]

dev:10252019-12-16 05:57:36.241 pm debugSharpAquos - releaseMutex ?91? - Released mutex permit [1]

dev:10252019-12-16 05:57:36.236 pm debugSharpAquos - sendSocketCommand - telnet - sending [VOLM? ]

dev:10252019-12-16 05:57:36.232 pm debugSharpAquos - request 92 - Interface [telnet] Socket [open] CMD [VOLM] Params [?]

dev:10252019-12-16 05:57:36.228 pm debugSharpAquos - getMutex 92 - Acquired mutex permit [0]

dev:10252019-12-16 05:57:36.224 pm debugSharpAquos - releaseMutex ?91? - Release mutex request [0]

dev:10252019-12-16 05:57:36.220 pm debugSharpAquos - parse - 1 - COMMAND [RCKY33 ] RESPONSE [ERR]

dev:10252019-12-16 05:57:36.215 pm debugSharpAquos - IOBUFFER: 2 entries [[RCKY33 , ERR]]

dev:10252019-12-16 05:57:36.208 pm debugSharpAquos - getMutex 92 - Acquire mutex request [0]

dev:10252019-12-16 05:57:36.204 pm debugSharpAquos - sendSocketCommand - telnet - sending [RCKY33 ]

dev:10252019-12-16 05:57:36.196 pm debugSharpAquos - request 91 - Interface [telnet] Socket [open] CMD [RCKY] Params [33]

dev:10252019-12-16 05:57:36.183 pm debugSharpAquos - getMutex 91 - Acquired mutex permit [0]

dev:10252019-12-16 05:57:36.179 pm debugSharpAquos - getMutex 91 - Acquire mutex request [1]

dev:10252019-12-16 05:57:36.149 pm debugSharpAquos - volumeUp()

dev:10252019-12-16 05:57:35.507 pm debugSharpAquos - releaseMutex ?89? - Released mutex permit [1]

dev:10252019-12-16 05:57:35.504 pm debugSharpAquos - releaseMutex ?89? - ERROR - Permit release not required [1]

dev:10252019-12-16 05:57:35.500 pm debugSharpAquos - releaseMutex ?89? - Release mutex request [1]

dev:10252019-12-16 05:57:35.489 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 05:57:35.477 pm debugSharpAquos - releaseMutex ?89? - Released mutex permit [1]

dev:10252019-12-16 05:57:35.473 pm debugSharpAquos - releaseMutex ?89? - ERROR - Permit release not required [1]

dev:10252019-12-16 05:57:35.469 pm debugSharpAquos - releaseMutex ?89? - Release mutex request [1]

dev:10252019-12-16 05:57:35.465 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 05:57:35.453 pm debugSharpAquos - releaseMutex ?89? - Released mutex permit [1]

dev:10252019-12-16 05:57:35.448 pm debugSharpAquos - releaseMutex ?89? - Release mutex request [0]

dev:10252019-12-16 05:57:35.444 pm debugSharpAquos - parse - 1 - COMMAND [VOLM? ] RESPONSE [OK]

dev:10252019-12-16 05:57:35.440 pm debugSharpAquos - IOBUFFER: 2 entries [[VOLM? , OK]]

dev:10252019-12-16 05:57:35.419 pm debugSharpAquos - sendSocketCommand - telnet - sending [VOLM? ]

dev:10252019-12-16 05:57:35.396 pm debugSharpAquos - request 90 - Interface [telnet] Socket [open] CMD [VOLM] Params [?]

dev:10252019-12-16 05:57:35.392 pm debugSharpAquos - getMutex 90 - Acquired mutex permit [0]

dev:10252019-12-16 05:57:35.388 pm debugSharpAquos - releaseMutex ?89? - Released mutex permit [1]

dev:10252019-12-16 05:57:35.384 pm debugSharpAquos - releaseMutex ?89? - Release mutex request [0]

dev:10252019-12-16 05:57:35.376 pm debugSharpAquos - parse - 1 - COMMAND [RCKY33 ] RESPONSE [ERR]

dev:10252019-12-16 05:57:35.369 pm debugSharpAquos - IOBUFFER: 2 entries [[RCKY33 , ERR]]

dev:10252019-12-16 05:57:35.342 pm debugSharpAquos - getMutex 90 - Acquire mutex request [0]

dev:10252019-12-16 05:57:35.335 pm debugSharpAquos - sendSocketCommand - telnet - sending [RCKY33 ]

dev:10252019-12-16 05:57:35.328 pm debugSharpAquos - request 89 - Interface [telnet] Socket [open] CMD [RCKY] Params [33]

dev:10252019-12-16 05:57:35.319 pm debugSharpAquos - getMutex 89 - Acquired mutex permit [0]

dev:10252019-12-16 05:57:35.313 pm debugSharpAquos - getMutex 89 - Acquire mutex request [1]

dev:10252019-12-16 05:57:35.306 pm debugSharpAquos - volumeUp()

dev:10252019-12-16 05:57:34.916 pm debugSharpAquos - releaseMutex ?87? - Released mutex permit [1]

dev:10252019-12-16 05:57:34.912 pm debugSharpAquos - releaseMutex ?87? - ERROR - Permit release not required [1]

dev:10252019-12-16 05:57:34.909 pm debugSharpAquos - releaseMutex ?87? - Release mutex request [1]

dev:10252019-12-16 05:57:34.905 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 05:57:34.895 pm debugSharpAquos - releaseMutex ?87? - Released mutex permit [1]

dev:10252019-12-16 05:57:34.892 pm debugSharpAquos - releaseMutex ?87? - ERROR - Permit release not required [1]

dev:10252019-12-16 05:57:34.888 pm debugSharpAquos - releaseMutex ?87? - Release mutex request [1]

dev:10252019-12-16 05:57:34.884 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 05:57:34.865 pm debugSharpAquos - releaseMutex ?87? - Released mutex permit [1]

dev:10252019-12-16 05:57:34.861 pm debugSharpAquos - releaseMutex ?87? - Release mutex request [0]

dev:10252019-12-16 05:57:34.857 pm debugSharpAquos - parse - 1 - COMMAND [VOLM? ] RESPONSE [OK]

dev:10252019-12-16 05:57:34.853 pm debugSharpAquos - IOBUFFER: 2 entries [[VOLM? , OK]]

dev:10252019-12-16 05:57:34.844 pm debugSharpAquos - sendSocketCommand - telnet - sending [VOLM? ]

dev:10252019-12-16 05:57:34.840 pm debugSharpAquos - request 88 - Interface [telnet] Socket [open] CMD [VOLM] Params [?]

dev:10252019-12-16 05:57:34.835 pm debugSharpAquos - releaseMutex ?87? - Released mutex permit [1]

dev:10252019-12-16 05:57:34.830 pm debugSharpAquos - getMutex 88 - Acquired mutex permit [0]

dev:10252019-12-16 05:57:34.826 pm debugSharpAquos - releaseMutex ?87? - Release mutex request [0]

dev:10252019-12-16 05:57:34.822 pm debugSharpAquos - parse - 1 - COMMAND [RCKY33 ] RESPONSE [ERR]

dev:10252019-12-16 05:57:34.819 pm debugSharpAquos - IOBUFFER: 2 entries [[RCKY33 , ERR]]

dev:10252019-12-16 05:57:34.811 pm debugSharpAquos - getMutex 88 - Acquire mutex request [0]

dev:10252019-12-16 05:57:34.808 pm debugSharpAquos - sendSocketCommand - telnet - sending [RCKY33 ]

dev:10252019-12-16 05:57:34.804 pm debugSharpAquos - request 87 - Interface [telnet] Socket [open] CMD [RCKY] Params [33]

dev:10252019-12-16 05:57:34.794 pm debugSharpAquos - getMutex 87 - Acquired mutex permit [0]

dev:10252019-12-16 05:57:34.790 pm debugSharpAquos - getMutex 87 - Acquire mutex request [1]

dev:10252019-12-16 05:57:34.786 pm debugSharpAquos - volumeUp()

[albertsmark]

Volumes up and down again, power on and off logs, all working great! Hub seems to be running good too!

Summary

dev:10252019-12-16 06:07:13.040 pm debugSharpAquos - releaseMutex ?135? - Released mutex permit [1]

dev:10252019-12-16 06:07:13.037 pm debugSharpAquos - releaseMutex ?135? - ERROR - Permit release not required [1]

dev:10252019-12-16 06:07:13.033 pm debugSharpAquos - releaseMutex ?135? - Release mutex request [1]

dev:10252019-12-16 06:07:13.029 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 06:07:12.999 pm debugSharpAquos - releaseMutex ?135? - Released mutex permit [1]

dev:10252019-12-16 06:07:12.995 pm debugSharpAquos - releaseMutex ?135? - ERROR - Permit release not required [1]

dev:10252019-12-16 06:07:12.992 pm debugSharpAquos - releaseMutex ?135? - Release mutex request [1]

dev:10252019-12-16 06:07:12.988 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 06:07:12.978 pm debugSharpAquos - releaseMutex ?135? - Released mutex permit [1]

dev:10252019-12-16 06:07:12.974 pm debugSharpAquos - releaseMutex ?135? - Release mutex request [0]

dev:10252019-12-16 06:07:12.971 pm debugSharpAquos - parsePower - ERROR - invalid power [ERR]

dev:10252019-12-16 06:07:12.968 pm debugSharpAquos - parsePower - response 'ERR'

dev:10252019-12-16 06:07:12.964 pm debugSharpAquos - parse - 1 - COMMAND [POWR? ] RESPONSE [ERR]

dev:10252019-12-16 06:07:12.960 pm debugSharpAquos - IOBUFFER: 2 entries [[POWR? , ERR]]

dev:10252019-12-16 06:07:12.957 pm debugSharpAquos - sendSocketCommand - telnet - sending [POWR? ]

dev:10252019-12-16 06:07:12.953 pm debugSharpAquos - request 136 - Interface [telnet] Socket [open] CMD [POWR] Params [?]

dev:10252019-12-16 06:07:12.949 pm debugSharpAquos - getMutex 136 - Acquired mutex permit [0]

dev:10252019-12-16 06:07:12.946 pm debugSharpAquos - releaseMutex ?135? - Released mutex permit [1]

dev:10252019-12-16 06:07:12.942 pm debugSharpAquos - releaseMutex ?135? - Release mutex request [0]

dev:10252019-12-16 06:07:12.938 pm debugSharpAquos - parsePower - ERROR - invalid power [ERR]

dev:10252019-12-16 06:07:12.929 pm debugSharpAquos - parsePower - response 'ERR'

dev:10252019-12-16 06:07:12.925 pm debugSharpAquos - parse - 1 - COMMAND [POWR1 ] RESPONSE [ERR]

dev:10252019-12-16 06:07:12.921 pm debugSharpAquos - IOBUFFER: 2 entries [[POWR1 , ERR]]

dev:10252019-12-16 06:07:12.917 pm debugSharpAquos - getMutex 136 - Acquire mutex request [0]

dev:10252019-12-16 06:07:12.913 pm debugSharpAquos - sendSocketCommand - telnet - sending [POWR1 ]

dev:10252019-12-16 06:07:12.908 pm debugSharpAquos - request 135 - Interface [telnet] Socket [open] CMD [POWR] Params [1]

dev:10252019-12-16 06:07:12.899 pm debugSharpAquos - getMutex 135 - Acquired mutex permit [0]

dev:10252019-12-16 06:07:12.895 pm debugSharpAquos - getMutex 135 - Acquire mutex request [1]

dev:10252019-12-16 06:07:12.891 pm debugSharpAquos - Turn on TV

dev:10252019-12-16 06:07:02.163 pm debugSharpAquos - releaseMutex ?133? - Released mutex permit [1]

dev:10252019-12-16 06:07:02.160 pm debugSharpAquos - releaseMutex ?133? - ERROR - Permit release not required [1]

dev:10252019-12-16 06:07:02.156 pm debugSharpAquos - releaseMutex ?133? - Release mutex request [1]

dev:10252019-12-16 06:07:02.151 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 06:07:02.143 pm debugSharpAquos - releaseMutex ?133? - Released mutex permit [1]

dev:10252019-12-16 06:07:02.139 pm debugSharpAquos - releaseMutex ?133? - ERROR - Permit release not required [1]

dev:10252019-12-16 06:07:02.135 pm debugSharpAquos - releaseMutex ?133? - Release mutex request [1]

dev:10252019-12-16 06:07:02.131 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 06:07:01.976 pm debugSharpAquos - releaseMutex ?133? - Released mutex permit [1]

dev:10252019-12-16 06:07:01.973 pm debugSharpAquos - releaseMutex ?133? - Release mutex request [0]

dev:10252019-12-16 06:07:01.968 pm debugSharpAquos - parse - 1 - COMMAND [POWR? ] RESPONSE [OK]

dev:10252019-12-16 06:07:01.964 pm debugSharpAquos - IOBUFFER: 2 entries [[POWR? , OK]]

dev:10252019-12-16 06:07:01.932 pm debugSharpAquos - sendSocketCommand - telnet - sending [POWR? ]

dev:10252019-12-16 06:07:01.928 pm debugSharpAquos - request 134 - Interface [telnet] Socket [open] CMD [POWR] Params [?]

dev:10252019-12-16 06:07:01.925 pm debugSharpAquos - getMutex 134 - Acquired mutex permit [0]

dev:10252019-12-16 06:07:01.921 pm debugSharpAquos - releaseMutex ?133? - Released mutex permit [1]

dev:10252019-12-16 06:07:01.917 pm debugSharpAquos - releaseMutex ?133? - Release mutex request [0]

dev:10252019-12-16 06:07:01.913 pm debugSharpAquos - parsePower - ERROR - invalid power [ERR]

dev:10252019-12-16 06:07:01.908 pm debugSharpAquos - parsePower - response 'ERR'

dev:10252019-12-16 06:07:01.903 pm debugSharpAquos - parse - 1 - COMMAND [POWR0 ] RESPONSE [ERR]

dev:10252019-12-16 06:07:01.900 pm debugSharpAquos - IOBUFFER: 2 entries [[POWR0 , ERR]]

dev:10252019-12-16 06:07:01.895 pm debugSharpAquos - getMutex 134 - Acquire mutex request [0]

dev:10252019-12-16 06:07:01.891 pm debugSharpAquos - sendSocketCommand - telnet - sending [POWR0 ]

dev:10252019-12-16 06:07:01.887 pm debugSharpAquos - request 133 - Interface [telnet] Socket [open] CMD [POWR] Params [0]

dev:10252019-12-16 06:07:01.879 pm debugSharpAquos - getMutex 133 - Acquired mutex permit [0]

dev:10252019-12-16 06:07:01.875 pm debugSharpAquos - getMutex 133 - Acquire mutex request [1]

dev:10252019-12-16 06:07:01.871 pm debugSharpAquos - Turn off TV

dev:10252019-12-16 06:06:20.127 pm debugSharpAquos - releaseMutex ?131? - Released mutex permit [1]

dev:10252019-12-16 06:06:20.123 pm debugSharpAquos - releaseMutex ?131? - ERROR - Permit release not required [1]

dev:10252019-12-16 06:06:20.120 pm debugSharpAquos - releaseMutex ?131? - Release mutex request [1]

dev:10252019-12-16 06:06:20.116 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 06:06:20.109 pm debugSharpAquos - releaseMutex ?131? - Released mutex permit [1]

dev:10252019-12-16 06:06:20.105 pm debugSharpAquos - releaseMutex ?131? - ERROR - Permit release not required [1]

dev:10252019-12-16 06:06:20.101 pm debugSharpAquos - releaseMutex ?131? - Release mutex request [1]

dev:10252019-12-16 06:06:20.097 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 06:06:20.093 pm debugSharpAquos - releaseMutex ?131? - Released mutex permit [1]

dev:10252019-12-16 06:06:20.089 pm debugSharpAquos - releaseMutex ?131? - Release mutex request [0]

dev:10252019-12-16 06:06:20.085 pm debugSharpAquos - parse - 1 - COMMAND [MUTE? ] RESPONSE [OK]

dev:10252019-12-16 06:06:20.081 pm debugSharpAquos - IOBUFFER: 2 entries [[MUTE? , OK]]

dev:10252019-12-16 06:06:20.077 pm debugSharpAquos - toggleMute() - completed

dev:10252019-12-16 06:06:20.073 pm debugSharpAquos - sendSocketCommand - telnet - sending [MUTE? ]

dev:10252019-12-16 06:06:20.070 pm debugSharpAquos - request 132 - Interface [telnet] Socket [open] CMD [MUTE] Params [?]

dev:10252019-12-16 06:06:20.066 pm debugSharpAquos - releaseMutex ?131? - Released mutex permit [1]

dev:10252019-12-16 06:06:20.062 pm debugSharpAquos - getMutex 132 - Acquired mutex permit [0]

dev:10252019-12-16 06:06:20.052 pm debugSharpAquos - releaseMutex ?131? - Release mutex request [0]

dev:10252019-12-16 06:06:20.048 pm debugSharpAquos - parse - 1 - COMMAND [RCKY31 ] RESPONSE [ERR]

dev:10252019-12-16 06:06:20.045 pm debugSharpAquos - IOBUFFER: 2 entries [[RCKY31 , ERR]]

dev:10252019-12-16 06:06:20.041 pm debugSharpAquos - getMutex 132 - Acquire mutex request [0]

dev:10252019-12-16 06:06:20.037 pm debugSharpAquos - sendSocketCommand - telnet - sending [RCKY31 ]

dev:10252019-12-16 06:06:20.033 pm debugSharpAquos - request 131 - Interface [telnet] Socket [open] CMD [RCKY] Params [31]

dev:10252019-12-16 06:06:20.024 pm debugSharpAquos - getMutex 131 - Acquired mutex permit [0]

dev:10252019-12-16 06:06:20.020 pm debugSharpAquos - getMutex 131 - Acquire mutex request [1]

dev:10252019-12-16 06:06:20.015 pm debugSharpAquos - toggleMute()

dev:10252019-12-16 06:06:17.140 pm debugSharpAquos - releaseMutex ?129? - Released mutex permit [1]

dev:10252019-12-16 06:06:17.122 pm debugSharpAquos - releaseMutex ?129? - ERROR - Permit release not required [1]

dev:10252019-12-16 06:06:17.118 pm debugSharpAquos - releaseMutex ?129? - Release mutex request [1]

dev:10252019-12-16 06:06:17.114 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 06:06:17.086 pm debugSharpAquos - releaseMutex ?129? - Released mutex permit [1]

dev:10252019-12-16 06:06:17.082 pm debugSharpAquos - releaseMutex ?129? - ERROR - Permit release not required [1]

dev:10252019-12-16 06:06:17.077 pm debugSharpAquos - releaseMutex ?129? - Release mutex request [1]

dev:10252019-12-16 06:06:17.072 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 06:06:16.880 pm debugSharpAquos - releaseMutex ?129? - Released mutex permit [1]

dev:10252019-12-16 06:06:16.877 pm debugSharpAquos - releaseMutex ?129? - Release mutex request [0]

dev:10252019-12-16 06:06:16.873 pm debugSharpAquos - parse - 1 - COMMAND [MUTE? ] RESPONSE [OK]

dev:10252019-12-16 06:06:16.870 pm debugSharpAquos - IOBUFFER: 2 entries [[MUTE? , OK]]

dev:10252019-12-16 06:06:16.866 pm debugSharpAquos - toggleMute() - completed

dev:10252019-12-16 06:06:16.862 pm debugSharpAquos - sendSocketCommand - telnet - sending [MUTE? ]

dev:10252019-12-16 06:06:16.859 pm debugSharpAquos - request 130 - Interface [telnet] Socket [open] CMD [MUTE] Params [?]

dev:10252019-12-16 06:06:16.855 pm debugSharpAquos - releaseMutex ?129? - Released mutex permit [1]

dev:10252019-12-16 06:06:16.851 pm debugSharpAquos - getMutex 130 - Acquired mutex permit [0]

dev:10252019-12-16 06:06:16.843 pm debugSharpAquos - releaseMutex ?129? - Release mutex request [0]

dev:10252019-12-16 06:06:16.839 pm debugSharpAquos - parse - 1 - COMMAND [RCKY31 ] RESPONSE [ERR]

dev:10252019-12-16 06:06:16.836 pm debugSharpAquos - IOBUFFER: 2 entries [[RCKY31 , ERR]]

dev:10252019-12-16 06:06:16.832 pm debugSharpAquos - getMutex 130 - Acquire mutex request [0]

dev:10252019-12-16 06:06:16.827 pm debugSharpAquos - sendSocketCommand - telnet - sending [RCKY31 ]

dev:10252019-12-16 06:06:16.823 pm debugSharpAquos - request 129 - Interface [telnet] Socket [open] CMD [RCKY] Params [31]

dev:10252019-12-16 06:06:16.812 pm debugSharpAquos - getMutex 129 - Acquired mutex permit [0]

dev:10252019-12-16 06:06:16.807 pm debugSharpAquos - getMutex 129 - Acquire mutex request [1]

dev:10252019-12-16 06:06:16.803 pm debugSharpAquos - toggleMute()

dev:10252019-12-16 06:06:13.730 pm debugSharpAquos - releaseMutex ?127? - Released mutex permit [1]

dev:10252019-12-16 06:06:13.723 pm debugSharpAquos - releaseMutex ?127? - ERROR - Permit release not required [1]

dev:10252019-12-16 06:06:13.717 pm debugSharpAquos - releaseMutex ?127? - Release mutex request [1]

dev:10252019-12-16 06:06:13.710 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 06:06:13.692 pm debugSharpAquos - releaseMutex ?127? - Released mutex permit [1]

dev:10252019-12-16 06:06:13.688 pm debugSharpAquos - releaseMutex ?127? - ERROR - Permit release not required [1]

dev:10252019-12-16 06:06:13.683 pm debugSharpAquos - releaseMutex ?127? - Release mutex request [1]

dev:10252019-12-16 06:06:13.679 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 06:06:13.667 pm debugSharpAquos - releaseMutex ?127? - Released mutex permit [1]

dev:10252019-12-16 06:06:13.663 pm debugSharpAquos - releaseMutex ?127? - Release mutex request [0]

dev:10252019-12-16 06:06:13.659 pm debugSharpAquos - parse - 1 - COMMAND [VOLM? ] RESPONSE [OK]

dev:10252019-12-16 06:06:13.656 pm debugSharpAquos - IOBUFFER: 2 entries [[VOLM? , OK]]

dev:10252019-12-16 06:06:13.652 pm debugSharpAquos - sendSocketCommand - telnet - sending [VOLM? ]

dev:10252019-12-16 06:06:13.648 pm debugSharpAquos - request 128 - Interface [telnet] Socket [open] CMD [VOLM] Params [?]

dev:10252019-12-16 06:06:13.645 pm debugSharpAquos - getMutex 128 - Acquired mutex permit [0]

dev:10252019-12-16 06:06:13.641 pm debugSharpAquos - releaseMutex ?127? - Released mutex permit [1]

dev:10252019-12-16 06:06:13.635 pm debugSharpAquos - releaseMutex ?127? - Release mutex request [0]

dev:10252019-12-16 06:06:13.631 pm debugSharpAquos - parse - 1 - COMMAND [RCKY32 ] RESPONSE [ERR]

dev:10252019-12-16 06:06:13.627 pm debugSharpAquos - IOBUFFER: 2 entries [[RCKY32 , ERR]]

dev:10252019-12-16 06:06:13.622 pm debugSharpAquos - getMutex 128 - Acquire mutex request [0]

dev:10252019-12-16 06:06:13.617 pm debugSharpAquos - sendSocketCommand - telnet - sending [RCKY32 ]

dev:10252019-12-16 06:06:13.613 pm debugSharpAquos - request 127 - Interface [telnet] Socket [open] CMD [RCKY] Params [32]

dev:10252019-12-16 06:06:13.607 pm debugSharpAquos - getMutex 127 - Acquired mutex permit [0]

dev:10252019-12-16 06:06:13.603 pm debugSharpAquos - getMutex 127 - Acquire mutex request [1]

dev:10252019-12-16 06:06:13.598 pm debugSharpAquos - volumeDown()

dev:10252019-12-16 06:06:09.825 pm debugSharpAquos - releaseMutex ?125? - Released mutex permit [1]

dev:10252019-12-16 06:06:09.821 pm debugSharpAquos - releaseMutex ?125? - ERROR - Permit release not required [1]

dev:10252019-12-16 06:06:09.817 pm debugSharpAquos - releaseMutex ?125? - Release mutex request [1]

dev:10252019-12-16 06:06:09.812 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 06:06:09.791 pm debugSharpAquos - releaseMutex ?125? - Released mutex permit [1]

dev:10252019-12-16 06:06:09.786 pm debugSharpAquos - releaseMutex ?125? - ERROR - Permit release not required [1]

dev:10252019-12-16 06:06:09.779 pm debugSharpAquos - releaseMutex ?125? - Release mutex request [1]

dev:10252019-12-16 06:06:09.775 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 06:06:09.748 pm debugSharpAquos - releaseMutex ?125? - Released mutex permit [1]

dev:10252019-12-16 06:06:09.744 pm debugSharpAquos - releaseMutex ?125? - Release mutex request [0]

dev:10252019-12-16 06:06:09.740 pm debugSharpAquos - parse - 1 - COMMAND [VOLM? ] RESPONSE [OK]

dev:10252019-12-16 06:06:09.736 pm debugSharpAquos - IOBUFFER: 2 entries [[VOLM? , OK]]

dev:10252019-12-16 06:06:09.703 pm debugSharpAquos - sendSocketCommand - telnet - sending [VOLM? ]

dev:10252019-12-16 06:06:09.699 pm debugSharpAquos - request 126 - Interface [telnet] Socket [open] CMD [VOLM] Params [?]

dev:10252019-12-16 06:06:09.694 pm debugSharpAquos - releaseMutex ?125? - Released mutex permit [1]

dev:10252019-12-16 06:06:09.690 pm debugSharpAquos - getMutex 126 - Acquired mutex permit [0]

dev:10252019-12-16 06:06:09.686 pm debugSharpAquos - releaseMutex ?125? - Release mutex request [0]

dev:10252019-12-16 06:06:09.682 pm debugSharpAquos - parse - 1 - COMMAND [RCKY33 ] RESPONSE [ERR]

dev:10252019-12-16 06:06:09.678 pm debugSharpAquos - IOBUFFER: 2 entries [[RCKY33 , ERR]]

dev:10252019-12-16 06:06:09.673 pm debugSharpAquos - getMutex 126 - Acquire mutex request [0]

dev:10252019-12-16 06:06:09.670 pm debugSharpAquos - sendSocketCommand - telnet - sending [RCKY33 ]

dev:10252019-12-16 06:06:09.665 pm debugSharpAquos - request 125 - Interface [telnet] Socket [open] CMD [RCKY] Params [33]

dev:10252019-12-16 06:06:09.650 pm debugSharpAquos - getMutex 125 - Acquired mutex permit [0]

dev:10252019-12-16 06:06:09.647 pm debugSharpAquos - getMutex 125 - Acquire mutex request [1]

dev:10252019-12-16 06:06:09.643 pm debugSharpAquos - volumeUp()

[albertsmark]

Quick update....everything running great now. All buttons still working and very quick and most importantly the hub is operating very good now, no hub delays/slowdowns/lockups like I had before. No errors either, here is a recent BIG sample log of good running.....

Summary

dev:10252019-12-16 09:29:58.418 pm debugSharpAquos - releaseMutex ?564? - Released mutex permit [1]

dev:10252019-12-16 09:29:58.414 pm debugSharpAquos - releaseMutex ?564? - ERROR - Permit release not required [1]

dev:10252019-12-16 09:29:58.409 pm debugSharpAquos - releaseMutex ?564? - Release mutex request [1]

dev:10252019-12-16 09:29:58.405 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 09:29:58.391 pm debugSharpAquos - releaseMutex ?564? - Released mutex permit [1]

dev:10252019-12-16 09:29:58.386 pm debugSharpAquos - releaseMutex ?564? - ERROR - Permit release not required [1]

dev:10252019-12-16 09:29:58.382 pm debugSharpAquos - releaseMutex ?564? - Release mutex request [1]

dev:10252019-12-16 09:29:58.371 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 09:29:58.367 pm debugSharpAquos - releaseMutex ?564? - Released mutex permit [1]

dev:10252019-12-16 09:29:58.352 pm debugSharpAquos - releaseMutex ?564? - ERROR - Permit release not required [1]

dev:10252019-12-16 09:29:58.348 pm debugSharpAquos - releaseMutex ?564? - Release mutex request [1]

dev:10252019-12-16 09:29:58.344 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 09:29:58.318 pm debugSharpAquos - releaseMutex ?564? - Released mutex permit [1]

dev:10252019-12-16 09:29:58.312 pm debugSharpAquos - releaseMutex ?564? - ERROR - Permit release not required [1]

dev:10252019-12-16 09:29:58.306 pm debugSharpAquos - releaseMutex ?564? - Release mutex request [1]

dev:10252019-12-16 09:29:58.300 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 09:29:58.263 pm debugSharpAquos - releaseMutex ?564? - Released mutex permit [1]

dev:10252019-12-16 09:29:58.256 pm debugSharpAquos - releaseMutex ?564? - ERROR - Permit release not required [1]

dev:10252019-12-16 09:29:58.250 pm debugSharpAquos - releaseMutex ?564? - Release mutex request [1]

dev:10252019-12-16 09:29:58.244 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 09:29:58.220 pm debugSharpAquos - releaseMutex ?564? - Released mutex permit [1]

dev:10252019-12-16 09:29:58.216 pm debugSharpAquos - releaseMutex ?564? - ERROR - Permit release not required [1]

dev:10252019-12-16 09:29:58.203 pm debugSharpAquos - releaseMutex ?564? - Release mutex request [1]

dev:10252019-12-16 09:29:58.196 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 09:29:58.178 pm debugSharpAquos - releaseMutex ?564? - Released mutex permit [1]

dev:10252019-12-16 09:29:58.174 pm debugSharpAquos - releaseMutex ?564? - ERROR - Permit release not required [1]

dev:10252019-12-16 09:29:58.170 pm debugSharpAquos - releaseMutex ?564? - Release mutex request [1]

dev:10252019-12-16 09:29:58.164 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 09:29:58.135 pm debugSharpAquos - releaseMutex ?564? - Released mutex permit [1]

dev:10252019-12-16 09:29:58.131 pm debugSharpAquos - releaseMutex ?564? - ERROR - Permit release not required [1]

dev:10252019-12-16 09:29:58.127 pm debugSharpAquos - releaseMutex ?564? - Release mutex request [1]

dev:10252019-12-16 09:29:58.123 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 09:29:58.097 pm debugSharpAquos - releaseMutex ?564? - Released mutex permit [1]

dev:10252019-12-16 09:29:58.078 pm debugSharpAquos - releaseMutex ?564? - ERROR - Permit release not required [1]

dev:10252019-12-16 09:29:58.070 pm debugSharpAquos - releaseMutex ?564? - Release mutex request [1]

dev:10252019-12-16 09:29:58.066 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 09:29:58.052 pm debugSharpAquos - releaseMutex ?564? - Released mutex permit [1]

dev:10252019-12-16 09:29:58.048 pm debugSharpAquos - releaseMutex ?564? - ERROR - Permit release not required [1]

dev:10252019-12-16 09:29:58.044 pm debugSharpAquos - releaseMutex ?564? - Release mutex request [1]

dev:10252019-12-16 09:29:58.040 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 09:29:58.036 pm debugSharpAquos - releaseMutex ?564? - Released mutex permit [1]

dev:10252019-12-16 09:29:58.033 pm debugSharpAquos - releaseMutex ?564? - ERROR - Permit release not required [1]

dev:10252019-12-16 09:29:58.029 pm debugSharpAquos - releaseMutex ?564? - Release mutex request [1]

dev:10252019-12-16 09:29:58.025 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 09:29:58.021 pm debugSharpAquos - releaseMutex ?564? - Released mutex permit [1]

dev:10252019-12-16 09:29:58.017 pm debugSharpAquos - releaseMutex ?564? - ERROR - Permit release not required [1]

dev:10252019-12-16 09:29:58.013 pm debugSharpAquos - releaseMutex ?564? - Release mutex request [1]

dev:10252019-12-16 09:29:58.009 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 09:29:58.004 pm debugSharpAquos - releaseMutex ?564? - Released mutex permit [1]

dev:10252019-12-16 09:29:58.000 pm debugSharpAquos - releaseMutex ?564? - ERROR - Permit release not required [1]

dev:10252019-12-16 09:29:57.997 pm debugSharpAquos - releaseMutex ?564? - Release mutex request [1]

dev:10252019-12-16 09:29:57.993 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 09:29:57.989 pm debugSharpAquos - releaseMutex ?564? - Released mutex permit [1]

dev:10252019-12-16 09:29:57.984 pm debugSharpAquos - releaseMutex ?564? - ERROR - Permit release not required [1]

dev:10252019-12-16 09:29:57.980 pm debugSharpAquos - releaseMutex ?564? - Release mutex request [1]

dev:10252019-12-16 09:29:57.977 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 09:29:57.973 pm debugSharpAquos - releaseMutex ?564? - Released mutex permit [1]

dev:10252019-12-16 09:29:57.969 pm debugSharpAquos - releaseMutex ?564? - ERROR - Permit release not required [1]

dev:10252019-12-16 09:29:57.966 pm debugSharpAquos - releaseMutex ?564? - Release mutex request [1]

dev:10252019-12-16 09:29:57.961 pm debugSharpAquos - parseDC11 - ERROR - received [ERR]

dev:10252019-12-16 09:29:57.957 pm debugSharpAquos - parseDC11 - response 'ERR'

dev:10252019-12-16 09:29:57.953 pm debugSharpAquos - parse - 1 - COMMAND [DC11? ] RESPONSE [ERR]

dev:10252019-12-16 09:29:57.949 pm debugSharpAquos - IOBUFFER: 2 entries [[DC11? , ERR]]

dev:10252019-12-16 09:29:57.945 pm debugSharpAquos - releaseMutex ?564? - Released mutex permit [1]

dev:10252019-12-16 09:29:57.941 pm debugSharpAquos - releaseMutex ?564? - Release mutex request [0]

dev:10252019-12-16 09:29:57.936 pm debugSharpAquos - parseDC10 - ERROR - received [ERR]

dev:10252019-12-16 09:29:57.932 pm debugSharpAquos - parseDC10 - response 'ERR'

dev:10252019-12-16 09:29:57.928 pm debugSharpAquos - parse - 2 - COMMAND [DC10? ] RESPONSE [ERR]

dev:10252019-12-16 09:29:57.924 pm debugSharpAquos - IOBUFFER: 3 entries [[DC10? , DC11? , ERR]]

dev:10252019-12-16 09:29:57.920 pm debugSharpAquos - getDeviceState() Completed.

dev:10252019-12-16 09:29:57.915 pm debugSharpAquos - getChannelStatus() Completed.

dev:10252019-12-16 09:29:57.910 pm debugSharpAquos - releaseMutex ?564? - Released mutex permit [1]

dev:10252019-12-16 09:29:57.905 pm debugSharpAquos - sendSocketCommand - telnet - sending [DC11? ]

dev:10252019-12-16 09:29:57.865 pm debugSharpAquos - request 565 - Interface [telnet] Socket [open] CMD [DC11] Params [?]

dev:10252019-12-16 09:29:57.860 pm debugSharpAquos - getMutex 565 - Acquired mutex permit [0]

dev:10252019-12-16 09:29:57.855 pm debugSharpAquos - releaseMutex ?564? - Release mutex request [0]

dev:10252019-12-16 09:29:57.851 pm debugSharpAquos - parseDC2L - ERROR - could not process response [ERR]

dev:10252019-12-16 09:29:57.847 pm debugSharpAquos - parseDC2L - response 'ERR'

dev:10252019-12-16 09:29:57.842 pm debugSharpAquos - parse - 2 - COMMAND [DC2L? ] RESPONSE [ERR]

dev:10252019-12-16 09:29:57.838 pm debugSharpAquos - IOBUFFER: 3 entries [[DC2L? , DC10? , ERR]]

dev:10252019-12-16 09:29:57.833 pm debugSharpAquos - releaseMutex ?563? - Released mutex permit [1]

dev:10252019-12-16 09:29:57.829 pm debugSharpAquos - getMutex 565 - Acquire mutex request [0]

dev:10252019-12-16 09:29:57.824 pm debugSharpAquos - sendSocketCommand - telnet - sending [DC10? ]

dev:10252019-12-16 09:29:57.819 pm debugSharpAquos - request 564 - Interface [telnet] Socket [open] CMD [DC10] Params [?]

dev:10252019-12-16 09:29:57.815 pm debugSharpAquos - getMutex 564 - Acquired mutex permit [0]

dev:10252019-12-16 09:29:57.810 pm debugSharpAquos - releaseMutex ?563? - Release mutex request [0]

dev:10252019-12-16 09:29:57.806 pm debugSharpAquos - parseDC2U - ERROR - could not process response [ERR]

dev:10252019-12-16 09:29:57.802 pm debugSharpAquos - parseDC2U - response 'ERR'

dev:10252019-12-16 09:29:57.797 pm debugSharpAquos - parse - 2 - COMMAND [DC2U? ] RESPONSE [ERR]

dev:10252019-12-16 09:29:57.780 pm debugSharpAquos - IOBUFFER: 3 entries [[DC2U? , DC2L? , ERR]]

dev:10252019-12-16 09:29:57.775 pm debugSharpAquos - getMutex 564 - Acquire mutex request [0]

dev:10252019-12-16 09:29:57.771 pm debugSharpAquos - sendSocketCommand - telnet - sending [DC2L? ]

dev:10252019-12-16 09:29:57.766 pm debugSharpAquos - request 563 - Interface [telnet] Socket [open] CMD [DC2L] Params [?]

dev:10252019-12-16 09:29:57.761 pm debugSharpAquos - releaseMutex ?562? - Released mutex permit [1]

dev:10252019-12-16 09:29:57.757 pm debugSharpAquos - getMutex 563 - Acquired mutex permit [0]

dev:10252019-12-16 09:29:57.752 pm debugSharpAquos - releaseMutex ?562? - Release mutex request [0]

dev:10252019-12-16 09:29:57.748 pm debugSharpAquos - parseDA2P - SET - channel_type [Digital] channel_source [Air] channel [2]

dev:10252019-12-16 09:29:57.743 pm infoSharpAquos - EVENT - [[name:channel, value:2]]

dev:10252019-12-16 09:29:57.738 pm infoSharpAquos - EVENT - [[name:channel_source, value:Air]]

dev:10252019-12-16 09:29:57.733 pm infoSharpAquos - EVENT - [[name:channel_type, value:Digital]]

dev:10252019-12-16 09:29:57.724 pm debugSharpAquos - parseDA2P - response '2'

dev:10252019-12-16 09:29:57.716 pm debugSharpAquos - parse - 2 - COMMAND [DA2P? ] RESPONSE [2]

dev:10252019-12-16 09:29:57.711 pm debugSharpAquos - IOBUFFER: 3 entries [[DA2P? , DC2U? , 2]]

dev:10252019-12-16 09:29:57.707 pm debugSharpAquos - getMutex 563 - Acquire mutex request [0]

dev:10252019-12-16 09:29:57.702 pm debugSharpAquos - sendSocketCommand - telnet - sending [DC2U? ]

dev:10252019-12-16 09:29:57.698 pm debugSharpAquos - releaseMutex ?561? - Released mutex permit [0]

dev:10252019-12-16 09:29:57.693 pm debugSharpAquos - request 562 - Interface [telnet] Socket [open] CMD [DC2U] Params [?]

dev:10252019-12-16 09:29:57.689 pm debugSharpAquos - getMutex 562 - Acquired mutex permit [0]

dev:10252019-12-16 09:29:57.684 pm debugSharpAquos - releaseMutex ?561? - Release mutex request [0]

dev:10252019-12-16 09:29:57.679 pm debugSharpAquos - parseDCCH - ERROR - received [ERR]

dev:10252019-12-16 09:29:57.675 pm debugSharpAquos - parseDCCH - response 'ERR'

dev:10252019-12-16 09:29:57.670 pm debugSharpAquos - parse - 2 - COMMAND [DCCH? ] RESPONSE [ERR]

dev:10252019-12-16 09:29:57.665 pm debugSharpAquos - IOBUFFER: 3 entries [[DCCH? , DA2P? , ERR]]

dev:10252019-12-16 09:29:57.660 pm debugSharpAquos - getMutex 562 - Acquire mutex request [0]

dev:10252019-12-16 09:29:57.650 pm debugSharpAquos - sendSocketCommand - telnet - sending [DA2P? ]

dev:10252019-12-16 09:29:57.645 pm debugSharpAquos - request 561 - Interface [telnet] Socket [open] CMD [DA2P] Params [?]

dev:10252019-12-16 09:29:57.641 pm debugSharpAquos - getMutex 561 - Acquired mutex permit [0]

dev:10252019-12-16 09:29:57.634 pm debugSharpAquos - releaseMutex ?560? - Released mutex permit [1]

dev:10252019-12-16 09:29:57.629 pm debugSharpAquos - releaseMutex ?560? - Release mutex request [0]

dev:10252019-12-16 09:29:57.624 pm debugSharpAquos - parseSleepTimer - ERROR - Invalid SleepTimer [8]

dev:10252019-12-16 09:29:57.611 pm debugSharpAquos - parseSleepTimer - response '8'

dev:10252019-12-16 09:29:57.598 pm debugSharpAquos - parse - 2 - COMMAND [OFTM? ] RESPONSE [8]

dev:10252019-12-16 09:29:57.593 pm debugSharpAquos - IOBUFFER: 3 entries [[OFTM? , DCCH? , 8]]

dev:10252019-12-16 09:29:57.588 pm debugSharpAquos - getMutex 561 - Acquire mutex request [0]

dev:10252019-12-16 09:29:57.584 pm debugSharpAquos - sendSocketCommand - telnet - sending [DCCH? ]

dev:10252019-12-16 09:29:57.579 pm debugSharpAquos - request 560 - Interface [telnet] Socket [open] CMD [DCCH] Params [?]

dev:10252019-12-16 09:29:57.573 pm debugSharpAquos - getMutex 560 - Acquired mutex permit [0]

dev:10252019-12-16 09:29:57.568 pm debugSharpAquos - releaseMutex ?559? - Released mutex permit [1]

dev:10252019-12-16 09:29:57.561 pm debugSharpAquos - releaseMutex ?559? - Release mutex request [0]

dev:10252019-12-16 09:29:57.557 pm debugSharpAquos - parse3DMode - ERROR - Invalid 3DMode [ERR]

dev:10252019-12-16 09:29:57.553 pm debugSharpAquos - parse3DMode - response 'ERR'

dev:10252019-12-16 09:29:57.548 pm debugSharpAquos - parse - 2 - COMMAND [TDCH? ] RESPONSE [ERR]

dev:10252019-12-16 09:29:57.543 pm debugSharpAquos - IOBUFFER: 3 entries [[TDCH? , OFTM? , ERR]]

dev:10252019-12-16 09:29:57.537 pm debugSharpAquos - getMutex 560 - Acquire mutex request [0]

dev:10252019-12-16 09:29:57.532 pm debugSharpAquos - getChannelStatus() Starting

dev:10252019-12-16 09:29:57.528 pm debugSharpAquos - releaseMutex ?558? - Released mutex permit [0]

dev:10252019-12-16 09:29:57.523 pm debugSharpAquos - sendSocketCommand - telnet - sending [OFTM? ]

dev:10252019-12-16 09:29:57.519 pm debugSharpAquos - request 559 - Interface [telnet] Socket [open] CMD [OFTM] Params [?]

dev:10252019-12-16 09:29:57.509 pm debugSharpAquos - getMutex 559 - Acquired mutex permit [0]

dev:10252019-12-16 09:29:57.504 pm debugSharpAquos - releaseMutex ?558? - Release mutex request [0]

dev:10252019-12-16 09:29:57.499 pm debugSharpAquos - parseVideoMode - SET - VideoMode [1] VideoModeName [STANDARD]

dev:10252019-12-16 09:29:57.495 pm infoSharpAquos - EVENT - [[name:VideoModeName, value:STANDARD]]

dev:10252019-12-16 09:29:57.490 pm infoSharpAquos - EVENT - [[name:VideoMode, value:1]]

dev:10252019-12-16 09:29:57.485 pm debugSharpAquos - parseVideoMode - response '1'

dev:10252019-12-16 09:29:57.481 pm debugSharpAquos - parse - 2 - COMMAND [AVMD? ] RESPONSE [1]

dev:10252019-12-16 09:29:57.476 pm debugSharpAquos - IOBUFFER: 3 entries [[AVMD? , TDCH? , 1]]

dev:10252019-12-16 09:29:57.472 pm debugSharpAquos - releaseMutex ?557? - Released mutex permit [1]

dev:10252019-12-16 09:29:57.468 pm debugSharpAquos - getMutex 559 - Acquire mutex request [0]

dev:10252019-12-16 09:29:57.463 pm debugSharpAquos - sendSocketCommand - telnet - sending [TDCH? ]

dev:10252019-12-16 09:29:57.459 pm debugSharpAquos - request 558 - Interface [telnet] Socket [open] CMD [TDCH] Params [?]

dev:10252019-12-16 09:29:57.455 pm debugSharpAquos - getMutex 558 - Acquired mutex permit [0]

dev:10252019-12-16 09:29:57.446 pm debugSharpAquos - releaseMutex ?557? - Release mutex request [0]

dev:10252019-12-16 09:29:57.442 pm debugSharpAquos - parseSurroundMode - ERROR - Invalid SurroundMode [ERR]

dev:10252019-12-16 09:29:57.438 pm debugSharpAquos - parseSurround - response 'ERR'

dev:10252019-12-16 09:29:57.434 pm debugSharpAquos - parse - 2 - COMMAND [ACSU? ] RESPONSE [ERR]

dev:10252019-12-16 09:29:57.430 pm debugSharpAquos - IOBUFFER: 3 entries [[ACSU? , AVMD? , ERR]]

dev:10252019-12-16 09:29:57.426 pm debugSharpAquos - getMutex 558 - Acquire mutex request [0]

dev:10252019-12-16 09:29:57.421 pm debugSharpAquos - sendSocketCommand - telnet - sending [AVMD? ]

dev:10252019-12-16 09:29:57.417 pm debugSharpAquos - request 557 - Interface [telnet] Socket [open] CMD [AVMD] Params [?]

dev:10252019-12-16 09:29:57.413 pm debugSharpAquos - releaseMutex ?556? - Released mutex permit [1]

dev:10252019-12-16 09:29:57.409 pm debugSharpAquos - getMutex 557 - Acquired mutex permit [0]

dev:10252019-12-16 09:29:57.401 pm debugSharpAquos - releaseMutex ?556? - Release mutex request [0]

dev:10252019-12-16 09:29:57.397 pm debugSharpAquos - parseViewMode - ERROR - Invalid ViewMode [12]

dev:10252019-12-16 09:29:57.392 pm debugSharpAquos - parseViewMode - response '12'

dev:10252019-12-16 09:29:57.388 pm debugSharpAquos - parse - 2 - COMMAND [WIDE? ] RESPONSE [12]

dev:10252019-12-16 09:29:57.376 pm debugSharpAquos - IOBUFFER: 3 entries [[WIDE? , ACSU? , 12]]

dev:10252019-12-16 09:29:57.371 pm debugSharpAquos - getMutex 557 - Acquire mutex request [0]

dev:10252019-12-16 09:29:57.367 pm debugSharpAquos - releaseMutex ?555? - Released mutex permit [0]

dev:10252019-12-16 09:29:57.363 pm debugSharpAquos - sendSocketCommand - telnet - sending [ACSU? ]

dev:10252019-12-16 09:29:57.355 pm debugSharpAquos - request 556 - Interface [telnet] Socket [open] CMD [ACSU] Params [?]

dev:10252019-12-16 09:29:57.339 pm debugSharpAquos - getMutex 556 - Acquired mutex permit [0]

dev:10252019-12-16 09:29:57.335 pm debugSharpAquos - releaseMutex ?555? - Release mutex request [0]

dev:10252019-12-16 09:29:57.330 pm debugSharpAquos - parseInput - SET - input [0] InputName [TV]

dev:10252019-12-16 09:29:57.326 pm infoSharpAquos - EVENT - [[name:inputName, value:TV]]

dev:10252019-12-16 09:29:57.322 pm infoSharpAquos - EVENT - [[name:input, value:0]]

dev:10252019-12-16 09:29:57.317 pm debugSharpAquos - parseInput - response 'ERR -> 0'

dev:10252019-12-16 09:29:57.313 pm debugSharpAquos - parse - 2 - COMMAND [IAVD? ] RESPONSE [ERR]

dev:10252019-12-16 09:29:57.306 pm debugSharpAquos - IOBUFFER: 3 entries [[IAVD? , WIDE? , ERR]]

dev:10252019-12-16 09:29:57.302 pm debugSharpAquos - getMutex 556 - Acquire mutex request [0]

dev:10252019-12-16 09:29:57.297 pm debugSharpAquos - sendSocketCommand - telnet - sending [WIDE? ]

dev:10252019-12-16 09:29:57.291 pm debugSharpAquos - request 555 - Interface [telnet] Socket [open] CMD [WIDE] Params [?]

dev:10252019-12-16 09:29:57.286 pm debugSharpAquos - getMutex 555 - Acquired mutex permit [0]

dev:10252019-12-16 09:29:57.282 pm debugSharpAquos - releaseMutex ?554? - Released mutex permit [1]

dev:10252019-12-16 09:29:57.277 pm debugSharpAquos - releaseMutex ?554? - Release mutex request [0]

dev:10252019-12-16 09:29:57.270 pm debugSharpAquos - parseVolume - SET - volume [2]

dev:10252019-12-16 09:29:57.265 pm infoSharpAquos - EVENT - [[name:volume, value:2]]

dev:10252019-12-16 09:29:57.261 pm debugSharpAquos - parseVolume - response '2'

dev:10252019-12-16 09:29:57.256 pm debugSharpAquos - parse - 2 - COMMAND [VOLM? ] RESPONSE [2]

dev:10252019-12-16 09:29:57.250 pm debugSharpAquos - IOBUFFER: 3 entries [[VOLM? , IAVD? , 2]]

dev:10252019-12-16 09:29:57.246 pm debugSharpAquos - getMutex 555 - Acquire mutex request [0]

dev:10252019-12-16 09:29:57.241 pm debugSharpAquos - sendSocketCommand - telnet - sending [IAVD? ]

dev:10252019-12-16 09:29:57.236 pm debugSharpAquos - request 554 - Interface [telnet] Socket [open] CMD [IAVD] Params [?]

dev:10252019-12-16 09:29:57.232 pm debugSharpAquos - getMutex 554 - Acquired mutex permit [0]

dev:10252019-12-16 09:29:57.227 pm debugSharpAquos - releaseMutex ?552? - Released mutex permit [1]

dev:10252019-12-16 09:29:57.220 pm debugSharpAquos - getMutex 554 - Acquire mutex request [1]

dev:10252019-12-16 09:29:57.207 pm debugSharpAquos - releaseMutex ?552? - Release mutex request [0]

dev:10252019-12-16 09:29:57.201 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-16 09:29:57.196 pm debugSharpAquos - releaseMutex ?552? - Released mutex permit [0]

dev:10252019-12-16 09:29:57.187 pm debugSharpAquos - sendSocketCommand - telnet - sending [VOLM? ]

dev:10252019-12-16 09:29:57.183 pm debugSharpAquos - request 553 - Interface [telnet] Socket [open] CMD [VOLM] Params [?]

dev:10252019-12-16 09:29:57.179 pm debugSharpAquos - getMutex 553 - Acquired mutex permit [0]

dev:10252019-12-16 09:29:57.170 pm debugSharpAquos - releaseMutex ?552? - Release mutex request [0]

dev:10252019-12-16 09:29:57.165 pm debugSharpAquos - parseMute - SET - mute [muted]

dev:10252019-12-16 09:29:57.160 pm infoSharpAquos - EVENT - [[name:mute, value:muted]]

dev:10252019-12-16 09:29:57.156 pm debugSharpAquos - parseMute - response '1'

dev:10252019-12-16 09:29:57.149 pm debugSharpAquos - parse - 1 - COMMAND [MUTE? ] RESPONSE [1]

dev:10252019-12-16 09:29:57.136 pm debugSharpAquos - IOBUFFER: 2 entries [[MUTE? , 1]]

dev:10252019-12-16 09:29:57.130 pm debugSharpAquos - getMutex 553 - Acquire mutex request [0]

dev:10252019-12-16 09:29:57.119 pm debugSharpAquos - sendSocketCommand - telnet - sending [MUTE? ]

dev:10252019-12-16 09:29:57.115 pm debugSharpAquos - request 552 - Interface [telnet] Socket [open] CMD [MUTE] Params [?]

dev:10252019-12-16 09:29:57.110 pm debugSharpAquos - getMutex 552 - Acquired mutex permit [0]

dev:10252019-12-16 09:29:57.106 pm debugSharpAquos - releaseMutex ?551? - Released mutex permit [1]

dev:10252019-12-16 09:29:57.102 pm debugSharpAquos - releaseMutex ?551? - Release mutex request [0]

dev:10252019-12-16 09:29:57.098 pm debugSharpAquos - parsePower - ERROR - invalid power [ERR]

dev:10252019-12-16 09:29:57.094 pm debugSharpAquos - parsePower - response 'ERR'

dev:10252019-12-16 09:29:57.085 pm debugSharpAquos - parse - 1 - COMMAND [POWR? ] RESPONSE [ERR]

dev:10252019-12-16 09:29:57.081 pm debugSharpAquos - IOBUFFER: 2 entries [[POWR? , ERR]]

dev:10252019-12-16 09:29:57.077 pm debugSharpAquos - getMutex 552 - Acquire mutex request [0]

dev:10252019-12-16 09:29:57.072 pm debugSharpAquos - sendSocketCommand - telnet - sending [POWR? ]

dev:10252019-12-16 09:29:57.049 pm debugSharpAquos - request 551 - Interface [telnet] Socket [open] CMD [POWR] Params [?]

dev:10252019-12-16 09:29:57.042 pm debugSharpAquos - getMutex 551 - Acquired mutex permit [0]

dev:10252019-12-16 09:29:57.035 pm debugSharpAquos - getMutex 551 - Acquire mutex request [1]

dev:10252019-12-16 09:29:57.030 pm debugSharpAquos - getDeviceState() Starting

[albertsmark]

Update 2, every things running great, no errors, actions are very quick to control the tv. Hub is running great, no lags at all. Logs are just like what I posted above. @cybrmage had outdone himself.....fantastic job! Will see how things go through the night.

[albertsmark]

Turn on commands in the morning....things were a little slow but seem to work ok after a minute......

Summary

dev:10252019-12-17 06:19:48.906 am debugSharpAquos - getMutex 2066 - Acquire mutex request [0]

dev:10252019-12-17 06:19:48.904 am debugSharpAquos - sendSocketCommand - telnet - sending [POWR1 ]

dev:10252019-12-17 06:19:48.902 am debugSharpAquos - request 2065 - Interface [telnet] Socket [open] CMD [POWR] Params [1]

dev:10252019-12-17 06:19:48.898 am debugSharpAquos - getMutex 2065 - Acquired mutex permit [0]

dev:10252019-12-17 06:19:48.897 am debugSharpAquos - getMutex 2065 - Acquire mutex request [1]

dev:10252019-12-17 06:19:48.896 am debugSharpAquos - Turn on TV

dev:10252019-12-17 06:17:36.601 am debugSharpAquos - releaseMutex ?2063? - Released mutex permit [1]

dev:10252019-12-17 06:17:36.599 am debugSharpAquos - releaseMutex ?2063? - ERROR - Permit release not required [1]

dev:10252019-12-17 06:17:36.597 am debugSharpAquos - releaseMutex ?2063? - Release mutex request [1]

dev:10252019-12-17 06:17:36.595 am debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-17 06:17:36.579 am debugSharpAquos - releaseMutex ?2063? - Released mutex permit [1]

dev:10252019-12-17 06:17:36.577 am debugSharpAquos - releaseMutex ?2063? - ERROR - Permit release not required [1]

dev:10252019-12-17 06:17:36.576 am debugSharpAquos - releaseMutex ?2063? - Release mutex request [1]

dev:10252019-12-17 06:17:36.571 am debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-17 06:17:36.544 am debugSharpAquos - releaseMutex ?2063? - Released mutex permit [1]

dev:10252019-12-17 06:17:36.542 am debugSharpAquos - releaseMutex ?2063? - Release mutex request [0]

dev:10252019-12-17 06:17:36.540 am debugSharpAquos - parsePower - ERROR - invalid power [ERR]

dev:10252019-12-17 06:17:36.538 am debugSharpAquos - parsePower - response 'ERR'

dev:10252019-12-17 06:17:36.535 am debugSharpAquos - parse - 1 - COMMAND [POWR? ] RESPONSE [ERR]

dev:10252019-12-17 06:17:36.533 am debugSharpAquos - IOBUFFER: 2 entries [[POWR? , ERR]]

dev:10252019-12-17 06:17:36.497 am debugSharpAquos - sendSocketCommand - telnet - sending [POWR? ]

dev:10252019-12-17 06:17:36.494 am debugSharpAquos - request 2064 - Interface [telnet] Socket [open] CMD [POWR] Params [?]

dev:10252019-12-17 06:17:36.485 am debugSharpAquos - getMutex 2064 - Acquired mutex permit [0]

dev:10252019-12-17 06:17:36.485 am debugSharpAquos - releaseMutex ?2063? - Released mutex permit [1]

dev:10252019-12-17 06:17:36.483 am debugSharpAquos - releaseMutex ?2063? - Release mutex request [0]

dev:10252019-12-17 06:17:36.481 am debugSharpAquos - parsePower - ERROR - invalid power [ERR]

dev:10252019-12-17 06:17:36.479 am debugSharpAquos - parsePower - response 'ERR'

dev:10252019-12-17 06:17:36.477 am debugSharpAquos - parse - 1 - COMMAND [POWR1 ] RESPONSE [ERR]

dev:10252019-12-17 06:17:36.475 am debugSharpAquos - IOBUFFER: 2 entries [[POWR1 , ERR]]

dev:10252019-12-17 06:17:36.455 am debugSharpAquos - getMutex 2064 - Acquire mutex request [0]

dev:10252019-12-17 06:17:36.453 am debugSharpAquos - sendSocketCommand - telnet - sending [POWR1 ]

dev:10252019-12-17 06:17:36.450 am debugSharpAquos - request 2063 - Interface [telnet] Socket [open] CMD [POWR] Params [1]

dev:10252019-12-17 06:17:36.446 am debugSharpAquos - getMutex 2063 - Acquired mutex permit [0]

dev:10252019-12-17 06:17:36.442 am debugSharpAquos - getMutex 2063 - Acquire mutex request [1]

dev:10252019-12-17 06:17:36.440 am debugSharpAquos - Turn on TV

dev:10252019-12-17 06:14:57.901 am debugSharpAquos - releaseMutex ?2061? - Released mutex permit [1]

dev:10252019-12-17 06:14:57.900 am debugSharpAquos - releaseMutex ?2061? - ERROR - Permit release not required [1]

dev:10252019-12-17 06:14:57.897 am debugSharpAquos - releaseMutex ?2061? - Release mutex request [1]

dev:10252019-12-17 06:14:57.895 am debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-17 06:14:57.879 am debugSharpAquos - releaseMutex ?2061? - Released mutex permit [1]

dev:10252019-12-17 06:14:57.878 am debugSharpAquos - releaseMutex ?2061? - ERROR - Permit release not required [1]

dev:10252019-12-17 06:14:57.877 am debugSharpAquos - releaseMutex ?2061? - Release mutex request [1]

dev:10252019-12-17 06:14:57.875 am debugSharpAquos - IOBUFFER: 0 entries

[albertsmark]

The Only seems to be the one that is asleep and is awaken by the Sharp HDMI and then take a minute to fully come to life.....

Summary

dev:5862019-12-17 06:32:00.168 am debuginitialize...

dev:5862019-12-17 06:28:00.394 am infoLiving Room Receiver volume volume is 80

dev:5862019-12-17 06:28:00.348 am infoLiving Room Receiver is unmuted

dev:5862019-12-17 06:28:00.323 am infoLiving Room Receiver is on

dev:5862019-12-17 06:28:00.270 am infoLiving Room Receiver input is Directv

dev:5862019-12-17 06:28:00.139 am debuginitialize...

dev:5862019-12-17 06:24:00.559 am infoLiving Room Receiver volume volume is 80

dev:5862019-12-17 06:24:00.518 am infoLiving Room Receiver is unmuted

dev:5862019-12-17 06:24:00.488 am infoLiving Room Receiver is on

dev:5862019-12-17 06:24:00.458 am infoLiving Room Receiver input is Directv

dev:5862019-12-17 06:24:00.154 am debuginitialize...

dev:5862019-12-17 06:20:45.535 am infoLiving Room Receiver volume volume is 80

dev:5862019-12-17 06:20:45.389 am infoLiving Room Receiver volume volume is 81

dev:5862019-12-17 06:20:44.815 am infoLiving Room Receiver volume volume is 82

dev:5862019-12-17 06:20:43.436 am infoLiving Room Receiver volume volume is 83

dev:5862019-12-17 06:20:43.182 am infoLiving Room Receiver volume volume is 84

dev:5862019-12-17 06:20:42.660 am infoLiving Room Receiver volume volume is 85

dev:5862019-12-17 06:20:42.155 am infoLiving Room Receiver volume volume is 86

dev:5862019-12-17 06:20:41.453 am infoLiving Room Receiver volume volume is 87

dev:5862019-12-17 06:20:36.652 am infoLiving Room Receiver is on

dev:5862019-12-17 06:20:36.371 am infoLiving Room Receiver input is Directv

dev:5862019-12-17 06:20:23.161 am infoLiving Room Receiver is on

dev:5862019-12-17 06:20:23.126 am infoLiving Room Receiver input is AlbertsPC

dev:5862019-12-17 06:20:17.467 am infoLiving Room Receiver is on

dev:5862019-12-17 06:20:17.435 am infoLiving Room Receiver input is Directv

dev:5862019-12-17 06:20:10.406 am infoLiving Room Receiver is on

dev:5862019-12-17 06:20:10.392 am infoLiving Room Receiver input is AlbertsPC

dev:5862019-12-17 06:20:06.310 am infoLiving Room Receiver volume volume is 88

dev:5862019-12-17 06:20:00.809 am infoLiving Room Receiver volume volume is 87

dev:5862019-12-17 06:20:00.611 am infoLiving Room Receiver is unmuted

dev:5862019-12-17 06:20:00.491 am infoLiving Room Receiver is on

dev:5862019-12-17 06:20:00.448 am infoLiving Room Receiver input is Directv

dev:5862019-12-17 06:20:00.329 am debuginitialize...

dev:5862019-12-17 06:16:03.253 am errortelnet connect error:No route to host (Host unreachable)

dev:5862019-12-17 06:16:00.187 am debuginitialize...

dev:5862019-12-17 06:12:03.466 am errortelnet connect error:No route to host (Host unreachable)

dev:5862019-12-17 06:12:00.373 am debuginitialize...

[albertsmark]

Looks like the Onkyo logs were my fault,

dev:5862019-12-17 06:12:03.466 am errortelnet connect error:No route to host (Host unreachable)

dev:5862019-12-17 06:12:00.373 am debuginitialize...

Didn't have the telenet connection turned on in standby mode of the Onklyo.

Sharp TV Control is working GREAT!

[albertsmark]

@cybrmage things ran great today, no errors and hub was great too.

Wonderful job!

Here's some logs for you to check out the most recent activities for yourself.....

Summary

dev:10252019-12-17 11:29:58.220 pm debugSharpAquos - releaseMutex ?4678? - Released mutex permit [1]

dev:10252019-12-17 11:29:58.217 pm debugSharpAquos - releaseMutex ?4678? - ERROR - Permit release not required [1]

dev:10252019-12-17 11:29:58.214 pm debugSharpAquos - releaseMutex ?4678? - Release mutex request [1]

dev:10252019-12-17 11:29:58.211 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-17 11:29:58.178 pm debugSharpAquos - releaseMutex ?4678? - Released mutex permit [1]

dev:10252019-12-17 11:29:58.175 pm debugSharpAquos - releaseMutex ?4678? - ERROR - Permit release not required [1]

dev:10252019-12-17 11:29:58.171 pm debugSharpAquos - releaseMutex ?4678? - Release mutex request [1]

dev:10252019-12-17 11:29:58.168 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-17 11:29:58.135 pm debugSharpAquos - releaseMutex ?4678? - Released mutex permit [1]

dev:10252019-12-17 11:29:58.132 pm debugSharpAquos - releaseMutex ?4678? - ERROR - Permit release not required [1]

dev:10252019-12-17 11:29:58.129 pm debugSharpAquos - releaseMutex ?4678? - Release mutex request [1]

dev:10252019-12-17 11:29:58.126 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-17 11:29:58.102 pm debugSharpAquos - releaseMutex ?4678? - Released mutex permit [1]

dev:10252019-12-17 11:29:58.080 pm debugSharpAquos - releaseMutex ?4678? - ERROR - Permit release not required [1]

dev:10252019-12-17 11:29:58.077 pm debugSharpAquos - releaseMutex ?4678? - Release mutex request [1]

dev:10252019-12-17 11:29:58.074 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-17 11:29:58.043 pm debugSharpAquos - releaseMutex ?4678? - Released mutex permit [1]

dev:10252019-12-17 11:29:58.032 pm debugSharpAquos - releaseMutex ?4678? - ERROR - Permit release not required [1]

dev:10252019-12-17 11:29:58.028 pm debugSharpAquos - releaseMutex ?4678? - Release mutex request [1]

dev:10252019-12-17 11:29:58.025 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-17 11:29:57.988 pm debugSharpAquos - releaseMutex ?4678? - Released mutex permit [1]

dev:10252019-12-17 11:29:57.985 pm debugSharpAquos - releaseMutex ?4678? - ERROR - Permit release not required [1]

dev:10252019-12-17 11:29:57.982 pm debugSharpAquos - releaseMutex ?4678? - Release mutex request [1]

dev:10252019-12-17 11:29:57.979 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-17 11:29:57.945 pm debugSharpAquos - releaseMutex ?4678? - Released mutex permit [1]

dev:10252019-12-17 11:29:57.942 pm debugSharpAquos - releaseMutex ?4678? - ERROR - Permit release not required [1]

dev:10252019-12-17 11:29:57.938 pm debugSharpAquos - releaseMutex ?4678? - Release mutex request [1]

dev:10252019-12-17 11:29:57.935 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-17 11:29:57.902 pm debugSharpAquos - releaseMutex ?4678? - Released mutex permit [1]

dev:10252019-12-17 11:29:57.898 pm debugSharpAquos - releaseMutex ?4678? - ERROR - Permit release not required [1]

dev:10252019-12-17 11:29:57.895 pm debugSharpAquos - releaseMutex ?4678? - Release mutex request [1]

dev:10252019-12-17 11:29:57.892 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-17 11:29:57.852 pm debugSharpAquos - releaseMutex ?4678? - Released mutex permit [1]

dev:10252019-12-17 11:29:57.849 pm debugSharpAquos - releaseMutex ?4678? - ERROR - Permit release not required [1]

dev:10252019-12-17 11:29:57.833 pm debugSharpAquos - releaseMutex ?4678? - Release mutex request [1]

dev:10252019-12-17 11:29:57.830 pm debugSharpAquos - IOBUFFER: 0 entries [[]]

dev:10252019-12-17 11:29:57.785 pm debugSharpAquos - releaseMutex ?4678? - Released mutex permit [1]

dev:10252019-12-17 11:29:57.782 pm debugSharpAquos - releaseMutex ?4678? - Release mutex request [0]

dev:10252019-12-17 11:29:57.778 pm debugSharpAquos - parseSleepTimer - ERROR - Invalid SleepTimer [ERR]

dev:10252019-12-17 11:29:57.775 pm debugSharpAquos - parseSleepTimer - response 'ERR'

dev:10252019-12-17 11:29:57.770 pm debugSharpAquos - parse - 1 - COMMAND [OFTM? ] RESPONSE [ERR]

dev:10252019-12-17 11:29:57.767 pm debugSharpAquos - IOBUFFER: 2 entries [[OFTM? , ERR]]

dev:10252019-12-17 11:29:57.720 pm debugSharpAquos - getDeviceState() Completed.

dev:10252019-12-17 11:29:57.717 pm debugSharpAquos - releaseMutex ?4678? - Released mutex permit [1]

dev:10252019-12-17 11:29:57.713 pm debugSharpAquos - sendSocketCommand - telnet - sending [OFTM? ]

dev:10252019-12-17 11:29:57.710 pm debugSharpAquos - request 4679 - Interface [telnet] Socket [open] CMD [OFTM] Params [?]

dev:10252019-12-17 11:29:57.706 pm debugSharpAquos - getMutex 4679 - Acquired mutex permit [0]

dev:10252019-12-17 11:29:57.694 pm debugSharpAquos - releaseMutex ?4678? - Release mutex request [0]

dev:10252019-12-17 11:29:57.673 pm debugSharpAquos - parse3DMode - SET - 3DMode [1] 3DModeName [2D to 3D]

dev:10252019-12-17 11:29:57.670 pm infoSharpAquos - EVENT - [[name:3DModeName, value:2D to 3D]]

dev:10252019-12-17 11:29:57.652 pm infoSharpAquos - EVENT - [[name:3DMode, value:1]]

dev:10252019-12-17 11:29:57.648 pm debugSharpAquos - parse3DMode - response '1'

dev:10252019-12-17 11:29:57.645 pm debugSharpAquos - parse - 1 - COMMAND [TDCH? ] RESPONSE [1]

dev:10252019-12-17 11:29:57.642 pm debugSharpAquos - IOBUFFER: 2 entries [[TDCH? , 1]]

dev:10252019-12-17 11:29:57.620 pm debugSharpAquos - getMutex 4679 - Acquire mutex request [0]

dev:10252019-12-17 11:29:57.617 pm debugSharpAquos - sendSocketCommand - telnet - sending [TDCH? ]

dev:10252019-12-17 11:29:57.614 pm debugSharpAquos - request 4678 - Interface [telnet] Socket [open] CMD [TDCH] Params [?]

dev:10252019-12-17 11:29:57.611 pm debugSharpAquos - releaseMutex ?4677? - Released mutex permit [1]

dev:10252019-12-17 11:29:57.608 pm debugSharpAquos - getMutex 4678 - Acquired mutex permit [0]

dev:10252019-12-17 11:29:57.605 pm debugSharpAquos - releaseMutex ?4677? - Release mutex request [0]

dev:10252019-12-17 11:29:57.601 pm debugSharpAquos - parseVideoMode - ERROR - Invalid VideoMode [ERR]

dev:10252019-12-17 11:29:57.598 pm debugSharpAquos - parseVideoMode - response 'ERR'

dev:10252019-12-17 11:29:57.595 pm debugSharpAquos - parse - 1 - COMMAND [AVMD? ] RESPONSE [ERR]

dev:10252019-12-17 11:29:57.591 pm debugSharpAquos - IOBUFFER: 2 entries [[AVMD? , ERR]]

dev:10252019-12-17 11:29:57.580 pm debugSharpAquos - getMutex 4678 - Acquire mutex request [0]

dev:10252019-12-17 11:29:57.576 pm debugSharpAquos - sendSocketCommand - telnet - sending [AVMD? ]

dev:10252019-12-17 11:29:57.573 pm debugSharpAquos - request 4677 - Interface [telnet] Socket [open] CMD [AVMD] Params [?]

dev:10252019-12-17 11:29:57.570 pm debugSharpAquos - getMutex 4677 - Acquired mutex permit [0]

dev:10252019-12-17 11:29:57.566 pm debugSharpAquos - releaseMutex ?4676? - Released mutex permit [1]

dev:10252019-12-17 11:29:57.563 pm debugSharpAquos - releaseMutex ?4676? - Release mutex request [0]

dev:10252019-12-17 11:29:57.558 pm debugSharpAquos - parseSurroundMode - ERROR - Invalid SurroundMode [12]

dev:10252019-12-17 11:29:57.547 pm debugSharpAquos - parseSurround - response '12'

dev:10252019-12-17 11:29:57.544 pm debugSharpAquos - parse - 1 - COMMAND [ACSU? ] RESPONSE [12]

dev:10252019-12-17 11:29:57.541 pm debugSharpAquos - IOBUFFER: 2 entries [[ACSU? , 12]]

dev:10252019-12-17 11:29:57.504 pm debugSharpAquos - getMutex 4677 - Acquire mutex request [0]

dev:10252019-12-17 11:29:57.501 pm debugSharpAquos - releaseMutex ?4675? - Released mutex permit [0]

dev:10252019-12-17 11:29:57.498 pm debugSharpAquos - sendSocketCommand - telnet - sending [ACSU? ]

dev:10252019-12-17 11:29:57.494 pm debugSharpAquos - request 4676 - Interface [telnet] Socket [open] CMD [ACSU] Params [?]

dev:10252019-12-17 11:29:57.490 pm debugSharpAquos - getMutex 4676 - Acquired mutex permit [0]

dev:10252019-12-17 11:29:57.487 pm debugSharpAquos - releaseMutex ?4675? - Release mutex request [0]

dev:10252019-12-17 11:29:57.483 pm debugSharpAquos - parseViewMode - ERROR - Invalid ViewMode [ERR]

dev:10252019-12-17 11:29:57.480 pm debugSharpAquos - parseViewMode - response 'ERR'

dev:10252019-12-17 11:29:57.477 pm debugSharpAquos - parse - 1 - COMMAND [WIDE? ] RESPONSE [ERR]

dev:10252019-12-17 11:29:57.474 pm debugSharpAquos - IOBUFFER: 2 entries [[WIDE? , ERR]]

dev:10252019-12-17 11:29:57.447 pm debugSharpAquos - getMutex 4676 - Acquire mutex request [0]

dev:10252019-12-17 11:29:57.444 pm debugSharpAquos - sendSocketCommand - telnet - sending [WIDE? ]

dev:10252019-12-17 11:29:57.441 pm debugSharpAquos - request 4675 - Interface [telnet] Socket [open] CMD [WIDE] Params [?]

dev:10252019-12-17 11:29:57.438 pm debugSharpAquos - getMutex 4675 - Acquired mutex permit [0]

dev:10252019-12-17 11:29:57.434 pm debugSharpAquos - releaseMutex ?4674? - Released mutex permit [1]

dev:10252019-12-17 11:29:57.430 pm debugSharpAquos - releaseMutex ?4674? - Release mutex request [0]

dev:10252019-12-17 11:29:57.427 pm debugSharpAquos - parseInput - SET - input [2] InputName [HDMI IN 2]

dev:10252019-12-17 11:29:57.412 pm infoSharpAquos - EVENT - [[name:inputName, value:HDMI IN 2]]

dev:10252019-12-17 11:29:57.409 pm infoSharpAquos - EVENT - [[name:input, value:2]]

dev:10252019-12-17 11:29:57.398 pm debugSharpAquos - parseInput - response '2'

dev:10252019-12-17 11:29:57.395 pm debugSharpAquos - parse - 1 - COMMAND [IAVD? ] RESPONSE [2]

dev:10252019-12-17 11:29:57.392 pm debugSharpAquos - IOBUFFER: 2 entries [[IAVD? , 2]]

dev:10252019-12-17 11:29:57.371 pm debugSharpAquos - getMutex 4675 - Acquire mutex request [0]

dev:10252019-12-17 11:29:57.367 pm debugSharpAquos - sendSocketCommand - telnet - sending [IAVD? ]

dev:10252019-12-17 11:29:57.363 pm debugSharpAquos - request 4674 - Interface [telnet] Socket [open] CMD [IAVD] Params [?]

dev:10252019-12-17 11:29:57.360 pm debugSharpAquos - releaseMutex ?4673? - Released mutex permit [1]

dev:10252019-12-17 11:29:57.357 pm debugSharpAquos - getMutex 4674 - Acquired mutex permit [0]

dev:10252019-12-17 11:29:57.354 pm debugSharpAquos - releaseMutex ?4673? - Release mutex request [0]

dev:10252019-12-17 11:29:57.344 pm debugSharpAquos - parseVolume - ERROR - invalid volume [null]

dev:10252019-12-17 11:29:57.332 pm debugSharpAquos - parseVolume - response 'ERR'

dev:10252019-12-17 11:29:57.329 pm debugSharpAquos - parse - 1 - COMMAND [VOLM? ] RESPONSE [ERR]

dev:10252019-12-17 11:29:57.326 pm debugSharpAquos - IOBUFFER: 2 entries [[VOLM? , ERR]]

dev:10252019-12-17 11:29:57.255 pm debugSharpAquos - getMutex 4674 - Acquire mutex request [0]

dev:10252019-12-17 11:29:57.251 pm debugSharpAquos - releaseMutex ?4672? - Released mutex permit [0]

dev:10252019-12-17 11:29:57.248 pm debugSharpAquos - sendSocketCommand - telnet - sending [VOLM? ]

dev:10252019-12-17 11:29:57.244 pm debugSharpAquos - request 4673 - Interface [telnet] Socket [open] CMD [VOLM] Params [?]

dev:10252019-12-17 11:29:57.239 pm debugSharpAquos - getMutex 4673 - Acquired mutex permit [0]

dev:10252019-12-17 11:29:57.236 pm debugSharpAquos - releaseMutex ?4672? - Release mutex request [0]

dev:10252019-12-17 11:29:57.229 pm debugSharpAquos - parseMute - SET - mute [muted]

dev:10252019-12-17 11:29:57.226 pm infoSharpAquos - EVENT - [[name:mute, value:muted]]

dev:10252019-12-17 11:29:57.221 pm debugSharpAquos - parseMute - response '1'

dev:10252019-12-17 11:29:57.218 pm debugSharpAquos - parse - 1 - COMMAND [MUTE? ] RESPONSE [1]

dev:10252019-12-17 11:29:57.215 pm debugSharpAquos - IOBUFFER: 2 entries [[MUTE? , 1]]

dev:10252019-12-17 11:29:57.194 pm debugSharpAquos - releaseMutex ?4671? - Released mutex permit [1]

dev:10252019-12-17 11:29:57.190 pm debugSharpAquos - getMutex 4673 - Acquire mutex request [0]

dev:10252019-12-17 11:29:57.187 pm debugSharpAquos - sendSocketCommand - telnet - sending [MUTE? ]

dev:10252019-12-17 11:29:57.183 pm debugSharpAquos - request 4672 - Interface [telnet] Socket [open] CMD [MUTE] Params [?]

dev:10252019-12-17 11:29:57.179 pm debugSharpAquos - getMutex 4672 - Acquired mutex permit [0]

dev:10252019-12-17 11:29:57.174 pm debugSharpAquos - releaseMutex ?4671? - Release mutex request [0]

dev:10252019-12-17 11:29:57.170 pm debugSharpAquos - parsePower - ERROR - invalid power [ERR]

dev:10252019-12-17 11:29:57.165 pm debugSharpAquos - parsePower - response 'ERR'

dev:10252019-12-17 11:29:57.147 pm debugSharpAquos - parse - 1 - COMMAND [POWR? ] RESPONSE [ERR]

dev:10252019-12-17 11:29:57.144 pm debugSharpAquos - IOBUFFER: 2 entries [[POWR? , ERR]]

dev:10252019-12-17 11:29:57.100 pm debugSharpAquos - getMutex 4672 - Acquire mutex request [0]

dev:10252019-12-17 11:29:57.096 pm debugSharpAquos - sendSocketCommand - telnet - sending [POWR? ]

dev:10252019-12-17 11:29:57.093 pm debugSharpAquos - request 4671 - Interface [telnet] Socket [open] CMD [POWR] Params [?]

dev:10252019-12-17 11:29:57.090 pm debugSharpAquos - getMutex 4671 - Acquired mutex permit [0]

dev:10252019-12-17 11:29:57.087 pm debugSharpAquos - getMutex 4671 - Acquire mutex request [1]

dev:10252019-12-17 11:29:57.083 pm debugSharpAquos - getDeviceState() Starting

dev:10252019-12-17 11:28:21.802 pm debugSharpAquos - openSocket: Socket opened.

dev:10252019-12-17 11:28:20.790 pm debugSharpAquos - openSocket: Connecting to 192.168.254.205:10002

dev:10252019-12-17 11:28:15.778 pm debugSharpAquos - socketStatus - Stream Closed - Attempting reconnect [1]

dev:10252019-12-17 11:28:15.775 pm debugSharpAquos - releaseMutex ?4669? - Released mutex permit [1]

dev:10252019-12-17 11:28:15.772 pm debugSharpAquos - releaseMutex ?4669? - ERROR - Permit release not required [1]

dev:10252019-12-17 11:28:15.769 pm debugSharpAquos - releaseMutex ?4669? - Release mutex request [1]

dev:10252019-12-17 11:28:15.767 pm warnsocketStatus - Socket [open] Message [receive error: Stream is closed]

dev:10252019-12-17 11:28:15.762 pm debugSharpAquos - telnetStatus - Socket [open] Message [receive error: Stream is closed] - Passing to socketStatus...

[cybrmage]

It looks like the communications with the TV are now stable...

But, it's still loosing sync with the command/response ordering... (you should notice that the values in the device page don't match the true state of the TV)

So... A few minor tweaks to (hopefully) fix it...

[Old version removed]