Russound RIO

Tomtibo · December 30, 2016, 7:12pm

Yes sure!

14:10:25.315 [DEBUG] [org.openhab.binding.russound        ] - BundleEvent STARTING - org.openhab.binding.russound
14:10:25.361 [DEBUG] [org.openhab.binding.russound        ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={component.name=binding.russound, component.id=188, service.id=319, service.bundleid=205, service.scope=bundle} - org.openhab.binding.russound
14:10:25.400 [DEBUG] [org.openhab.binding.russound        ] - BundleEvent STARTED - org.openhab.binding.russound
14:10:25.684 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'russound:rio:home' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
14:10:26.684 [DEBUG] [.russound.internal.net.SocketSession] - >>> creating socket
14:10:26.719 [DEBUG] [.russound.internal.net.SocketSession] - Connecting to 192.168.11.74:9621
14:10:26.721 [DEBUG] [.russound.internal.net.SocketSession] - >>> creating writer
14:10:26.724 [DEBUG] [.russound.internal.net.SocketSession] - >>> creating reader
14:10:26.726 [DEBUG] [.russound.internal.net.SocketSession] - >>> starting dispatcher
14:10:26.729 [DEBUG] [.russound.internal.net.SocketSession] - >>> starting response reader
14:10:26.730 [DEBUG] [.russound.internal.net.SocketSession] - >>> starting dispatcher2
14:10:26.733 [INFO ] [ussound.rio.system.RioSystemProtocol] - Russound System now connected
14:10:26.733 [DEBUG] [.russound.internal.net.SocketSession] - >>> Started listening for responses
14:10:26.735 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:26.745 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'russound:rio:home' changed from INITIALIZING to ONLINE
14:10:26.745 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'WATCH SYSTEM ON'
14:10:26.750 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET System.status'
14:10:26.751 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'VERSION'
14:10:26.757 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET System.language'
14:10:26.759 [INFO ] [russound.rio.system.RioSystemHandler] - Going online
14:10:26.759 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'VERSION'
14:10:26.777 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET System.language'
14:10:26.785 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'russound:controller:controller' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
14:10:26.798 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].type'
14:10:26.799 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'russound:controller:controller' changed from INITIALIZING to ONLINE
14:10:26.800 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].ipAddress'
14:10:26.809 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].macAddress'
14:10:26.823 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'WATCH C[1].Z[1] ON'
14:10:26.823 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'russound:zone:1' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
14:10:26.825 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].enabled'
14:10:26.832 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'russound:zone:1' changed from INITIALIZING to ONLINE
14:10:26.839 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].name'
14:10:26.843 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].currentSource'
14:10:26.847 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].bass'
14:10:26.851 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].treble'
14:10:26.855 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].balance'
14:10:26.858 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].loudness'
14:10:26.865 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].doNotDisturb'
14:10:26.872 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].partyMode'
14:10:26.877 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].status'
14:10:26.883 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].volume'
14:10:26.887 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].mute'
14:10:26.889 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].page'
14:10:26.892 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].sharedSource'
14:10:26.895 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].sleepTimeRemaining'
14:10:26.897 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].lastError'
14:10:27.732 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:27.739 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:28.734 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:28.742 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:29.736 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:29.748 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:30.742 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:30.755 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:31.747 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:31.762 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:32.753 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:32.765 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:33.754 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:33.768 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:34.756 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:34.771 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:35.758 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:35.775 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:36.760 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:36.778 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:37.762 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:37.781 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:38.763 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:38.784 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:39.765 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:39.787 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:40.767 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:40.790 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:41.769 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:41.793 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:42.772 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:42.797 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:43.773 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:43.800 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:44.775 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:44.803 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:45.778 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:45.808 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:46.780 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:46.813 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:47.782 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:47.817 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:48.784 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:48.822 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:49.794 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:49.829 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:50.799 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:50.833 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:51.803 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:51.838 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:52.806 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:52.841 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:53.808 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:53.844 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:54.810 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:54.847 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:55.813 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:55.853 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:56.759 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: ''
14:10:56.815 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:56.860 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:57.817 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:57.866 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:58.820 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:58.870 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:10:59.822 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:10:59.873 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:11:00.824 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:11:00.877 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:11:01.826 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:11:01.881 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:11:02.828 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:11:02.885 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:11:03.830 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:11:03.888 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:11:04.832 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:11:04.891 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:11:05.837 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:11:05.895 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:11:06.839 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:11:06.899 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:11:07.841 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:11:07.904 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:11:08.843 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:11:08.908 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:11:09.845 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:11:09.911 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:11:10.848 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:11:10.916 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:11:11.850 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:11:11.919 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:11:12.854 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:11:12.924 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:11:13.855 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:11:13.926 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:11:14.857 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null

