Sharp Aquos TV Driver

Other error I thing we saw before.....

Summary

dev:8972019-12-15 12:39:19.965 am debugSharpAquos - getDeviceState() Completed.

dev:8972019-12-15 12:39:19.962 am debugSharpAquos - getChannelStatus() Completed.

dev:8972019-12-15 12:39:19.959 am debugSharpAquos - request 203 - request = [DC11? ] to 192.168.254.205:10002

dev:8972019-12-15 12:39:19.956 am debugSharpAquos - request 203 - Socket [open] CMD [DC11] Params [?]

dev:8972019-12-15 12:39:19.953 am debugSharpAquos - getMutex 203 - Acquired mutex permit

dev:8972019-12-15 12:39:19.871 am debugSharpAquos - releaseMutex ?203? - Released mutex permit

dev:8972019-12-15 12:39:19.865 am debugSharpAquos - parseSleepTimer - ERROR - Invalid SleepTimer [ERR]

dev:8972019-12-15 12:39:19.861 am debugSharpAquos - parseSleepTimer - response 'ERR'

dev:8972019-12-15 12:39:19.856 am debugSharpAquos - parse - 2 - COMMAND [OFTM? ] RESPONSE [ERR]

dev:8972019-12-15 12:39:19.849 am debugSharpAquos - IOBUFFER: 15 entries [[OFTM? , POWR? , MUTE? , VOLM? , IAVD? , WIDE? , ACSU? , AVMD? , TDCH? , OFTM? , DCCH? , DA2P? , DC2L? , DC10? , ERR]]

dev:8972019-12-15 12:39:19.754 am debugSharpAquos - getMutex 203 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 12:39:19.751 am debugSharpAquos - request 202 - request = [DC10? ] to 192.168.254.205:10002

dev:8972019-12-15 12:39:19.740 am debugSharpAquos - request 202 - Socket [open] CMD [DC10] Params [?]

dev:8972019-12-15 12:39:19.738 am debugSharpAquos - getMutex 202 - Acquired mutex permit

dev:8972019-12-15 12:39:19.703 am debugSharpAquos - releaseMutex ?202? - Released mutex permit

dev:8972019-12-15 12:39:19.688 am debugSharpAquos - parse3DMode - ERROR - Invalid 3DMode [ERR]

dev:8972019-12-15 12:39:19.685 am debugSharpAquos - parse3DMode - response 'ERR'

dev:8972019-12-15 12:39:19.682 am debugSharpAquos - parse - 2 - COMMAND [TDCH? ] RESPONSE [ERR]

dev:8972019-12-15 12:39:19.673 am debugSharpAquos - IOBUFFER: 15 entries [[TDCH? , OFTM? , POWR? , MUTE? , VOLM? , IAVD? , WIDE? , ACSU? , AVMD? , TDCH? , OFTM? , DCCH? , DA2P? , DC2L? , ERR]]

dev:8972019-12-15 12:39:19.534 am debugSharpAquos - getMutex 202 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 12:39:19.530 am debugSharpAquos - request 201 - request = [DC2L? ] to 192.168.254.205:10002

dev:8972019-12-15 12:39:19.527 am debugSharpAquos - request 201 - Socket [open] CMD [DC2L] Params [?]

dev:8972019-12-15 12:39:19.435 am debugSharpAquos - getMutex 201 - Acquired mutex permit

dev:8972019-12-15 12:39:19.310 am debugSharpAquos - getMutex 201 - Detected stale mutex - forcing mutex release.

dev:8972019-12-15 12:38:20.225 am debugSharpAquos - getMutex 201 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 12:38:20.220 am errorSharpAquos - request 200 - ERROR. Unable to acquire mutex permit.

dev:8972019-12-15 12:38:20.214 am debugSharpAquos - request 200 - Socket [open] CMD [DC2U] Params [?] Handler [null]

dev:8972019-12-15 12:38:20.208 am debugSharpAquos - getMutex 200 - Failed to acquired mutex permit

dev:8972019-12-15 12:37:19.360 am errorjava.lang.NumberFormatException: For input string: "7.1" on line 540 (parse)

dev:8972019-12-15 12:37:19.290 am debugSharpAquos - parseVideoMode - response '7.1'

dev:8972019-12-15 12:37:19.287 am debugSharpAquos - parse - 2 - COMMAND [AVMD? ] RESPONSE [7.1]

dev:8972019-12-15 12:37:19.283 am debugSharpAquos - IOBUFFER: 15 entries [[AVMD? , TDCH? , OFTM? , POWR? , MUTE? , VOLM? , IAVD? , WIDE? , ACSU? , AVMD? , TDCH? , OFTM? , DCCH? , DA2P? , 7.1]]

dev:8972019-12-15 12:37:19.253 am debugSharpAquos - getMutex 200 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 12:37:19.250 am debugSharpAquos - request 199 - request = [DA2P? ] to 192.168.254.205:10002

dev:8972019-12-15 12:37:19.247 am debugSharpAquos - request 199 - Socket [open] CMD [DA2P] Params [?]

dev:8972019-12-15 12:37:19.244 am debugSharpAquos - getMutex 199 - Acquired mutex permit

No more errors everything operates good and fast....I am trying to brake it but your programming is too good:wink:

Tried to turn on the tv and nothing happened, finally had to use the IR remote, here are the logs, the onkyo did not turn on either.....

Summary

dev:8972019-12-15 05:16:56.143 am debugSharpAquos - releaseMutex ?1238? - Released mutex permit

dev:8972019-12-15 05:16:56.134 am debugSharpAquos - parse3DMode - SET - 3DMode [1] 3DModeName [2D to 3D]

dev:8972019-12-15 05:16:56.132 am infoSharpAquos - EVENT - [[name:3DModeName, value:2D to 3D]]

dev:8972019-12-15 05:16:56.127 am infoSharpAquos - EVENT - [[name:3DMode, value:1]]

dev:8972019-12-15 05:16:56.121 am debugSharpAquos - parse3DMode - response '1'

dev:8972019-12-15 05:16:56.120 am debugSharpAquos - parse - 2 - COMMAND [TDCH? ] RESPONSE [1]

dev:8972019-12-15 05:16:56.093 am debugSharpAquos - IOBUFFER: 75 entries [[TDCH? , OFTM? , DCCH? , DA2P? , DC2U? , DC2L? , DC10? , DC11? , POWR? , MUTE? , VOLM? , IAVD? , WIDE? , ACSU? , AVMD? , TDCH? , OFTM? , DCCH? , DA2P? , DC2U? , DC2L? , DC10? , DC11? , POWR? , VOLM? , IAVD? , WIDE? , ACSU? , AVMD? , TDCH? , OFTM? , DCCH? , DA2P? , DC2U? , DC2L? , DC10? , DC11? , POWR? , MUTE? , VOLM? , IAVD? , WIDE? , ACSU? , AVMD? , TDCH? , OFTM? , DCCH? , DA2P? , DC2U? , DC2L? , DC10? , DC11? , POWR1 , POWR? , POWR? , POWR? , POWR? , POWR? , POWR? , POWR? , POWR? , POWR? , MUTE? , VOLM? , IAVD? , WIDE? , ACSU? , AVMD? , TDCH? , OFTM? , POWR1 , POWR? , POWR1 , POWR? , 1]]

dev:8972019-12-15 05:16:55.785 am debugSharpAquos - request 1238 - request = [POWR? ] to 192.168.254.205:10002

dev:8972019-12-15 05:16:55.782 am debugSharpAquos - request 1238 - Socket [open] CMD [POWR] Params [?]

dev:8972019-12-15 05:16:55.781 am debugSharpAquos - getMutex 1238 - Acquired mutex permit

dev:8972019-12-15 05:16:55.778 am debugSharpAquos - request 1237 - request = [POWR1 ] to 192.168.254.205:10002

dev:8972019-12-15 05:16:55.776 am debugSharpAquos - request 1237 - Socket [open] CMD [POWR] Params [1]

dev:8972019-12-15 05:16:55.774 am debugSharpAquos - getMutex 1237 - Acquired mutex permit

dev:8972019-12-15 05:16:55.770 am debugSharpAquos - Turn on TV

dev:8972019-12-15 05:16:50.455 am debugSharpAquos - openSocket: Socket opened.

dev:8972019-12-15 05:16:49.437 am debugSharpAquos - releaseMutex ?1236? - Released mutex permit

dev:8972019-12-15 05:16:49.432 am debugSharpAquos - socketStatus - Socket [open] Message [receive error: Stream closed.]

dev:8972019-12-15 05:16:49.369 am debugSharpAquos - openSocket: Connecting to 192.168.254.205:10002

dev:8972019-12-15 05:16:49.361 am debugSharpAquos - socketStatus - Write Failed - Attempting reconnect

dev:8972019-12-15 05:16:49.356 am debugSharpAquos - releaseMutex ?1236? - Released mutex permit

dev:8972019-12-15 05:16:49.349 am debugSharpAquos - socketStatus - Socket [open] Message [send error: Broken pipe (Write failed)]

dev:8972019-12-15 05:16:49.294 am debugSharpAquos - request 1236 - request = [POWR? ] to 192.168.254.205:10002

dev:8972019-12-15 05:16:49.288 am debugSharpAquos - request 1236 - Socket [open] CMD [POWR] Params [?]

dev:8972019-12-15 05:16:49.283 am debugSharpAquos - getMutex 1236 - Acquired mutex permit

dev:8972019-12-15 05:16:49.226 am debugSharpAquos - request 1235 - request = [POWR1 ] to 192.168.254.205:10002

dev:8972019-12-15 05:16:49.222 am debugSharpAquos - request 1235 - Socket [open] CMD [POWR] Params [1]

dev:8972019-12-15 05:16:49.220 am debugSharpAquos - getMutex 1235 - Acquired mutex permit

dev:8972019-12-15 05:16:49.199 am debugSharpAquos - Turn on TV

dev:8972019-12-15 05:13:33.569 am debugSharpAquos - openSocket: Socket opened.

