Mopidy.core.playback: StreamBackend needs to be updated to work with this version of Mopidy

General
1

Hi there,

I’m using mopidy as a UPnP renderer (via upmpdcli) for music served by mediatomb on the same machine, which is a RasPi B. This used to work fine until I recentiy did an apt-get upgrade (hoping this would resolve some issues with my WiFI dongle always falling asleep). When I now try to tell mopidy to play some music (using BubbleUPnP on my tablet), nothing happens, and the log says:

2015-09-03 14:27:41,409 WARNING [2541:StreamBackend-3] mopidy.stream.actor: Problem looking up http://192.168.0.19:50500/content/media/object_id/90562/res_id/0/ext/file.mp3: Timeout after 5000ms
2015-09-03 14:27:41,548 INFO [2541:MpdSession-12] mopidy.mpd.session: New MPD connection from [::1]:47383
2015-09-03 14:27:42,224 INFO [2541:HttpServer] root: 304 GET /musicbox_webclient/images/icons/pause_32x32.png (192.168.0.7) 92.63ms
2015-09-03 14:27:42,743 ERROR [2541:Core-7] mopidy.core.playback: StreamBackend needs to be updated to work with this version of Mopidy.
2015-09-03 14:27:42,752 WARNING [2541:Core-7] mopidy.core.tracklist: Track is not playable: http://192.168.0.19:50500/content/media/object_id/90562/res_id/0/ext/file.mp3

mediatomb is probably not the cause of the problem; when using the tablet’s local UPnP renderer, everything is fine. So I guess it mus have to do something with incompatible/not completely updated packages in the apt repository?

Or am I missing something else?

Thanx for any advice –

– tcrass

2

Mopidy-Stream is bundled with Mopidy, so a version mismatch should be impossible.

What version of Mopidy are you running? mopidy --version

Can you reproduce the issue with verbose logging on? mopidy -v

3

The check in https://github.com/mopidy/mopidy/blob/develop/mopidy/core/playback.py#L350 is a bit crude, so this could also be some other type error than the one we intend to catch. So re-running with verbose logging would help.

We might also consider using the inspect module to check for the error this is trying to catch, but only in the exception path. And if it isn’t what we are looking for we just re-raise.

4

Hi there,

mopidy --version says “Mopidy 1.1.0” (actually 1.1.0-2, according to apt), and mopidy -v yields the following output when I attempt to play something via UPnP:

DEBUG    2015-09-03 19:59:18,779 [7729:MpdSession-10] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:34026: status                                     
DEBUG    2015-09-03 19:59:18,871 [7729:MpdSession-10] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:34026:                                             
    volume: 100                                                                     
    repeat: 0                                                                       
    random: 0                                                                       
    single: 0                                                                       
    consume: 0                                                                      
    playlist: 1                                                                     
    playlistlength: 0                                                               
    xfade: 0                                                                        
    state: stop                                                                     
    OK                                                                              
DEBUG    2015-09-03 19:59:18,893 [7729:MpdSession-10] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:34026: playlistinfo                               
DEBUG    2015-09-03 19:59:18,913 [7729:MpdSession-10] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:34026: OK                                          
DEBUG    2015-09-03 19:59:18,935 [7729:MpdSession-10] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:34026: addid "http://192.168.0.19:50500/content/media/object_id/90575/res_id/0/ext/file.mp3" "0"                                      

** (mopidy:7729): WARNING **: Cannot autolaunch D-Bus without X11 $DISPLAY

** (mopidy:7729): WARNING **: Cannot autolaunch D-Bus without X11 $DISPLAY

** (mopidy:7729): WARNING **: Cannot autolaunch D-Bus without X11 $DISPLAY

** (mopidy:7729): WARNING **: Cannot autolaunch D-Bus without X11 $DISPLAY

** (mopidy:7729): WARNING **: Cannot autolaunch D-Bus without X11 $DISPLAY

** (mopidy:7729): WARNING **: Cannot autolaunch D-Bus without X11 $DISPLAY

** (mopidy:7729): WARNING **: Cannot autolaunch D-Bus without X11 $DISPLAY

** (mopidy:7729): WARNING **: Cannot autolaunch D-Bus without X11 $DISPLAY

** (mopidy:7729): WARNING **: Cannot autolaunch D-Bus without X11 $DISPLAY

** (mopidy:7729): WARNING **: Cannot autolaunch D-Bus without X11 $DISPLAY
WARNING  2015-09-03 19:59:21,794 [7729:StreamBackend-3] mopidy.stream.actor
  Problem looking up http://192.168.0.19:50500/content/media/object_id/90575/res_id/0/ext/file.mp3: Timeout after 5000ms                                                