tmrobert8 · December 30, 2016, 7:29pm

Wow - one more try (redownload and try again - that log was interesting)

Tomtibo · December 30, 2016, 7:32pm

Here is the new logs!

14:30:35.553 [DEBUG] [org.openhab.binding.russound        ] - BundleEvent INSTALLED - org.openhab.binding.russound
14:30:35.733 [DEBUG] [org.openhab.binding.russound        ] - BundleEvent RESOLVED - org.openhab.binding.russound
14:30:35.744 [DEBUG] [org.openhab.binding.russound        ] - BundleEvent STARTING - org.openhab.binding.russound
14:30:35.797 [DEBUG] [org.openhab.binding.russound        ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={component.name=binding.russound, component.id=189, service.id=320, service.bundleid=206, service.scope=bundle} - org.openhab.binding.russound
14:30:35.875 [DEBUG] [org.openhab.binding.russound        ] - BundleEvent STARTED - org.openhab.binding.russound
14:30:36.245 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'russound:rio:home' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
14:30:37.275 [DEBUG] [.russound.internal.net.SocketSession] - >>> creating socket
14:30:37.315 [DEBUG] [.russound.internal.net.SocketSession] - Connecting to 192.168.11.74:9621
14:30:37.317 [DEBUG] [.russound.internal.net.SocketSession] - >>> creating writer
14:30:37.320 [DEBUG] [.russound.internal.net.SocketSession] - >>> creating reader
14:30:37.323 [DEBUG] [.russound.internal.net.SocketSession] - >>> starting dispatcher
14:30:37.326 [DEBUG] [.russound.internal.net.SocketSession] - >>> starting response reader
14:30:37.327 [DEBUG] [.russound.internal.net.SocketSession] - >>> starting dispatcher2
14:30:37.330 [INFO ] [ussound.rio.system.RioSystemProtocol] - Russound System now connected
14:30:37.331 [DEBUG] [.russound.internal.net.SocketSession] - >>> Started listening for responses
14:30:37.333 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:30:37.339 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'WATCH SYSTEM ON'
14:30:37.340 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'russound:rio:home' changed from INITIALIZING to ONLINE
14:30:37.346 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.354 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET System.status'
14:30:37.356 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.365 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET System.language'
14:30:37.374 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.379 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'VERSION'
14:30:37.381 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.383 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET System.language'
14:30:37.385 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.390 [INFO ] [russound.rio.system.RioSystemHandler] - Going online
14:30:37.401 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'russound:controller:controller' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
14:30:37.411 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].type'
14:30:37.412 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'russound:controller:controller' changed from INITIALIZING to ONLINE
14:30:37.413 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.414 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].ipAddress'
14:30:37.416 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.418 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].macAddress'
14:30:37.420 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.446 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'russound:zone:1' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
14:30:37.462 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'russound:zone:1' changed from INITIALIZING to ONLINE
14:30:37.461 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'WATCH C[1].Z[1] ON'
14:30:37.464 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.466 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].name'
14:30:37.469 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.471 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].enabled'
14:30:37.473 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.474 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].currentSource'
14:30:37.476 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.481 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].bass'
14:30:37.485 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.487 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].treble'
14:30:37.489 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.492 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].balance'
14:30:37.494 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.497 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].loudness'
14:30:37.498 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.501 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].doNotDisturb'
14:30:37.503 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.505 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].partyMode'
14:30:37.507 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.510 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].status'
14:30:37.511 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.514 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].volume'
14:30:37.515 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.518 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].mute'
14:30:37.520 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.522 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].page'
14:30:37.524 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.526 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].sharedSource'
14:30:37.528 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.531 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].sleepTimeRemaining'
14:30:37.533 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:37.535 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: 'GET C[1].Z[1].lastError'
14:30:37.537 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:30:38.331 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:30:38.342 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:30:38.344 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:30:39.333 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:30:39.347 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:30:39.348 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:30:40.334 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:30:40.350 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:30:40.352 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:30:41.336 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:30:41.355 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:30:41.357 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:30:42.338 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:30:42.366 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:30:42.369 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:30:43.340 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:30:43.374 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:30:43.375 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:30:44.342 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:30:44.377 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:30:44.378 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:30:45.344 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:30:45.381 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:30:45.383 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:30:46.348 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:30:46.386 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:30:46.389 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:30:47.350 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:30:47.394 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:30:47.397 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:30:48.352 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:30:48.402 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:30:48.405 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:30:49.354 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:30:49.410 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:30:49.411 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:30:50.356 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:30:50.415 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:30:50.416 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:30:51.358 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:30:51.420 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:30:51.421 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:30:52.360 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:30:52.433 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:30:52.436 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:30:53.362 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:30:53.441 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:30:53.443 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:30:54.364 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:30:54.445 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:30:54.447 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:30:55.365 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:30:55.450 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:30:55.452 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:30:56.367 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:30:56.456 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:30:56.457 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:30:57.369 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:30:57.463 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:30:57.465 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:30:58.371 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:30:58.469 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:30:58.471 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:30:59.373 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:30:59.474 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:30:59.476 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:00.375 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:00.479 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:00.481 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:01.378 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:01.484 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:01.496 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:02.381 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:02.499 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:02.501 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:03.383 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:03.504 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:03.505 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:04.384 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:04.508 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:04.511 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:05.386 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:05.515 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:05.516 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:06.389 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:06.519 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:06.521 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:07.390 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: ''
14:31:07.391 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:07.392 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:31:07.530 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:07.531 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:08.393 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:08.534 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:08.535 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:09.395 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:09.538 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:09.540 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:10.396 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:10.542 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:10.544 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:11.400 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:11.548 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:11.549 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:12.402 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:12.593 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:12.595 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:13.404 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:13.598 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:13.599 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:14.405 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:14.602 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:14.604 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:15.407 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:15.607 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:15.609 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:16.409 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:16.612 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:16.613 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:17.411 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:17.616 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:17.618 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:18.413 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:18.621 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:18.623 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:19.415 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:19.626 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:19.629 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:20.417 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:20.634 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:20.637 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:21.419 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:21.642 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:21.644 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:22.421 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:22.647 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:22.649 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:23.423 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:23.652 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:23.653 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:24.424 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:24.656 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:24.659 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:25.426 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:25.664 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:25.665 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:26.429 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:26.669 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:26.670 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:27.431 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:27.674 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:27.676 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:28.433 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:28.679 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:28.680 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:29.435 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:29.684 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:29.685 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:30.437 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:30.689 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:30.690 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:31.439 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:31.693 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:31.695 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:32.441 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:32.698 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:32.703 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:33.444 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:33.706 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:33.707 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:34.445 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:34.710 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:34.713 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:35.447 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:35.717 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:35.720 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:36.449 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:36.725 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:36.728 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:37.390 [DEBUG] [.russound.internal.net.SocketSession] - Sending Command: ''
14:31:37.392 [DEBUG] [.russound.internal.net.SocketSession] - >>> Command sent
14:31:37.451 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:37.733 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:37.776 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:38.454 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null
14:31:38.780 [DEBUG] [.russound.internal.net.SocketSession] - >>> socket timeout: Read timed out
14:31:38.782 [DEBUG] [.russound.internal.net.SocketSession] - >>> waiting for read response
14:31:39.456 [DEBUG] [.russound.internal.net.SocketSession] - >>> dispatch response: null

