2013-04-24, 17:42
Hi,
(XBMC 12.2-PRE GIT:F1016FC on AMD Fusion 450)
Occasionally, while playing videos i am getting only audio. XBMC GUI still remains on screen and if i try to enable full screen mode for the video i am getting a black screen with audio playing.
Log below:
18:19:46 T:140492825794368 DEBUG: LIRC: Update - NEW at 432061:160 0 KEY_OK devinput (KEY_OK)
18:19:46 T:140492825794368 DEBUG: OnKey: 11 (0b) pressed, action is Select
18:19:46 T:140492825794368 DEBUG: OnPlayMedia smb://COHIBA/Multimedia/TV Series/BeyBlade/beyblade 107 thirteen candles - RebourneD.mkv
18:19:46 T:140492825794368 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
18:19:46 T:140492825794368 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
18:19:46 T:140492825794368 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
18:19:46 T:140492825794368 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
18:19:46 T:140492825794368 DEBUG: CPlayerCoreFactory::GetPlayers(smb://COHIBA/Multimedia/TV Series/BeyBlade/beyblade 107 thirteen candles - RebourneD.mkv)
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
18:19:46 T:140492825794368 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
18:19:46 T:140492825794368 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
18:19:46 T:140492825794368 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
18:19:46 T:140492825794368 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
18:19:46 T:140492825794368 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
18:19:46 T:140492825794368 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
18:19:46 T:140492825794368 NOTICE: DVDPlayer: Opening: smb://COHIBA/Multimedia/TV Series/BeyBlade/beyblade 107 thirteen candles - RebourneD.mkv
18:19:46 T:140492825794368 WARNING: CDVDMessageQueue(player):
ut MSGQ_NOT_INITIALIZED
18:19:46 T:140492825794368 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
18:19:46 T:140492825794368 NOTICE: CXBMCRenderManager::ResetRenderBuffer - using 5 render buffers
18:19:46 T:140492825794368 DEBUG: LinuxRendererGL: Cleaning up GL resources
18:19:46 T:140492825794368 DEBUG: CLinuxRendererGL:reInit - precision of luminance 16 is 16
18:19:46 T:140492825794368 DEBUG: DllSwScale: Using libswscale system library
18:19:46 T:140491559511808 NOTICE: Thread CDVDPlayer start, auto delete: false
18:19:46 T:140491559511808 NOTICE: Creating InputStream
18:19:46 T:140491559511808 DEBUG: CSmbFile::Open - opened Multimedia/TV Series/BeyBlade/beyblade 107 thirteen candles - RebourneD.mkv, fd=10001
18:19:46 T:140491559511808 DEBUG: ScanForExternalSubtitles: Searching for subtitles...
18:19:46 T:140491559511808 DEBUG: OpenDir - Using authentication url smb://admin:hidden@COHIBA/Multimedia/TV%20Series/BeyBlade
18:19:46 T:140491559511808 DEBUG: ScanForExternalSubtitles: END (total time: 92 ms)
18:19:46 T:140491559511808 NOTICE: Creating Demuxer
18:19:46 T:140491559511808 DEBUG: DllAvUtilBase: Using libavutil system library
18:19:46 T:140491559511808 DEBUG: DllAvCodec: Using libavcodec system library
18:19:46 T:140491559511808 DEBUG: DllAvFormat: Using libavformat system library
18:19:46 T:140492825794368 DEBUG: ------ Window Init (DialogBusy.xml) ------
18:19:46 T:140491559511808 DEBUG: Open - probing detected format [matroska,webm]
18:19:46 T:140491559511808 DEBUG: Open - avformat_find_stream_info starting
18:19:46 T:140492825794368 DEBUG: LIRC: Update - NEW at 432276:160 0 KEY_OK_UP devinput (KEY_OK_UP)
18:19:46 T:140491559511808 DEBUG: Open - av_find_stream_info finished
18:19:46 T:140491559511808 INFO: ffmpeg[BD7FA700]: Input #0, matroska,webm, from 'smb://COHIBA/Multimedia/TV Series/BeyBlade/beyblade 107 thirteen candles - RebourneD.mkv':
18:19:46 T:140491559511808 INFO: ffmpeg[BD7FA700]: Metadata:
18:19:46 T:140491559511808 INFO: ffmpeg[BD7FA700]: title : Encoded with MiniCoder
18:19:46 T:140491559511808 INFO: ffmpeg[BD7FA700]: Duration: 00:21:13.43, start: 0.000000, bitrate: 325 kb/s
18:19:46 T:140491559511808 INFO: ffmpeg[BD7FA700]: Chapter #0.0: start 0.097000, end 1273.430000
18:19:46 T:140491559511808 INFO: ffmpeg[BD7FA700]: Metadata:
18:19:46 T:140491559511808 INFO: ffmpeg[BD7FA700]: title : 00:00:00.097
18:19:46 T:140491559511808 INFO: ffmpeg[BD7FA700]: Stream #0:0: Video: h264 (High), yuv420p, 640x480, SAR 1:1 DAR 4:3, 23.98 fps, 23.98 tbr, 1k tbn, 47.95 tbc (default)
18:19:46 T:140491559511808 INFO: ffmpeg[BD7FA700]: Stream #0:1: Audio: aac, 48000 Hz, stereo, s16 (default)
18:19:46 T:140491559511808 NOTICE: Opening video stream: 0 source: 256
18:19:46 T:140491559511808 NOTICE: Creating video codec with codec id: 28
18:19:46 T:140491559511808 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:no OpenMax:no VDPAU:no VAAPI:no XVBA:yes
18:19:46 T:140491559511808 DEBUG: FactoryCodec - Video: - Opening
18:19:46 T:140491559511808 DEBUG: DllAvUtilBase: Using libavutil system library
18:19:46 T:140491559511808 DEBUG: DllAvCodec: Using libavcodec system library
18:19:46 T:140491559511808 DEBUG: DllSwScale: Using libswscale system library
18:19:46 T:140491559511808 DEBUG: DllAvFilter: Using libavfilter system library
18:19:46 T:140491559511808 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
18:19:46 T:140491559511808 DEBUG: FactoryCodec - Video: ff-h264 - Opened
18:19:46 T:140491559511808 NOTICE: Creating video thread
18:19:46 T:140491576297216 NOTICE: Thread CDVDPlayerVideo start, auto delete: false
18:19:46 T:140491559511808 NOTICE: Opening audio stream: 1 source: 256
18:19:46 T:140491559511808 NOTICE: Finding audio codec for: 86018
18:19:46 T:140491559511808 DEBUG: FactoryCodec - Audio: FFmpeg - Opening
18:19:46 T:140491559511808 DEBUG: DllAvUtilBase: Using libavutil system library
18:19:46 T:140491559511808 DEBUG: DllAvCodec: Using libavcodec system library
18:19:46 T:140491559511808 DEBUG: DllAvFormat: Using libswresample system library
18:19:46 T:140491559511808 DEBUG: FactoryCodec - Audio: FFmpeg - Opened
18:19:46 T:140491559511808 NOTICE: Creating audio thread
18:19:46 T:140491576297216 NOTICE: running thread: video_thread
18:19:46 T:140491576297216 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
18:19:46 T:140491567904512 NOTICE: Thread CDVDPlayerAudio start, auto delete: false
18:19:46 T:140491567904512 NOTICE: running thread: CDVDPlayerAudio:rocess()
18:19:46 T:140491559511808 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb://COHIBA/Multimedia/TV Series/BeyBlade/beyblade 107 thirteen candles - RebourneD.mkv
18:19:46 T:140491559511808 DEBUG: CDVDPlayer::SetCaching - caching state 3
18:19:46 T:140491576297216 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
18:19:46 T:140491567904512 NOTICE: Creating audio stream (codec id: 86018, channels: 2, sample rate: 48000, no pass-through)
18:19:46 T:140491567904512 INFO: CSoftAE::MakeStream - AE_FMT_S16NE, 48000, FL,FR
18:19:46 T:140491576297216 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:19:46 T:140491576297216 NOTICE: (XVBA::Open) opening xvba decoder, id: 2
18:19:46 T:140491576297216 DEBUG: DllAvUtilBase: Using libavutil system library
18:19:46 T:140491576297216 NOTICE: (XVBA) using decoder capability id: 256 flags: 3
18:19:46 T:140491576297216 NOTICE: (XVBA) using surface type: 3231564e
18:19:46 T:140491551119104 NOTICE: Thread XVBA Output Thread start, auto delete: false
18:19:46 T:140491551119104 NOTICE: COutput::OnStartup: Output Thread created
18:19:46 T:140491559511808 DEBUG: CDVDPlayer::SetCaching - caching state 0
18:19:46 T:140492823627520 DEBUG: CSoftAE::Run - Sink restart flagged
18:19:46 T:140492823627520 INFO: CSoftAE::InternalOpenSink - sink incompatible, re-starting
18:19:46 T:140491551119104 NOTICE: XVBA::COutput::CreateGlxContext - created context
18:19:46 T:140491551119104 DEBUG: XVBA::GetTexture - created shared surface
18:19:46 T:140491576297216 DEBUG: Previous line repeats 8 times.
18:19:46 T:140491576297216 DEBUG: XVBA::FFGetBuffer - created video surface
18:19:46 T:140491576297216 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(42000.000000, 0)
18:19:46 T:140491576297216 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:19:46 T:140491576297216 DEBUG: XVBA::FFGetBuffer - created video surface
18:19:46 T:140491576297216 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:19:46 T:140491576297216 DEBUG: XVBA::FFGetBuffer - created video surface
18:19:46 T:140491576297216 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:19:46 T:140491551119104 ERROR: (XVBA) failed to transfer surface
18:19:46 T:140491551119104 WARNING: COutput::StateMachine - signal: 3 form port: timer not handled for state: 1
18:19:46 T:140491576297216 DEBUG: XVBA::FFGetBuffer - created video surface
18:19:46 T:140491551119104 WARNING: COutput::StateMachine - signal: 0 form port: OutputDataPort not handled for state: 1
18:19:46 T:140492825794368 DEBUG: CGUIInfoManager::SetCurrentMovie(smb://COHIBA/Multimedia/TV Series/BeyBlade/beyblade 107 thirteen candles - RebourneD.mkv)
18:19:46 T:140492825794368 DEBUG: GetMovieId (smb://COHIBA/Multimedia/TV Series/BeyBlade/beyblade 107 thirteen candles - RebourneD.mkv), query = select idMovie from movie where idFile=2195
18:19:46 T:140492825794368 DEBUG: GetEpisodeId (smb://COHIBA/Multimedia/TV Series/BeyBlade/beyblade 107 thirteen candles - RebourneD.mkv), query = select idEpisode from episode where idFile=2195
18:19:46 T:140492825794368 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
18:19:46 T:140492825794368 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
18:19:46 T:140492823627520 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=Generic,DEV=0"
18:19:46 T:140492823627520 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=Generic,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x02"
18:19:46 T:140492823627520 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
18:19:46 T:140492823627520 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S16NE
18:19:46 T:140492823627520 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600
18:19:46 T:140492823627520 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600
18:19:46 T:140492823627520 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms
18:19:46 T:140492823627520 DEBUG: CSoftAE::InternalOpenSink - ALSA Initialized:
18:19:46 T:140492823627520 DEBUG: Output Device : HD-Audio Generic
18:19:46 T:140492823627520 DEBUG: Sample Rate : 48000
18:19:46 T:140492823627520 DEBUG: Sample Format : AE_FMT_S16NE
18:19:46 T:140492823627520 DEBUG: Channel Count : 2
18:19:46 T:140492823627520 DEBUG: Channel Layout: FL,FR
18:19:46 T:140492823627520 DEBUG: Frames : 2400
18:19:46 T:140492823627520 DEBUG: Frame Samples : 4800
18:19:46 T:140492823627520 DEBUG: Frame Size : 4
18:19:46 T:140492823627520 DEBUG: CSoftAE::InternalOpenSink - Using speaker layout: 2.0
18:19:46 T:140492823627520 DEBUG: CSoftAE::InternalOpenSink - Internal Buffer Size: 19200
18:19:47 T:140492825794368 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
18:19:47 T:140492823627520 DEBUG: AERemap: Downmix normalization is disabled
18:19:47 T:140492823627520 DEBUG: Previous line repeats 2 times.
18:19:47 T:140492823627520 DEBUG: CSoftAEStream::CSoftAEStream - Converting from AE_FMT_S16NE to AE_FMT_FLOAT
18:19:47 T:140491567904512 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback
18:19:47 T:140491567904512 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(42000.000000, 1)
18:19:47 T:140491567904512 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:-24.340333, should be:-22.765333, error:1.575000
18:19:47 T:140491559511808 DEBUG: CDVDPlayer::HandleMessages - player started 1
18:19:47 T:140492823627520 DEBUG: CSoftAE::Run - Sink restart flagged
18:19:47 T:140492823627520 INFO: CSoftAE::InternalOpenSink - keeping old sink with : AE_FMT_FLOAT, FL,FR, 48000hz
18:19:47 T:140492823627520 DEBUG: CSoftAE::InternalOpenSink - Using speaker layout: 2.0
18:19:47 T:140492823627520 DEBUG: CSoftAE::InternalOpenSink - Internal Buffer Size: 19200
18:19:47 T:140491551119104 WARNING: COutput::StateMachine - signal: 3 form port: timer not handled for state: 1
18:19:48 T:140491567904512 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:966267.118667, should be:851618.378667, error:-114648.740000
18:19:48 T:140491576297216 ERROR: XVBA:
ecode - timed out waiting for output message
18:19:48 T:140491576297216 DEBUG: CDVDPlayerVideo - video decoder returned error
18:19:48 T:140491576297216 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:19:48 T:140491576297216 NOTICE: XVBA::Check - Attempting recovery
18:19:48 T:140491551119104 WARNING: COutput::StateMachine - signal: 3 form port: timer not handled for state: 1
18:19:48 T:140491551119104 NOTICE: COutput::OnExit: Output Thread terminated
18:19:48 T:140491551119104 DEBUG: Thread XVBA Output Thread 140491551119104 terminating
18:19:48 T:140491576297216 DEBUG: CDVDPlayerVideo - video decoder returned error
18:19:48 T:140491576297216 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:19:48 T:140491551119104 NOTICE: Thread XVBA Output Thread start, auto delete: false
18:19:48 T:140491551119104 NOTICE: COutput::OnStartup: Output Thread created
18:19:48 T:140491551119104 NOTICE: XVBA::COutput::CreateGlxContext - created context
18:19:48 T:140491551119104 DEBUG: XVBA::GetTexture - created shared surface
18:19:48 T:140491576297216 DEBUG: Previous line repeats 8 times.
18:19:48 T:140491576297216 DEBUG: XVBA::FFGetBuffer - created video surface
18:19:48 T:140491576297216 WARNING: XVBA:ecode - ignoring invalid buffer
18:19:48 T:140491576297216 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:19:48 T:140491576297216 DEBUG: XVBA::FFGetBuffer - created video surface
18:19:49 T:140491576297216 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:19:49 T:140491551119104 ERROR: (XVBA) failed to transfer surface
18:19:49 T:140491551119104 WARNING: COutput::StateMachine - signal: 3 form port: timer not handled for state: 1
18:19:49 T:140491576297216 DEBUG: XVBA::FFGetBuffer - created video surface
18:19:49 T:140491551119104 WARNING: COutput::StateMachine - signal: 0 form port: OutputDataPort not handled for state: 1
18:19:50 T:140491551119104 WARNING: COutput::StateMachine - signal: 3 form port: timer not handled for state: 1
18:19:50 T:140491567904512 DEBUG: CDVDPlayerAudio:: Discontinuity2 - was:2852897.565667, should be:2832635.031139, error:-20262.534528
18:19:50 T:140492825794368 DEBUG: LIRC: Update - NEW at 435975:80 0 KEY_STOP devinput (KEY_STOP)
18:19:50 T:140492825794368 DEBUG: OnKey: 224 (e0) pressed, action is Stop
18:19:50 T:140492825794368 NOTICE: CDVDPlayer::CloseFile()
18:19:50 T:140492825794368 NOTICE: DVDPlayer: waiting for threads to exit
18:19:50 T:140491559511808 NOTICE: CDVDPlayer::OnExit()
18:19:50 T:140491559511808 NOTICE: DVDPlayer: closing audio stream
18:19:50 T:140491559511808 NOTICE: Closing audio stream
18:19:50 T:140491559511808 NOTICE: Waiting for audio thread to exit
18:19:50 T:140491567904512 NOTICE: thread end: CDVDPlayerAudio::OnExit()
18:19:50 T:140491567904512 DEBUG: Thread CDVDPlayerAudio 140491567904512 terminating
18:19:50 T:140491559511808 NOTICE: Closing audio device
18:19:50 T:140491559511808 DEBUG: CSoftAEStream::~CSoftAEStream - Destructed
18:19:50 T:140491559511808 NOTICE: Deleting audio codec
18:19:50 T:140491559511808 NOTICE: DVDPlayer: closing video stream
18:19:50 T:140491559511808 NOTICE: Closing video stream
18:19:50 T:140491559511808 NOTICE: waiting for video thread to exit
18:19:50 T:140492823627520 DEBUG: CSoftAE::Run - Sink restart flagged
18:19:50 T:140492823627520 INFO: CSoftAE::InternalOpenSink - sink incompatible, re-starting
18:19:50 T:140492823627520 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=Generic,DEV=0"
18:19:50 T:140492823627520 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=Generic,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00"
18:19:50 T:140492823627520 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
18:19:50 T:140492823627520 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S16NE
18:19:50 T:140492823627520 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820
18:19:50 T:140492823627520 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2208, bufferSize 8832
18:19:50 T:140492823627520 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 201 ms
18:19:50 T:140492823627520 DEBUG: CSoftAE::InternalOpenSink - ALSA Initialized:
18:19:50 T:140492823627520 DEBUG: Output Device : HD-Audio Generic
18:19:50 T:140492823627520 DEBUG: Sample Rate : 44100
18:19:50 T:140492823627520 DEBUG: Sample Format : AE_FMT_S16NE
18:19:50 T:140492823627520 DEBUG: Channel Count : 2
18:19:50 T:140492823627520 DEBUG: Channel Layout: FL,FR
18:19:50 T:140492823627520 DEBUG: Frames : 2208
18:19:50 T:140492823627520 DEBUG: Frame Samples : 4416
18:19:50 T:140492823627520 DEBUG: Frame Size : 4
18:19:50 T:140492823627520 DEBUG: CSoftAE::InternalOpenSink - Using speaker layout: 2.0
18:19:50 T:140492823627520 DEBUG: CSoftAE::InternalOpenSink - Internal Buffer Size: 17664
18:19:50 T:140492823627520 DEBUG: AERemap: Downmix normalization is disabled
18:19:51 T:140491576297216 DEBUG: Previous line repeats 1 times.
18:19:51 T:140491576297216 ERROR: XVBA:ecode - timed out waiting for output message
18:19:51 T:140491576297216 NOTICE: thread end: video_thread
18:19:51 T:140491576297216 DEBUG: Thread CDVDPlayerVideo 140491576297216 terminating
18:19:51 T:140491551119104 WARNING: COutput::StateMachine - signal: 3 form port: timer not handled for state: 1
18:19:51 T:140491559511808 NOTICE: deleting video codec
18:19:51 T:140491559511808 DEBUG: XVBA::FFReleaseBuffer - ignoring invalid buffer
18:19:51 T:140491559511808 NOTICE: XVBA::Release pre-cleanup
18:19:51 T:140491559511808 NOTICE: XVBA::Close - closing decoder, id: 2
18:19:51 T:140491551119104 NOTICE: COutput::OnExit: Output Thread terminated
18:19:51 T:140491551119104 DEBUG: Thread XVBA Output Thread 140491551119104 terminating
18:19:51 T:140491559511808 NOTICE: CDVDPlayer::OnExit() deleting demuxer
18:19:51 T:140491559511808 NOTICE: CDVDPlayer::OnExit() deleting input stream
18:19:51 T:140491559511808 DEBUG: CSmbFile::Close closing fd 10001
18:19:51 T:140491559511808 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
18:19:51 T:140491559511808 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
18:19:51 T:140491559511808 DEBUG: Thread CDVDPlayer 140491559511808 terminating
18:19:51 T:140492825794368 NOTICE: DVDPlayer: finished waiting
18:19:51 T:140492825794368 DEBUG: LinuxRendererGL: Cleaning up GL resources
18:19:51 T:140492825794368 NOTICE: CDVDPlayer::CloseFile()
18:19:51 T:140492825794368 WARNING: CDVDMessageQueue(player):ut MSGQ_NOT_INITIALIZED
18:19:51 T:140492825794368 NOTICE: DVDPlayer: waiting for threads to exit
18:19:51 T:140492825794368 NOTICE: DVDPlayer: finished waiting
18:19:51 T:140492825794368 DEBUG: LinuxRendererGL: Cleaning up GL resources
18:19:51 T:140492563080960 DEBUG: DoWork - Saving file state for video item smb://COHIBA/Multimedia/TV Series/BeyBlade/beyblade 107 thirteen candles - RebourneD.mkv
18:19:51 T:140492825794368 DEBUG: LIRC: Update - NEW at 436742:80 0 KEY_STOP_UP devinput (KEY_STOP_UP)
18:19:51 T:140492563080960 DEBUG: Mysql execute: update files set playCount=NULL,lastPlayed='2013-04-24 18:19:51' where idFile=2195
18:19:51 T:140492563080960 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc
18:19:51 T:140492563080960 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate
18:19:51 T:140492563080960 DEBUG: Mysql execute: INSERT INTO settings (idFile,Deinterlace,ViewMode,ZoomAmount,PixelRatio, VerticalShift, AudioStream,SubtitleStream,SubtitleDelay,SubtitlesOn,Brightness,Contrast,Gamma,VolumeAmplification,AudioDelay,OutputToAllSpeakers,ResumeTime,Crop,CropLeft,CropRight,CropTop,CropBottom,Sharpness,NoiseReduction,NonLinStretch,PostProcess,ScalingMethod,DeinterlaceMode) VALUES (2195,1,4,1.000000,0.670466,0.000000,-1,-1,0.000000,1,50.000000,50.000000,20.000000,0.000000,0.000000,0,0,1,0,0,0,0,0.000000,0.000000,0,0,1,2)
18:19:52 T:140492579866368 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetTVShows", "params": {"properties": ["title", "studio", "mpaa", "file", "art"], "sort": {"order": "descending", "method": "lastplayed"}, "filter": {"field": "inprogress", "operator": "true", "value": ""}, "limits": {"end": 20}}, "id": 1}
18:19:52 T:140492579866368 DEBUG: JSONRPC: Calling videolibrary.gettvshows
18:19:54 T:140492579866368 DEBUG: RunQuery took 2082 ms for 15 items query: SELECT * FROM tvshowview WHERE (tvshowview.idShow IN (select tvshowview.idShow from tvshowview where (watchedcount > 0 AND watchedcount < totalCount) OR (watchedcount = 0 AND tvshowview.idShow IN (select episodeview.idShow from episodeview WHERE episodeview.idShow = tvshowview.idShow AND episodeview.resumeTimeInSeconds > 0))))
18:19:54 T:140492579866368 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetEpisodes", "params": {"tvshowid": 43, "properties": ["title", "playcount", "plot", "season", "episode", "showtitle", "file", "lastplayed", "rating", "resume", "art", "streamdetails", "firstaired", "runtime"], "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "is", "value": "0"}, "limits": {"end": 1}}, "id": 1}
18:19:54 T:140492579866368 DEBUG: JSONRPC: Calling videolibrary.getepisodes
18:19:54 T:140492579866368 DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=43) order by idShow
18:19:54 T:140492579866368 DEBUG: RunQuery took 24 ms for 45 items query: select * from episodeview WHERE (episodeview.idShow = 43) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
18:19:54 T:140492579866368 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 23
18:19:54 T:140492579866368 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetEpisodes", "params": {"tvshowid": 45, "properties": ["title", "playcount", "plot", "season", "episode", "showtitle", "file", "lastplayed", "rating", "resume", "art", "streamdetails", "firstaired", "runtime"], "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "is", "value": "0"}, "limits": {"end": 1}}, "id": 1}
18:19:54 T:140492579866368 DEBUG: JSONRPC: Calling videolibrary.getepisodes
18:19:54 T:140492579866368 DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=45) order by idShow
18:19:54 T:140492579866368 DEBUG: RunQuery took 72 ms for 199 items query: select * from episodeview WHERE (episodeview.idShow = 45) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
18:19:54 T:140492579866368 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 23
18:19:54 T:140492579866368 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetEpisodes", "params": {"tvshowid": 7, "properties": ["title", "playcount", "plot", "season", "episode", "showtitle", "file", "lastplayed", "rating", "resume", "art", "streamdetails", "firstaired", "runtime"], "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "is", "value": "0"}, "limits": {"end": 1}}, "id": 1}
18:19:54 T:140492579866368 DEBUG: JSONRPC: Calling videolibrary.getepisodes
18:19:54 T:140492579866368 DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=7) order by idShow
18:19:54 T:140492579866368 DEBUG: RunQuery took 58 ms for 167 items query: select * from episodeview WHERE (episodeview.idShow = 7) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
18:19:54 T:140492579866368 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 23
18:19:54 T:140492579866368 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetEpisodes", "params": {"tvshowid": 42, "properties": ["title", "playcount", "plot", "season", "episode", "showtitle", "file", "lastplayed", "rating", "resume", "art", "streamdetails", "firstaired", "runtime"], "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "is", "value": "0"}, "limits": {"end": 1}}, "id": 1}
18:19:54 T:140492579866368 DEBUG: JSONRPC: Calling videolibrary.getepisodes
18:19:54 T:140492579866368 DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=42) order by idShow
18:19:54 T:140492579866368 DEBUG: RunQuery took 43 ms for 90 items query: select * from episodeview WHERE (episodeview.idShow = 42) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
18:19:54 T:140492579866368 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 23
18:19:54 T:140492579866368 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetEpisodes", "params": {"tvshowid": 44, "properties": ["title", "playcount", "plot", "season", "episode", "showtitle", "file", "lastplayed", "rating", "resume", "art", "streamdetails", "firstaired", "runtime"], "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "is", "value": "0"}, "limits": {"end": 1}}, "id": 1}
18:19:54 T:140492579866368 DEBUG: JSONRPC: Calling videolibrary.getepisodes
18:19:54 T:140492579866368 DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=44) order by idShow
18:19:54 T:140492579866368 DEBUG: RunQuery took 75 ms for 212 items query: select * from episodeview WHERE (episodeview.idShow = 44) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
18:19:54 T:140492579866368 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 23
Any ideas?
(XBMC 12.2-PRE GIT:F1016FC on AMD Fusion 450)
Occasionally, while playing videos i am getting only audio. XBMC GUI still remains on screen and if i try to enable full screen mode for the video i am getting a black screen with audio playing.
Log below:
18:19:46 T:140492825794368 DEBUG: LIRC: Update - NEW at 432061:160 0 KEY_OK devinput (KEY_OK)
18:19:46 T:140492825794368 DEBUG: OnKey: 11 (0b) pressed, action is Select
18:19:46 T:140492825794368 DEBUG: OnPlayMedia smb://COHIBA/Multimedia/TV Series/BeyBlade/beyblade 107 thirteen candles - RebourneD.mkv
18:19:46 T:140492825794368 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
18:19:46 T:140492825794368 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
18:19:46 T:140492825794368 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
18:19:46 T:140492825794368 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
18:19:46 T:140492825794368 DEBUG: CPlayerCoreFactory::GetPlayers(smb://COHIBA/Multimedia/TV Series/BeyBlade/beyblade 107 thirteen candles - RebourneD.mkv)
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
18:19:46 T:140492825794368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
18:19:46 T:140492825794368 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
18:19:46 T:140492825794368 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
18:19:46 T:140492825794368 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
18:19:46 T:140492825794368 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
18:19:46 T:140492825794368 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
18:19:46 T:140492825794368 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
18:19:46 T:140492825794368 NOTICE: DVDPlayer: Opening: smb://COHIBA/Multimedia/TV Series/BeyBlade/beyblade 107 thirteen candles - RebourneD.mkv
18:19:46 T:140492825794368 WARNING: CDVDMessageQueue(player):
ut MSGQ_NOT_INITIALIZED18:19:46 T:140492825794368 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
18:19:46 T:140492825794368 NOTICE: CXBMCRenderManager::ResetRenderBuffer - using 5 render buffers
18:19:46 T:140492825794368 DEBUG: LinuxRendererGL: Cleaning up GL resources
18:19:46 T:140492825794368 DEBUG: CLinuxRendererGL:
reInit - precision of luminance 16 is 1618:19:46 T:140492825794368 DEBUG: DllSwScale: Using libswscale system library
18:19:46 T:140491559511808 NOTICE: Thread CDVDPlayer start, auto delete: false
18:19:46 T:140491559511808 NOTICE: Creating InputStream
18:19:46 T:140491559511808 DEBUG: CSmbFile::Open - opened Multimedia/TV Series/BeyBlade/beyblade 107 thirteen candles - RebourneD.mkv, fd=10001
18:19:46 T:140491559511808 DEBUG: ScanForExternalSubtitles: Searching for subtitles...
18:19:46 T:140491559511808 DEBUG: OpenDir - Using authentication url smb://admin:hidden@COHIBA/Multimedia/TV%20Series/BeyBlade
18:19:46 T:140491559511808 DEBUG: ScanForExternalSubtitles: END (total time: 92 ms)
18:19:46 T:140491559511808 NOTICE: Creating Demuxer
18:19:46 T:140491559511808 DEBUG: DllAvUtilBase: Using libavutil system library
18:19:46 T:140491559511808 DEBUG: DllAvCodec: Using libavcodec system library
18:19:46 T:140491559511808 DEBUG: DllAvFormat: Using libavformat system library
18:19:46 T:140492825794368 DEBUG: ------ Window Init (DialogBusy.xml) ------
18:19:46 T:140491559511808 DEBUG: Open - probing detected format [matroska,webm]
18:19:46 T:140491559511808 DEBUG: Open - avformat_find_stream_info starting
18:19:46 T:140492825794368 DEBUG: LIRC: Update - NEW at 432276:160 0 KEY_OK_UP devinput (KEY_OK_UP)
18:19:46 T:140491559511808 DEBUG: Open - av_find_stream_info finished
18:19:46 T:140491559511808 INFO: ffmpeg[BD7FA700]: Input #0, matroska,webm, from 'smb://COHIBA/Multimedia/TV Series/BeyBlade/beyblade 107 thirteen candles - RebourneD.mkv':
18:19:46 T:140491559511808 INFO: ffmpeg[BD7FA700]: Metadata:
18:19:46 T:140491559511808 INFO: ffmpeg[BD7FA700]: title : Encoded with MiniCoder
18:19:46 T:140491559511808 INFO: ffmpeg[BD7FA700]: Duration: 00:21:13.43, start: 0.000000, bitrate: 325 kb/s
18:19:46 T:140491559511808 INFO: ffmpeg[BD7FA700]: Chapter #0.0: start 0.097000, end 1273.430000
18:19:46 T:140491559511808 INFO: ffmpeg[BD7FA700]: Metadata:
18:19:46 T:140491559511808 INFO: ffmpeg[BD7FA700]: title : 00:00:00.097
18:19:46 T:140491559511808 INFO: ffmpeg[BD7FA700]: Stream #0:0: Video: h264 (High), yuv420p, 640x480, SAR 1:1 DAR 4:3, 23.98 fps, 23.98 tbr, 1k tbn, 47.95 tbc (default)
18:19:46 T:140491559511808 INFO: ffmpeg[BD7FA700]: Stream #0:1: Audio: aac, 48000 Hz, stereo, s16 (default)
18:19:46 T:140491559511808 NOTICE: Opening video stream: 0 source: 256
18:19:46 T:140491559511808 NOTICE: Creating video codec with codec id: 28
18:19:46 T:140491559511808 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:no OpenMax:no VDPAU:no VAAPI:no XVBA:yes
18:19:46 T:140491559511808 DEBUG: FactoryCodec - Video: - Opening
18:19:46 T:140491559511808 DEBUG: DllAvUtilBase: Using libavutil system library
18:19:46 T:140491559511808 DEBUG: DllAvCodec: Using libavcodec system library
18:19:46 T:140491559511808 DEBUG: DllSwScale: Using libswscale system library
18:19:46 T:140491559511808 DEBUG: DllAvFilter: Using libavfilter system library
18:19:46 T:140491559511808 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
18:19:46 T:140491559511808 DEBUG: FactoryCodec - Video: ff-h264 - Opened
18:19:46 T:140491559511808 NOTICE: Creating video thread
18:19:46 T:140491576297216 NOTICE: Thread CDVDPlayerVideo start, auto delete: false
18:19:46 T:140491559511808 NOTICE: Opening audio stream: 1 source: 256
18:19:46 T:140491559511808 NOTICE: Finding audio codec for: 86018
18:19:46 T:140491559511808 DEBUG: FactoryCodec - Audio: FFmpeg - Opening
18:19:46 T:140491559511808 DEBUG: DllAvUtilBase: Using libavutil system library
18:19:46 T:140491559511808 DEBUG: DllAvCodec: Using libavcodec system library
18:19:46 T:140491559511808 DEBUG: DllAvFormat: Using libswresample system library
18:19:46 T:140491559511808 DEBUG: FactoryCodec - Audio: FFmpeg - Opened
18:19:46 T:140491559511808 NOTICE: Creating audio thread
18:19:46 T:140491576297216 NOTICE: running thread: video_thread
18:19:46 T:140491576297216 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
18:19:46 T:140491567904512 NOTICE: Thread CDVDPlayerAudio start, auto delete: false
18:19:46 T:140491567904512 NOTICE: running thread: CDVDPlayerAudio:
rocess()18:19:46 T:140491559511808 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb://COHIBA/Multimedia/TV Series/BeyBlade/beyblade 107 thirteen candles - RebourneD.mkv
18:19:46 T:140491559511808 DEBUG: CDVDPlayer::SetCaching - caching state 3
18:19:46 T:140491576297216 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
18:19:46 T:140491567904512 NOTICE: Creating audio stream (codec id: 86018, channels: 2, sample rate: 48000, no pass-through)
18:19:46 T:140491567904512 INFO: CSoftAE::MakeStream - AE_FMT_S16NE, 48000, FL,FR
18:19:46 T:140491576297216 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:19:46 T:140491576297216 NOTICE: (XVBA::Open) opening xvba decoder, id: 2
18:19:46 T:140491576297216 DEBUG: DllAvUtilBase: Using libavutil system library
18:19:46 T:140491576297216 NOTICE: (XVBA) using decoder capability id: 256 flags: 3
18:19:46 T:140491576297216 NOTICE: (XVBA) using surface type: 3231564e
18:19:46 T:140491551119104 NOTICE: Thread XVBA Output Thread start, auto delete: false
18:19:46 T:140491551119104 NOTICE: COutput::OnStartup: Output Thread created
18:19:46 T:140491559511808 DEBUG: CDVDPlayer::SetCaching - caching state 0
18:19:46 T:140492823627520 DEBUG: CSoftAE::Run - Sink restart flagged
18:19:46 T:140492823627520 INFO: CSoftAE::InternalOpenSink - sink incompatible, re-starting
18:19:46 T:140491551119104 NOTICE: XVBA::COutput::CreateGlxContext - created context
18:19:46 T:140491551119104 DEBUG: XVBA::GetTexture - created shared surface
18:19:46 T:140491576297216 DEBUG: Previous line repeats 8 times.
18:19:46 T:140491576297216 DEBUG: XVBA::FFGetBuffer - created video surface
18:19:46 T:140491576297216 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(42000.000000, 0)
18:19:46 T:140491576297216 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:19:46 T:140491576297216 DEBUG: XVBA::FFGetBuffer - created video surface
18:19:46 T:140491576297216 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:19:46 T:140491576297216 DEBUG: XVBA::FFGetBuffer - created video surface
18:19:46 T:140491576297216 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:19:46 T:140491551119104 ERROR: (XVBA) failed to transfer surface
18:19:46 T:140491551119104 WARNING: COutput::StateMachine - signal: 3 form port: timer not handled for state: 1
18:19:46 T:140491576297216 DEBUG: XVBA::FFGetBuffer - created video surface
18:19:46 T:140491551119104 WARNING: COutput::StateMachine - signal: 0 form port: OutputDataPort not handled for state: 1
18:19:46 T:140492825794368 DEBUG: CGUIInfoManager::SetCurrentMovie(smb://COHIBA/Multimedia/TV Series/BeyBlade/beyblade 107 thirteen candles - RebourneD.mkv)
18:19:46 T:140492825794368 DEBUG: GetMovieId (smb://COHIBA/Multimedia/TV Series/BeyBlade/beyblade 107 thirteen candles - RebourneD.mkv), query = select idMovie from movie where idFile=2195
18:19:46 T:140492825794368 DEBUG: GetEpisodeId (smb://COHIBA/Multimedia/TV Series/BeyBlade/beyblade 107 thirteen candles - RebourneD.mkv), query = select idEpisode from episode where idFile=2195
18:19:46 T:140492825794368 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
18:19:46 T:140492825794368 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
18:19:46 T:140492823627520 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=Generic,DEV=0"
18:19:46 T:140492823627520 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=Generic,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x02"
18:19:46 T:140492823627520 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
18:19:46 T:140492823627520 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S16NE
18:19:46 T:140492823627520 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600
18:19:46 T:140492823627520 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600
18:19:46 T:140492823627520 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms
18:19:46 T:140492823627520 DEBUG: CSoftAE::InternalOpenSink - ALSA Initialized:
18:19:46 T:140492823627520 DEBUG: Output Device : HD-Audio Generic
18:19:46 T:140492823627520 DEBUG: Sample Rate : 48000
18:19:46 T:140492823627520 DEBUG: Sample Format : AE_FMT_S16NE
18:19:46 T:140492823627520 DEBUG: Channel Count : 2
18:19:46 T:140492823627520 DEBUG: Channel Layout: FL,FR
18:19:46 T:140492823627520 DEBUG: Frames : 2400
18:19:46 T:140492823627520 DEBUG: Frame Samples : 4800
18:19:46 T:140492823627520 DEBUG: Frame Size : 4
18:19:46 T:140492823627520 DEBUG: CSoftAE::InternalOpenSink - Using speaker layout: 2.0
18:19:46 T:140492823627520 DEBUG: CSoftAE::InternalOpenSink - Internal Buffer Size: 19200
18:19:47 T:140492825794368 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
18:19:47 T:140492823627520 DEBUG: AERemap: Downmix normalization is disabled
18:19:47 T:140492823627520 DEBUG: Previous line repeats 2 times.
18:19:47 T:140492823627520 DEBUG: CSoftAEStream::CSoftAEStream - Converting from AE_FMT_S16NE to AE_FMT_FLOAT
18:19:47 T:140491567904512 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback
18:19:47 T:140491567904512 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(42000.000000, 1)
18:19:47 T:140491567904512 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:-24.340333, should be:-22.765333, error:1.575000
18:19:47 T:140491559511808 DEBUG: CDVDPlayer::HandleMessages - player started 1
18:19:47 T:140492823627520 DEBUG: CSoftAE::Run - Sink restart flagged
18:19:47 T:140492823627520 INFO: CSoftAE::InternalOpenSink - keeping old sink with : AE_FMT_FLOAT, FL,FR, 48000hz
18:19:47 T:140492823627520 DEBUG: CSoftAE::InternalOpenSink - Using speaker layout: 2.0
18:19:47 T:140492823627520 DEBUG: CSoftAE::InternalOpenSink - Internal Buffer Size: 19200
18:19:47 T:140491551119104 WARNING: COutput::StateMachine - signal: 3 form port: timer not handled for state: 1
18:19:48 T:140491567904512 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:966267.118667, should be:851618.378667, error:-114648.740000
18:19:48 T:140491576297216 ERROR: XVBA:
ecode - timed out waiting for output message18:19:48 T:140491576297216 DEBUG: CDVDPlayerVideo - video decoder returned error
18:19:48 T:140491576297216 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:19:48 T:140491576297216 NOTICE: XVBA::Check - Attempting recovery
18:19:48 T:140491551119104 WARNING: COutput::StateMachine - signal: 3 form port: timer not handled for state: 1
18:19:48 T:140491551119104 NOTICE: COutput::OnExit: Output Thread terminated
18:19:48 T:140491551119104 DEBUG: Thread XVBA Output Thread 140491551119104 terminating
18:19:48 T:140491576297216 DEBUG: CDVDPlayerVideo - video decoder returned error
18:19:48 T:140491576297216 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:19:48 T:140491551119104 NOTICE: Thread XVBA Output Thread start, auto delete: false
18:19:48 T:140491551119104 NOTICE: COutput::OnStartup: Output Thread created
18:19:48 T:140491551119104 NOTICE: XVBA::COutput::CreateGlxContext - created context
18:19:48 T:140491551119104 DEBUG: XVBA::GetTexture - created shared surface
18:19:48 T:140491576297216 DEBUG: Previous line repeats 8 times.
18:19:48 T:140491576297216 DEBUG: XVBA::FFGetBuffer - created video surface
18:19:48 T:140491576297216 WARNING: XVBA:
ecode - ignoring invalid buffer18:19:48 T:140491576297216 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:19:48 T:140491576297216 DEBUG: XVBA::FFGetBuffer - created video surface
18:19:49 T:140491576297216 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:19:49 T:140491551119104 ERROR: (XVBA) failed to transfer surface
18:19:49 T:140491551119104 WARNING: COutput::StateMachine - signal: 3 form port: timer not handled for state: 1
18:19:49 T:140491576297216 DEBUG: XVBA::FFGetBuffer - created video surface
18:19:49 T:140491551119104 WARNING: COutput::StateMachine - signal: 0 form port: OutputDataPort not handled for state: 1
18:19:50 T:140491551119104 WARNING: COutput::StateMachine - signal: 3 form port: timer not handled for state: 1
18:19:50 T:140491567904512 DEBUG: CDVDPlayerAudio:: Discontinuity2 - was:2852897.565667, should be:2832635.031139, error:-20262.534528
18:19:50 T:140492825794368 DEBUG: LIRC: Update - NEW at 435975:80 0 KEY_STOP devinput (KEY_STOP)
18:19:50 T:140492825794368 DEBUG: OnKey: 224 (e0) pressed, action is Stop
18:19:50 T:140492825794368 NOTICE: CDVDPlayer::CloseFile()
18:19:50 T:140492825794368 NOTICE: DVDPlayer: waiting for threads to exit
18:19:50 T:140491559511808 NOTICE: CDVDPlayer::OnExit()
18:19:50 T:140491559511808 NOTICE: DVDPlayer: closing audio stream
18:19:50 T:140491559511808 NOTICE: Closing audio stream
18:19:50 T:140491559511808 NOTICE: Waiting for audio thread to exit
18:19:50 T:140491567904512 NOTICE: thread end: CDVDPlayerAudio::OnExit()
18:19:50 T:140491567904512 DEBUG: Thread CDVDPlayerAudio 140491567904512 terminating
18:19:50 T:140491559511808 NOTICE: Closing audio device
18:19:50 T:140491559511808 DEBUG: CSoftAEStream::~CSoftAEStream - Destructed
18:19:50 T:140491559511808 NOTICE: Deleting audio codec
18:19:50 T:140491559511808 NOTICE: DVDPlayer: closing video stream
18:19:50 T:140491559511808 NOTICE: Closing video stream
18:19:50 T:140491559511808 NOTICE: waiting for video thread to exit
18:19:50 T:140492823627520 DEBUG: CSoftAE::Run - Sink restart flagged
18:19:50 T:140492823627520 INFO: CSoftAE::InternalOpenSink - sink incompatible, re-starting
18:19:50 T:140492823627520 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=Generic,DEV=0"
18:19:50 T:140492823627520 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=Generic,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00"
18:19:50 T:140492823627520 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
18:19:50 T:140492823627520 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S16NE
18:19:50 T:140492823627520 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820
18:19:50 T:140492823627520 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2208, bufferSize 8832
18:19:50 T:140492823627520 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 201 ms
18:19:50 T:140492823627520 DEBUG: CSoftAE::InternalOpenSink - ALSA Initialized:
18:19:50 T:140492823627520 DEBUG: Output Device : HD-Audio Generic
18:19:50 T:140492823627520 DEBUG: Sample Rate : 44100
18:19:50 T:140492823627520 DEBUG: Sample Format : AE_FMT_S16NE
18:19:50 T:140492823627520 DEBUG: Channel Count : 2
18:19:50 T:140492823627520 DEBUG: Channel Layout: FL,FR
18:19:50 T:140492823627520 DEBUG: Frames : 2208
18:19:50 T:140492823627520 DEBUG: Frame Samples : 4416
18:19:50 T:140492823627520 DEBUG: Frame Size : 4
18:19:50 T:140492823627520 DEBUG: CSoftAE::InternalOpenSink - Using speaker layout: 2.0
18:19:50 T:140492823627520 DEBUG: CSoftAE::InternalOpenSink - Internal Buffer Size: 17664
18:19:50 T:140492823627520 DEBUG: AERemap: Downmix normalization is disabled
18:19:51 T:140491576297216 DEBUG: Previous line repeats 1 times.
18:19:51 T:140491576297216 ERROR: XVBA:
ecode - timed out waiting for output message18:19:51 T:140491576297216 NOTICE: thread end: video_thread
18:19:51 T:140491576297216 DEBUG: Thread CDVDPlayerVideo 140491576297216 terminating
18:19:51 T:140491551119104 WARNING: COutput::StateMachine - signal: 3 form port: timer not handled for state: 1
18:19:51 T:140491559511808 NOTICE: deleting video codec
18:19:51 T:140491559511808 DEBUG: XVBA::FFReleaseBuffer - ignoring invalid buffer
18:19:51 T:140491559511808 NOTICE: XVBA::Release pre-cleanup
18:19:51 T:140491559511808 NOTICE: XVBA::Close - closing decoder, id: 2
18:19:51 T:140491551119104 NOTICE: COutput::OnExit: Output Thread terminated
18:19:51 T:140491551119104 DEBUG: Thread XVBA Output Thread 140491551119104 terminating
18:19:51 T:140491559511808 NOTICE: CDVDPlayer::OnExit() deleting demuxer
18:19:51 T:140491559511808 NOTICE: CDVDPlayer::OnExit() deleting input stream
18:19:51 T:140491559511808 DEBUG: CSmbFile::Close closing fd 10001
18:19:51 T:140491559511808 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
18:19:51 T:140491559511808 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
18:19:51 T:140491559511808 DEBUG: Thread CDVDPlayer 140491559511808 terminating
18:19:51 T:140492825794368 NOTICE: DVDPlayer: finished waiting
18:19:51 T:140492825794368 DEBUG: LinuxRendererGL: Cleaning up GL resources
18:19:51 T:140492825794368 NOTICE: CDVDPlayer::CloseFile()
18:19:51 T:140492825794368 WARNING: CDVDMessageQueue(player):
ut MSGQ_NOT_INITIALIZED18:19:51 T:140492825794368 NOTICE: DVDPlayer: waiting for threads to exit
18:19:51 T:140492825794368 NOTICE: DVDPlayer: finished waiting
18:19:51 T:140492825794368 DEBUG: LinuxRendererGL: Cleaning up GL resources
18:19:51 T:140492563080960 DEBUG: DoWork - Saving file state for video item smb://COHIBA/Multimedia/TV Series/BeyBlade/beyblade 107 thirteen candles - RebourneD.mkv
18:19:51 T:140492825794368 DEBUG: LIRC: Update - NEW at 436742:80 0 KEY_STOP_UP devinput (KEY_STOP_UP)
18:19:51 T:140492563080960 DEBUG: Mysql execute: update files set playCount=NULL,lastPlayed='2013-04-24 18:19:51' where idFile=2195
18:19:51 T:140492563080960 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc
18:19:51 T:140492563080960 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate
18:19:51 T:140492563080960 DEBUG: Mysql execute: INSERT INTO settings (idFile,Deinterlace,ViewMode,ZoomAmount,PixelRatio, VerticalShift, AudioStream,SubtitleStream,SubtitleDelay,SubtitlesOn,Brightness,Contrast,Gamma,VolumeAmplification,AudioDelay,OutputToAllSpeakers,ResumeTime,Crop,CropLeft,CropRight,CropTop,CropBottom,Sharpness,NoiseReduction,NonLinStretch,PostProcess,ScalingMethod,DeinterlaceMode) VALUES (2195,1,4,1.000000,0.670466,0.000000,-1,-1,0.000000,1,50.000000,50.000000,20.000000,0.000000,0.000000,0,0,1,0,0,0,0,0.000000,0.000000,0,0,1,2)
18:19:52 T:140492579866368 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetTVShows", "params": {"properties": ["title", "studio", "mpaa", "file", "art"], "sort": {"order": "descending", "method": "lastplayed"}, "filter": {"field": "inprogress", "operator": "true", "value": ""}, "limits": {"end": 20}}, "id": 1}
18:19:52 T:140492579866368 DEBUG: JSONRPC: Calling videolibrary.gettvshows
18:19:54 T:140492579866368 DEBUG: RunQuery took 2082 ms for 15 items query: SELECT * FROM tvshowview WHERE (tvshowview.idShow IN (select tvshowview.idShow from tvshowview where (watchedcount > 0 AND watchedcount < totalCount) OR (watchedcount = 0 AND tvshowview.idShow IN (select episodeview.idShow from episodeview WHERE episodeview.idShow = tvshowview.idShow AND episodeview.resumeTimeInSeconds > 0))))
18:19:54 T:140492579866368 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetEpisodes", "params": {"tvshowid": 43, "properties": ["title", "playcount", "plot", "season", "episode", "showtitle", "file", "lastplayed", "rating", "resume", "art", "streamdetails", "firstaired", "runtime"], "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "is", "value": "0"}, "limits": {"end": 1}}, "id": 1}
18:19:54 T:140492579866368 DEBUG: JSONRPC: Calling videolibrary.getepisodes
18:19:54 T:140492579866368 DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=43) order by idShow
18:19:54 T:140492579866368 DEBUG: RunQuery took 24 ms for 45 items query: select * from episodeview WHERE (episodeview.idShow = 43) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
18:19:54 T:140492579866368 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 23
18:19:54 T:140492579866368 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetEpisodes", "params": {"tvshowid": 45, "properties": ["title", "playcount", "plot", "season", "episode", "showtitle", "file", "lastplayed", "rating", "resume", "art", "streamdetails", "firstaired", "runtime"], "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "is", "value": "0"}, "limits": {"end": 1}}, "id": 1}
18:19:54 T:140492579866368 DEBUG: JSONRPC: Calling videolibrary.getepisodes
18:19:54 T:140492579866368 DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=45) order by idShow
18:19:54 T:140492579866368 DEBUG: RunQuery took 72 ms for 199 items query: select * from episodeview WHERE (episodeview.idShow = 45) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
18:19:54 T:140492579866368 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 23
18:19:54 T:140492579866368 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetEpisodes", "params": {"tvshowid": 7, "properties": ["title", "playcount", "plot", "season", "episode", "showtitle", "file", "lastplayed", "rating", "resume", "art", "streamdetails", "firstaired", "runtime"], "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "is", "value": "0"}, "limits": {"end": 1}}, "id": 1}
18:19:54 T:140492579866368 DEBUG: JSONRPC: Calling videolibrary.getepisodes
18:19:54 T:140492579866368 DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=7) order by idShow
18:19:54 T:140492579866368 DEBUG: RunQuery took 58 ms for 167 items query: select * from episodeview WHERE (episodeview.idShow = 7) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
18:19:54 T:140492579866368 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 23
18:19:54 T:140492579866368 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetEpisodes", "params": {"tvshowid": 42, "properties": ["title", "playcount", "plot", "season", "episode", "showtitle", "file", "lastplayed", "rating", "resume", "art", "streamdetails", "firstaired", "runtime"], "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "is", "value": "0"}, "limits": {"end": 1}}, "id": 1}
18:19:54 T:140492579866368 DEBUG: JSONRPC: Calling videolibrary.getepisodes
18:19:54 T:140492579866368 DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=42) order by idShow
18:19:54 T:140492579866368 DEBUG: RunQuery took 43 ms for 90 items query: select * from episodeview WHERE (episodeview.idShow = 42) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
18:19:54 T:140492579866368 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 23
18:19:54 T:140492579866368 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetEpisodes", "params": {"tvshowid": 44, "properties": ["title", "playcount", "plot", "season", "episode", "showtitle", "file", "lastplayed", "rating", "resume", "art", "streamdetails", "firstaired", "runtime"], "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "is", "value": "0"}, "limits": {"end": 1}}, "id": 1}
18:19:54 T:140492579866368 DEBUG: JSONRPC: Calling videolibrary.getepisodes
18:19:54 T:140492579866368 DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=44) order by idShow
18:19:54 T:140492579866368 DEBUG: RunQuery took 75 ms for 212 items query: select * from episodeview WHERE (episodeview.idShow = 44) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
18:19:54 T:140492579866368 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 23
Any ideas?