DEBUG    2015-09-03 19:59:21,814 [7729:Core-6] mopidy.core.tracklist
  Triggering event: tracklist_changed()                                             
DEBUG    2015-09-03 19:59:21,822 [7729:MainThread] mopidy.listener
  Sending tracklist_changed to CoreListener: {}                                     
DEBUG    2015-09-03 19:59:21,837 [7729:MpdSession-10] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:34026:                                             
    Id: 0                                                                           
    OK                                                                              
DEBUG    2015-09-03 19:59:21,895 [7729:MpdSession-10] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:34026: addtagid "0" "Artist" "Jean-Michel Jarre"  
DEBUG    2015-09-03 19:59:21,905 [7729:MpdSession-10] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:34026: ACK [0@0] {addtagid} Not implemented        
DEBUG    2015-09-03 19:59:21,955 [7729:MpdSession-10] mopidy.internal.network
  Client most likely disconnected.                                                  
INFO     2015-09-03 19:59:21,959 [7729:MpdSession-11] mopidy.mpd.session
  New MPD connection from [::ffff:127.0.0.1]:34042                                  
DEBUG    2015-09-03 19:59:21,990 [7729:MpdSession-10] mopidy.internal.network
  Already stopping: Actor is shutting down.                                         
DEBUG    2015-09-03 19:59:22,005 [7729:MpdSession-11] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:34042: status                                     
DEBUG    2015-09-03 19:59:22,107 [7729:MpdSession-11] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:34042:                                             
    volume: 100                                                                     
    repeat: 0                                                                       
    random: 0                                                                       
    single: 0                                                                       
    consume: 0                                                                      
    playlist: 2                                                                     
    playlistlength: 1                                                               
    xfade: 0                                                                        
    state: stop                                                                     
    OK                                                                              
DEBUG    2015-09-03 19:59:22,136 [7729:MpdSession-11] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:34042: status                                     
DEBUG    2015-09-03 19:59:22,220 [7729:MpdSession-11] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:34042:                                             
    volume: 100                                                                     
    repeat: 0                                                                       
    random: 0                                                                       
    single: 0                                                                       
    consume: 0                                                                      
    playlist: 2                                                                     
    playlistlength: 1                                                               
    xfade: 0                                                                        
    state: stop                                                                     
    OK                                                                              
DEBUG    2015-09-03 19:59:22,251 [7729:MpdSession-11] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:34042: status                                     
DEBUG    2015-09-03 19:59:22,330 [7729:MpdSession-11] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:34042:                                             
    volume: 100                                                                     
    repeat: 0                                                                       
    random: 0                                                                       
    single: 0                                                                       
    consume: 0                                                                      
    playlist: 2                                                                     
    playlistlength: 1                                                               
    xfade: 0                                                                        
    state: stop                                                                     
    OK                                                                              
DEBUG    2015-09-03 19:59:22,352 [7729:MpdSession-11] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:34042: playlistinfo                               
DEBUG    2015-09-03 19:59:22,388 [7729:MpdSession-11] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:34042:                                             
    file: http://192.168.0.19:50500/content/media/object_id/90575/res_id/0/ext/file.mp3                                                                                 
    Time: 0                                                                         
    Pos: 0                                                                          
    Id: 0                                                                           
    OK                                                                              
DEBUG    2015-09-03 19:59:22,405 [7729:MpdSession-11] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:34042: addid "http://192.168.0.19:50500/content/media/object_id/90562/res_id/0/ext/file.mp3" "0"                                      

** (mopidy:7729): WARNING **: Cannot autolaunch D-Bus without X11 $DISPLAY

** (mopidy:7729): WARNING **: Cannot autolaunch D-Bus without X11 $DISPLAY

** (mopidy:7729): WARNING **: Cannot autolaunch D-Bus without X11 $DISPLAY

** (mopidy:7729): WARNING **: Cannot autolaunch D-Bus without X11 $DISPLAY

** (mopidy:7729): WARNING **: Cannot autolaunch D-Bus without X11 $DISPLAY
DEBUG    2015-09-03 19:59:22,942 [7729:Core-6] mopidy.core.tracklist
  Triggering event: tracklist_changed()                                             
DEBUG    2015-09-03 19:59:22,954 [7729:MainThread] mopidy.listener
  Sending tracklist_changed to CoreListener: {}                                     