tmrobert8 · December 30, 2016, 7:40pm

So we are definitely send commands to the controller but we aren’t getting anything back from it (not even a single byte). When you telnet’d into the controller earlier and executed those commands - you did that from the same machine that openhab is running from? If not, would you mind retrying from the openhab machine (don’t bother posting the results - just tell me if you received anything back). BTW - is openhab running on a linux or windows or macos machine?

Tomtibo · December 30, 2016, 7:47pm

I’m using a raspberrypi 2 on openhabian (raspbian OS). And yes I’ve retry telnet using this same machine and showing the same good response from the russound.

Tomtibo · December 30, 2016, 7:53pm

Maybe Should I update and or reboot the Russound controller, just to make sure there is not a previously made telnet session, that was not properly closed. Maybe this could interfere with the binding…??

tmrobert8 · December 30, 2016, 8:03pm

Actually - I just had a thought. Maybe it’s not the read side but the write side. I’m sending every response with a simple newline - maybe it wants a carriage return/newline combo and then it will respond correctly. I’ve just uploaded a new zip - give it a whirl

Tomtibo · December 30, 2016, 8:14pm

Oh Yeah! You got it! Now it is working!

tmrobert8 · December 30, 2016, 8:15pm

Great!! Would you mind post a bit of the log for me - just want to see what it’s sending back

Tomtibo · December 30, 2016, 8:31pm

There a huge amount of logs so it’s hard to grab it all, but here is some.