dev:8972019-12-15 05:13:32.603 am debugSharpAquos - releaseMutex ?1234? - Released mutex permit

dev:8972019-12-15 05:13:32.601 am debugSharpAquos - socketStatus - Socket [open] Message [receive error: Stream closed.]

dev:8972019-12-15 05:13:32.556 am debugSharpAquos - openSocket: Connecting to 192.168.254.205:10002

dev:8972019-12-15 05:13:32.555 am debugSharpAquos - socketStatus - Socket Closed - Attempting reconnect

dev:8972019-12-15 05:13:32.553 am debugSharpAquos - releaseMutex ?1234? - Released mutex permit

dev:8972019-12-15 05:13:32.552 am debugSharpAquos - socketStatus - Socket [open] Message [send error: Socket closed]

dev:8972019-12-15 05:13:32.523 am debugSharpAquos - openSocket: Socket opened.

dev:8972019-12-15 05:13:31.547 am debugSharpAquos - releaseMutex ?1234? - Released mutex permit

dev:8972019-12-15 05:13:31.545 am debugSharpAquos - socketStatus - Socket [open] Message [receive error: Stream closed.]

dev:8972019-12-15 05:13:31.515 am debugSharpAquos - openSocket: Connecting to 192.168.254.205:10002

dev:8972019-12-15 05:13:31.513 am debugSharpAquos - socketStatus - Socket Closed - Attempting reconnect

dev:8972019-12-15 05:13:31.512 am debugSharpAquos - releaseMutex ?1234? - Released mutex permit

dev:8972019-12-15 05:13:31.510 am debugSharpAquos - socketStatus - Socket [open] Message [send error: Socket closed]

dev:8972019-12-15 05:13:31.461 am debugSharpAquos - openSocket: Socket opened.

The Onkyo did not turn on either and did this at the same time until I used the IR remote.

Summary

dev:5862019-12-15 05:16:56.206 am infoLiving Room Receiver input is AlbertsPC

dev:5862019-12-15 05:16:55.819 am infoLiving Room Receiver is on

dev:5862019-12-15 05:16:50.064 am infoLiving Room Receiver input is Directv

dev:5862019-12-15 05:16:49.251 am infoLiving Room Receiver is on

dev:5862019-12-15 05:16:00.579 am infoLiving Room Receiver volume volume is 110

dev:5862019-12-15 05:16:00.457 am infoLiving Room Receiver is unmuted

dev:5862019-12-15 05:16:00.384 am infoLiving Room Receiver is on

dev:5862019-12-15 05:16:00.357 am infoLiving Room Receiver input is AlbertsPC

dev:5862019-12-15 05:16:00.233 am debuginitialize...

dev:5862019-12-15 05:12:03.370 am errortelnet connect error:No route to host (Host unreachable)

dev:5862019-12-15 05:12:00.283 am debuginitialize...

dev:5862019-12-15 05:08:03.499 am errortelnet connect error:No route to host (Host unreachable)

dev:5862019-12-15 05:08:00.377 am debuginitialize...

Interactions between the devices up to and including the push of the button to turn TV/Stereo on......

Summary

dev:8972019-12-15 05:11:43.642 am debugSharpAquos - getMutex 1209 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 05:11:43.639 am debugSharpAquos - Turn on TV

dev:9322019-12-15 05:11:43.451 am infoStereo/TV ON PC button 1 was pushed

dev:5912019-12-15 05:11:40.773 am infoFront Deck Motion is inactive

dev:8972019-12-15 05:11:40.032 am debugSharpAquos - getMutex 1208 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 05:11:40.028 am debugSharpAquos - Turn on TV

dev:9322019-12-15 05:11:39.846 am infoStereo/TV ON PC button 1 was pushed

dev:8972019-12-15 05:11:27.129 am debugSharpAquos - getMutex 1207 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 05:11:27.072 am debugSharpAquos - request 1206 - request = [POWR1 ] to 192.168.254.205:10002

dev:8972019-12-15 05:11:27.068 am debugSharpAquos - request 1206 - Socket [open] CMD [POWR] Params [1]

dev:8972019-12-15 05:11:27.066 am debugSharpAquos - getMutex 1206 - Acquired mutex permit

dev:8972019-12-15 05:11:27.045 am debugSharpAquos - Turn on TV

dev:9322019-12-15 05:11:26.702 am infoStereo/TV ON PC button 1 was pushed

dev:5862019-12-15 05:08:03.499 am errortelnet connect error:No route to host (Host unreachable)

dev:5862019-12-15 05:08:00.377 am debuginitialize...

dev:8972019-12-15 05:07:16.813 am debugSharpAquos - releaseMutex ?1205? - Released mutex permit

dev:8972019-12-15 05:07:16.808 am debugSharpAquos - parseDCCH - ERROR - received [ERR]

dev:8972019-12-15 05:07:16.807 am debugSharpAquos - parseDCCH - response 'ERR'

dev:8972019-12-15 05:07:16.805 am debugSharpAquos - parse - 2 - COMMAND [DCCH? ] RESPONSE [ERR]

dev:8972019-12-15 05:07:16.793 am debugSharpAquos - IOBUFFER: 65 entries [[DCCH? , DA2P? , DC2U? , DC2L? , DC10? , DC11? , POWR? , VOLM? , IAVD? , WIDE? , ACSU? , AVMD? , TDCH? , OFTM? , DCCH? , DA2P? , DC2U? , DC2L? , DC10? , DC11? , POWR? , MUTE? , VOLM? , IAVD? , WIDE? , ACSU? , AVMD? , TDCH? , OFTM? , DCCH? , DA2P? , DC2U? , DC2L? , DC10? , DC11? , POWR? , VOLM? , IAVD? , WIDE? , ACSU? , AVMD? , TDCH? , OFTM? , DCCH? , DA2P? , DC2U? , DC2L? , DC10? , DC11? , POWR? , MUTE? , VOLM? , IAVD? , WIDE? , ACSU? , AVMD? , TDCH? , OFTM? , DCCH? , DA2P? , DC2U? , DC2L? , DC10? , DC11? , ERR]]

dev:8972019-12-15 05:07:16.774 am debugSharpAquos - getDeviceState() Completed.

dev:8972019-12-15 05:07:16.773 am debugSharpAquos - getChannelStatus() Completed.

dev:8972019-12-15 05:07:16.770 am debugSharpAquos - request 1205 - request = [DC11? ] to 192.168.254.205:10002

dev:8972019-12-15 05:07:16.768 am debugSharpAquos - request 1205 - Socket [open] CMD [DC11] Params [?]

dev:8972019-12-15 05:07:16.767 am debugSharpAquos - getMutex 1205 - Acquired mutex permit

dev:8972019-12-15 05:07:16.715 am debugSharpAquos - releaseMutex ?1205? - Released mutex permit

dev:8972019-12-15 05:07:16.710 am debugSharpAquos - parseSleepTimer - ERROR - Invalid SleepTimer [ERR]

dev:8972019-12-15 05:07:16.709 am debugSharpAquos - parseSleepTimer - response 'ERR'

dev:8972019-12-15 05:07:16.707 am debugSharpAquos - parse - 2 - COMMAND [OFTM? ] RESPONSE [ERR]

dev:8972019-12-15 05:07:16.695 am debugSharpAquos - IOBUFFER: 65 entries [[OFTM? , DCCH? , DA2P? , DC2U? , DC2L? , DC10? , DC11? , POWR? , VOLM? , IAVD? , WIDE? , ACSU? , AVMD? , TDCH? , OFTM? , DCCH? , DA2P? , DC2U? , DC2L? , DC10? , DC11? , POWR? , MUTE? , VOLM? , IAVD? , WIDE? , ACSU? , AVMD? , TDCH? , OFTM? , DCCH? , DA2P? , DC2U? , DC2L? , DC10? , DC11? , POWR? , VOLM? , IAVD? , WIDE? , ACSU? , AVMD? , TDCH? , OFTM? , DCCH? , DA2P? , DC2U? , DC2L? , DC10? , DC11? , POWR? , MUTE? , VOLM? , IAVD? , WIDE? , ACSU? , AVMD? , TDCH? , OFTM? , DCCH? , DA2P? , DC2U? , DC2L? , DC10? , ERR]]

Things just stop working after a bit......I hit the button (probably twice) really fast by accident, touch screen, and nothing. Then I just tapped it a ton of times because nothing was happening.

Summary

dev:8972019-12-15 06:58:34.030 am debugSharpAquos - request 1424 - request = [POWR0 ] to 192.168.254.205:10002

dev:8972019-12-15 06:58:34.029 am debugSharpAquos - request 1423 - request = [POWR? ] to 192.168.254.205:10002

dev:8972019-12-15 06:58:34.028 am debugSharpAquos - request 1424 - Socket [open] CMD [POWR] Params [0]

dev:8972019-12-15 06:58:34.027 am debugSharpAquos - getMutex 1424 - Acquired mutex permit

dev:8972019-12-15 06:58:34.025 am debugSharpAquos - request 1421 - request = [POWR? ] to 192.168.254.205:10002

dev:8972019-12-15 06:58:34.025 am debugSharpAquos - request 1423 - Socket [open] CMD [POWR] Params [?]

dev:8972019-12-15 06:58:34.024 am debugSharpAquos - getMutex 1423 - Acquired mutex permit

dev:8972019-12-15 06:58:34.023 am debugSharpAquos - request 1421 - Socket [open] CMD [POWR] Params [?]

dev:8972019-12-15 06:58:34.021 am debugSharpAquos - getMutex 1421 - Acquired mutex permit

dev:8972019-12-15 06:58:34.021 am debugSharpAquos - request 1419 - request = [POWR? ] to 192.168.254.205:10002

dev:8972019-12-15 06:58:34.019 am debugSharpAquos - request 1419 - Socket [open] CMD [POWR] Params [?]

dev:8972019-12-15 06:58:34.018 am debugSharpAquos - request 1422 - request = [POWR? ] to 192.168.254.205:10002