DEBUG    2015-09-03 19:59:22,960 [7729:MpdSession-11] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:34042:                                             
    Id: 1                                                                           
    OK                                                                              
DEBUG    2015-09-03 19:59:23,009 [7729:MpdSession-11] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:34042: addtagid "1" "Artist" "Jean-Michel Jarre"  
DEBUG    2015-09-03 19:59:23,027 [7729:MpdSession-11] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:34042: ACK [0@0] {addtagid} Not implemented        
DEBUG    2015-09-03 19:59:23,066 [7729:MpdSession-11] mopidy.internal.network
  Client most likely disconnected.                                                  
INFO     2015-09-03 19:59:23,079 [7729:MpdSession-12] mopidy.mpd.session
  New MPD connection from [::ffff:127.0.0.1]:34045                                  
DEBUG    2015-09-03 19:59:23,116 [7729:MpdSession-12] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:34045: status                                     
DEBUG    2015-09-03 19:59:23,099 [7729:MpdSession-11] mopidy.internal.network
  Already stopping: Actor is shutting down.                                         
DEBUG    2015-09-03 19:59:23,214 [7729:MpdSession-12] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:34045:                                             
    volume: 100                                                                     
    repeat: 0                                                                       
    random: 0                                                                       
    single: 0                                                                       
    consume: 0                                                                      
    playlist: 3                                                                     
    playlistlength: 2                                                               
    xfade: 0                                                                        
    state: stop                                                                     
    OK                                                                              
DEBUG    2015-09-03 19:59:23,238 [7729:MpdSession-12] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:34045: status                                     
DEBUG    2015-09-03 19:59:23,333 [7729:MpdSession-12] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:34045:                                             
    volume: 100                                                                     
    repeat: 0                                                                       
    random: 0                                                                       
    single: 0                                                                       
    consume: 0                                                                      
    playlist: 3                                                                     
    playlistlength: 2                                                               
    xfade: 0                                                                        
    state: stop                                                                     
    OK                                                                              
DEBUG    2015-09-03 19:59:23,358 [7729:MpdSession-12] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:34045: status                                     
DEBUG    2015-09-03 19:59:23,459 [7729:MpdSession-12] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:34045:                                             
    volume: 100                                                                     
    repeat: 0                                                                       
    random: 0                                                                       
    single: 0                                                                       
    consume: 0                                                                      
    playlist: 3                                                                     
    playlistlength: 2                                                               
    xfade: 0                                                                        
    state: stop                                                                     
    OK                                                                              
DEBUG    2015-09-03 19:59:23,485 [7729:MpdSession-12] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:34045: playlistinfo                               
DEBUG    2015-09-03 19:59:23,511 [7729:MpdSession-12] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:34045:                                             
    file: http://192.168.0.19:50500/content/media/object_id/90562/res_id/0/ext/file.mp3                                                                                 
    Time: 254                                                                       
    Artist: Jean-Michel Jarre                                                       
    Album: Oxygene                                                                  
    Title: Oxygene Part 4                                                           
    Date: 1976-01-01                                                                
    Track: 4                                                                        
    Pos: 0                                                                          
    Id: 1                                                                           
    AlbumArtist: Jean-Michel Jarre                                                  
    Genre: Elektronik & Ambient                                                     
    file: http://192.168.0.19:50500/content/media/object_id/90575/res_id/0/ext/file.mp3                                                                                 
    Time: 0                                                                         
    Pos: 1                                                                          
    Id: 0                                                                           
    OK                                                                              
DEBUG    2015-09-03 19:59:23,528 [7729:MpdSession-12] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:34045: playid "0"                                 
DEBUG    2015-09-03 19:59:23,561 [7729:Core-6] mopidy.core.playback
  Changing state: stopped -> playing                                                
DEBUG    2015-09-03 19:59:23,577 [7729:Core-6] mopidy.core.playback
  Triggering playback state change event                                            
DEBUG    2015-09-03 19:59:23,593 [7729:MainThread] mopidy.listener
  Sending playback_state_changed to CoreListener: {'old_state': u'stopped', 'new_state': u'playing'}                                                                    
DEBUG    2015-09-03 19:59:23,720 [7729:Audio-2] mopidy.audio.gst
  State change to GST_STATE_READY: result=GST_STATE_CHANGE_SUCCESS                  
DEBUG    2015-09-03 19:59:23,733 [7729:MainThread] mopidy.audio.gst
  Got state-changed message: old=GST_STATE_NULL new=GST_STATE_READY pending=GST_STATE_VOID_PENDING                                                                      

