Track is not playable

Hi again,

I just can’t get it to work… Reminder: setup is mopidy on a RasPi B+ (now upgraded to Jessie), controlled by upmpdcli on the same machine. UPnP control software is BubbleUPnP running on an Android tablet.

I purged mopidy and upmpdcli as well as all its dependencies (as identified by deborphan), re-installed both packages, configured them, and still I get errors like the following when asking mopidy (via BubbleUPnP via upmpdcli) to play a song served by a minidlna server running on my desktop:

DEBUG    2015-09-10 21:13:49,413 [1741:MpdSession-10] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:47911: status                                                                                                       
DEBUG    2015-09-10 21:13:49,459 [1741:MpdSession-10] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:47911:                                                                                                               
    volume: 100                                                                                                                                       
    repeat: 0                                                                                                                                         
    random: 0                                                                                                                                         
    single: 0                                                                                                                                         
    consume: 0                                                                                                                                        
    playlist: 1                                                                                                                                       
    playlistlength: 0                                                                                                                                 
    xfade: 0                                                                                                                                          
    state: stop                                                                                                                                       
    OK                                                                                                                                                
DEBUG    2015-09-10 21:13:49,471 [1741:MpdSession-10] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:47911: playlistinfo                                                                                                 
DEBUG    2015-09-10 21:13:49,483 [1741:MpdSession-10] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:47911: OK                                                                                                            
DEBUG    2015-09-10 21:13:49,495 [1741:MpdSession-10] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:47911: addid "http://192.168.0.7:8200/MediaItems/114355.mp3" "0"                                                    
DEBUG    2015-09-10 21:13:50,408 [1741:Core-6] mopidy.core.tracklist
  Triggering event: tracklist_changed()                                                                                                               
DEBUG    2015-09-10 21:13:50,416 [1741:MainThread] mopidy.listener
  Sending tracklist_changed to CoreListener: {}                                                                                                       
DEBUG    2015-09-10 21:13:50,422 [1741:MpdSession-10] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:47911:                                                                                                               
    Id: 0                                                                                                                                             
    OK                                                                                                                                                
DEBUG    2015-09-10 21:13:50,448 [1741:MpdSession-10] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:47911: addtagid "0" "Artist" "Kraftwerk"                                                                            
DEBUG    2015-09-10 21:13:50,462 [1741:MpdSession-10] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:47911: ACK [0@0] {addtagid} Not implemented                                                                          
DEBUG    2015-09-10 21:13:50,487 [1741:MpdSession-10] mopidy.internal.network
  Client most likely disconnected.                                                                                                                    
INFO     2015-09-10 21:13:50,495 [1741:MpdSession-11] mopidy.mpd.session
  New MPD connection from [::ffff:127.0.0.1]:47918                                                                                                    
DEBUG    2015-09-10 21:13:50,508 [1741:MpdSession-11] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:47918: status                                                                                                       
DEBUG    2015-09-10 21:13:50,516 [1741:MpdSession-10] mopidy.internal.network
  Already stopping: Actor is shutting down.                                                                                                           
DEBUG    2015-09-10 21:13:50,570 [1741:MpdSession-11] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:47918:                                                                                                               
    volume: 100                                                                                                                                       
    repeat: 0                                                                                                                                         
    random: 0                                                                                                                                         
    single: 0                                                                                                                                         
    consume: 0                                                                                                                                        
    playlist: 2                                                                                                                                       
    playlistlength: 1                                                                                                                                 
    xfade: 0                                                                                                                                          
    state: stop                                                                                                                                       
    OK                                                                                                                                                
DEBUG    2015-09-10 21:13:50,582 [1741:MpdSession-11] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:47918: status                                                                                                       
DEBUG    2015-09-10 21:13:50,626 [1741:MpdSession-11] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:47918:                                                                                                               
    volume: 100                                                                                                                                       
    repeat: 0                                                                                                                                         
    random: 0                                                                                                                                         
    single: 0                                                                                                                                         
    consume: 0                                                                                                                                        
    playlist: 2                                                                                                                                       
    playlistlength: 1                                                                                                                                 
    xfade: 0                                                                                                                                          
    state: stop                                                                                                                                       
    OK                                                                                                                                                
DEBUG    2015-09-10 21:13:50,634 [1741:MpdSession-11] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:47918: status                                                                                                       
DEBUG    2015-09-10 21:13:50,680 [1741:MpdSession-11] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:47918:                                                                                                               
    volume: 100                                                                                                                                       
    repeat: 0                                                                                                                                         
    random: 0                                                                                                                                         
    single: 0                                                                                                                                         
    consume: 0                                                                                                                                        
    playlist: 2                                                                                                                                       
    playlistlength: 1                                                                                                                                 
    xfade: 0                                                                                                                                          
    state: stop                                                                                                                                       
    OK                                                                                                                                                