dev:8972019-12-15 06:58:34.018 am debugSharpAquos - request 1420 - request = [POWR? ] to 192.168.254.205:10002

dev:8972019-12-15 06:58:34.017 am debugSharpAquos - getMutex 1419 - Acquired mutex permit

dev:8972019-12-15 06:58:34.016 am debugSharpAquos - request 1420 - Socket [open] CMD [POWR] Params [?]

dev:8972019-12-15 06:58:34.016 am debugSharpAquos - request 1422 - Socket [open] CMD [POWR] Params [?]

dev:8972019-12-15 06:58:34.015 am debugSharpAquos - getMutex 1420 - Acquired mutex permit

dev:8972019-12-15 06:58:34.015 am debugSharpAquos - getMutex 1422 - Acquired mutex permit

dev:8972019-12-15 06:58:33.901 am debugSharpAquos - getMutex 1423 - Detected stale mutex - forcing mutex release.

dev:8972019-12-15 06:58:33.909 am debugSharpAquos - getMutex 1419 - Detected stale mutex - forcing mutex release.

dev:8972019-12-15 06:58:33.908 am debugSharpAquos - getMutex 1420 - Detected stale mutex - forcing mutex release.

dev:8972019-12-15 06:58:33.901 am debugSharpAquos - getMutex 1422 - Detected stale mutex - forcing mutex release.

dev:8972019-12-15 06:58:33.900 am debugSharpAquos - getMutex 1421 - Detected stale mutex - forcing mutex release.

dev:8972019-12-15 06:58:33.900 am debugSharpAquos - getMutex 1424 - Detected stale mutex - forcing mutex release.

dev:8972019-12-15 06:58:18.436 am debugSharpAquos - getMutex 1424 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 06:58:18.432 am debugSharpAquos - Turn off TV

dev:8972019-12-15 06:57:53.102 am debugSharpAquos - getMutex 1423 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 06:57:53.098 am errorSharpAquos - request 1418 - ERROR. Unable to acquire mutex permit.

dev:8972019-12-15 06:57:53.094 am debugSharpAquos - request 1418 - Socket [open] CMD [POWR] Params [1] Handler [null]

dev:8972019-12-15 06:57:53.083 am debugSharpAquos - getMutex 1418 - Failed to acquired mutex permit

dev:8972019-12-15 06:57:47.050 am debugSharpAquos - getMutex 1422 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 06:57:47.049 am errorSharpAquos - request 1417 - ERROR. Unable to acquire mutex permit.

dev:8972019-12-15 06:57:47.048 am debugSharpAquos - request 1417 - Socket [open] CMD [POWR] Params [1] Handler [null]

dev:8972019-12-15 06:57:47.046 am debugSharpAquos - getMutex 1417 - Failed to acquired mutex permit

dev:8972019-12-15 06:57:46.965 am debugSharpAquos - getMutex 1421 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 06:57:46.964 am errorSharpAquos - request 1416 - ERROR. Unable to acquire mutex permit.

dev:8972019-12-15 06:57:46.960 am debugSharpAquos - request 1416 - Socket [open] CMD [POWR] Params [1] Handler [null]

dev:8972019-12-15 06:57:46.946 am debugSharpAquos - getMutex 1416 - Failed to acquired mutex permit

dev:8972019-12-15 06:57:42.892 am debugSharpAquos - getMutex 1420 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 06:57:42.890 am errorSharpAquos - request 1415 - ERROR. Unable to acquire mutex permit.

dev:8972019-12-15 06:57:42.888 am debugSharpAquos - request 1415 - Socket [open] CMD [POWR] Params [1] Handler [null]

dev:8972019-12-15 06:57:42.887 am debugSharpAquos - getMutex 1419 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 06:57:42.884 am errorSharpAquos - request 1414 - ERROR. Unable to acquire mutex permit.

dev:8972019-12-15 06:57:42.880 am debugSharpAquos - request 1414 - Socket [open] CMD [POWR] Params [1] Handler [null]

dev:8972019-12-15 06:57:42.866 am debugSharpAquos - getMutex 1415 - Failed to acquired mutex permit

dev:8972019-12-15 06:57:42.866 am debugSharpAquos - getMutex 1414 - Failed to acquired mutex permit

dev:8972019-12-15 06:56:51.902 am debugSharpAquos - getMutex 1418 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 06:56:51.898 am debugSharpAquos - Turn on TV

dev:8972019-12-15 06:56:45.837 am debugSharpAquos - getMutex 1417 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 06:56:45.833 am debugSharpAquos - Turn on TV

dev:8972019-12-15 06:56:45.827 am debugSharpAquos - getMutex 1416 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 06:56:45.822 am debugSharpAquos - Turn on TV

dev:8972019-12-15 06:56:41.709 am debugSharpAquos - getMutex 1415 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 06:56:41.706 am debugSharpAquos - Turn on TV

dev:8972019-12-15 06:56:41.706 am debugSharpAquos - getMutex 1414 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 06:56:41.702 am debugSharpAquos - Turn on TV

dev:8972019-12-15 06:56:33.838 am debugSharpAquos - request 1413 - request = [POWR0 ] to 192.168.254.205:10002

dev:8972019-12-15 06:56:33.835 am debugSharpAquos - request 1413 - Socket [open] CMD [POWR] Params [0]

dev:8972019-12-15 06:56:33.834 am debugSharpAquos - getMutex 1413 - Acquired mutex permit

dev:8972019-12-15 06:56:33.830 am debugSharpAquos - Turn off TV

dev:8972019-12-15 06:56:33.694 am debugSharpAquos - request 1412 - request = [POWR0 ] to 192.168.254.205:10002

dev:8972019-12-15 06:56:33.688 am debugSharpAquos - request 1412 - Socket [open] CMD [POWR] Params [0]

dev:8972019-12-15 06:56:33.684 am debugSharpAquos - getMutex 1412 - Acquired mutex permit

dev:8972019-12-15 06:56:33.676 am debugSharpAquos - Turn off TV

dev:8972019-12-15 06:55:03.457 am debugSharpAquos - request 1411 - request = [POWR1 ] to 192.168.254.205:10002

dev:8972019-12-15 06:55:03.456 am debugSharpAquos - request 1411 - Socket [open] CMD [POWR] Params [1]

dev:8972019-12-15 06:55:03.454 am debugSharpAquos - getMutex 1411 - Acquired mutex permit

dev:8972019-12-15 06:55:03.413 am debugSharpAquos - Turn on TV

dev:8972019-12-15 06:54:11.845 am debugSharpAquos - request 1410 - request = [POWR1 ] to 192.168.254.205:10002

dev:8972019-12-15 06:54:11.843 am debugSharpAquos - request 1410 - Socket [open] CMD [POWR] Params [1]

dev:8972019-12-15 06:54:11.842 am debugSharpAquos - getMutex 1410 - Acquired mutex permit

dev:8972019-12-15 06:54:11.838 am debugSharpAquos - Turn on TV

dev:8972019-12-15 06:54:05.290 am debugSharpAquos - request 1409 - request = [POWR1 ] to 192.168.254.205:10002

dev:8972019-12-15 06:54:05.288 am debugSharpAquos - request 1409 - Socket [open] CMD [POWR] Params [1]

dev:8972019-12-15 06:54:05.287 am debugSharpAquos - getMutex 1409 - Acquired mutex permit

dev:8972019-12-15 06:54:05.283 am debugSharpAquos - Turn on TV

dev:8972019-12-15 06:52:34.972 am debugSharpAquos - request 1408 - request = [POWR1 ] to 192.168.254.205:10002

dev:8972019-12-15 06:52:34.970 am debugSharpAquos - request 1408 - Socket [open] CMD [POWR] Params [1]

dev:8972019-12-15 06:52:34.968 am debugSharpAquos - getMutex 1408 - Acquired mutex permit

dev:8972019-12-15 06:52:34.965 am debugSharpAquos - Turn on TV

dev:8972019-12-15 06:51:02.227 am debugSharpAquos - request 1407 - request = [POWR1 ] to 192.168.254.205:10002

dev:8972019-12-15 06:51:02.225 am debugSharpAquos - request 1407 - Socket [open] CMD [POWR] Params [1]

dev:8972019-12-15 06:51:02.223 am debugSharpAquos - getMutex 1407 - Acquired mutex permit

dev:8972019-12-15 06:51:02.219 am debugSharpAquos - Turn on TV

dev:8972019-12-15 06:51:01.585 am debugSharpAquos - request 1406 - request = [POWR1 ] to 192.168.254.205:10002

dev:8972019-12-15 06:51:01.582 am debugSharpAquos - request 1406 - Socket [open] CMD [POWR] Params [1]

dev:8972019-12-15 06:51:01.581 am debugSharpAquos - getMutex 1406 - Acquired mutex permit

dev:8972019-12-15 06:51:01.577 am debugSharpAquos - Turn on TV

dev:8972019-12-15 06:51:00.784 am debugSharpAquos - request 1405 - request = [POWR1 ] to 192.168.254.205:10002

dev:8972019-12-15 06:51:00.782 am debugSharpAquos - request 1405 - Socket [open] CMD [POWR] Params [1]

dev:8972019-12-15 06:51:00.781 am debugSharpAquos - getMutex 1405 - Acquired mutex permit

dev:8972019-12-15 06:51:00.777 am debugSharpAquos - Turn on TV

dev:8972019-12-15 06:50:45.905 am debugSharpAquos - request 1404 - request = [POWR1 ] to 192.168.254.205:10002

dev:8972019-12-15 06:50:45.903 am debugSharpAquos - request 1404 - Socket [open] CMD [POWR] Params [1]

dev:8972019-12-15 06:50:45.902 am debugSharpAquos - getMutex 1404 - Acquired mutex permit

dev:8972019-12-15 06:50:45.898 am debugSharpAquos - Turn on TV

dev:8972019-12-15 06:50:36.614 am debugSharpAquos - request 1403 - request = [POWR1 ] to 192.168.254.205:10002