15:26:06.128 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 93: S C[1].Z[1]
15:26:06.130 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.130 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 46
15:26:06.131 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].
15:26:06.132 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 46: S C[1].Z[1].
15:26:06.133 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.134 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 112
15:26:06.135 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].p
15:26:06.136 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 112: S C[1].Z[1].p
15:26:06.136 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.137 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 97
15:26:06.138 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].pa
15:26:06.139 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 97: S C[1].Z[1].pa
15:26:06.140 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.141 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 103
15:26:06.142 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].pag
15:26:06.143 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 103: S C[1].Z[1].pag
15:26:06.144 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.145 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 101
15:26:06.146 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].page
15:26:06.147 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 101: S C[1].Z[1].page
15:26:06.148 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.148 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 61
15:26:06.149 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].page=
15:26:06.150 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 61: S C[1].Z[1].page=
15:26:06.152 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.152 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 34
15:26:06.153 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].page="
15:26:06.154 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 34: S C[1].Z[1].page="
15:26:06.155 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.155 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 79
15:26:06.156 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].page="O
15:26:06.157 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 79: S C[1].Z[1].page="O
15:26:06.158 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.159 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 70
15:26:06.160 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].page="OF
15:26:06.161 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 70: S C[1].Z[1].page="OF
15:26:06.162 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.163 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 70
15:26:06.164 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].page="OFF
15:26:06.165 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 70: S C[1].Z[1].page="OFF
15:26:06.166 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.166 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 34
15:26:06.167 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].page="OFF"
15:26:06.168 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 34: S C[1].Z[1].page="OFF"
15:26:06.169 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.170 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 13
15:26:06.171 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].page="OFF"
15:26:06.172 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 13: S C[1].Z[1].page="OFF"
15:26:06.173 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.174 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 10
15:26:06.175 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].page="OFF"

15:26:06.176 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> key char found: 

15:26:06.177 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> got a line: S C[1].Z[1].page="OFF"

15:26:06.177 [DEBUG] [ound.internal.net.BasicSocketSession] - Received response: S C[1].Z[1].page="OFF"
15:26:06.178 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 10: 
15:26:06.178 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> dispatch response: S C[1].Z[1].page="OFF"
15:26:06.179 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.179 [DEBUG] [ound.internal.net.BasicSocketSession] - Dispatching response: S C[1].Z[1].page="OFF"
15:26:06.180 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 83
15:26:06.180 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S
15:26:06.181 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 83: S
15:26:06.182 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.183 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 32
15:26:06.184 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S 
15:26:06.185 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> key char found:  
15:26:06.186 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 32: S 
15:26:06.187 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.188 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 67
15:26:06.189 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C
15:26:06.190 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 67: S C
15:26:06.190 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.192 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 91
15:26:06.193 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[
15:26:06.194 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 91: S C[
15:26:06.194 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.195 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 49
15:26:06.196 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1
15:26:06.197 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 49: S C[1
15:26:06.198 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.199 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 93
15:26:06.200 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1]
15:26:06.201 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 93: S C[1]
15:26:06.201 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.203 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 46
15:26:06.203 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].
15:26:06.205 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 46: S C[1].
15:26:06.206 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.206 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 90
15:26:06.207 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z
15:26:06.208 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 90: S C[1].Z
15:26:06.209 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.210 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 91
15:26:06.211 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[
15:26:06.212 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 91: S C[1].Z[
15:26:06.213 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.214 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 49
15:26:06.215 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1
15:26:06.216 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 49: S C[1].Z[1
15:26:06.217 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.218 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 93
15:26:06.218 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1]
15:26:06.219 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 93: S C[1].Z[1]
15:26:06.220 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.221 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 46
15:26:06.222 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].
15:26:06.223 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 46: S C[1].Z[1].
15:26:06.224 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.225 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 115
15:26:06.226 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].s
15:26:06.227 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 115: S C[1].Z[1].s
15:26:06.227 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.228 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 104
15:26:06.229 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sh
15:26:06.230 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 104: S C[1].Z[1].sh
15:26:06.230 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.232 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 97
15:26:06.233 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sha
15:26:06.234 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 97: S C[1].Z[1].sha
15:26:06.234 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.235 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 114
15:26:06.236 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].shar
15:26:06.237 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 114: S C[1].Z[1].shar
15:26:06.238 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.239 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 101
15:26:06.240 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].share
15:26:06.240 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 101: S C[1].Z[1].share
15:26:06.241 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.242 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 100
15:26:06.243 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].shared
15:26:06.244 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 100: S C[1].Z[1].shared
15:26:06.245 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.246 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 83
15:26:06.246 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sharedS
15:26:06.248 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 83: S C[1].Z[1].sharedS
15:26:06.249 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.250 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 111
15:26:06.250 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sharedSo
15:26:06.251 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 111: S C[1].Z[1].sharedSo
15:26:06.252 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.253 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 117
15:26:06.254 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sharedSou
15:26:06.255 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 117: S C[1].Z[1].sharedSou
15:26:06.256 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.257 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 114
15:26:06.257 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sharedSour
15:26:06.258 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 114: S C[1].Z[1].sharedSour
15:26:06.259 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.260 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 99
15:26:06.261 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sharedSourc
15:26:06.262 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 99: S C[1].Z[1].sharedSourc
15:26:06.263 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.264 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 101
15:26:06.264 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sharedSource
15:26:06.266 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 101: S C[1].Z[1].sharedSource
15:26:06.266 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.267 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 61
15:26:06.268 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sharedSource=
15:26:06.269 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 61: S C[1].Z[1].sharedSource=
15:26:06.270 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.271 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 34
15:26:06.272 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sharedSource="
15:26:06.273 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 34: S C[1].Z[1].sharedSource="
15:26:06.274 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.274 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 79
15:26:06.275 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sharedSource="O
15:26:06.276 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 79: S C[1].Z[1].sharedSource="O
15:26:06.277 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.278 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 78
15:26:06.279 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sharedSource="ON
15:26:06.280 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 78: S C[1].Z[1].sharedSource="ON
15:26:06.281 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.283 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 34
15:26:06.283 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sharedSource="ON"
15:26:06.284 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 34: S C[1].Z[1].sharedSource="ON"
15:26:06.284 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.285 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 13
15:26:06.285 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sharedSource="ON"
15:26:06.286 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 13: S C[1].Z[1].sharedSource="ON"
15:26:06.286 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.287 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 10
15:26:06.287 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sharedSource="ON"