** (mopidy:7729): WARNING **: Cannot autolaunch D-Bus without X11 $DISPLAY

** (mopidy:7729): WARNING **: Cannot autolaunch D-Bus without X11 $DISPLAY

** (mopidy:7729): WARNING **: Cannot autolaunch D-Bus without X11 $DISPLAY

** (mopidy:7729): WARNING **: Cannot autolaunch D-Bus without X11 $DISPLAY

** (mopidy:7729): WARNING **: Cannot autolaunch D-Bus without X11 $DISPLAY
ERROR    2015-09-03 19:59:24,228 [7729:Core-6] mopidy.core.playback
  StreamBackend needs to be updated to work with this version of Mopidy.            
DEBUG    2015-09-03 19:59:24,240 [7729:Core-6] mopidy.core.playback
  Backend exception                                                                 
Traceback (most recent call last):                                                  
  File "/usr/lib/python2.7/dist-packages/mopidy/core/playback.py", line 348, in _play                                                                                   
    backend.playback.change_track(tl_track.track).get() and                         
  File "/usr/lib/python2.7/dist-packages/pykka/threading.py", line 52, in get       
    compat.reraise(*self._data['exc_info'])                                         
  File "/usr/lib/python2.7/dist-packages/pykka/compat.py", line 12, in reraise      
    exec('raise tp, value, tb')                                                     
  File "/usr/lib/python2.7/dist-packages/pykka/actor.py", line 201, in _actor_loop  
    response = self._handle_receive(message)                                        
  File "/usr/lib/python2.7/dist-packages/pykka/actor.py", line 295, in _handle_receive                                                                                  
    return callee(*message['args'], **message['kwargs'])                            
  File "/usr/lib/python2.7/dist-packages/mopidy/backend.py", line 245, in change_track                                                                                  
    uri = self.translate_uri(track.uri)                                             
  File "/usr/lib/python2.7/dist-packages/mopidy/stream/actor.py", line 86, in translate_uri                                                                             
    content = self._download(uri)                                                   
  File "/usr/lib/python2.7/dist-packages/mopidy/stream/actor.py", line 105, in _download                                                                                
    uri, stream=True, timeout=timeout)                                              
  File "/usr/lib/python2.7/dist-packages/requests/sessions.py", line 249, in get    
    return self.request('get', url, **kwargs)                                       
TypeError: request() got an unexpected keyword argument 'stream'                    
WARNING  2015-09-03 19:59:24,290 [7729:Core-6] mopidy.core.tracklist
  Track is not playable: http://192.168.0.19:50500/content/media/object_id/90575/res_id/0/ext/file.mp3                                                                  
DEBUG    2015-09-03 19:59:24,315 [7729:Audio-2] mopidy.audio.actor
  Position query failed                                                             
DEBUG    2015-09-03 19:59:24,348 [7729:Audio-2] mopidy.audio.gst
  State change to GST_STATE_NULL: result=GST_STATE_CHANGE_SUCCESS                   
DEBUG    2015-09-03 19:59:24,364 [7729:Core-6] mopidy.core.playback
  Changing state: playing -> stopped                                                
DEBUG    2015-09-03 19:59:24,372 [7729:Core-6] mopidy.core.playback
  Triggering playback state change event                                            
DEBUG    2015-09-03 19:59:24,391 [7729:MainThread] mopidy.listener
  Sending playback_state_changed to CoreListener: {'old_state': u'playing', 'new_state': u'stopped'}                                                                    
DEBUG    2015-09-03 19:59:24,422 [7729:Core-6] mopidy.core.playback
  Triggering track playback ended event                                             
DEBUG    2015-09-03 19:59:24,445 [7729:MainThread] mopidy.listener
  Sending track_playback_ended to CoreListener: {'time_position': 0, 'tl_track': TlTrack(tlid=0, track=Track(uri='http://192.168.0.19:50500/content/media/object_id/90575/res_id/0/ext/file.mp3'))}                                                         
DEBUG    2015-09-03 19:59:24,451 [7729:MpdSession-12] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:34045: OK                                          
DEBUG    2015-09-03 19:59:24,482 [7729:MpdSession-12] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:34045: status                                     
DEBUG    2015-09-03 19:59:24,601 [7729:MpdSession-12] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:34045:                                             
    volume: 100                                                                     
    repeat: 0                                                                       
    random: 0                                                                       
    single: 0                                                                       
    consume: 0                                                                      
    playlist: 3                                                                     
    playlistlength: 2                                                               
    xfade: 0                                                                        
    state: stop                                                                     
    OK