dev:8972019-12-15 06:50:36.611 am debugSharpAquos - request 1403 - Socket [open] CMD [POWR] Params [1]

dev:8972019-12-15 06:50:36.609 am debugSharpAquos - getMutex 1403 - Acquired mutex permit

dev:8972019-12-15 06:50:36.605 am debugSharpAquos - Turn on TV

dev:8972019-12-15 06:50:13.490 am debugSharpAquos - request 1402 - request = [POWR1 ] to 192.168.254.205:10002

dev:8972019-12-15 06:50:13.488 am debugSharpAquos - request 1402 - Socket [open] CMD [POWR] Params [1]

dev:8972019-12-15 06:50:13.487 am debugSharpAquos - getMutex 1402 - Acquired mutex permit

dev:8972019-12-15 06:50:13.483 am debugSharpAquos - Turn on TV

dev:8972019-12-15 06:49:40.134 am debugSharpAquos - request 1401 - request = [POWR1 ] to 192.168.254.205:10002

dev:8972019-12-15 06:49:40.131 am debugSharpAquos - request 1401 - Socket [open] CMD [POWR] Params [1]

dev:8972019-12-15 06:49:40.130 am debugSharpAquos - getMutex 1401 - Acquired mutex permit

dev:8972019-12-15 06:49:40.126 am debugSharpAquos - Turn on TV

dev:8972019-12-15 06:49:00.916 am debugSharpAquos - request 1400 - request = [POWR1 ] to 192.168.254.205:10002

dev:8972019-12-15 06:49:00.911 am debugSharpAquos - request 1400 - Socket [open] CMD [POWR] Params [1]

dev:8972019-12-15 06:49:00.909 am debugSharpAquos - getMutex 1400 - Acquired mutex permit

dev:8972019-12-15 06:49:00.905 am debugSharpAquos - openSocket: Socket opened.

dev:8972019-12-15 06:49:00.741 am debugSharpAquos - openSocket: Socket opened.

dev:8972019-12-15 06:48:59.957 am debugSharpAquos - releaseMutex ?1400? - Released mutex permit

dev:8972019-12-15 06:48:59.955 am debugSharpAquos - socketStatus - Socket [closed] Message [receive error: Stream closed.]

dev:8972019-12-15 06:48:59.894 am debugSharpAquos - openSocket: Connecting to 192.168.254.205:10002

dev:8972019-12-15 06:48:59.893 am debugSharpAquos - releaseMutex ?1400? - Released mutex permit

dev:8972019-12-15 06:48:59.890 am errorSharpAquos - checkConnectionState - ERROR. TV connection is closed. Attempting reconnect.

dev:8972019-12-15 06:48:59.882 am debugSharpAquos - Turn on TV

dev:8972019-12-15 06:48:59.814 am debugSharpAquos - releaseMutex ?1399? - Released mutex permit

dev:8972019-12-15 06:48:59.812 am debugSharpAquos - socketStatus - Socket [open] Message [receive error: Stream closed.]

dev:8972019-12-15 06:48:59.697 am debugSharpAquos - openSocket: Connecting to 192.168.254.205:10002

dev:8972019-12-15 06:48:59.695 am debugSharpAquos - socketStatus - Write Failed - Attempting reconnect

dev:8972019-12-15 06:48:59.693 am debugSharpAquos - releaseMutex ?1399? - Released mutex permit

dev:8972019-12-15 06:48:59.691 am debugSharpAquos - socketStatus - Socket [open] Message [send error: Broken pipe (Write failed)]

dev:8972019-12-15 06:48:59.618 am debugSharpAquos - request 1399 - request = [POWR1 ] to 192.168.254.205:10002

dev:8972019-12-15 06:48:59.615 am debugSharpAquos - request 1399 - Socket [open] CMD [POWR] Params [1]

dev:8972019-12-15 06:48:59.613 am debugSharpAquos - getMutex 1399 - Acquired mutex permit

dev:8972019-12-15 06:48:59.608 am debugSharpAquos - Turn on TV

Maybe its my hub, commands dont seem to do anything when I push the dashboard button but if I do them manually in the device they work, I will reboot the hub.....somethings weird.

Well one off my wall tablets was not doing anything when the button on the dashboard was pressed, the other one worked fine, maybe that was part of my problem, I reloaded the dashboard on it and it works fine again. Things are very snappy again...

Somethings still fishy.......

Summary

dev:8972019-12-15 07:55:09.845 am debugSharpAquos - getMutex 83 - Acquired mutex permit

dev:8972019-12-15 07:55:09.841 am debugSharpAquos - request 82 - Socket [open] CMD [POWR] Params [?]

dev:8972019-12-15 07:55:09.837 am debugSharpAquos - request 80 - Socket [open] CMD [DC2L] Params [?]

dev:8972019-12-15 07:55:09.833 am debugSharpAquos - getMutex 80 - Acquired mutex permit

dev:8972019-12-15 07:55:09.829 am debugSharpAquos - getMutex 81 - Acquired mutex permit

dev:8972019-12-15 07:55:09.824 am debugSharpAquos - getMutex 82 - Acquired mutex permit

dev:8972019-12-15 07:55:09.732 am debugSharpAquos - getMutex 83 - Detected stale mutex - forcing mutex release.

dev:8972019-12-15 07:55:09.729 am debugSharpAquos - getMutex 81 - Detected stale mutex - forcing mutex release.

dev:8972019-12-15 07:55:09.726 am debugSharpAquos - getMutex 82 - Detected stale mutex - forcing mutex release.

dev:8972019-12-15 07:55:09.722 am debugSharpAquos - getMutex 80 - Detected stale mutex - forcing mutex release.

dev:8972019-12-15 07:55:04.974 am debugSharpAquos - getMutex 83 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 07:55:04.971 am errorSharpAquos - request 79 - ERROR. Unable to acquire mutex permit.

dev:8972019-12-15 07:55:04.968 am debugSharpAquos - request 79 - Socket [open] CMD [POWR] Params [1] Handler [null]

dev:8972019-12-15 07:55:04.965 am debugSharpAquos - getMutex 79 - Failed to acquired mutex permit

dev:8972019-12-15 07:54:50.303 am debugSharpAquos - getMutex 82 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 07:54:50.300 am errorSharpAquos - request 78 - ERROR. Unable to acquire mutex permit.

dev:8972019-12-15 07:54:50.297 am debugSharpAquos - request 78 - Socket [open] CMD [POWR] Params [1] Handler [null]

dev:8972019-12-15 07:54:50.294 am debugSharpAquos - getMutex 78 - Failed to acquired mutex permit

dev:8972019-12-15 07:54:38.640 am debugSharpAquos - getMutex 81 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 07:54:38.635 am errorSharpAquos - request 77 - ERROR. Unable to acquire mutex permit.

dev:8972019-12-15 07:54:38.630 am debugSharpAquos - request 77 - Socket [open] CMD [POWR] Params [1] Handler [null]

dev:8972019-12-15 07:54:38.616 am debugSharpAquos - getMutex 77 - Failed to acquired mutex permit

dev:8972019-12-15 07:54:10.707 am debugSharpAquos - getMutex 80 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 07:54:10.668 am errorSharpAquos - request 76 - ERROR. Unable to acquire mutex permit.

dev:8972019-12-15 07:54:10.662 am debugSharpAquos - request 76 - Socket [open] CMD [DC2U] Params [?] Handler [null]

dev:8972019-12-15 07:54:10.656 am debugSharpAquos - getMutex 76 - Failed to acquired mutex permit

dev:8972019-12-15 07:54:03.820 am debugSharpAquos - getMutex 79 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 07:54:03.815 am debugSharpAquos - Turn on TV

It looks like the TV has stopped responding... There are no responses from the TV in the last three logs... And one of the logs showed an IOBUFFER with 65 entries in it... and not a single one of those entries was a response from the TV...

Maybe a power cycle of the HE (do a shutdown from the settings menu - then physically remove the power cable - let stand for 30 seconds then reconnect power to reboot) and of the TV (unplug the power cable from outlet - wait 30 seconds - plug the power cord back in).

I have been running the last version here (with a sharp TV emulator) for over 24 hours without a single error, and only a few times where it has had to wait to be given a mutex...

Let me know what happens after the power cycle of the equipment...

Sounds good, will do....

All looks good so far....logs right after reboot.

Summary

dev:8972019-12-15 02:27:40.445 pm debugSharpAquos - releaseMutex ?18? - Released mutex permit

dev:8972019-12-15 02:27:40.436 pm debugSharpAquos - parseSleepTimer - SET - SleepTimer [0] SleepTimerSetting [Off]

dev:8972019-12-15 02:27:40.433 pm infoSharpAquos - EVENT - [[name:SleepTimerSetting, value:Off]]

dev:8972019-12-15 02:27:40.430 pm infoSharpAquos - EVENT - [[name:SleepTimer, value:0]]

dev:8972019-12-15 02:27:40.409 pm debugSharpAquos - parseSleepTimer - response '0'

dev:8972019-12-15 02:27:40.398 pm debugSharpAquos - parse - 1 - COMMAND [OFTM? ] RESPONSE [0]

dev:8972019-12-15 02:27:40.395 pm debugSharpAquos - IOBUFFER: 2 entries [[OFTM? , 0]]

dev:8972019-12-15 02:27:40.351 pm debugSharpAquos - getDeviceState() Completed.

dev:8972019-12-15 02:27:40.340 pm debugSharpAquos - request 18 - request = [OFTM? ] to 192.168.254.205:10002

dev:8972019-12-15 02:27:40.336 pm debugSharpAquos - request 18 - Socket [open] CMD [OFTM] Params [?]

dev:8972019-12-15 02:27:40.333 pm debugSharpAquos - getMutex 18 - Acquired mutex permit

dev:8972019-12-15 02:27:40.297 pm debugSharpAquos - releaseMutex ?18? - Released mutex permit

