2008-12-26, 12:34
I did the mistake of posting this in the General forum but I guess it belongs here.
I have been "streaming" stuff from a friends ftp server for several years without any problems on my old xbox. With my ATV however, some of the items will not start. The xbmc log outputs the error: "ERROR: Playlist Player: skipping unplayable item: -1". The exact same items starts right away on my old xbox (where by the way xbmc has not been updated for probably a couple of years).
If I download any of the "unplayable items" to a computer at my home and play it through an smb share it works flawlessly.
My friend is running a FileZilla server on a Windows server. I have installed vsftp server on my Ubuntu server (so we really do not have very much in common except the ATV). All the same items failes over ftp both from my ubuntu server as on my friends windows server.
If anyone needs a test file to recreate the bug, please PM me.
I am running:
ATV OS 2.3
XBMC Atlantis
Here is some more detailed information from my log (There is however a lot of debug log, so I am not sure if this is the most interesting part:
22:40:55 T:43185152 M: 20123648 DEBUG: Playback has started
22:40:55 T:43185152 M: 20123648 NOTICE: CDVDPlayer::OnExit()
22:40:55 T:43185152 M: 20123648 NOTICE: DVDPlayer: eof, waiting for queues to empty
22:40:55 T:43185152 M: 20123648 NOTICE: DVDPlayer: closing audio stream
22:40:55 T:43185152 M: 20123648 NOTICE: Closing audio stream
22:40:55 T:43185152 M: 20123648 NOTICE: waiting for audio thread to exit
22:40:55 T:43377664 M: 20123648 DEBUG: CDVDPlayerAudio::Process - Abort recieved, exiting thread
22:40:55 T:43377664 M: 20123648 NOTICE: Closing audio device
22:40:55 T:43377664 M: 20123648 NOTICE: thread end: CDVDPlayerAudio::OnExit()
22:40:55 T:43377664 M: 20123648 DEBUG: staticThread, deleting thread graphic context
22:40:55 T:43377664 M: 20123648 DEBUG: Thread 43377664 terminating
22:40:55 T:43185152 M: 20131840 NOTICE: Deleting audio codec
22:40:55 T:43185152 M: 20164608 NOTICE: DVDPlayer: closing video stream
22:40:55 T:43185152 M: 20164608 NOTICE: Closing video stream
22:40:55 T:43185152 M: 20164608 NOTICE: waiting for video thread to exit
22:40:55 T:43428352 M: 20164608 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
22:40:55 T:43428352 M: 20164608 NOTICE: thread end: video_thread
22:40:55 T:43428352 M: 20164608 DEBUG: staticThread, deleting thread graphic context
22:40:55 T:43428352 M: 20164608 DEBUG: Thread 43428352 terminating
22:40:55 T:43185152 M: 20172800 NOTICE: deleting video codec
22:40:55 T:43185152 M: 20176896 NOTICE: CDVDPlayer::OnExit() deleting demuxer
22:40:55 T:43185152 M: 20180992 NOTICE: CDVDPlayer::OnExit() deleting input stream
22:40:55 T:43185152 M: 20180992 DEBUG: FileCurl::Close(0xa2c81e0) ftp://user:pwd@ip_address:port/my%20...04180000%2empg
22:40:55 T:43185152 M: 20279296 DEBUG: Playback has finished
22:40:55 T:43185152 M: 20279296 DEBUG: staticThread, deleting thread graphic context
22:40:55 T:43185152 M: 20279296 DEBUG: Thread 43185152 terminating
22:40:55 T:43352064 M: 20279296 DEBUG: staticThread, deleting thread object
22:40:55 T:43352064 M: 20279296 DEBUG: staticThread, deleting thread graphic context
22:40:55 T:43352064 M: 20279296 DEBUG: Thread 43352064 terminating
22:40:56 T:2684407808 M: 20275200 DEBUG: GetMovieId (ftp://user:pwd@ip_address:port/my recordings/Manual Recordings/BarneTV_20081204180000.mpg), query = select idMovie from movie where idFile=43
22:40:56 T:2684407808 M: 20275200 DEBUG: GetEpisodeId (ftp://user:pwd@ip_address:port/my recordings/Manual Recordings/BarneTV_20081204180000.mpg), query = select idEpisode from episode where idFile=43
22:40:56 T:2684407808 M: 20275200 DEBUG: GetMusicVideoId (ftp://user:pwd@ip_address:port/my recordings/Manual Recordings/BarneTV_20081204180000.mpg), query = select idMVideo from musicvideo where idFile=43
22:40:56 T:2684407808 M: 20275200 ERROR: Playlist Player: skipping unplayable item: -1, path [ftp://user:pwd@ip_address:port/my recordings/Manual Recordings/BarneTV_20081204180000.mpg]
22:40:56 T:2684407808 M: 20275200 WARNING: Attempt to set unplayable index -1
22:40:56 T:2684407808 M: 20275200 DEBUG: Playlist Player: too many consecutive failures... aborting playback
22:40:56 T:2684407808 M: 20275200 INFO: Loading skin file: DialogOK.xml
I have been "streaming" stuff from a friends ftp server for several years without any problems on my old xbox. With my ATV however, some of the items will not start. The xbmc log outputs the error: "ERROR: Playlist Player: skipping unplayable item: -1". The exact same items starts right away on my old xbox (where by the way xbmc has not been updated for probably a couple of years).
If I download any of the "unplayable items" to a computer at my home and play it through an smb share it works flawlessly.
My friend is running a FileZilla server on a Windows server. I have installed vsftp server on my Ubuntu server (so we really do not have very much in common except the ATV). All the same items failes over ftp both from my ubuntu server as on my friends windows server.
If anyone needs a test file to recreate the bug, please PM me.
I am running:
ATV OS 2.3
XBMC Atlantis
Here is some more detailed information from my log (There is however a lot of debug log, so I am not sure if this is the most interesting part:
22:40:55 T:43185152 M: 20123648 DEBUG: Playback has started
22:40:55 T:43185152 M: 20123648 NOTICE: CDVDPlayer::OnExit()
22:40:55 T:43185152 M: 20123648 NOTICE: DVDPlayer: eof, waiting for queues to empty
22:40:55 T:43185152 M: 20123648 NOTICE: DVDPlayer: closing audio stream
22:40:55 T:43185152 M: 20123648 NOTICE: Closing audio stream
22:40:55 T:43185152 M: 20123648 NOTICE: waiting for audio thread to exit
22:40:55 T:43377664 M: 20123648 DEBUG: CDVDPlayerAudio::Process - Abort recieved, exiting thread
22:40:55 T:43377664 M: 20123648 NOTICE: Closing audio device
22:40:55 T:43377664 M: 20123648 NOTICE: thread end: CDVDPlayerAudio::OnExit()
22:40:55 T:43377664 M: 20123648 DEBUG: staticThread, deleting thread graphic context
22:40:55 T:43377664 M: 20123648 DEBUG: Thread 43377664 terminating
22:40:55 T:43185152 M: 20131840 NOTICE: Deleting audio codec
22:40:55 T:43185152 M: 20164608 NOTICE: DVDPlayer: closing video stream
22:40:55 T:43185152 M: 20164608 NOTICE: Closing video stream
22:40:55 T:43185152 M: 20164608 NOTICE: waiting for video thread to exit
22:40:55 T:43428352 M: 20164608 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
22:40:55 T:43428352 M: 20164608 NOTICE: thread end: video_thread
22:40:55 T:43428352 M: 20164608 DEBUG: staticThread, deleting thread graphic context
22:40:55 T:43428352 M: 20164608 DEBUG: Thread 43428352 terminating
22:40:55 T:43185152 M: 20172800 NOTICE: deleting video codec
22:40:55 T:43185152 M: 20176896 NOTICE: CDVDPlayer::OnExit() deleting demuxer
22:40:55 T:43185152 M: 20180992 NOTICE: CDVDPlayer::OnExit() deleting input stream
22:40:55 T:43185152 M: 20180992 DEBUG: FileCurl::Close(0xa2c81e0) ftp://user:pwd@ip_address:port/my%20...04180000%2empg
22:40:55 T:43185152 M: 20279296 DEBUG: Playback has finished
22:40:55 T:43185152 M: 20279296 DEBUG: staticThread, deleting thread graphic context
22:40:55 T:43185152 M: 20279296 DEBUG: Thread 43185152 terminating
22:40:55 T:43352064 M: 20279296 DEBUG: staticThread, deleting thread object
22:40:55 T:43352064 M: 20279296 DEBUG: staticThread, deleting thread graphic context
22:40:55 T:43352064 M: 20279296 DEBUG: Thread 43352064 terminating
22:40:56 T:2684407808 M: 20275200 DEBUG: GetMovieId (ftp://user:pwd@ip_address:port/my recordings/Manual Recordings/BarneTV_20081204180000.mpg), query = select idMovie from movie where idFile=43
22:40:56 T:2684407808 M: 20275200 DEBUG: GetEpisodeId (ftp://user:pwd@ip_address:port/my recordings/Manual Recordings/BarneTV_20081204180000.mpg), query = select idEpisode from episode where idFile=43
22:40:56 T:2684407808 M: 20275200 DEBUG: GetMusicVideoId (ftp://user:pwd@ip_address:port/my recordings/Manual Recordings/BarneTV_20081204180000.mpg), query = select idMVideo from musicvideo where idFile=43
22:40:56 T:2684407808 M: 20275200 ERROR: Playlist Player: skipping unplayable item: -1, path [ftp://user:pwd@ip_address:port/my recordings/Manual Recordings/BarneTV_20081204180000.mpg]
22:40:56 T:2684407808 M: 20275200 WARNING: Attempt to set unplayable index -1
22:40:56 T:2684407808 M: 20275200 DEBUG: Playlist Player: too many consecutive failures... aborting playback
22:40:56 T:2684407808 M: 20275200 INFO: Loading skin file: DialogOK.xml