So the error is still there. I hope the stack trace right underneath may contain some clues about what’s going on?

(Side note: I used to have some mopidy remnants from a previous pip installation hanging around, but I’m actually pretty sure that meanwhile I’ve removed them properly.)

Well, bedtime over here on this side of the world. Good night, and thanks for caring –

– tcrass

5

It seems like requests doesn’t handle the ‘stream=True’ argument.

What version of requests do you have? You can check with ‘mopidy deps’. Paste all of the output if you want to.

6

Good morning,

mopidy deps says:

Executable: /usr/bin/mopidy
Platform: Linux-4.1.6+-armv6l-with-debian-7.8
Python: CPython 2.7.3 from /usr/lib/python2.7
Mopidy: 1.1.0 from /usr/lib/python2.7/dist-packages
  Pykka>=1.1: 1.2.1 from /usr/lib/python2.7/dist-packages
  requests: 0.12.1 from /usr/lib/python2.7/dist-packages
  tornado>=2.3: 2.3 from /usr/lib/python2.7/dist-packages
Mopidy-MusicBox-Webclient: 2.0.0 from /usr/local/lib/python2.7/dist-packages
  setuptools: 0.6c11 from /usr/lib/python2.7/dist-packages
  Mopidy>=0.19: 1.1.0 from /usr/lib/python2.7/dist-packages
    Pykka>=1.1: 1.2.1 from /usr/lib/python2.7/dist-packages
    requests: 0.12.1 from /usr/lib/python2.7/dist-packages
    tornado>=2.3: 2.3 from /usr/lib/python2.7/dist-packages
GStreamer: 0.10.36.0 from /usr/lib/python2.7/dist-packages/gst-0.10/gst
  Detailed information: 
    Python wrapper: gst-python 0.10.22.0
    Relevant elements:
      Found:
        uridecodebin
        souphttpsrc
        appsrc
        alsasink
        osssink
        oss4sink
        pulsesink
        id3demux
        id3v2mux
        lame
        mad
        mp3parse
        vorbisdec
        vorbisenc
        vorbisparse
        oggdemux
        oggmux
        oggparse
        flacdec
        flacparse
        shout2send
      Not found:
        flump3dec

Hope that helps?

Best regards –

– tcrass

7

It seems you have a way to old python-requests package. 0.12.1 is the version shipped with Debian wheezy. Debian backports have a 2.0.0 version for wheezy, which we also ship at apt.mopidy.com.

I think the problem here is that you’re running Debian wheezy, but use the stable/jessie dist from apt.mopidy.com. You should switch to using our oldstable/wheezy dist, which includes the requests backport (and also other niceties, like a Mopidy-Spotify that works on wheezy).

To get the packages for wheezy instead, you just need to:

  1. Edit /etc/apt/sources.list.d/mopidy.list and replace all occurrences of “stable” with “wheezy”:
    sudo sed -i -e 's/stable/wheezy/' /etc/apt/sources.list.d/mopidy.list
  2. Run sudo apt-get update
  3. Run sudo apt-get upgrade
8

[Edit 2015-09-11: I upgraded to jessie, of course, not to wheezy…]

So,

I upgraded the whole system to wheezy. Took me like 36 hours, though, to figure out why mediatomb wouldn’t show up any more on my network – apparently there’s now an /etc/default/mediatomb file in which one must define the network interface mediatomb is to bind to…

Anyway, now I get two different kinds of messages when running mopidy in verbose mode.

First kind of message:

DEBUG    2015-09-06 18:40:23,574 [23631:MpdSession-11] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:47747: status                                                 
DEBUG    2015-09-06 18:40:23,617 [23631:MpdSession-11] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:47747:                                                         
    volume: 100                                                                                 
    repeat: 0                                                                                   
    random: 0                                                                                   
    single: 0                                                                                   
    consume: 0                                                                                  
    playlist: 2                                                                                 
    playlistlength: 1                                                                           
    xfade: 0                                                                                    
    state: stop                                                                                 
    OK                                                                                          