dev:8972019-12-15 02:27:40.294 pm debugSharpAquos - parse3DMode - SET - 3DMode [0] 3DModeName [3D Off]

dev:8972019-12-15 02:27:40.277 pm infoSharpAquos - EVENT - [[name:3DModeName, value:3D Off]]

dev:8972019-12-15 02:27:40.270 pm infoSharpAquos - EVENT - [[name:3DMode, value:0]]

dev:8972019-12-15 02:27:40.266 pm debugSharpAquos - parse3DMode - response '0'

dev:8972019-12-15 02:27:40.263 pm debugSharpAquos - parse - 1 - COMMAND [TDCH? ] RESPONSE [0]

dev:8972019-12-15 02:27:40.260 pm debugSharpAquos - IOBUFFER: 2 entries [[TDCH? , 0]]

dev:8972019-12-15 02:27:40.219 pm debugSharpAquos - getMutex 18 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 02:27:40.215 pm debugSharpAquos - request 17 - request = [TDCH? ] to 192.168.254.205:10002

dev:8972019-12-15 02:27:40.211 pm debugSharpAquos - request 17 - Socket [open] CMD [TDCH] Params [?]

dev:8972019-12-15 02:27:40.207 pm debugSharpAquos - getMutex 17 - Acquired mutex permit

dev:8972019-12-15 02:27:40.204 pm debugSharpAquos - releaseMutex ?17? - Released mutex permit

dev:8972019-12-15 02:27:40.198 pm debugSharpAquos - parseVideoMode - SET - VideoMode [4] VideoModeName [USER]

dev:8972019-12-15 02:27:40.188 pm infoSharpAquos - EVENT - [[name:VideoModeName, value:USER]]

dev:8972019-12-15 02:27:40.184 pm infoSharpAquos - EVENT - [[name:VideoMode, value:4]]

dev:8972019-12-15 02:27:40.179 pm debugSharpAquos - parseVideoMode - response '4'

dev:8972019-12-15 02:27:40.154 pm debugSharpAquos - parse - 1 - COMMAND [AVMD? ] RESPONSE [4]

dev:8972019-12-15 02:27:40.150 pm debugSharpAquos - IOBUFFER: 2 entries [[AVMD? , 4]]

dev:8972019-12-15 02:27:39.910 pm debugSharpAquos - getMutex 17 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 02:27:39.905 pm debugSharpAquos - request 16 - request = [AVMD? ] to 192.168.254.205:10002

dev:8972019-12-15 02:27:39.901 pm debugSharpAquos - request 16 - Socket [open] CMD [AVMD] Params [?]

dev:8972019-12-15 02:27:39.898 pm debugSharpAquos - getMutex 16 - Acquired mutex permit

dev:8972019-12-15 02:27:39.894 pm debugSharpAquos - releaseMutex ?16? - Released mutex permit

dev:8972019-12-15 02:27:39.889 pm debugSharpAquos - parseSurroundMode - SET - SurroundMode [2] SurroundModeName [Off]

dev:8972019-12-15 02:27:39.880 pm infoSharpAquos - EVENT - [[name:SurroundModeName, value:Off]]

dev:8972019-12-15 02:27:39.877 pm infoSharpAquos - EVENT - [[name:SurroundMode, value:2]]

dev:8972019-12-15 02:27:39.869 pm debugSharpAquos - parseSurround - response '2'

dev:8972019-12-15 02:27:39.851 pm debugSharpAquos - parse - 1 - COMMAND [ACSU? ] RESPONSE [2]

dev:8972019-12-15 02:27:39.847 pm debugSharpAquos - IOBUFFER: 2 entries [[ACSU? , 2]]

dev:8972019-12-15 02:27:39.787 pm debugSharpAquos - getMutex 16 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 02:27:39.784 pm debugSharpAquos - request 15 - request = [ACSU? ] to 192.168.254.205:10002

dev:8972019-12-15 02:27:39.780 pm debugSharpAquos - request 15 - Socket [open] CMD [ACSU] Params [?]

dev:8972019-12-15 02:27:39.776 pm debugSharpAquos - getMutex 15 - Acquired mutex permit

dev:8972019-12-15 02:27:39.743 pm debugSharpAquos - releaseMutex ?15? - Released mutex permit

dev:8972019-12-15 02:27:39.730 pm debugSharpAquos - parseViewMode - SET - ViewMode [8] ViewModeName [Dot by Dot]

dev:8972019-12-15 02:27:39.727 pm infoSharpAquos - EVENT - [[name:ViewModeName, value:Dot by Dot]]

dev:8972019-12-15 02:27:39.723 pm infoSharpAquos - EVENT - [[name:ViewMode, value:8]]

dev:8972019-12-15 02:27:39.707 pm debugSharpAquos - parseViewMode - response '8'

dev:8972019-12-15 02:27:39.704 pm debugSharpAquos - parse - 1 - COMMAND [WIDE? ] RESPONSE [8]

dev:8972019-12-15 02:27:39.698 pm debugSharpAquos - IOBUFFER: 2 entries [[WIDE? , 8]]

dev:8972019-12-15 02:27:39.678 pm debugSharpAquos - getMutex 15 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 02:27:39.674 pm debugSharpAquos - request 14 - request = [WIDE? ] to 192.168.254.205:10002

dev:8972019-12-15 02:27:39.666 pm debugSharpAquos - request 14 - Socket [open] CMD [WIDE] Params [?]

dev:8972019-12-15 02:27:39.662 pm debugSharpAquos - getMutex 14 - Acquired mutex permit

dev:8972019-12-15 02:27:39.644 pm debugSharpAquos - releaseMutex ?14? - Released mutex permit

dev:8972019-12-15 02:27:39.631 pm debugSharpAquos - parseInput - SET - input [1] InputName [HDMI IN 1]

dev:8972019-12-15 02:27:39.628 pm infoSharpAquos - EVENT - [[name:inputName, value:HDMI IN 1]]

dev:8972019-12-15 02:27:39.625 pm infoSharpAquos - EVENT - [[name:input, value:1]]

dev:8972019-12-15 02:27:39.622 pm debugSharpAquos - parseInput - response '1'

dev:8972019-12-15 02:27:39.596 pm debugSharpAquos - parse - 1 - COMMAND [IAVD? ] RESPONSE [1]

dev:8972019-12-15 02:27:39.592 pm debugSharpAquos - IOBUFFER: 2 entries [[IAVD? , 1]]

dev:8972019-12-15 02:27:39.578 pm debugSharpAquos - getMutex 14 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 02:27:39.575 pm debugSharpAquos - request 13 - request = [IAVD? ] to 192.168.254.205:10002

dev:8972019-12-15 02:27:39.571 pm debugSharpAquos - request 13 - Socket [open] CMD [IAVD] Params [?]

dev:8972019-12-15 02:27:39.567 pm debugSharpAquos - getMutex 13 - Acquired mutex permit

dev:8972019-12-15 02:27:39.546 pm debugSharpAquos - releaseMutex ?13? - Released mutex permit

dev:8972019-12-15 02:27:39.543 pm debugSharpAquos - parseVolume - SET - volume [4]

dev:8972019-12-15 02:27:39.540 pm infoSharpAquos - EVENT - [[name:volume, value:4]]

dev:8972019-12-15 02:27:39.523 pm debugSharpAquos - parseVolume - response '4'

dev:8972019-12-15 02:27:39.519 pm debugSharpAquos - parse - 1 - COMMAND [VOLM? ] RESPONSE [4]

dev:8972019-12-15 02:27:39.515 pm debugSharpAquos - IOBUFFER: 2 entries [[VOLM? , 4]]

dev:8972019-12-15 02:27:39.475 pm debugSharpAquos - getMutex 13 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 02:27:39.471 pm debugSharpAquos - request 12 - request = [VOLM? ] to 192.168.254.205:10002

dev:8972019-12-15 02:27:39.467 pm debugSharpAquos - request 12 - Socket [open] CMD [VOLM] Params [?]

dev:8972019-12-15 02:27:39.464 pm debugSharpAquos - getMutex 12 - Acquired mutex permit

dev:8972019-12-15 02:27:39.461 pm debugSharpAquos - releaseMutex ?12? - Released mutex permit

dev:8972019-12-15 02:27:39.437 pm debugSharpAquos - parseMute - SET - mute [unmuted]

dev:8972019-12-15 02:27:39.434 pm infoSharpAquos - EVENT - [[name:mute, value:unmuted]]

dev:8972019-12-15 02:27:39.430 pm debugSharpAquos - parseMute - response '2'

dev:8972019-12-15 02:27:39.418 pm debugSharpAquos - parse - 1 - COMMAND [MUTE? ] RESPONSE [2]

dev:8972019-12-15 02:27:39.414 pm debugSharpAquos - IOBUFFER: 2 entries [[MUTE? , 2]]

dev:8972019-12-15 02:27:39.339 pm debugSharpAquos - getMutex 12 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 02:27:39.333 pm debugSharpAquos - request 11 - request = [MUTE? ] to 192.168.254.205:10002

dev:8972019-12-15 02:27:39.330 pm debugSharpAquos - request 11 - Socket [open] CMD [MUTE] Params [?]

dev:8972019-12-15 02:27:39.326 pm debugSharpAquos - getMutex 11 - Acquired mutex permit

dev:8972019-12-15 02:27:39.260 pm debugSharpAquos - releaseMutex ?11? - Released mutex permit

dev:8972019-12-15 02:27:39.257 pm debugSharpAquos - parsePower - SET - power [on]

dev:8972019-12-15 02:27:39.250 pm infoSharpAquos - EVENT - [[name:switch, value:on]]

dev:8972019-12-15 02:27:39.246 pm infoSharpAquos - EVENT - [[name:power, value:on]]

dev:8972019-12-15 02:27:39.243 pm debugSharpAquos - parsePower - response '1'

dev:8972019-12-15 02:27:39.230 pm debugSharpAquos - parse - 1 - COMMAND [POWR? ] RESPONSE [1]

