eclipse-archived / smarthome

Eclipse SmartHome™ project
https://www.eclipse.org/smarthome/
Eclipse Public License 2.0
862 stars 787 forks source link

[Sonos] Too much time to get a thing ONLINE when device is powered ON #6779

Open lolodomo opened 5 years ago

lolodomo commented 5 years ago

When a Sonos device is powered ON, it first comes ONLINE and then immediately OFFLINE. Several minutes later, it becomes ONLINE definitively.

This is the result of an error in the jUPnP library when the action GetZoneInfo is invoiked. The library returns the following error: Current state of service prevents invoking that action

Here are the logs:

22:05:30.807 [DEBUG] [covery.ZonePlayerDiscoveryParticipant] - Discovered a Sonos 'sonos:PLAY5' thing with UDN 'RINCON_000E588EAE6601400'
22:05:30.813 [DEBUG] [sport.upnp.internal.UpnpIOServiceImpl] - Device 'RINCON_000E588EAE6601400' reachability status changed to 'true'
22:05:30.831 [DEBUG] [os.internal.handler.ZonePlayerHandler] - Polling job
22:05:30.837 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'sonos:PLAY5:chambre' changed from OFFLINE (COMMUNICATION_ERROR): Le périphérique UPnP RINCON_000E588EAE6601400 n'est pas encore découvert. to ONLINE
22:05:30.855 [TRACE] [sport.upnp.internal.UpnpIOServiceImpl] - Invoking Action 'GetZoneInfo' of service 'DeviceProperties' for participant 'RINCON_000E588EAE6601400'
22:05:30.871 [DEBUG] [sport.upnp.internal.UpnpIOServiceImpl] - Current state of service prevents invoking that action. Connection error or no response received.
22:05:30.878 [DEBUG] [covery.ZonePlayerDiscoveryParticipant] - Created a DiscoveryResult for device '192.168.x.xx - Sonos Play:5' with UDN 'RINCON_000E588EAE6601400'
22:05:30.886 [DEBUG] [os.internal.handler.ZonePlayerHandler] - Sonos player RINCON_000E588EAE6601400 is not available in local network

Forum discussion: https://community.openhab.org/t/sonos-binding-offline-in-2-4-0-stable/62214