DEBUG    2015-09-06 18:40:23,625 [23631:MpdSession-11] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:47747: playlistinfo                                           
DEBUG    2015-09-06 18:40:23,638 [23631:MpdSession-11] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:47747:                                                         
    file: http://192.168.0.19:50500/content/media/object_id/96808/res_id/0/ext/file.mp3         
    Time: 254                                                                                   
    Artist: Jean-Michel Jarre                                                                   
    Album: Oxygene                                                                              
    Title: Oxygene Part 4                                                                       
    Date: 1976-01-01                                                                            
    Track: 4                                                                                    
    Pos: 0                                                                                      
    Id: 0                                                                                       
    AlbumArtist: Jean-Michel Jarre                                                              
    Genre: Elektronik & Ambient                                                                 
    OK                                                                                          
DEBUG    2015-09-06 18:40:23,883 [23631:MpdSession-11] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:47747: playid "0"                                             
DEBUG    2015-09-06 18:40:23,903 [23631:Core-6] mopidy.core.playback
  Changing state: stopped -> playing                                                            
DEBUG    2015-09-06 18:40:23,910 [23631:Core-6] mopidy.core.playback
  Triggering playback state change event                                                        
DEBUG    2015-09-06 18:40:23,918 [23631:MainThread] mopidy.listener
  Sending playback_state_changed to CoreListener: {'old_state': u'stopped', 'new_state': u'playing'}                                                                                            
DEBUG    2015-09-06 18:40:23,978 [23631:Audio-2] mopidy.audio.gst
  State change to GST_STATE_READY: result=GST_STATE_CHANGE_SUCCESS                              
DEBUG    2015-09-06 18:40:23,990 [23631:MainThread] mopidy.audio.gst
  Got state-changed message: old=GST_STATE_NULL new=GST_STATE_READY pending=GST_STATE_VOID_PENDING                                                                                              
ERROR    2015-09-06 18:40:25,941 [23631:MpdSession-11] pykka
  Unhandled exception in MpdSession (urn:uuid:407078ae-8c11-47cd-a84e-6aa2681d3d89):            