dev:8972019-12-15 02:27:39.226 pm debugSharpAquos - IOBUFFER: 2 entries [[POWR? , 1]]

dev:8972019-12-15 02:27:39.122 pm debugSharpAquos - getMutex 11 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 02:27:39.118 pm debugSharpAquos - request 10 - request = [POWR? ] to 192.168.254.205:10002

dev:8972019-12-15 02:27:39.109 pm debugSharpAquos - request 10 - Socket [open] CMD [POWR] Params [?]

dev:8972019-12-15 02:27:39.104 pm debugSharpAquos - getMutex 10 - Acquired mutex permit

dev:8972019-12-15 02:27:39.091 pm debugSharpAquos - getDeviceState() Starting

dev:8972019-12-15 02:25:55.426 pm debugSharpAquos - releaseMutex ?9? - Released mutex permit

dev:8972019-12-15 02:25:55.413 pm debugSharpAquos - parseSleepTimer - SET - SleepTimer [0] SleepTimerSetting [Off]

dev:8972019-12-15 02:25:55.392 pm infoSharpAquos - EVENT - [[name:SleepTimerSetting, value:Off]]

dev:8972019-12-15 02:25:55.162 pm infoSharpAquos - EVENT - [[name:SleepTimer, value:0]]

dev:8972019-12-15 02:25:55.143 pm debugSharpAquos - parseSleepTimer - response '0'

dev:8972019-12-15 02:25:55.139 pm debugSharpAquos - parse - 1 - COMMAND [OFTM? ] RESPONSE [0]

dev:8972019-12-15 02:25:55.135 pm debugSharpAquos - IOBUFFER: 2 entries [[OFTM? , 0]]

dev:8972019-12-15 02:25:55.093 pm debugSharpAquos - getDeviceState() Completed.

dev:8972019-12-15 02:25:54.964 pm debugSharpAquos - request 9 - request = [OFTM? ] to 192.168.254.205:10002

dev:8972019-12-15 02:25:54.960 pm debugSharpAquos - request 9 - Socket [open] CMD [OFTM] Params [?]

dev:8972019-12-15 02:25:54.956 pm debugSharpAquos - getMutex 9 - Acquired mutex permit

dev:8972019-12-15 02:25:54.889 pm debugSharpAquos - releaseMutex ?9? - Released mutex permit

dev:8972019-12-15 02:25:54.885 pm debugSharpAquos - parse3DMode - SET - 3DMode [0] 3DModeName [3D Off]

dev:8972019-12-15 02:25:54.868 pm infoSharpAquos - EVENT - [[name:3DModeName, value:3D Off]]

dev:8972019-12-15 02:25:54.863 pm infoSharpAquos - EVENT - [[name:3DMode, value:0]]

dev:8972019-12-15 02:25:54.847 pm debugSharpAquos - parse3DMode - response '0'

dev:8972019-12-15 02:25:54.843 pm debugSharpAquos - parse - 1 - COMMAND [TDCH? ] RESPONSE [0]

dev:8972019-12-15 02:25:54.839 pm debugSharpAquos - IOBUFFER: 2 entries [[TDCH? , 0]]

dev:8972019-12-15 02:25:54.733 pm debugSharpAquos - getMutex 9 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 02:25:54.717 pm debugSharpAquos - request 8 - request = [TDCH? ] to 192.168.254.205:10002

dev:8972019-12-15 02:25:54.705 pm debugSharpAquos - request 8 - Socket [open] CMD [TDCH] Params [?]

dev:8972019-12-15 02:25:54.701 pm debugSharpAquos - getMutex 8 - Acquired mutex permit

dev:8972019-12-15 02:25:54.630 pm debugSharpAquos - releaseMutex ?8? - Released mutex permit

dev:8972019-12-15 02:25:54.625 pm debugSharpAquos - parseVideoMode - SET - VideoMode [4] VideoModeName [USER]

dev:8972019-12-15 02:25:54.613 pm infoSharpAquos - EVENT - [[name:VideoModeName, value:USER]]

dev:8972019-12-15 02:25:54.588 pm infoSharpAquos - EVENT - [[name:VideoMode, value:4]]

dev:8972019-12-15 02:25:54.574 pm debugSharpAquos - parseVideoMode - response '4'

dev:8972019-12-15 02:25:54.570 pm debugSharpAquos - parse - 1 - COMMAND [AVMD? ] RESPONSE [4]

dev:8972019-12-15 02:25:54.566 pm debugSharpAquos - IOBUFFER: 2 entries [[AVMD? , 4]]

dev:8972019-12-15 02:25:54.398 pm debugSharpAquos - getMutex 8 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 02:25:54.393 pm debugSharpAquos - request 7 - request = [AVMD? ] to 192.168.254.205:10002

dev:8972019-12-15 02:25:54.381 pm debugSharpAquos - request 7 - Socket [open] CMD [AVMD] Params [?]

dev:8972019-12-15 02:25:54.377 pm debugSharpAquos - getMutex 7 - Acquired mutex permit

dev:8972019-12-15 02:25:54.298 pm debugSharpAquos - releaseMutex ?7? - Released mutex permit

dev:8972019-12-15 02:25:54.286 pm debugSharpAquos - parseSurroundMode - SET - SurroundMode [2] SurroundModeName [Off]

dev:8972019-12-15 02:25:54.281 pm infoSharpAquos - EVENT - [[name:SurroundModeName, value:Off]]

dev:8972019-12-15 02:25:54.239 pm infoSharpAquos - EVENT - [[name:SurroundMode, value:2]]

dev:8972019-12-15 02:25:54.226 pm debugSharpAquos - parseSurround - response '2'

dev:8972019-12-15 02:25:54.220 pm debugSharpAquos - parse - 1 - COMMAND [ACSU? ] RESPONSE [2]

dev:8972019-12-15 02:25:54.213 pm debugSharpAquos - IOBUFFER: 2 entries [[ACSU? , 2]]

dev:8972019-12-15 02:25:53.911 pm debugSharpAquos - getMutex 7 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 02:25:53.893 pm debugSharpAquos - request 6 - request = [ACSU? ] to 192.168.254.205:10002

dev:8972019-12-15 02:25:53.870 pm debugSharpAquos - request 6 - Socket [open] CMD [ACSU] Params [?]

dev:8972019-12-15 02:25:53.866 pm debugSharpAquos - getMutex 6 - Acquired mutex permit

dev:8972019-12-15 02:25:53.835 pm debugSharpAquos - releaseMutex ?6? - Released mutex permit

dev:8972019-12-15 02:25:53.830 pm debugSharpAquos - parseViewMode - SET - ViewMode [8] ViewModeName [Dot by Dot]

dev:8972019-12-15 02:25:53.816 pm infoSharpAquos - EVENT - [[name:ViewModeName, value:Dot by Dot]]

dev:8972019-12-15 02:25:53.811 pm infoSharpAquos - EVENT - [[name:ViewMode, value:8]]

dev:8972019-12-15 02:25:53.786 pm debugSharpAquos - parseViewMode - response '8'

dev:8972019-12-15 02:25:53.733 pm debugSharpAquos - parse - 1 - COMMAND [WIDE? ] RESPONSE [8]

dev:8972019-12-15 02:25:53.726 pm debugSharpAquos - IOBUFFER: 2 entries [[WIDE? , 8]]

dev:8972019-12-15 02:25:53.569 pm debugSharpAquos - getMutex 6 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 02:25:53.552 pm debugSharpAquos - request 5 - request = [WIDE? ] to 192.168.254.205:10002

dev:8972019-12-15 02:25:53.530 pm debugSharpAquos - request 5 - Socket [open] CMD [WIDE] Params [?]

dev:8972019-12-15 02:25:53.526 pm debugSharpAquos - getMutex 5 - Acquired mutex permit

dev:8972019-12-15 02:25:53.513 pm debugSharpAquos - releaseMutex ?5? - Released mutex permit

dev:8972019-12-15 02:25:53.484 pm debugSharpAquos - parseInput - SET - input [1] InputName [HDMI IN 1]

dev:8972019-12-15 02:25:53.471 pm infoSharpAquos - EVENT - [[name:inputName, value:HDMI IN 1]]

dev:8972019-12-15 02:25:53.467 pm infoSharpAquos - EVENT - [[name:input, value:1]]

dev:8972019-12-15 02:25:53.449 pm debugSharpAquos - parseInput - response '1'

dev:8972019-12-15 02:25:53.445 pm debugSharpAquos - parse - 1 - COMMAND [IAVD? ] RESPONSE [1]

dev:8972019-12-15 02:25:53.396 pm debugSharpAquos - IOBUFFER: 2 entries [[IAVD? , 1]]

dev:8972019-12-15 02:25:53.102 pm debugSharpAquos - getMutex 5 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 02:25:53.098 pm debugSharpAquos - request 4 - request = [IAVD? ] to 192.168.254.205:10002

dev:8972019-12-15 02:25:53.089 pm debugSharpAquos - request 4 - Socket [open] CMD [IAVD] Params [?]

dev:8972019-12-15 02:25:53.085 pm debugSharpAquos - getMutex 4 - Acquired mutex permit

dev:8972019-12-15 02:25:53.056 pm debugSharpAquos - releaseMutex ?4? - Released mutex permit

dev:8972019-12-15 02:25:53.050 pm debugSharpAquos - parseVolume - SET - volume [4]

dev:8972019-12-15 02:25:52.977 pm infoSharpAquos - EVENT - [[name:volume, value:4]]

dev:8972019-12-15 02:25:52.973 pm debugSharpAquos - parseVolume - response '4'

dev:8972019-12-15 02:25:52.969 pm debugSharpAquos - parse - 1 - COMMAND [VOLM? ] RESPONSE [4]

dev:8972019-12-15 02:25:52.939 pm debugSharpAquos - IOBUFFER: 2 entries [[VOLM? , 4]]

dev:8972019-12-15 02:25:52.792 pm debugSharpAquos - getMutex 4 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 02:25:52.771 pm debugSharpAquos - request 3 - request = [VOLM? ] to 192.168.254.205:10002