15:26:06.288 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> key char found: 

15:26:06.288 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> got a line: S C[1].Z[1].sharedSource="ON"

15:26:06.289 [DEBUG] [ound.internal.net.BasicSocketSession] - Received response: S C[1].Z[1].sharedSource="ON"
15:26:06.289 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 10: 
15:26:06.289 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> dispatch response: S C[1].Z[1].sharedSource="ON"
15:26:06.290 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.290 [DEBUG] [ound.internal.net.BasicSocketSession] - Dispatching response: S C[1].Z[1].sharedSource="ON"
15:26:06.290 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 83
15:26:06.291 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S
15:26:06.291 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 83: S
15:26:06.292 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.293 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 32
15:26:06.293 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S 
15:26:06.294 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> key char found:  
15:26:06.295 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 32: S 
15:26:06.295 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.296 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 67
15:26:06.296 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C
15:26:06.297 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 67: S C
15:26:06.297 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.297 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 91
15:26:06.298 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[
15:26:06.298 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 91: S C[
15:26:06.299 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.299 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 49
15:26:06.300 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1
15:26:06.300 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 49: S C[1
15:26:06.301 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.301 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 93
15:26:06.302 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1]
15:26:06.302 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 93: S C[1]
15:26:06.303 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.303 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 46
15:26:06.304 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].
15:26:06.304 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 46: S C[1].
15:26:06.305 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.305 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 90
15:26:06.306 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z
15:26:06.306 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 90: S C[1].Z
15:26:06.306 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.307 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 91
15:26:06.307 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[
15:26:06.308 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 91: S C[1].Z[
15:26:06.308 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.309 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 49
15:26:06.309 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1
15:26:06.310 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 49: S C[1].Z[1
15:26:06.310 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.310 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 93
15:26:06.311 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1]
15:26:06.312 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 93: S C[1].Z[1]
15:26:06.312 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.313 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 46
15:26:06.313 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].
15:26:06.314 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 46: S C[1].Z[1].
15:26:06.314 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.314 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 115
15:26:06.315 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].s
15:26:06.315 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 115: S C[1].Z[1].s
15:26:06.316 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.316 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 108
15:26:06.317 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sl
15:26:06.317 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 108: S C[1].Z[1].sl
15:26:06.318 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.318 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 101
15:26:06.319 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sle
15:26:06.319 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 101: S C[1].Z[1].sle
15:26:06.320 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.320 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 101
15:26:06.321 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].slee
15:26:06.322 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 101: S C[1].Z[1].slee
15:26:06.322 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.323 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 112
15:26:06.323 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sleep
15:26:06.324 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 112: S C[1].Z[1].sleep
15:26:06.325 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.327 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 84
15:26:06.328 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sleepT
15:26:06.329 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 84: S C[1].Z[1].sleepT
15:26:06.330 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.332 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 105
15:26:06.333 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sleepTi
15:26:06.334 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 105: S C[1].Z[1].sleepTi
15:26:06.335 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.336 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 109
15:26:06.337 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sleepTim
15:26:06.338 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 109: S C[1].Z[1].sleepTim
15:26:06.339 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.340 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 101
15:26:06.341 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sleepTime
15:26:06.343 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 101: S C[1].Z[1].sleepTime
15:26:06.344 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.345 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 82
15:26:06.346 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sleepTimeR
15:26:06.347 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 82: S C[1].Z[1].sleepTimeR
15:26:06.348 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.349 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 101
15:26:06.350 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sleepTimeRe
15:26:06.352 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 101: S C[1].Z[1].sleepTimeRe
15:26:06.352 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.354 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 109
15:26:06.355 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sleepTimeRem
15:26:06.355 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 109: S C[1].Z[1].sleepTimeRem
15:26:06.356 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.358 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 97
15:26:06.359 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sleepTimeRema
15:26:06.360 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 97: S C[1].Z[1].sleepTimeRema
15:26:06.361 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.362 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 105
15:26:06.363 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sleepTimeRemai
15:26:06.364 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 105: S C[1].Z[1].sleepTimeRemai
15:26:06.365 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.367 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 110
15:26:06.368 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sleepTimeRemain
15:26:06.369 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 110: S C[1].Z[1].sleepTimeRemain
15:26:06.370 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.371 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 105
15:26:06.376 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sleepTimeRemaini
15:26:06.377 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 105: S C[1].Z[1].sleepTimeRemaini
15:26:06.378 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.379 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 110
15:26:06.380 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sleepTimeRemainin
15:26:06.381 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 110: S C[1].Z[1].sleepTimeRemainin
15:26:06.382 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.383 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 103
15:26:06.384 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sleepTimeRemaining
15:26:06.385 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 103: S C[1].Z[1].sleepTimeRemaining
15:26:06.386 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.387 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 61
15:26:06.388 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sleepTimeRemaining=
15:26:06.389 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 61: S C[1].Z[1].sleepTimeRemaining=
15:26:06.389 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.390 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 34
15:26:06.391 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sleepTimeRemaining="
15:26:06.392 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 34: S C[1].Z[1].sleepTimeRemaining="
15:26:06.393 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.394 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 48
15:26:06.395 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sleepTimeRemaining="0
15:26:06.396 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 48: S C[1].Z[1].sleepTimeRemaining="0
15:26:06.397 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.398 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 34
15:26:06.399 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sleepTimeRemaining="0"
15:26:06.400 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 34: S C[1].Z[1].sleepTimeRemaining="0"
15:26:06.401 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.401 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 13
15:26:06.402 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sleepTimeRemaining="0"
15:26:06.403 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> reading: 13: S C[1].Z[1].sleepTimeRemaining="0"
15:26:06.404 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> waiting for read response
15:26:06.405 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> read response: 10
15:26:06.406 [DEBUG] [ound.internal.net.BasicSocketSession] - >>> added to response: S C[1].Z[1].sleepTimeRemaining="0"