Traceback (most recent call last):                                                              
  File "/usr/lib/python2.7/dist-packages/pykka/actor.py", line 201, in _actor_loop              
    response = self._handle_receive(message)                                                    
  File "/usr/lib/python2.7/dist-packages/pykka/actor.py", line 304, in _handle_receive          
    return self.on_receive(message)                                                             
  File "/usr/lib/python2.7/dist-packages/mopidy/internal/network.py", line 370, in on_receive   
    self.on_line_received(line)                                                                 
  File "/usr/lib/python2.7/dist-packages/mopidy/mpd/session.py", line 34, in on_line_received   
    response = self.dispatcher.handle_request(line)                                             
  File "/usr/lib/python2.7/dist-packages/mopidy/mpd/dispatcher.py", line 47, in handle_request  
    return self._call_next_filter(request, response, filter_chain)                              
  File "/usr/lib/python2.7/dist-packages/mopidy/mpd/dispatcher.py", line 68, in _call_next_filter                                                                                               
    return next_filter(request, response, filter_chain)                                         
  File "/usr/lib/python2.7/dist-packages/mopidy/mpd/dispatcher.py", line 76, in _catch_mpd_ack_errors_filter                                                                                    
    return self._call_next_filter(request, response, filter_chain)                              
  File "/usr/lib/python2.7/dist-packages/mopidy/mpd/dispatcher.py", line 68, in _call_next_filter                                                                                               
    return next_filter(request, response, filter_chain)                                         
  File "/usr/lib/python2.7/dist-packages/mopidy/mpd/dispatcher.py", line 86, in _authenticate_filter                                                                                            
    return self._call_next_filter(request, response, filter_chain)                              
  File "/usr/lib/python2.7/dist-packages/mopidy/mpd/dispatcher.py", line 68, in _call_next_filter                                                                                               
    return next_filter(request, response, filter_chain)                                         
  File "/usr/lib/python2.7/dist-packages/mopidy/mpd/dispatcher.py", line 105, in _command_list_filter                                                                                           
    response = self._call_next_filter(request, response, filter_chain)                          
  File "/usr/lib/python2.7/dist-packages/mopidy/mpd/dispatcher.py", line 68, in _call_next_filter                                                                                               
    return next_filter(request, response, filter_chain)                                         
  File "/usr/lib/python2.7/dist-packages/mopidy/mpd/dispatcher.py", line 134, in _idle_filter   
    response = self._call_next_filter(request, response, filter_chain)                          
  File "/usr/lib/python2.7/dist-packages/mopidy/mpd/dispatcher.py", line 68, in _call_next_filter                                                                                               
    return next_filter(request, response, filter_chain)                                         
  File "/usr/lib/python2.7/dist-packages/mopidy/mpd/dispatcher.py", line 147, in _add_ok_filter 
    response = self._call_next_filter(request, response, filter_chain)                          
  File "/usr/lib/python2.7/dist-packages/mopidy/mpd/dispatcher.py", line 68, in _call_next_filter                                                                                               
    return next_filter(request, response, filter_chain)                                         
  File "/usr/lib/python2.7/dist-packages/mopidy/mpd/dispatcher.py", line 159, in _call_handler_filter                                                                                           
    response = self._format_response(self._call_handler(request))                               
  File "/usr/lib/python2.7/dist-packages/mopidy/mpd/dispatcher.py", line 174, in _call_handler  
    return protocol.commands.call(tokens, context=self.context)                                 
  File "/usr/lib/python2.7/dist-packages/mopidy/mpd/protocol/__init__.py", line 180, in call    
    return self.handlers[tokens[0]](context, *tokens[1:])                                       
  File "/usr/lib/python2.7/dist-packages/mopidy/mpd/protocol/__init__.py", line 158, in validate
    return func(**callargs)                                                                     
  File "/usr/lib/python2.7/dist-packages/mopidy/mpd/protocol/playback.py", line 227, in playid  
    return context.core.playback.play(tl_tracks[0]).get()                                       
  File "/usr/lib/python2.7/dist-packages/pykka/threading.py", line 52, in get                   
    compat.reraise(*self._data['exc_info'])                                                     
  File "/usr/lib/python2.7/dist-packages/pykka/compat.py", line 12, in reraise                  
    exec('raise tp, value, tb')                                                                 
  File "/usr/lib/python2.7/dist-packages/pykka/actor.py", line 201, in _actor_loop              
    response = self._handle_receive(message)                                                    
  File "/usr/lib/python2.7/dist-packages/pykka/actor.py", line 295, in _handle_receive          
    return callee(*message['args'], **message['kwargs'])                                        
  File "/usr/lib/python2.7/dist-packages/mopidy/core/playback.py", line 305, in play            
    self._play(tl_track=tl_track, tlid=tlid, on_error_step=1)                                   
  File "/usr/lib/python2.7/dist-packages/mopidy/core/playback.py", line 348, in _play           
    backend.playback.change_track(tl_track.track).get() and                                     
  File "/usr/lib/python2.7/dist-packages/pykka/threading.py", line 52, in get                   
    compat.reraise(*self._data['exc_info'])                                                     
  File "/usr/lib/python2.7/dist-packages/pykka/compat.py", line 12, in reraise                  
    exec('raise tp, value, tb')                                                                 
  File "/usr/lib/python2.7/dist-packages/pykka/actor.py", line 201, in _actor_loop              
    response = self._handle_receive(message)                                                    
  File "/usr/lib/python2.7/dist-packages/pykka/actor.py", line 295, in _handle_receive          
    return callee(*message['args'], **message['kwargs'])                                        
  File "/usr/lib/python2.7/dist-packages/mopidy/backend.py", line 245, in change_track          
    uri = self.translate_uri(track.uri)                                                         
  File "/usr/lib/python2.7/dist-packages/mopidy/stream/actor.py", line 90, in translate_uri     
    tracks = list(playlists.parse(content))                                                     
  File "/usr/lib/python2.7/dist-packages/mopidy/internal/playlists.py", line 28, in parse       
    return parse_urilist(data)  # Fallback                                                      
  File "/usr/lib/python2.7/dist-packages/mopidy/internal/playlists.py", line 125, in parse_urilist                                                                                              
    if not line.strip() or line.startswith('#'):                                                
UnicodeDecodeError: 'ascii' codec can't decode byte 0xff in position 192: ordinal not in range(128)

Second case:

DEBUG    2015-09-06 19:23:38,287 [26245:MpdSession-11] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:58775: status                                                 
DEBUG    2015-09-06 19:23:38,377 [26245:MpdSession-11] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:58775:                                                         
    volume: 100                                                                                 
    repeat: 0                                                                                   
    random: 0                                                                                   
    single: 0                                                                                   
    consume: 0                                                                                  
    playlist: 2                                                                                 
    playlistlength: 1                                                                           
    xfade: 0                                                                                    
    state: stop                                                                                 
    OK                                                                                          