dev:8972019-12-15 02:25:52.742 pm debugSharpAquos - request 3 - Socket [open] CMD [VOLM] Params [?]

dev:8972019-12-15 02:25:52.738 pm debugSharpAquos - getMutex 3 - Acquired mutex permit

dev:8972019-12-15 02:25:52.700 pm debugSharpAquos - releaseMutex ?3? - Released mutex permit

dev:8972019-12-15 02:25:52.678 pm debugSharpAquos - parseMute - SET - mute [unmuted]

dev:8972019-12-15 02:25:52.674 pm infoSharpAquos - EVENT - [[name:mute, value:unmuted]]

dev:8972019-12-15 02:25:52.653 pm debugSharpAquos - parseMute - response '2'

dev:8972019-12-15 02:25:52.649 pm debugSharpAquos - parse - 1 - COMMAND [MUTE? ] RESPONSE [2]

dev:8972019-12-15 02:25:52.633 pm debugSharpAquos - parsePower - SET - power [on]

dev:8972019-12-15 02:25:52.629 pm infoSharpAquos - EVENT - [[name:switch, value:on]]

dev:8972019-12-15 02:25:52.587 pm infoSharpAquos - EVENT - [[name:power, value:on]]

dev:8972019-12-15 02:25:52.562 pm debugSharpAquos - parsePower - response '1'

dev:8972019-12-15 02:25:52.556 pm debugSharpAquos - parse - 2 - COMMAND [POWR? ] RESPONSE [1]

dev:8972019-12-15 02:25:52.535 pm debugSharpAquos - parse - 2 - COMMAND [RSPW2 ] RESPONSE [OK]

dev:8972019-12-15 02:25:52.463 pm debugSharpAquos - IOBUFFER: 6 entries [[RSPW2 , POWR? , MUTE? , OK, 1, 2]]

dev:8972019-12-15 02:25:51.594 pm debugSharpAquos - getMutex 3 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 02:25:51.580 pm debugSharpAquos - request 2 - request = [MUTE? ] to 192.168.254.205:10002

dev:8972019-12-15 02:25:51.576 pm debugSharpAquos - request 2 - Socket [open] CMD [MUTE] Params [?]

dev:8972019-12-15 02:25:51.557 pm debugSharpAquos - getMutex 2 - Acquired mutex permit

dev:8972019-12-15 02:25:51.553 pm debugSharpAquos - request 1 - request = [POWR? ] to 192.168.254.205:10002

dev:8972019-12-15 02:25:51.538 pm debugSharpAquos - request 1 - Socket [open] CMD [POWR] Params [?]

dev:8972019-12-15 02:25:51.533 pm debugSharpAquos - getMutex 1 - Acquired mutex permit

dev:8972019-12-15 02:25:51.519 pm debugSharpAquos - getDeviceState() Starting

dev:8972019-12-15 02:25:51.491 pm debugSharpAquos - request 0 - request = [RSPW2 ] to 192.168.254.205:10002

dev:8972019-12-15 02:25:51.472 pm debugSharpAquos - request 0 - Socket [open] CMD [RSPW] Params [2]

dev:8972019-12-15 02:25:51.468 pm debugSharpAquos - getMutex 0 - Acquired mutex permit

dev:8972019-12-15 02:25:51.441 pm debugSharpAquos - initialize - Poll Rate set to every 5 minutes

dev:8972019-12-15 02:25:51.316 pm debugSharpAquos - openSocket: Socket opened.

dev:8972019-12-15 02:25:49.990 pm debugSharpAquos - openSocket: Connecting to 192.168.254.205:10002

dev:8972019-12-15 02:25:49.986 pm debugSharpAquos - initialize - Set DNI = [C0A8FECD]

dev:8972019-12-15 02:25:48.932 pm debugSharpAquos - initialize - TV mac address not yet available. Retry in 1 second.

dev:8972019-12-15 02:25:47.902 pm debugSharpAquos - initialize - TV mac address not yet available. Retry in 1 second.

dev:8972019-12-15 02:25:46.874 pm debugSharpAquos - initialize - TV mac address not yet available. Retry in 1 second.

dev:8972019-12-15 02:25:45.828 pm debugSharpAquos - initialize - TV mac address not yet available. Retry in 1 second.

dev:8972019-12-15 02:25:44.795 pm debugSharpAquos - initialize - TV mac address not yet available. Retry in 1 second.

dev:8972019-12-15 02:25:44.560 pm debugSharpAquos - initialize - IP: 192.168.254.205 PORT: 10002 USER: null PASS: null Poll Rate: 5

dev:8972019-12-15 02:25:43.985 pm debugSharpAquos - closeSocket: Socket closed.

dev:8972019-12-15 02:25:42.756 pm debugSharpAquos - closeSocket: Socket close requested.

dev:8972019-12-15 02:25:41.754 pm debuginitialize - Driver v0.32 debug [true] verbose [true] events [true]

Everything seems good, this is the only error I see every 5 minutes....

Summary

dev:8972019-12-15 03:02:39.181 pm debugSharpAquos - request 97 - request = [POWR? ] to 192.168.254.205:10002

dev:8972019-12-15 03:02:39.178 pm debugSharpAquos - request 97 - Socket [open] CMD [POWR] Params [?]

dev:8972019-12-15 03:02:39.175 pm debugSharpAquos - getMutex 97 - Acquired mutex permit

dev:8972019-12-15 03:02:39.073 pm debugSharpAquos - getMutex 97 - Detected stale mutex - forcing mutex release.

dev:8972019-12-15 03:02:39.069 pm debugSharpAquos - getMutex 97 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 03:02:39.064 pm debugSharpAquos - getDeviceState() Starting

dev:8972019-12-15 02:59:40.826 pm debugSharpAquos - request 96 - request = [DC2L? ] to 192.168.254.205:10002

dev:8972019-12-15 02:59:40.823 pm debugSharpAquos - request 96 - Socket [open] CMD [DC2L] Params [?]

dev:8972019-12-15 02:59:40.821 pm debugSharpAquos - getMutex 96 - Acquired mutex permit

dev:8972019-12-15 02:59:40.719 pm debugSharpAquos - getMutex 96 - Detected stale mutex - forcing mutex release.

dev:8972019-12-15 02:58:41.712 pm debugSharpAquos - getMutex 96 - mutex permit not yet available. Waiting up to 60 seconds.

dev:8972019-12-15 02:58:41.710 pm errorSharpAquos - request 95 - ERROR. Unable to acquire mutex permit.

dev:8972019-12-15 02:58:41.707 pm debugSharpAquos - request 95 - Socket [open] CMD [DC2U] Params [?] Handler [null]

dev:8972019-12-15 02:58:41.703 pm debugSharpAquos - getMutex 95 - Failed to acquired mutex permit

dev:8972019-12-15 02:57:40.790 pm errorjava.lang.NumberFormatException: For input string: "7.1" on line 540 (parse)

dev:8972019-12-15 02:57:40.766 pm debugSharpAquos - parseVideoMode - response '7.1'

dev:8972019-12-15 02:57:40.764 pm debugSharpAquos - parse - 2 - COMMAND [AVMD? ] RESPONSE [7.1]

dev:8972019-12-15 02:57:40.761 pm debugSharpAquos - IOBUFFER: 6 entries [[AVMD? , TDCH? , OFTM? , DCCH? , DA2P? , 7.1]]

[cybrmage] do you think the logs could be dragging down my hub, to the point were hardly anything works after a period of time.

The logs shouldn't be... the problems with the mutex mechanism probably are...

I've been working on a few things and had a breakthrough...

  1. I managed to overcome a limitation of the HE groovy environment that previously prevented me from coding the mutex locks in the optimal way... I think I now have the mutex locks working properly... I stress tested the driver with ~350 commands in very quick succession without errors...
  2. The HE rawSocket implementation is minimal and problematic (doesn't detect socket errors properly, etc - plainly stated in the developer documentation)... The regular socket implementation is marginally better, but still has issues... The telnet interface is more robust and stable... Originally, rawSockets was used as that is what we got to work. I have now been able to get all three protocols working (with the emulator i'm using...). All three should work with a real TV...

Since telnet is most reliable, it is the default protocol used by the driver... If it does not work with a real TV, you can select one of the other protocols - save preferences, and the driver will reinitialize with that protocol... (Note: When you save preferences, you may get errors every few seconds for several minutes after the re-initialization... This is normal... After the errors have stopped... things should work normally)

After you upload this version, you will probably want to reboot your HE from the settings menu...

[Old version removed]

Thank you again....I will give it a try!

Install logs......

Summary

dev:9932019-12-16 12:11:03.401 am debugSharpAquos - openSocket: Socket opened.

dev:9932019-12-16 12:11:02.400 am debugSharpAquos - openSocket: Connecting to 192.168.254.205:10002

dev:9932019-12-16 12:10:57.397 am debugSharpAquos - socketStatus - Stream Closed - Attempting reconnect [1]

dev:9932019-12-16 12:10:57.353 am debugSharpAquos - releaseMutex ?-1? - Released mutex permit [1]

dev:9932019-12-16 12:10:57.349 am debugSharpAquos - releaseMutex ?-1? - Release mutex request [0]

dev:9932019-12-16 12:10:57.344 am warnsocketStatus - Socket [null] Message [receive error: Stream is closed]

dev:9932019-12-16 12:10:57.336 am debugSharpAquos - telnetStatus - Socket [null] Message [receive error: Stream is closed] - Passing to socketStatus...

dev:9932019-12-16 12:07:55.345 am debugSharpAquos - sendSocketCommand - telnet - sending [RSPW2 ]

dev:9932019-12-16 12:07:55.342 am debugSharpAquos - request 0 - Interface [telnet] Socket [open] CMD [RSPW] Params [2]

dev:9932019-12-16 12:07:55.339 am debugSharpAquos - getMutex 0 - Acquired mutex permit [0]

