search

openhab / openhab-addons

Add-ons for openHAB
https://www.openhab.org/
Eclipse Public License 2.0
1.85k stars 3.55k forks source link

[upnpcontrol] Device goes offline with device not registered error #16638

Open mjcumming opened 2 months ago

mjcumming commented 2 months ago

I am openHAB 4.2.0-SNAPSHOT - Build #4031

When starting OpenHAB, the UPNP service connects to my UPNP player and works fine. After 20-30 minutes, the binding loses connection with the device with a "Device Not Registered" error. Trace level output is below. Output is from startup to connection lost. It looks like to me the discovery service (very.UpnpControlDiscoveryParticipant) is finding the device a second time which then cause the device to go offline with a device not registered error.

2024-04-11 05:31:00.285 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D' changed from UNINITIALIZED (NOT_YET_READY) to INITIALIZING 2024-04-11 05:31:00.299 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D' changed from INITIALIZING to UNKNOWN 2024-04-11 05:31:00.364 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D' changed from UNKNOWN to ONLINE 2024-04-11 05:31:00.561 [DEBUG] [ontrol.internal.util.UpnpControlUtil] - No .fav files in /var/lib/openhab/upnpcontrol/ 2024-04-11 05:31:00.562 [DEBUG] [pnpDynamicCommandDescriptionProvider] - Adding command description for channel upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D:favoriteselect 2024-04-11 05:31:00.563 [DEBUG] [pnpDynamicCommandDescriptionProvider] - Adding command description for channel upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D:playlistselect 2024-04-11 05:31:00.565 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:00.566 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:00.568 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen add upnp subscription on AVTransport 2024-04-11 05:31:00.585 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen add upnp subscription on RenderingControl 2024-04-11 05:31:00.602 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action GetMute on service RenderingControl reply {} 2024-04-11 05:31:00.603 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action GetVolume on service RenderingControl with inputs {InstanceID=0, Channel=Single} 2024-04-11 05:31:00.639 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action GetVolume on service RenderingControl reply {CurrentVolume=22} 2024-04-11 05:31:00.640 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable SingleVolume with value 22 from service RenderingControl 2024-04-11 05:31:00.642 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action GetTransportInfo on service AVTransport with inputs {InstanceID=0} 2024-04-11 05:31:00.677 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen received subscription reply true from service RenderingControl 2024-04-11 05:31:00.677 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action GetTransportInfo on service AVTransport reply {CurrentSpeed=1, CurrentTransportState=NO_MEDIA_PRESENT, CurrentTransportStatus=OK} 2024-04-11 05:31:00.678 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable CurrentSpeed with value 1 from service AVTransport 2024-04-11 05:31:00.679 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable CurrentTransportState with value NO_MEDIA_PRESENT from service AVTransport 2024-04-11 05:31:00.681 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable CurrentTransportStatus with value OK from service AVTransport 2024-04-11 05:31:00.686 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen received subscription reply true from service AVTransport 2024-04-11 05:31:00.707 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable LastChange with value <Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/"> 2024-04-11 05:31:00.713 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable LastChange with value <Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"> 2024-04-11 05:31:00.720 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable InstanceID with value 0 from service RenderingControl 2024-04-11 05:31:00.721 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable CurrentTrackURI with value from service AVTransport 2024-04-11 05:31:00.721 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable Channel with value 0 from service RenderingControl 2024-04-11 05:31:00.722 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable MasterMute with value 0 from service RenderingControl 2024-04-11 05:31:00.725 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable Equaluzer with value 0 from service RenderingControl 2024-04-11 05:31:00.723 [TRACE] [internal.handler.UpnpRendererHandler] - Renderer Arylic Kitchen received URI: 2024-04-11 05:31:00.727 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable PresetNameList with value FactoryDefaults from service RenderingControl 2024-04-11 05:31:00.728 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable MasterVolume with value 22 from service RenderingControl 2024-04-11 05:31:00.731 [TRACE] [internal.handler.UpnpRendererHandler] - Renderer Arylic Kitchen current URI: , equal to received URI true 2024-04-11 05:31:00.732 [TRACE] [internal.handler.UpnpRendererHandler] - Renderer Arylic Kitchen next URI: 2024-04-11 05:31:00.734 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable AbsoluteTimePosition with value NOT_IMPLEMENTED from service AVTransport 2024-04-11 05:31:00.735 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable TransportPlaySpeed with value 1 from service AVTransport 2024-04-11 05:31:00.741 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable AbsoluteCounterPosition with value -1 from service AVTransport 2024-04-11 05:31:00.741 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable CurrentTrackURI with value from service AVTransport 2024-04-11 05:31:00.743 [TRACE] [internal.handler.UpnpRendererHandler] - Renderer Arylic Kitchen received URI: 2024-04-11 05:31:00.751 [TRACE] [internal.handler.UpnpRendererHandler] - Renderer Arylic Kitchen current URI: , equal to received URI true 2024-04-11 05:31:00.754 [TRACE] [internal.handler.UpnpRendererHandler] - Renderer Arylic Kitchen next URI: 2024-04-11 05:31:00.755 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable InstanceID with value 0 from service AVTransport 2024-04-11 05:31:00.756 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable CurrentTrackMetaData with value from service AVTransport 2024-04-11 05:31:00.776 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable RelativeTimePosition with value 00:00:00 from service AVTransport 2024-04-11 05:31:00.779 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable RelativeCounterPosition with value -1 from service AVTransport 2024-04-11 05:31:00.781 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable TransportStatus with value OK from service AVTransport 2024-04-11 05:31:00.782 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable CurrentTrackDuration with value 00:00:00 from service AVTransport 2024-04-11 05:31:00.790 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable CurrentPlayMode with value NORMAL from service AVTransport 2024-04-11 05:31:00.790 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable PossiblePlaybackStorageMedia with value NONE,STATION-NETWORK,SONGLIST-NETWORK,SONGLIST-LOCAL,SONGLIST-LOCAL_TF,THIRD-DLNA,AIRPLAY,UNKNOWN from service AVTransport 2024-04-11 05:31:00.792 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable CurrentTrack with value 0 from service AVTransport 2024-04-11 05:31:00.793 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable CurrentRecordQualityMode with value NOT_IMPLEMENTED from service AVTransport 2024-04-11 05:31:00.794 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable RecordMediumWriteStatus with value NOT_IMPLEMENTED from service AVTransport 2024-04-11 05:31:00.795 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable PlaybackStorageMedium with value NONE from service AVTransport 2024-04-11 05:31:00.796 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable PossibleRecordQualityModes with value NOT_IMPLEMENTED from service AVTransport 2024-04-11 05:31:00.797 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable CurrentTransportActions with value Play,Stop,Seek,X_DLNA_SeekTime,Pause from service AVTransport 2024-04-11 05:31:00.798 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable NumberOfTracks with value 0 from service AVTransport 2024-04-11 05:31:00.799 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable PossibleRecordStorageMedia with value NOT_IMPLEMENTED from service AVTransport 2024-04-11 05:31:00.801 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable CurrentMediaDuration with value 00:00:00 from service AVTransport 2024-04-11 05:31:00.801 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable RecordStorageMedium with value NOT_IMPLEMENTED from service AVTransport 2024-04-11 05:31:00.803 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable TransportState with value NO_MEDIA_PRESENT from service AVTransport 2024-04-11 05:31:01.700 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:01.701 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:01.703 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:01.703 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:01.704 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:01.704 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:01.705 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:01.703 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:01.705 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:01.706 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:01.707 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:01.708 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:01.708 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:01.708 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:01.709 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:01.708 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:01.710 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:01.711 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:02.333 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:02.333 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:02.334 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:02.334 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:02.334 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:02.335 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:02.334 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:02.339 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:02.342 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:02.342 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:02.343 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:02.343 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:02.343 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:02.343 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:02.384 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:02.385 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:02.726 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:02.726 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:02.726 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:02.727 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:02.727 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:02.727 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:02.730 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:02.731 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:02.732 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:02.731 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:02.735 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:02.735 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:02.738 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:02.736 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:02.740 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:02.737 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlevolume already exists 2024-04-11 05:31:02.742 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:31:02.742 [TRACE] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen, channel Singlemute already exists 2024-04-11 05:32:42.945 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command 32 for channel upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D:volume on renderer Arylic Kitchen 2024-04-11 05:32:42.946 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action SetVolume on service RenderingControl with inputs {InstanceID=0, Channel=Master, DesiredVolume=32} 2024-04-11 05:32:42.967 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action SetVolume on service RenderingControl reply {} 2024-04-11 05:34:04.850 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command PLAY for channel upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D:control on renderer Arylic Kitchen 2024-04-11 05:34:04.851 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Play on service AVTransport with inputs {Speed=1, InstanceID=0} 2024-04-11 05:34:04.871 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Play on service AVTransport reply {} 2024-04-11 05:34:45.947 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command NEXT for channel upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D:control on renderer Arylic Kitchen 2024-04-11 05:34:45.948 [DEBUG] [internal.handler.UpnpRendererHandler] - Cannot serve next, end of queue on renderer Arylic Kitchen 2024-04-11 05:34:45.949 [TRACE] [internal.handler.UpnpRendererHandler] - Reset to start queue on renderer Arylic Kitchen 2024-04-11 05:34:45.949 [TRACE] [internal.handler.UpnpRendererHandler] - Reset queue, current queue index: -1 2024-04-11 05:34:45.949 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Stop on service AVTransport with inputs {InstanceID=0} 2024-04-11 05:34:45.973 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Stop on service AVTransport reply {} 2024-04-11 05:34:56.597 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command PREVIOUS for channel upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D:control on renderer Arylic Kitchen 2024-04-11 05:34:56.598 [DEBUG] [internal.handler.UpnpRendererHandler] - Cannot serve previous, already at start of queue on renderer Arylic Kitchen 2024-04-11 05:34:56.598 [TRACE] [internal.handler.UpnpRendererHandler] - Reset to start queue on renderer Arylic Kitchen 2024-04-11 05:34:56.598 [TRACE] [internal.handler.UpnpRendererHandler] - Reset queue, current queue index: -1 2024-04-11 05:34:56.598 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Stop on service AVTransport with inputs {InstanceID=0} 2024-04-11 05:34:56.617 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Stop on service AVTransport reply {} 2024-04-11 05:34:56.779 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command PREVIOUS for channel upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D:control on renderer Arylic Kitchen 2024-04-11 05:34:56.779 [DEBUG] [internal.handler.UpnpRendererHandler] - Cannot serve previous, already at start of queue on renderer Arylic Kitchen 2024-04-11 05:34:56.779 [TRACE] [internal.handler.UpnpRendererHandler] - Reset to start queue on renderer Arylic Kitchen 2024-04-11 05:34:56.780 [TRACE] [internal.handler.UpnpRendererHandler] - Reset queue, current queue index: -1 2024-04-11 05:34:56.780 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Stop on service AVTransport with inputs {InstanceID=0} 2024-04-11 05:34:56.798 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Stop on service AVTransport reply {} 2024-04-11 05:34:56.921 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command PREVIOUS for channel upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D:control on renderer Arylic Kitchen 2024-04-11 05:34:56.922 [DEBUG] [internal.handler.UpnpRendererHandler] - Cannot serve previous, already at start of queue on renderer Arylic Kitchen 2024-04-11 05:34:56.922 [TRACE] [internal.handler.UpnpRendererHandler] - Reset to start queue on renderer Arylic Kitchen 2024-04-11 05:34:56.923 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Stop on service AVTransport with inputs {InstanceID=0} 2024-04-11 05:34:56.925 [TRACE] [internal.handler.UpnpRendererHandler] - Reset queue, current queue index: -1 2024-04-11 05:34:56.940 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Stop on service AVTransport reply {} 2024-04-11 05:34:57.100 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command PREVIOUS for channel upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D:control on renderer Arylic Kitchen 2024-04-11 05:34:57.100 [DEBUG] [internal.handler.UpnpRendererHandler] - Cannot serve previous, already at start of queue on renderer Arylic Kitchen 2024-04-11 05:34:57.101 [TRACE] [internal.handler.UpnpRendererHandler] - Reset to start queue on renderer Arylic Kitchen 2024-04-11 05:34:57.101 [TRACE] [internal.handler.UpnpRendererHandler] - Reset queue, current queue index: -1 2024-04-11 05:34:57.101 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Stop on service AVTransport with inputs {InstanceID=0} 2024-04-11 05:34:57.117 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Stop on service AVTransport reply {} 2024-04-11 05:35:11.094 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command PAUSE for channel upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D:control on renderer Arylic Kitchen 2024-04-11 05:35:11.097 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Pause on service AVTransport with inputs {InstanceID=0} 2024-04-11 05:35:11.117 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Pause on service AVTransport reply {} 2024-04-11 05:36:05.869 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command ON for channel upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D:mute on renderer Arylic Kitchen 2024-04-11 05:36:05.870 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action SetMute on service RenderingControl with inputs {InstanceID=0, Channel=Master, DesiredMute=1} 2024-04-11 05:36:05.892 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action SetMute on service RenderingControl reply {} 2024-04-11 05:36:07.232 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command OFF for channel upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D:mute on renderer Arylic Kitchen 2024-04-11 05:36:07.234 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action SetMute on service RenderingControl with inputs {InstanceID=0, Channel=Master, DesiredMute=0} 2024-04-11 05:36:07.259 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action SetMute on service RenderingControl reply {} 2024-04-11 05:50:18.589 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command PAUSE for channel upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D:control on renderer Arylic Kitchen 2024-04-11 05:50:18.594 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Pause on service AVTransport with inputs {InstanceID=0} 2024-04-11 05:50:18.608 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command ON for channel upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D:stop on renderer Arylic Kitchen 2024-04-11 05:50:18.617 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command ON for channel upnpcontrol:upnprenderer:FF31F09E-253B-7EF5-AF4E-0004FF31F09E:stop on renderer Arylic Dock 2024-04-11 05:50:18.620 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Dock invoke upnp action Stop on service AVTransport with inputs {InstanceID=0} 2024-04-11 05:50:18.621 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Dock invoke upnp action Stop on service AVTransport reply {} 2024-04-11 05:50:18.631 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Pause on service AVTransport reply {} 2024-04-11 05:50:18.632 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Stop on service AVTransport with inputs {InstanceID=0} 2024-04-11 05:50:18.650 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command ON for channel upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D:stop on renderer Arylic Kitchen 2024-04-11 05:50:18.654 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Stop on service AVTransport reply {} 2024-04-11 05:50:18.654 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Stop on service AVTransport with inputs {InstanceID=0} 2024-04-11 05:50:18.657 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command ON for channel upnpcontrol:upnprenderer:FF31F09E-253B-7EF5-AF4E-0004FF31F09E:stop on renderer Arylic Dock 2024-04-11 05:50:18.660 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Dock invoke upnp action Stop on service AVTransport with inputs {InstanceID=0} 2024-04-11 05:50:18.661 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Dock invoke upnp action Stop on service AVTransport reply {} 2024-04-11 05:50:18.673 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Stop on service AVTransport reply {} 2024-04-11 05:50:20.759 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command PLAY for channel upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D:control on renderer Arylic Kitchen 2024-04-11 05:50:20.760 [TRACE] [internal.handler.UpnpRendererHandler] - Serve media on renderer Arylic Kitchen 2024-04-11 05:50:21.724 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command PAUSE for channel upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D:control on renderer Arylic Kitchen 2024-04-11 05:50:21.727 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Pause on service AVTransport with inputs {InstanceID=0} 2024-04-11 05:50:21.741 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command ON for channel upnpcontrol:upnprenderer:FF31F09E-253B-7EF5-AF4E-0004FF31F09E:stop on renderer Arylic Dock 2024-04-11 05:50:21.741 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command ON for channel upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D:stop on renderer Arylic Kitchen 2024-04-11 05:50:21.743 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Dock invoke upnp action Stop on service AVTransport with inputs {InstanceID=0} 2024-04-11 05:50:21.743 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Dock invoke upnp action Stop on service AVTransport reply {} 2024-04-11 05:50:21.747 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Pause on service AVTransport reply {} 2024-04-11 05:50:21.747 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Stop on service AVTransport with inputs {InstanceID=0} 2024-04-11 05:50:21.763 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Stop on service AVTransport reply {} 2024-04-11 05:50:41.858 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command PAUSE for channel upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D:control on renderer Arylic Kitchen 2024-04-11 05:50:41.861 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Pause on service AVTransport with inputs {InstanceID=0} 2024-04-11 05:50:41.875 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command ON for channel upnpcontrol:upnprenderer:FF31F09E-253B-7EF5-AF4E-0004FF31F09E:stop on renderer Arylic Dock 2024-04-11 05:50:41.877 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Dock invoke upnp action Stop on service AVTransport with inputs {InstanceID=0} 2024-04-11 05:50:41.878 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Dock invoke upnp action Stop on service AVTransport reply {} 2024-04-11 05:50:41.890 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command ON for channel upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D:stop on renderer Arylic Kitchen 2024-04-11 05:50:41.891 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Pause on service AVTransport reply {} 2024-04-11 05:50:41.892 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Stop on service AVTransport with inputs {InstanceID=0} 2024-04-11 05:50:41.920 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Stop on service AVTransport reply {} 2024-04-11 06:01:00.603 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen add upnp subscription on AVTransport 2024-04-11 06:01:00.608 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen add upnp subscription on RenderingControl 2024-04-11 06:01:00.633 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen received subscription reply true from service AVTransport 2024-04-11 06:01:00.649 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen received subscription reply true from service RenderingControl 2024-04-11 06:01:00.652 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable LastChange with value <Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/"> 2024-04-11 06:01:00.658 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable CurrentTrackURI with value from service AVTransport 2024-04-11 06:01:00.658 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable LastChange with value <Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"> 2024-04-11 06:01:00.661 [TRACE] [internal.handler.UpnpRendererHandler] - Renderer Arylic Kitchen received URI: 2024-04-11 06:01:00.663 [TRACE] [internal.handler.UpnpRendererHandler] - Renderer Arylic Kitchen current URI: , equal to received URI true 2024-04-11 06:01:00.663 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable InstanceID with value 0 from service RenderingControl 2024-04-11 06:01:00.664 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable Channel with value 0 from service RenderingControl 2024-04-11 06:01:00.664 [TRACE] [internal.handler.UpnpRendererHandler] - Renderer Arylic Kitchen next URI: 2024-04-11 06:01:00.665 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable MasterMute with value 0 from service RenderingControl 2024-04-11 06:01:00.665 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable AbsoluteTimePosition with value NOT_IMPLEMENTED from service AVTransport 2024-04-11 06:01:00.665 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable TransportPlaySpeed with value 1 from service AVTransport 2024-04-11 06:01:00.666 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable Equaluzer with value 0 from service RenderingControl 2024-04-11 06:01:00.667 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable PresetNameList with value FactoryDefaults from service RenderingControl 2024-04-11 06:01:00.667 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable MasterVolume with value 32 from service RenderingControl 2024-04-11 06:01:00.668 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable AbsoluteCounterPosition with value -1 from service AVTransport 2024-04-11 06:01:00.669 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable CurrentTrackURI with value from service AVTransport 2024-04-11 06:01:00.673 [TRACE] [internal.handler.UpnpRendererHandler] - Renderer Arylic Kitchen received URI: 2024-04-11 06:01:00.675 [TRACE] [internal.handler.UpnpRendererHandler] - Renderer Arylic Kitchen current URI: , equal to received URI true 2024-04-11 06:01:00.675 [TRACE] [internal.handler.UpnpRendererHandler] - Renderer Arylic Kitchen next URI: 2024-04-11 06:01:00.676 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable InstanceID with value 0 from service AVTransport 2024-04-11 06:01:00.677 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable CurrentTrackMetaData with value from service AVTransport 2024-04-11 06:01:00.687 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable RelativeTimePosition with value 00:00:00 from service AVTransport 2024-04-11 06:01:00.689 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable RelativeCounterPosition with value -1 from service AVTransport 2024-04-11 06:01:00.690 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable TransportStatus with value OK from service AVTransport 2024-04-11 06:01:00.690 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable CurrentTrackDuration with value 00:00:00 from service AVTransport 2024-04-11 06:01:00.693 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable CurrentPlayMode with value NORMAL from service AVTransport 2024-04-11 06:01:00.694 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable PossiblePlaybackStorageMedia with value NONE,STATION-NETWORK,SONGLIST-NETWORK,SONGLIST-LOCAL,SONGLIST-LOCAL_TF,THIRD-DLNA,AIRPLAY,UNKNOWN from service AVTransport 2024-04-11 06:01:00.695 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable CurrentTrack with value 0 from service AVTransport 2024-04-11 06:01:00.696 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable CurrentRecordQualityMode with value NOT_IMPLEMENTED from service AVTransport 2024-04-11 06:01:00.697 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable RecordMediumWriteStatus with value NOT_IMPLEMENTED from service AVTransport 2024-04-11 06:01:00.698 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable PlaybackStorageMedium with value NONE from service AVTransport 2024-04-11 06:01:00.699 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable PossibleRecordQualityModes with value NOT_IMPLEMENTED from service AVTransport 2024-04-11 06:01:00.700 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable CurrentTransportActions with value Play,Stop,Seek,X_DLNA_SeekTime,Pause from service AVTransport 2024-04-11 06:01:00.701 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable NumberOfTracks with value 0 from service AVTransport 2024-04-11 06:01:00.702 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable PossibleRecordStorageMedia with value NOT_IMPLEMENTED from service AVTransport 2024-04-11 06:01:00.703 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable CurrentMediaDuration with value 00:00:00 from service AVTransport 2024-04-11 06:01:00.703 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable RecordStorageMedium with value NOT_IMPLEMENTED from service AVTransport 2024-04-11 06:01:00.704 [TRACE] [internal.handler.UpnpRendererHandler] - UPnP device Arylic Kitchen received variable TransportState with value NO_MEDIA_PRESENT from service AVTransport 2024-04-11 06:01:00.725 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command ON for channel upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D:stop on renderer Arylic Kitchen 2024-04-11 06:01:00.727 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Stop on service AVTransport with inputs {InstanceID=0} 2024-04-11 06:01:00.741 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Stop on service AVTransport reply {} 2024-04-11 06:01:00.744 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command ON for channel upnpcontrol:upnprenderer:FF31F09E-253B-7EF5-AF4E-0004FF31F09E:stop on renderer Arylic Dock 2024-04-11 06:01:00.746 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Dock invoke upnp action Stop on service AVTransport with inputs {InstanceID=0} 2024-04-11 06:01:00.747 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Dock invoke upnp action Stop on service AVTransport reply {} 2024-04-11 06:01:00.758 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command ON for channel upnpcontrol:upnprenderer:FF31F09E-253B-7EF5-AF4E-0004FF31F09E:stop on renderer Arylic Dock 2024-04-11 06:01:00.758 [DEBUG] [internal.handler.UpnpRendererHandler] - Handle command ON for channel upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D:stop on renderer Arylic Kitchen 2024-04-11 06:01:00.759 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Dock invoke upnp action Stop on service AVTransport with inputs {InstanceID=0} 2024-04-11 06:01:00.759 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Dock invoke upnp action Stop on service AVTransport reply {} 2024-04-11 06:01:00.761 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Stop on service AVTransport with inputs {InstanceID=0} 2024-04-11 06:01:00.776 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen invoke upnp action Stop on service AVTransport reply {} 2024-04-11 06:01:03.479 [DEBUG] [very.UpnpControlDiscoveryParticipant] - Device type MediaRenderer, manufacturer Rakoit Technology(SZ) Co., Ltd., model H50, SN# 00001, UDN FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D 2024-04-11 06:01:03.480 [DEBUG] [very.UpnpControlDiscoveryParticipant] - Media renderer found: Rakoit Technology(SZ) Co., Ltd., H50 2024-04-11 06:01:03.482 [DEBUG] [control.internal.handler.UpnpHandler] - UPnP device Arylic Kitchen received status update false 2024-04-11 06:01:03.484 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Communication lost with Arylic Kitchen 2024-04-11 06:02:00.612 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D' changed from OFFLINE (COMMUNICATION_ERROR): Communication lost with Arylic Kitchen to OFFLINE (COMMUNICATION_ERROR): UPnP device with UDN FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D not yet registered

