Rhythmbox can't deal with UPnP media from Fritz!Box

[ullix]

Binary package hint: rhythmbox

On Ubuntu Lucid with the package rhythmbox-plugin-coherence installed: Rhythmbox encounters myriads of errors when reading media served by an AVM Fritz!Mediaserver. On the very same system, totem can find and play the media (both music and video) perfectly well.

The mediaserver is an internal part of the DSL router Fritz!Box 7270 from company AVM. It reads from an external USB-disk, set up with some 2000 music files and a dozen video files.

Upon starting totem it finds this mediaserver and offers the files almost instantaneously, but when starting rhythmbox from the command line, first this is shown in the terminal (note: there is an additional mythtv video server):

~$ rhythmbox
WARN coherence Nov 28 13:32:19 Coherence UPnP framework version 0.6.6.2 starting... (coherence/base.py:283)
WARN webserver Nov 28 13:32:19 WebServer on port 55235 ready (coherence/base.py:124)
WARN rb_coherence_plugin Nov 28 13:32:19 Media Store available with UUID uuid:63e3f640-b98c-4e9e-9746-3a646662cddf (coherence/__init__.py:124)
WARN rb_coherence_plugin Nov 28 13:32:19 Media Renderer available with UUID uuid:60942b56-b942-4d0c-a34b-82828e349e64 (coherence/__init__.py:158)
WARN rb_coherence_plugin Nov 28 13:32:19 start looking for media servers (coherence/__init__.py:161)
WARN rb_media_store Nov 28 13:32:20 __init__ MediaStore {'urlbase': 'http://10.0.0.20:55235/63e3f640-b98c-4e9e-9746-3a646662cddf', 'no_thread_needed': True, 'uuid': 'uuid:63e3f640-b98c-4e9e-9746-3a646662cddf', 'plugin': <CoherencePlugin object at 0x92d0964 (RBPlugin at 0x9065da8)>, 'db': <__main__.RhythmDBTree object at 0x9713414 (RhythmDBTree at 0x89b0040)>, 'version': 2, 'icon': {'url': 'file:///home/ullix/.face', 'mimetype': 'image/jpeg', 'depth': '24', 'height': '96', 'width': '96'}} (coherence/MediaStore.py:341)
WARN rb_media_renderer Nov 28 13:32:20 __init__ RhythmboxPlayer {'shell': <rb.Shell object at 0x8f32b44 (RBShell at 0x8929000)>, 'no_thread_needed': True, 'uuid': 'uuid:60942b56-b942-4d0c-a34b-82828e349e64', 'urlbase': 'http://10.0.0.20:55235/60942b56-b942-4d0c-a34b-82828e349e64', 'version': 2, 'rb_mediaserver': <coherence.upnp.devices.media_server.MediaServer object at 0x975b64c>, 'icon': {'url': 'file:///home/ullix/.face', 'mimetype': 'image/jpeg', 'depth': '24', 'height': '96', 'width': '96'}} (coherence/MediaPlayer.py:37)
/usr/lib/pymodules/python2.6/coherence/extern/louie.py:44: UserWarning: extern.louie will soon be deprecated in favor of coherence.dispatcher.
  warnings.warn("extern.louie will soon be deprecated in favor of coherence.dispatcher.")