dev:9932019-12-16 12:07:55.324 am debugSharpAquos - getMutex 0 - Acquire mutex request [1]

dev:9932019-12-16 12:07:55.317 am debugSharpAquos - Initialize - IOBUFFER: 3 entries [[RSPW2 , �, �]]

dev:9932019-12-16 12:07:54.923 am errorSharpAquos - parse null - Plugin initializing, not parsing.

dev:9932019-12-16 12:07:54.903 am errorSharpAquos - parse null - Plugin initializing, not parsing.

dev:9932019-12-16 12:07:54.793 am debugSharpAquos - sendSocketCommand - telnet - sending [RSPW2 ]

dev:9932019-12-16 12:07:54.787 am debugSharpAquos - initialize - Poll Rate set to every 5 minutes

dev:9932019-12-16 12:07:54.692 am debugSharpAquos - openSocket: Socket opened.

dev:9932019-12-16 12:07:53.681 am debugSharpAquos - openSocket: Connecting to 192.168.254.205:10002

dev:9932019-12-16 12:07:53.679 am debugSharpAquos - initialize - Set DNI = [C0A8FECD]

dev:9932019-12-16 12:07:52.660 am debugSharpAquos - initialize - TV mac address not yet available. Retry in 1 second.

dev:9932019-12-16 12:07:51.646 am debugSharpAquos - initialize - TV mac address not yet available. Retry in 1 second.

dev:9932019-12-16 12:07:50.633 am debugSharpAquos - initialize - TV mac address not yet available. Retry in 1 second.

dev:9932019-12-16 12:07:49.618 am debugSharpAquos - initialize - TV mac address not yet available. Retry in 1 second.

dev:9932019-12-16 12:07:48.607 am debugSharpAquos - initialize - TV mac address not yet available. Retry in 1 second.

dev:9932019-12-16 12:07:48.562 am debugSharpAquos - initialize - IP: 192.168.254.205 PORT: 10002 USER: null PASS: null Poll Rate: 5

dev:9932019-12-16 12:07:48.059 am debugSharpAquos - closeSocket: Socket closed. [1]

dev:9932019-12-16 12:07:48.054 am warnFLUSHMUTEX - FLUSHED - Queued [false] Waiting [0] permits [1]

dev:9932019-12-16 12:07:46.945 am warnFLUSHMUTEX - flushing mutex queue. Queued [false] Waiting [0] permits [1]

dev:9932019-12-16 12:07:45.937 am debugSharpAquos - closeSocket: Socket close requested.

dev:9932019-12-16 12:07:45.934 am debugSharpAquos - releaseMutex ?-2? - Released mutex permit [1]

dev:9932019-12-16 12:07:45.931 am debugSharpAquos - releaseMutex ?-2? - ERROR - Permit release not required [1]

dev:9932019-12-16 12:07:45.924 am debugSharpAquos - releaseMutex ?-2? - Release mutex request [1]

dev:9932019-12-16 12:07:45.899 am warninitialize - Driver v0.34 debug [true] verbose [true] events [true] INTERFACE [telnet] permits [1] Init [null] Queue [0]

dev:9932019-12-16 12:07:45.836 am debugSharpAquos - updated - IP [192.168.254.205] PORT [10002] USER [null] PASS [null] INTERFACE [telnet]

dev:9932019-12-16 12:07:08.991 am debugSharpAquos - installed

Cant seem to get anything to work with telnet...here are the logs...

Summary

dev:9932019-12-16 12:25:42.084 am errorSharpAquos - request - ERROR. Request [POWR] [?] dropped. Plugin initializing.

dev:9932019-12-16 12:25:42.079 am errorSharpAquos - request - ERROR. Request [POWR] [1] dropped. Plugin initializing.

dev:9932019-12-16 12:25:42.074 am debugSharpAquos - Turn on TV

dev:9932019-12-16 12:25:30.105 am debuginitialize - Driver v0.34 - ERROR - initialize() already in progress. Aborting.

dev:9932019-12-16 12:24:33.655 am errorSharpAquos - request - ERROR. Request [POWR] [?] dropped. Plugin initializing.

dev:9932019-12-16 12:24:33.652 am errorSharpAquos - request - ERROR. Request [POWR] [1] dropped. Plugin initializing.

dev:9932019-12-16 12:24:33.648 am debugSharpAquos - Turn on TV

dev:9932019-12-16 12:24:11.335 am errorSharpAquos - request - ERROR. Request [POWR] [?] dropped. Plugin initializing.

dev:9932019-12-16 12:24:11.330 am errorSharpAquos - request - ERROR. Request [POWR] [0] dropped. Plugin initializing.

dev:9932019-12-16 12:24:11.326 am debugSharpAquos - Turn off TV

dev:9932019-12-16 12:24:06.205 am errorSharpAquos - request - ERROR. Request [POWR] [?] dropped. Plugin initializing.

dev:9932019-12-16 12:24:06.202 am errorSharpAquos - request - ERROR. Request [POWR] [0] dropped. Plugin initializing.

dev:9932019-12-16 12:24:06.199 am debugSharpAquos - Turn off TV

dev:9932019-12-16 12:23:39.173 am errorSharpAquos - request - ERROR. Request [VOLM] [?] dropped. Plugin initializing.

dev:9932019-12-16 12:23:39.168 am errorSharpAquos - request - ERROR. Request [RCKY] [33] dropped. Plugin initializing.

dev:9932019-12-16 12:23:39.163 am debugSharpAquos - volumeUp()

dev:9932019-12-16 12:23:38.590 am errorSharpAquos - request - ERROR. Request [VOLM] [?] dropped. Plugin initializing.

dev:9932019-12-16 12:23:38.587 am errorSharpAquos - request - ERROR. Request [RCKY] [33] dropped. Plugin initializing.

dev:9932019-12-16 12:23:38.569 am debugSharpAquos - volumeUp()

dev:9932019-12-16 12:23:25.040 am debugSharpAquos - getDeviceState() Completed.

dev:9932019-12-16 12:23:24.689 am errorSharpAquos - request - ERROR. Request [OFTM] [?] dropped. Plugin initializing.

dev:9932019-12-16 12:23:24.686 am errorSharpAquos - request - ERROR. Request [TDCH] [?] dropped. Plugin initializing.

dev:9932019-12-16 12:23:24.677 am errorSharpAquos - request - ERROR. Request [AVMD] [?] dropped. Plugin initializing.

dev:9932019-12-16 12:23:24.674 am errorSharpAquos - request - ERROR. Request [ACSU] [?] dropped. Plugin initializing.

dev:9932019-12-16 12:23:24.671 am errorSharpAquos - request - ERROR. Request [WIDE] [?] dropped. Plugin initializing.

dev:9932019-12-16 12:23:24.653 am errorSharpAquos - request - ERROR. Request [IAVD] [?] dropped. Plugin initializing.

dev:9932019-12-16 12:23:24.648 am errorSharpAquos - request - ERROR. Request [VOLM] [?] dropped. Plugin initializing.

dev:9932019-12-16 12:23:24.624 am errorSharpAquos - request - ERROR. Request [MUTE] [?] dropped. Plugin initializing.

dev:9932019-12-16 12:23:24.618 am errorSharpAquos - request - ERROR. Request [POWR] [?] dropped. Plugin initializing.

dev:9932019-12-16 12:23:24.614 am debugSharpAquos - getDeviceState() Starting

dev:9932019-12-16 12:23:22.176 am debugSharpAquos - toggleMute() - completed

dev:9932019-12-16 12:23:22.170 am errorSharpAquos - request - ERROR. Request [MUTE] [?] dropped. Plugin initializing.

dev:9932019-12-16 12:23:22.165 am errorSharpAquos - request - ERROR. Request [RCKY] [31] dropped. Plugin initializing.

dev:9932019-12-16 12:23:22.156 am debugSharpAquos - toggleMute()

dev:9932019-12-16 12:23:08.491 am debugSharpAquos - sendSocketCommand - telnet - sending [RSPW2 ]

dev:9932019-12-16 12:23:08.488 am debugSharpAquos - initialize - Poll Rate set to every 5 minutes

dev:9932019-12-16 12:23:08.256 am debugSharpAquos - openSocket: Socket opened.

dev:9932019-12-16 12:23:07.229 am debugSharpAquos - openSocket: Connecting to 192.168.254.205:10002

dev:9932019-12-16 12:23:07.225 am debugSharpAquos - initialize - Set DNI = [9CC7D1ED5F5F]

dev:9932019-12-16 12:23:07.131 am debugSharpAquos - initialize - IP: 192.168.254.205 PORT: 10002 USER: null PASS: null Poll Rate: 5

dev:9932019-12-16 12:23:06.631 am debugSharpAquos - closeSocket: Socket closed. [1]

dev:9932019-12-16 12:23:06.628 am warnFLUSHMUTEX - FLUSHED - Queued [false] Waiting [0] permits [1]

dev:9932019-12-16 12:23:05.524 am warnFLUSHMUTEX - flushing mutex queue. Queued [false] Waiting [0] permits [1]

dev:9932019-12-16 12:23:04.522 am debugSharpAquos - closeSocket: Socket close requested.

dev:9932019-12-16 12:23:04.519 am debugSharpAquos - releaseMutex ?89? - Released mutex permit [1]

dev:9932019-12-16 12:23:04.514 am debugSharpAquos - releaseMutex ?89? - Release mutex request [0]

dev:9932019-12-16 12:23:04.392 am warninitialize - Driver v0.34 debug [true] verbose [true] events [true] INTERFACE [telnet] permits [0] Init [false] Queue [0]

It looks like you have multiple instances of the driver running...

So... Stop!!! Do not click on any other commands... wait for about 5 minutes... Let the running instances run their course and settle down... Wait for the logs entries to stop (you should see "getDeviceState() Completed." and "initialize - COMPLETED")... then click on initialize... Things should then work better...

I rebooted my HE after I posted the latest version... And it's been did the same thing... I'm working on fixing it...

Thank you...will let it settle in....