DEBUG    2015-09-10 21:13:50,689 [1741:MpdSession-11] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:47918: playlistinfo                                                                                                 
DEBUG    2015-09-10 21:13:50,703 [1741:MpdSession-11] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:47918:                                                                                                               
    file: http://192.168.0.7:8200/MediaItems/114355.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-10 21:13:51,077 [1741:MpdSession-11] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:47918: playid "0"                                                                                                   
DEBUG    2015-09-10 21:13:51,094 [1741:Core-6] mopidy.core.playback
  Changing state: stopped -> playing                                                                                                                  
DEBUG    2015-09-10 21:13:51,101 [1741:Core-6] mopidy.core.playback
  Triggering playback state change event                                                                                                              
DEBUG    2015-09-10 21:13:51,108 [1741:MainThread] mopidy.listener
  Sending playback_state_changed to CoreListener: {'old_state': u'stopped', 'new_state': u'playing'}                                                  
DEBUG    2015-09-10 21:13:51,173 [1741:Audio-2] mopidy.audio.gst
  State change to GST_STATE_READY: result=GST_STATE_CHANGE_SUCCESS                                                                                    
DEBUG    2015-09-10 21:13:51,179 [1741:MainThread] mopidy.audio.gst
  Got state-changed message: old=GST_STATE_NULL new=GST_STATE_READY pending=GST_STATE_VOID_PENDING                                                    
DEBUG    2015-09-10 21:13:56,097 [1741:StreamBackend-3] mopidy.backend
  Backend translated URI from http://192.168.0.7:8200/MediaItems/114355.mp3 to None                                                                   
WARNING  2015-09-10 21:13:56,105 [1741:Core-6] mopidy.core.tracklist
  Track is not playable: http://192.168.0.7:8200/MediaItems/114355.mp3                                                                                
DEBUG    2015-09-10 21:13:56,119 [1741:Audio-2] mopidy.audio.actor
  Position query failed                                                                                                                               
DEBUG    2015-09-10 21:13:56,138 [1741:Audio-2] mopidy.audio.gst
  State change to GST_STATE_NULL: result=GST_STATE_CHANGE_SUCCESS                                                                                     
DEBUG    2015-09-10 21:13:56,146 [1741:Core-6] mopidy.core.playback
  Changing state: playing -> stopped                                                                                                                  
DEBUG    2015-09-10 21:13:56,152 [1741:Core-6] mopidy.core.playback
  Triggering playback state change event                                                                                                              
DEBUG    2015-09-10 21:13:56,160 [1741:MainThread] mopidy.listener
  Sending playback_state_changed to CoreListener: {'old_state': u'playing', 'new_state': u'stopped'}                                                  
DEBUG    2015-09-10 21:13:56,164 [1741:Core-6] mopidy.core.playback
  Triggering track playback ended event                                                                                                               
DEBUG    2015-09-10 21:13:56,185 [1741: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.7:8200/MediaItems/114355.mp3'))}                                            
DEBUG    2015-09-10 21:13:56,194 [1741:MpdSession-11] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:47918: OK                                                                                                            
DEBUG    2015-09-10 21:13:56,230 [1741:MpdSession-11] mopidy.mpd.session
  Request from [::ffff:127.0.0.1]:47918: status                                                                                                       
DEBUG    2015-09-10 21:13:56,278 [1741:MpdSession-11] mopidy.mpd.session
  Response to [::ffff:127.0.0.1]:47918:                                                                                                               
    volume: 100                                                                                                                                       
    repeat: 0                                                                                                                                         
    random: 0                                                                                                                                         
    single: 0                                                                                                                                         
    consume: 0                                                                                                                                        
    playlist: 2                                                                                                                                       
    playlistlength: 1                                                                                                                                 
    xfade: 0                                                                                                                                          
    state: stop                                                                                                                                       
    OK                                                                                                                                                

Playback of the same song works flawlessly with the tablet’s local renderer or our smart tv.

mopidy deps says:

Executable: /usr/bin/mopidy
Platform: Linux-4.1.6+-armv6l-with-debian-8.0
Python: CPython 2.7.9 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: 2.4.3 from /usr/lib/python2.7/dist-packages
  tornado>=2.3: 3.2.2 from /usr/lib/python2.7/dist-packages
Mopidy-MusicBox-Webclient: 2.0.0 from /usr/local/lib/python2.7/dist-packages
  setuptools: 5.5.1 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: 2.4.3 from /usr/lib/python2.7/dist-packages
    tornado>=2.3: 3.2.2 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

Still some package version mismatches? Or anything else? Anything…?

Best regards –

– Torsten

One mor bit of information: when just wgetting the URL mentioned in the log, I end up with a perfectly playable mp3 file on the disk, err… SD card. So I really guess the problem is on mopidy’s side, not with the UPnP server.

Thanks for any advice –

– Torsten

OK, I just re-installed the whole system, now I’m back to wheezy, also using mopidy’s wheezy repository. No other fancy stuff installed, just went directly for the mopidy/upmpdcli duo. Still getting the “Track is not playable” error.

Anybody out ther currently being able to perform a working mopidy/upmpdcli installation?

Best regards –

– Torsten