mjcumming commented 2 months ago

From my review of the code, we see a new device arrive via the output from UpnpControlDiscoveryParticipant.java and then a communication lost output from UpnpHandler.java at line 414. I am not a Java developer, so that's my best understanding of what is happening. Is it possible that the binding is trying to add a new thing when the thing already exists and that is what is causing the error?

mjcumming commented 2 months ago

Here is the output after disabling and enabling the thing. If I restart openhab the device will come back online for awhile before seeing the error above.

2024-04-12 20:02:10.665 [DEBUG] [internal.handler.UpnpRendererHandler] - Disposing handler for media renderer device Arylic Kitchen ==> /var/log/openhab/events.log <== 2024-04-12 20:02:10.657 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D' changed from OFFLINE (COMMUNICATION_ERROR): UPnP device with UDN FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D not yet registered to UNINITIALIZED 2024-04-12 20:02:10.668 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'ArylicKitchenTrackposition' updated to 0 s 2024-04-12 20:02:10.669 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'ArylicKitchenReltrackposition' updated to 0 2024-04-12 20:02:10.670 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'ArylicKitchenControl' updated to PLAY 2024-04-12 20:02:10.670 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ArylicKitchenControl' changed from PAUSE to PLAY 2024-04-12 20:02:10.674 [INFO ] [openhab.event.ItemCommandEvent ] - Item 'All_Music' received command ON 2024-04-12 20:02:10.675 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D' changed from UNINITIALIZED to UNINITIALIZED (DISABLED) 2024-04-12 20:02:11.534 [DEBUG] [l.internal.UpnpControlHandlerFactory] - Media renderer handler created for Arylic Kitchen with UID upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D 2024-04-12 20:02:11.547 [DEBUG] [ontrol.internal.util.UpnpControlUtil] - No .lst files in /var/lib/openhab/upnpcontrol/ 2024-04-12 20:02:11.548 [DEBUG] [pnpDynamicCommandDescriptionProvider] - Adding command description for channel upnpcontrol:upnprenderer:FF31F09E-7AAF-270E-A4C0-10F6FF31F09E:playlistselect 2024-04-12 20:02:11.548 [DEBUG] [pnpDynamicCommandDescriptionProvider] - Adding command description for channel upnpcontrol:upnprenderer:FF31F09E-253B-7EF5-AF4E-0004FF31F09E:playlistselect 2024-04-12 20:02:11.549 [DEBUG] [internal.handler.UpnpRendererHandler] - Initializing handler for media renderer device Arylic Kitchen ==> /var/log/openhab/events.log <== 2024-04-12 20:02:11.540 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D' changed from UNINITIALIZED (DISABLED) to INITIALIZING 2024-04-12 20:02:11.550 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D' changed from INITIALIZING to UNKNOWN 2024-04-12 20:02:11.550 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'upnpcontrol:upnprenderer:FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D' changed from UNKNOWN to OFFLINE (COMMUNICATION_ERROR): UPnP device with UDN FF97F35D-4C0A-1EB1-DA5E-0714FF97F35D not yet