WARN rb_media_store Nov 28 13:32:20 __init__ MediaStore initialized (coherence/MediaStore.py:431)
WARN mediaserver Nov 28 13:32:20 Rhythmbox on 10.0.0.20 MediaServer (<upnp_coherence.MediaStore.MediaStore object at 0x97635ac>) activated with 63e3f640-b98c-4e9e-9746-3a646662cddf (coherence/upnp/devices/media_server.py:636)
WARN mediarenderer Nov 28 13:32:20 Rhythmbox on 10.0.0.20 MediaRenderer (RhythmboxPlayer'>) activated with 60942b56-b942-4d0c-a34b-82828e349e64 (coherence/upnp/devices/media_renderer.py:142)

When I then click on the "AVM Fritz!Mediaserver" under Shared, after a delay of about 30 sec this output comes up, and these error reports seem to continue indefinitely:

Unhandled error in Deferred:
Traceback (most recent call last):
  File "/usr/lib/pymodules/python2.6/coherence/upnp/core/utils.py", line 507, in handleResponse
    self.factory.page(response)
  File "/usr/lib/pymodules/python2.6/coherence/upnp/core/utils.py", line 561, in page
    self.deferred.callback((page, self.response_headers))
  File "/usr/lib/python2.6/dist-packages/twisted/internet/defer.py", line 280, in callback
    self._startRunCallbacks(result)
  File "/usr/lib/python2.6/dist-packages/twisted/internet/defer.py", line 354, in _startRunCallbacks
    self._runCallbacks()
--- <exception caught here> ---
  File "/usr/lib/python2.6/dist-packages/twisted/internet/defer.py", line 371, in _runCallbacks
    self.result = callback(self.result, *args, **kw)
  File "/usr/lib/rhythmbox/plugins/upnp_coherence/UpnpSource.py", line 135, in new_process_media_server_browse
    seconds = int(h)*3600 + int(m)*60 + int(s)
exceptions.ValueError: invalid literal for int() with base 10: '00.000'
Unhandled error in Deferred:
Traceback (most recent call last):
  File "/usr/lib/pymodules/python2.6/coherence/upnp/core/utils.py", line 507, in handleResponse
    self.factory.page(response)
  File "/usr/lib/pymodules/python2.6/coherence/upnp/core/utils.py", line 561, in page
    self.deferred.callback((page, self.response_headers))
  File "/usr/lib/python2.6/dist-packages/twisted/internet/defer.py", line 280, in callback
    self._startRunCallbacks(result)
  File "/usr/lib/python2.6/dist-packages/twisted/internet/defer.py", line 354, in _startRunCallbacks
    self._runCallbacks()
--- <exception caught here> ---
  File "/usr/lib/python2.6/dist-packages/twisted/internet/defer.py", line 371, in _runCallbacks
    self.result = callback(self.result, *args, **kw)
  File "/usr/lib/rhythmbox/plugins/upnp_coherence/UpnpSource.py", line 135, in new_process_media_server_browse
    seconds = int(h)*3600 + int(m)*60 + int(s)
exceptions.ValueError: invalid literal for int() with base 10: '29.000'
....

[ullix (ullix)]

For completeness:
When instead of clicking on the "AVM Fritz!Mediaserver" as shown above I click on the "Mythtv AV Media Server", which serves a copy of the identical files as the Fritz-server (but from the internal hard disk), the files do show up after about 20 sec, with these messages in the terminal:

WARN soap Nov 28 13:50:00 error requesting url 'http://10.0.0.20:6544/CDS_Control' (coherence/upnp/core/soap_proxy.py:60)
WARN action Nov 28 13:50:00 error on Browse request with urn:schemas-upnp-org:service:ContentDirectory:1 CDS_Control (coherence/upnp/core/action.py:112)
WARN soap Nov 28 13:50:01 error requesting url 'http://10.0.0.20:6544/CDS_Control' (coherence/upnp/core/soap_proxy.py:60)
WARN action Nov 28 13:50:01 error on Browse request with urn:schemas-upnp-org:service:ContentDirectory:1 CDS_Control (coherence/upnp/core/action.py:112)
WARN soap Nov 28 13:50:02 error requesting url 'http://10.0.0.20:6544/CDS_Control' (coherence/upnp/core/soap_proxy.py:60)
WARN action Nov 28 13:50:02 error on Browse request with urn:schemas-upnp-org:service:ContentDirectory:1 CDS_Control (coherence/upnp/core/action.py:112)
WARN soap Nov 28 13:50:03 error requesting url 'http://10.0.0.20:6544/CDS_Control' (coherence/upnp/core/soap_proxy.py:60)
WARN action Nov 28 13:50:03 error on Browse request with urn:schemas-upnp-org:service:ContentDirectory:1 CDS_Control (coherence/upnp/core/action.py:112)
WARN soap Nov 28 13:50:03 error requesting url 'http://10.0.0.20:6544/CDS_Control' (coherence/upnp/core/soap_proxy.py:60)

[Pedro Villavicencio (pedro)]

Thanks for the report, it has been some time without any response or feedback in this bug report and we are wondering if this is still an issue for you with the latest release of Ubuntu the Natty Narwhal, May you please test with that version and comment back if you're still having or not the issue? Please have a look at http://www.ubuntu.com/download to know how to install that version. Thanks in advance and sorry for the late response.

[Pedro Villavicencio (pedro)]

We are closing this bug report because it lacks the information we need to investigate the problem, as described in the previous comments. Please reopen it if you can give us the missing information, and don't hesitate to submit bug reports in the future. To reopen the bug report you can click on the current status, under the Status column, and change the Status back to "New". Thanks again!