tmrobert8 · December 30, 2016, 8:35pm

Thanks - that was actually a perfect grab. I’ve just tested it on my mca5s and they don’t have issues with the new code. I’ve posted a new zip that removes all those debug messages - feel free to grab it. Let me know if you find any issues with any of the other functionality.

Good luck!
Tim

tmrobert8 · December 30, 2016, 9:03pm

FYI - just posted another one that switches the socket implementation and fixes a reconnection bug when the network drops. Please download to test…

Tomtibo · December 30, 2016, 9:12pm

Thanks for your good work! I will intensively test it all later, but for now it’s working. But there is a single warm log when starting the binding, and two warm log when I stop the binding.

16:05:49.676 [DEBUG] [org.openhab.binding.russound        ] - ServiceEvent UNREGISTERING - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={component.name=binding.russound, component.id=200, service.id=331, service.bundleid=209, service.scope=bundle} - org.openhab.binding.russound
16:05:49.696 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'WATCH C[1].Z[1] OFF'
16:05:49.698 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'russound:zone:1' changed from ONLINE to UNINITIALIZED (HANDLER_MISSING_ERROR)
16:05:49.705 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'russound:controller:controller' changed from ONLINE to UNINITIALIZED (HANDLER_MISSING_ERROR)
16:05:49.723 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'russound:rio:home' changed from ONLINE to UNINITIALIZED (HANDLER_MISSING_ERROR)
16:05:49.723 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'WATCH SYSTEM OFF'
16:05:49.725 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'WATCH SYSTEM OFF'
16:05:49.727 [DEBUG] [nd.internal.net.SocketChannelSession] - Disconnecting from 192.168.11.74:9621
16:05:49.731 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching exception: java.io.IOException: server closed connection
16:05:54.732 [WARN ] [nd.internal.net.SocketChannelSession] - Waited too long for dispatcher to finish
16:05:54.738 [WARN ] [ome.core.thing.internal.ThingManager] - Disposing handler for thing 'russound:rio:home' takes more than 5000ms.
16:05:54.764 [DEBUG] [org.openhab.binding.russound        ] - BundleEvent STOPPING - org.openhab.binding.russound
16:05:54.777 [DEBUG] [org.openhab.binding.russound        ] - BundleEvent STOPPED - org.openhab.binding.russound
16:05:54.783 [DEBUG] [org.openhab.binding.russound        ] - BundleEvent STARTING - org.openhab.binding.russound
16:05:54.817 [DEBUG] [org.openhab.binding.russound        ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={component.name=binding.russound, component.id=201, service.id=332, service.bundleid=209, service.scope=bundle} - org.openhab.binding.russound
16:05:54.845 [DEBUG] [org.openhab.binding.russound        ] - BundleEvent STARTED - org.openhab.binding.russound
16:05:55.064 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'russound:rio:home' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
16:05:56.064 [DEBUG] [nd.internal.net.SocketChannelSession] - Connecting to 192.168.11.74:9621
16:05:56.066 [DEBUG] [nd.internal.net.SocketChannelSession] - Waiting for connect
16:05:56.319 [INFO ] [ussound.rio.system.RioSystemProtocol] - Russound System now connected
16:05:56.329 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'russound:rio:home' changed from INITIALIZING to ONLINE
16:05:56.329 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'WATCH SYSTEM ON'
16:05:56.343 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'GET System.status'
16:05:56.347 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'VERSION'
16:05:56.350 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'GET System.language'
16:05:56.357 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'GET System.language'
16:05:56.357 [INFO ] [russound.rio.system.RioSystemHandler] - Going online
16:05:56.372 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'GET C[1].type'
16:05:56.374 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'GET C[1].ipAddress'
16:05:56.375 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'GET C[1].macAddress'
16:05:56.380 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'russound:controller:controller' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
16:05:56.386 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S
16:05:56.390 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'russound:controller:controller' changed from INITIALIZING to ONLINE
16:05:56.393 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: N System.status="ON"
16:05:56.405 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'russound:zone:1' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
16:05:56.410 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'WATCH C[1].Z[1] ON'
16:05:56.412 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'GET C[1].Z[1].enabled'
16:05:56.415 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'russound:zone:1' changed from INITIALIZING to ONLINE
16:05:56.419 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'GET C[1].Z[1].name'
16:05:56.423 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'GET C[1].Z[1].currentSource'
16:05:56.425 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'GET C[1].Z[1].bass'
16:05:56.428 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'GET C[1].Z[1].treble'
16:05:56.430 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'GET C[1].Z[1].balance'
16:05:56.434 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S System.status="ON"
16:05:56.435 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'GET C[1].Z[1].loudness'
16:05:56.436 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S VERSION="01.09.00"
16:05:56.438 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S System.language="FRENCH"
16:05:56.453 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'GET C[1].Z[1].doNotDisturb'
16:05:56.455 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S System.language="FRENCH"
16:05:56.458 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'GET C[1].Z[1].partyMode'
16:05:56.461 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'GET C[1].Z[1].status'
16:05:56.462 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S C[1].type="MCA-C6"
16:05:56.464 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S C[1].ipAddress="192.168.11.74"
16:05:56.466 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'GET C[1].Z[1].volume'
16:05:56.469 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'GET C[1].Z[1].mute'
16:05:56.472 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'GET C[1].Z[1].page'
16:05:56.475 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'GET C[1].Z[1].sharedSource'
16:05:56.479 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'GET C[1].Z[1].sleepTimeRemaining'
16:05:56.482 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: 'GET C[1].Z[1].lastError'
16:05:56.697 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S C[1].macAddress="00:21:C7:00:79:7F"
16:05:56.706 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S
16:05:56.707 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: N C[1].Z[1].name="Salon"
16:05:56.722 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: N C[1].Z[1].treble="0"
16:05:56.738 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: N C[1].Z[1].balance="0"
16:05:56.754 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: N C[1].Z[1].loudness="OFF"
16:05:56.770 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: N C[1].Z[1].turnOnVolume="10"
16:05:56.785 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: N C[1].Z[1].doNotDisturb="OFF"
16:05:56.799 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: N C[1].Z[1].currentSource="5"
16:05:56.813 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: N C[1].Z[1].volume="12"
16:05:56.827 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: N C[1].Z[1].status="ON"
16:05:56.843 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: N C[1].Z[1].mute="OFF"
16:05:56.857 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: N C[1].Z[1].partyMode="MASTER"
16:05:56.873 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: N C[1].Z[1].bass="2"
16:05:56.887 [WARN ] [nd.internal.net.SocketChannelSession] - Exception occurred processing the response 'N C[1].Z[1].bass="2"': java.lang.IllegalArgumentException: Value must be between 0 and 100
16:05:56.888 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: N C[1].Z[1].page="OFF"
16:05:56.902 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: N C[1].Z[1].sharedSource="ON"
16:05:56.920 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: N C[1].Z[1].sleepTimeRemaining="0"
16:05:56.935 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: N C[1].Z[1].lastError=""
16:05:56.951 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: N S[5].name="TV"
16:05:56.957 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: N S[5].type="Television"
16:05:56.961 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S C[1].Z[1].enabled="TRUE"
16:05:56.976 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S C[1].Z[1].name="Salon"
16:05:56.978 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S C[1].Z[1].currentSource="5"
16:05:56.982 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S C[1].Z[1].bass="2"
16:05:56.986 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S C[1].Z[1].treble="0"
16:05:56.989 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S C[1].Z[1].balance="0"
16:05:56.992 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S C[1].Z[1].loudness="OFF"
16:05:56.995 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S C[1].Z[1].doNotDisturb="OFF"
16:05:56.998 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S C[1].Z[1].partyMode="MASTER"
16:05:57.001 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S C[1].Z[1].status="ON"
16:05:57.004 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S C[1].Z[1].volume="12"
16:05:57.007 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S C[1].Z[1].mute="OFF"
16:05:57.010 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S C[1].Z[1].page="OFF"
16:05:57.013 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S C[1].Z[1].sharedSource="ON"
16:05:57.016 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S C[1].Z[1].sleepTimeRemaining="0"
16:05:57.020 [DEBUG] [nd.internal.net.SocketChannelSession] - Dispatching response: S C[1].Z[1].lastError=""
16:06:26.358 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: ''
16:06:56.357 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: ''
16:07:26.357 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: ''
16:07:56.357 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: ''
16:08:26.357 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: ''
16:08:56.357 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: ''
16:09:26.357 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: ''
16:09:56.357 [DEBUG] [nd.internal.net.SocketChannelSession] - Sending Command: ''

tmrobert8 · January 3, 2017, 2:54am

Tom,

I’ve solved the issue with the disconnect warning message (I’ve known about that one since forever but it didn’t affect anything - so I never bothered to fix it). The next update will fix that.

The other one (bass=‘2’) has me a bit confused. By any chance do you have that channel linked up to a dimmer? If so, a dimmer wouldn’t work because it expect 0 to 100 and the bass (along with treble, etc) is ranged from -10 to 10

tmrobert8 · January 3, 2017, 3:21am

FYI - just posted a new zip with the latest changes - grab that one to avoid the warning message (and an issue with changing config)

tmrobert8 · January 4, 2017, 2:37pm

Posted a new zip file containing many changes from Kai’s reveiw of the addon. Please note that the channels for presets and favorites have changed in this version

tmrobert8 · January 5, 2017, 2:45am

Tom,

If you could download the latest (that’s really the RC candidate) and test with that - would be great. I’m having Kai hold off on merging it until you’ve had a chance to test…

Tim

tmrobert8 · January 5, 2017, 6:38pm

FYI - the merge was accepted and should be in the next version! Thanks for your help testing!

Tim

tmrobert8 · February 28, 2017, 5:59pm

Tom,

Hoping you see this message - I have a new version of the binding that will auto-discover the russound system and autodiscover the zones/sources/etc. I was hoping you’d be interested in testing - please let me know.

Thanks,
Tim

dyeper · February 28, 2017, 10:17pm

Tim - I have an MCA-C5, and I am extremely interested in getting some automation wrapped around it, especially with Alexa. I am brand new to Openhab and have not yet installed it.

This is great news that you have implemented auto discover. I cant wait to find some time to implement it.