DEBUG    2015-09-06 19:23:38,388 [26245:MpdSession-11] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:58775: playlistinfo                                           
DEBUG    2015-09-06 19:23:38,406 [26245:MpdSession-11] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:58775:                                                         
    file: http://192.168.0.19:50500/content/media/object_id/96966/res_id/0/ext/file.mp3         
    Time: 396                                                                                   
    Artist: Kraftwerk                                                                           
    Album: Trans Europa Express                                                                 
    Title: Trans Europa Express                                                                 
    Date: 1977-01-01                                                                            
    Track: 4                                                                                    
    Pos: 0                                                                                      
    Id: 0                                                                                       
    AlbumArtist: Kraftwerk                                                                      
    Genre: Elektronik & Ambient                                                                 
    OK                                                                                          
DEBUG    2015-09-06 19:23:38,420 [26245:MpdSession-11] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:58775: playid "0"                                             
DEBUG    2015-09-06 19:23:38,436 [26245:Core-6] mopidy.core.playback
  Changing state: stopped -> playing                                                            
DEBUG    2015-09-06 19:23:38,442 [26245:Core-6] mopidy.core.playback
  Triggering playback state change event                                                        
DEBUG    2015-09-06 19:23:38,451 [26245:MainThread] mopidy.listener
  Sending playback_state_changed to CoreListener: {'old_state': u'stopped', 'new_state': u'playing'}                                                                                            
DEBUG    2015-09-06 19:23:38,513 [26245:Audio-2] mopidy.audio.gst
  State change to GST_STATE_READY: result=GST_STATE_CHANGE_SUCCESS                              
DEBUG    2015-09-06 19:23:38,520 [26245:MainThread] mopidy.audio.gst
  Got state-changed message: old=GST_STATE_NULL new=GST_STATE_READY pending=GST_STATE_VOID_PENDING                                                                                              
DEBUG    2015-09-06 19:23:40,340 [26245:StreamBackend-3] mopidy.backend
  Backend translated URI from http://192.168.0.19:50500/content/media/object_id/96966/res_id/0/ext/file.mp3 to None                                                                             
WARNING  2015-09-06 19:23:40,348 [26245:Core-6] mopidy.core.tracklist
  Track is not playable: http://192.168.0.19:50500/content/media/object_id/96966/res_id/0/ext/file.mp3                                                                                          
DEBUG    2015-09-06 19:23:40,361 [26245:Audio-2] mopidy.audio.actor
  Position query failed                                                                         
DEBUG    2015-09-06 19:23:40,378 [26245:Audio-2] mopidy.audio.gst
  State change to GST_STATE_NULL: result=GST_STATE_CHANGE_SUCCESS                               
DEBUG    2015-09-06 19:23:40,386 [26245:Core-6] mopidy.core.playback
  Changing state: playing -> stopped                                                            
DEBUG    2015-09-06 19:23:40,390 [26245:Core-6] mopidy.core.playback
  Triggering playback state change event                                                        
DEBUG    2015-09-06 19:23:40,400 [26245:MainThread] mopidy.listener
  Sending playback_state_changed to CoreListener: {'old_state': u'playing', 'new_state': u'stopped'}                                                                                            
DEBUG    2015-09-06 19:23:40,405 [26245:Core-6] mopidy.core.playback
  Triggering track playback ended event                                                         
DEBUG    2015-09-06 19:23:40,425 [26245:MainThread] mopidy.listener
  Sending track_playback_ended to CoreListener: {'time_position': 0, 'tl_track': TlTrack(tlid=0, track=Track(album=Album(artists=[Artist(name=u'Kraftwerk')], name=u'Trans Europa Express'), artists=[Artist(name=u'Kraftwerk')], bitrate=127739L, date='1977-01-01', genre=u'Elektronik & Ambient', length=396016L, name=u'Trans Europa Express', track_no=4L, uri='http://192.168.0.19:50500/content/media/object_id/96966/res_id/0/ext/file.mp3'))}                                          
DEBUG    2015-09-06 19:23:40,434 [26245:MpdSession-11] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:58775: OK                                                      
DEBUG    2015-09-06 19:23:40,459 [26245:MpdSession-11] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:58775: status                                                 
DEBUG    2015-09-06 19:23:40,508 [26245:MpdSession-11] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:58775:                                                         
    volume: 100                                                                                 
    repeat: 0                                                                                   
    random: 0                                                                                   
    single: 0                                                                                   
    consume: 0                                                                                  
    playlist: 2                                                                                 
    playlistlength: 1                                                                           
    xfade: 0                                                                                    
    state: stop                                                                                 
    OK

I also tried to serve those files from my desktop computer using minidlna – same result.

Both tracks, however, play fine when using e.g. the tablet’s local renderer.

Any further ideas…?

Thanx –

– tcrass