You are not logged in.
Hello!
I am currently setting up a HTPC with AMD A4-5300 with the integrated Radeon HD 7480D. To avoid the trouble with the fglrx driver, I would like to use the free xf86-video-ati driver.
I thought this would be no problem since the update to Mesa 3D 9.2 (Package version 9.2.0-1) yesterday. This should have brought support for the UVD in the GPU when at least kernel 3.10 is installed (3.10.9-1 is).
According to this Gentoo Wiki page, the Aruba GPU should use TAHITI_uvd.bin as the UVD firmware, the file exists in /usr/lib/firmware/radeon.
But now to the problems with XBMC (12.2-5). When I boot up the system, select a for example x264 encoded video file it plays fine most of the time. Low CPU load indicates that hardware decoding actually is used. When I then stop the video and simply select it again in the file list, XBMC freezes. Restarting works most of the time to workaround the problem, so after a reboot XBMC manages to play the video most of the time.
Sometimes it even freezes on the first attempt to start the movie. After just restarting X11, XBMC was never able to play a video file.
Here is a portion of the logfile where a film is played, then stopped and then selected again, causing a freeze:
19:14:30 T:139964276774656 NOTICE: Thread Background Loader start, auto delete: false
19:14:34 T:139965115230144 NOTICE: Previous line repeats 1 times.
19:14:34 T:139965115230144 NOTICE: DVDPlayer: Opening: smb://ANDI/xxx.mkv
19:14:34 T:139965115230144 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
19:14:34 T:139964276774656 NOTICE: Thread CDVDPlayer start, auto delete: false
19:14:34 T:139964276774656 NOTICE: Creating InputStream
19:14:34 T:139964276774656 NOTICE: Creating Demuxer
19:14:35 T:139964276774656 NOTICE: Opening video stream: 0 source: 256
19:14:35 T:139964276774656 NOTICE: Creating video codec with codec id: 28
19:14:35 T:139964276774656 NOTICE: CDVDVideoCodecFFmpeg::Open() Creating VDPAU(1920x1080, 28)
19:14:35 T:139964276774656 NOTICE: vdp_device = 0x00000001 vdp_st = 0x00000000
19:14:35 T:139964276774656 NOTICE: VDPAU Decoder capabilities:
19:14:35 T:139964276774656 NOTICE: name level macbs width height
19:14:35 T:139964276774656 NOTICE: ------------------------------------
19:14:35 T:139964276774656 NOTICE: MPEG1 16 9216 2048 1152
19:14:35 T:139964276774656 NOTICE: MPEG2_SIMPLE 16 9216 2048 1152
19:14:35 T:139964276774656 NOTICE: MPEG2_MAIN 16 9216 2048 1152
19:14:35 T:139964276774656 NOTICE: H264_BASELINE 16 9216 2048 1152
19:14:35 T:139964276774656 NOTICE: H264_MAIN 16 9216 2048 1152
19:14:35 T:139964276774656 NOTICE: H264_HIGH 16 9216 2048 1152
19:14:35 T:139964276774656 NOTICE: VC1_SIMPLE 16 9216 2048 1152
19:14:35 T:139964276774656 NOTICE: VC1_MAIN 16 9216 2048 1152
19:14:35 T:139964276774656 NOTICE: VC1_ADVANCED 16 9216 2048 1152
19:14:35 T:139964276774656 NOTICE: MPEG4_PART2_ASP 16 9216 2048 1152
19:14:35 T:139964276774656 NOTICE: ------------------------------------
19:14:35 T:139964276774656 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_NOISE_REDUCTION
19:14:35 T:139964276774656 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_SHARPNESS
19:14:35 T:139964276774656 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 (VDPAU acceleration)
19:14:35 T:139964285167360 NOTICE: Thread CVideoReferenceClock start, auto delete: false
19:14:36 T:139964276774656 NOTICE: Creating video thread
19:14:36 T:139963235432192 NOTICE: Thread CDVDPlayerVideo start, auto delete: false
19:14:36 T:139964276774656 NOTICE: Opening audio stream: 1 source: 256
19:14:36 T:139964276774656 NOTICE: Finding audio codec for: 86020
19:14:36 T:139964276774656 NOTICE: Creating audio thread
19:14:36 T:139963235432192 NOTICE: running thread: video_thread
19:14:36 T:139963227039488 NOTICE: Thread CDVDPlayerAudio start, auto delete: false
19:14:36 T:139963227039488 NOTICE: running thread: CDVDPlayerAudio::Process()
19:14:36 T:139963227039488 NOTICE: Creating audio stream (codec id: 86020, channels: 6, sample rate: 48000, no pass-through)
19:14:36 T:139963235432192 NOTICE: (VDPAU) screenWidth:0 vidWidth:1920 surfaceWidth:1920
19:14:36 T:139963235432192 NOTICE: (VDPAU) screenHeight:0 vidHeight:1080 surfaceHeight:1088
19:14:36 T:139963235432192 NOTICE: Creating 1920x1080 pixmap
19:14:36 T:139963235432192 NOTICE: (VDPAU) Total Output Surfaces Available: 2 of a max (tmp: 2 const: 4)
19:14:36 T:139963235432192 NOTICE: (VDPAU) Creating the video mixer
19:14:36 T:139963235432192 NOTICE: fps: 23.976024, pwidth: 1920, pheight: 1080, dwidth: 1920, dheight: 1080
19:14:36 T:139963235432192 NOTICE: Display resolution ADJUST : HDMI-0: 1920x1080 @ 24.00Hz (21) (weight: 0.001)
19:14:36 T:139965115230144 NOTICE: CVDPAU::OnLostDevice event
19:14:36 T:139965115230144 NOTICE: (VDPAU) FiniVDPAUOutput
19:14:36 T:139965115230144 ERROR: GLX: Same window as before, refreshing context
19:14:36 T:139965115230144 NOTICE: Using GL_TEXTURE_2D
19:14:36 T:139965115230144 NOTICE: GL: Using VDPAU render method
19:14:36 T:139965115230144 NOTICE: GL: NPOT texture support detected
19:14:36 T:139965115230144 NOTICE: GL: Using GL_ARB_pixel_buffer_object
19:14:36 T:139965115230144 NOTICE: CVDPAU::OnResetDevice event
19:14:36 T:139963235432192 NOTICE: Attempting recovery
19:14:36 T:139963235432192 NOTICE: vdp_device = 0x00000001 vdp_st = 0x00000000
19:14:36 T:139963235432192 NOTICE: (VDPAU) screenWidth:1920 vidWidth:1920 surfaceWidth:1920
19:14:36 T:139963235432192 NOTICE: (VDPAU) screenHeight:1080 vidHeight:1080 surfaceHeight:1088
19:14:36 T:139963235432192 NOTICE: Creating 1920x1080 pixmap
19:14:36 T:139963235432192 NOTICE: (VDPAU) Total Output Surfaces Available: 2 of a max (tmp: 2 const: 4)
19:14:36 T:139963235432192 NOTICE: (VDPAU) Creating the video mixer
19:14:37 T:139965115230144 ERROR: CWinSystemX11::XErrorHandler: BadDrawable (invalid Pixmap or Window parameter), type:0, serial:12680, error_code:9, request_code:152 minor_code:8
19:14:57 T:139965115230144 NOTICE: CDVDPlayer::CloseFile()
19:14:57 T:139965115230144 NOTICE: DVDPlayer: waiting for threads to exit
19:14:57 T:139964276774656 NOTICE: CDVDPlayer::OnExit()
19:14:57 T:139964276774656 NOTICE: DVDPlayer: closing audio stream
19:14:57 T:139964276774656 NOTICE: Closing audio stream
19:14:57 T:139964276774656 NOTICE: Waiting for audio thread to exit
19:14:57 T:139963227039488 NOTICE: thread end: CDVDPlayerAudio::OnExit()
19:14:57 T:139964276774656 NOTICE: Closing audio device
19:14:57 T:139964276774656 NOTICE: Deleting audio codec
19:14:57 T:139964276774656 NOTICE: DVDPlayer: closing video stream
19:14:57 T:139964276774656 NOTICE: Closing video stream
19:14:57 T:139964276774656 NOTICE: waiting for video thread to exit
19:14:57 T:139963235432192 NOTICE: thread end: video_thread
19:14:57 T:139964276774656 NOTICE: deleting video codec
19:14:57 T:139964276774656 NOTICE: CDVDPlayer::OnExit() deleting demuxer
19:14:57 T:139964276774656 NOTICE: CDVDPlayer::OnExit() deleting input stream
19:14:57 T:139965115230144 NOTICE: DVDPlayer: finished waiting
19:14:57 T:139965115230144 NOTICE: (VDPAU) Close
19:14:57 T:139965115230144 NOTICE: (VDPAU) FiniVDPAUOutput
19:14:57 T:139965115230144 ERROR: GLX: Same window as before, refreshing context
19:14:58 T:139963387606784 NOTICE: Thread Background Loader start, auto delete: false
19:14:58 T:139965115230144 NOTICE: Previous line repeats 1 times.
19:14:58 T:139965115230144 NOTICE: CDVDPlayer::CloseFile()
19:14:58 T:139965115230144 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
19:14:58 T:139965115230144 NOTICE: DVDPlayer: waiting for threads to exit
19:14:58 T:139965115230144 NOTICE: DVDPlayer: finished waiting
19:15:09 T:139965115230144 NOTICE: DVDPlayer: Opening: smb://ANDI/xxx.mkv
19:15:09 T:139965115230144 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
19:15:09 T:139964285167360 NOTICE: Thread CDVDPlayer start, auto delete: false
19:15:09 T:139964285167360 NOTICE: Creating InputStream
19:15:10 T:139964285167360 NOTICE: Creating Demuxer
19:15:10 T:139964285167360 NOTICE: Opening video stream: 0 source: 256
19:15:10 T:139964285167360 NOTICE: Creating video codec with codec id: 28
19:15:10 T:139964285167360 NOTICE: CDVDVideoCodecFFmpeg::Open() Creating VDPAU(1920x1080, 28)
Here a debug log from a similar action (play, stop, play, crash)
19:21:23 T:139993780455360 NOTICE: DVDPlayer: Opening: smb://xxx.mkv
19:21:23 T:139993780455360 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
19:21:23 T:139993780455360 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
19:21:23 T:139993780455360 DEBUG: LinuxRendererGL: Cleaning up GL resources
19:21:23 T:139993780455360 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
19:21:23 T:139993780455360 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avutil-51-x86_64-linux.so)
19:21:23 T:139993780455360 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avutil-51-x86_64-linux.so
19:21:23 T:139993780455360 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/swscale-2-x86_64-linux.so)
19:21:23 T:139993780455360 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/swscale-2-x86_64-linux.so
19:21:23 T:139992949044992 NOTICE: Thread CDVDPlayer start, auto delete: false
19:21:23 T:139992949044992 NOTICE: Creating InputStream
19:21:23 T:139992949044992 DEBUG: CSmbFile::Open - opened xxx.mkv, fd=10001
19:21:23 T:139992949044992 DEBUG: ScanForExternalSubtitles: Searching for subtitles...
19:21:23 T:139992949044992 DEBUG: OpenDir - Using authentication url smb://ANDI/Filme
19:21:23 T:139993780455360 DEBUG: ------ Window Init (DialogBusy.xml) ------
19:21:24 T:139992949044992 DEBUG: ScanForExternalSubtitles: END (total time: 639 ms)
19:21:24 T:139992949044992 NOTICE: Creating Demuxer
19:21:24 T:139992949044992 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avcodec-53-x86_64-linux.so)
19:21:24 T:139992949044992 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avcodec-53-x86_64-linux.so
19:21:24 T:139992949044992 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avformat-53-x86_64-linux.so)
19:21:24 T:139992949044992 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avformat-53-x86_64-linux.so
19:21:24 T:139992949044992 DEBUG: Open - probing detected format [matroska,webm]
19:21:24 T:139992949044992 DEBUG: Open - avformat_find_stream_info starting
19:21:24 T:139992949044992 DEBUG: Open - av_find_stream_info finished
19:21:24 T:139992949044992 INFO: ffmpeg[A5FFB700]: Input #0, matroska,webm, from 'smb://ANDI/xxx.mkv':
19:21:24 T:139992949044992 INFO: ffmpeg[A5FFB700]: Duration: 01:27:57.02, start: 0.000000, bitrate: 13143 kb/s
19:21:24 T:139992949044992 INFO: ffmpeg[A5FFB700]: Stream #0:0(ger): Video: h264 (High), yuv420p, 1920x1080 [SAR 1:1 DAR 16:9], 23.98 fps, 23.98 tbr, 1k tbn, 47.95 tbc (default)
19:21:24 T:139992949044992 INFO: ffmpeg[A5FFB700]: Stream #0:1(ger): Audio: dts (DTS), 48000 Hz, 5.1(side), s16, 1536 kb/s (default)
19:21:24 T:139992949044992 INFO: ffmpeg[A5FFB700]: Metadata:
19:21:24 T:139992949044992 INFO: ffmpeg[A5FFB700]: title : German
19:21:24 T:139992949044992 INFO: ffmpeg[A5FFB700]: Stream #0:2(eng): Audio: dts (DTS), 48000 Hz, 5.1(side), s16, 1536 kb/s
19:21:24 T:139992949044992 INFO: ffmpeg[A5FFB700]: Metadata:
19:21:24 T:139992949044992 INFO: ffmpeg[A5FFB700]: title : English
19:21:24 T:139992949044992 NOTICE: Opening video stream: 0 source: 256
19:21:24 T:139992949044992 NOTICE: Creating video codec with codec id: 28
19:21:24 T:139992949044992 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:no OpenMax:no VDPAU:yes VAAPI:yes
19:21:24 T:139992949044992 DEBUG: FactoryCodec - Video: - Opening
19:21:24 T:139992949044992 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/swresample-0-x86_64-linux.so)
19:21:24 T:139992949044992 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/swresample-0-x86_64-linux.so
19:21:24 T:139992949044992 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avfilter-2-x86_64-linux.so)
19:21:24 T:139992949044992 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avfilter-2-x86_64-linux.so
19:21:24 T:139992949044992 NOTICE: CDVDVideoCodecFFmpeg::Open() Creating VDPAU(1920x1080, 28)
19:21:24 T:139992949044992 NOTICE: vdp_device = 0x00000001 vdp_st = 0x00000000
19:21:24 T:139992949044992 NOTICE: VDPAU Decoder capabilities:
19:21:24 T:139992949044992 NOTICE: name level macbs width height
19:21:24 T:139992949044992 NOTICE: ------------------------------------
19:21:24 T:139992949044992 NOTICE: MPEG1 16 9216 2048 1152
19:21:24 T:139992949044992 NOTICE: MPEG2_SIMPLE 16 9216 2048 1152
19:21:24 T:139992949044992 NOTICE: MPEG2_MAIN 16 9216 2048 1152
19:21:24 T:139992949044992 NOTICE: H264_BASELINE 16 9216 2048 1152
19:21:24 T:139992949044992 NOTICE: H264_MAIN 16 9216 2048 1152
19:21:24 T:139992949044992 NOTICE: H264_HIGH 16 9216 2048 1152
19:21:24 T:139992949044992 NOTICE: VC1_SIMPLE 16 9216 2048 1152
19:21:24 T:139992949044992 NOTICE: VC1_MAIN 16 9216 2048 1152
19:21:24 T:139992949044992 NOTICE: VC1_ADVANCED 16 9216 2048 1152
19:21:24 T:139992949044992 NOTICE: MPEG4_PART2_ASP 16 9216 2048 1152
19:21:24 T:139992949044992 NOTICE: ------------------------------------
19:21:24 T:139992949044992 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_NOISE_REDUCTION
19:21:24 T:139992949044992 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_SHARPNESS
19:21:24 T:139992949044992 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 (VDPAU acceleration)
19:21:24 T:139992949044992 DEBUG: FactoryCodec - Video: ff-h264_vdpau-vdpau - Opened
19:21:24 T:139992957437696 NOTICE: Thread CVideoReferenceClock start, auto delete: false
19:21:24 T:139992957437696 DEBUG: CVideoReferenceClock: Setting up GLX
19:21:24 T:139992957437696 DEBUG: CVideoReferenceClock: GL_VENDOR:x.org, not using nvidia-settings
19:21:24 T:139992957437696 DEBUG: CVideoReferenceClock: Using RandR for refreshrate detection
19:21:24 T:139992957437696 DEBUG: CVideoReferenceClock: Detected refreshrate: 50 hertz
19:21:24 T:139992949044992 NOTICE: Creating video thread
19:21:24 T:139991896848128 NOTICE: Thread CDVDPlayerVideo start, auto delete: false
19:21:24 T:139991896848128 NOTICE: running thread: video_thread
19:21:24 T:139992949044992 NOTICE: Opening audio stream: 1 source: 256
19:21:24 T:139992949044992 NOTICE: Finding audio codec for: 86020
19:21:24 T:139991896848128 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
19:21:24 T:139992949044992 DEBUG: FactoryCodec - Audio: FFmpeg - Opening
19:21:24 T:139992949044992 DEBUG: FactoryCodec - Audio: FFmpeg - Opened
19:21:24 T:139992949044992 NOTICE: Creating audio thread
19:21:24 T:139991888455424 NOTICE: Thread CDVDPlayerAudio start, auto delete: false
19:21:24 T:139991888455424 NOTICE: running thread: CDVDPlayerAudio::Process()
19:21:24 T:139992949044992 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb://ANDI/xxx.mkv
19:21:24 T:139992949044992 DEBUG: CDVDPlayer::SetCaching - caching state 3
19:21:24 T:139991896848128 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:21:25 T:139991888455424 NOTICE: Creating audio stream (codec id: 86020, channels: 6, sample rate: 48000, no pass-through)
19:21:25 T:139991888455424 INFO: CSoftAE::MakeStream - AE_FMT_S16NE, 48000, FL,FR,FC,LFE,SL,SR
19:21:25 T:139991896848128 NOTICE: (VDPAU) screenWidth:0 vidWidth:1920 surfaceWidth:1920
19:21:25 T:139991896848128 NOTICE: (VDPAU) screenHeight:0 vidHeight:1080 surfaceHeight:1088
19:21:25 T:139993780455360 DEBUG: CGUIInfoManager::SetCurrentMovie(smb://ANDI/xxx.mkv)
19:21:25 T:139993780455360 DEBUG: GetMovieId (smb://ANDI/xxx.mkv), query = select idMovie from movie where idFile=17
19:21:25 T:139993780455360 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
19:21:25 T:139993780455360 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
19:21:25 T:139993780455360 DEBUG: Building didl for object 'smb://ANDI/xxx.mkv'
19:21:25 T:139991896848128 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(167000.000000, 0)
19:21:25 T:139991896848128 NOTICE: Creating 1920x1080 pixmap
19:21:25 T:139993337231104 DEBUG: CSoftAE::Run - Sink restart flagged
19:21:25 T:139993337231104 INFO: CSoftAE::LoadSettings - Stereo upmix is enabled
19:21:25 T:139993337231104 INFO: CSoftAE::InternalOpenSink - sink incompatible, re-starting
19:21:25 T:139991896848128 DEBUG: Found 60 fbconfigs.
19:21:25 T:139991896848128 DEBUG: Using fbconfig index 0.
19:21:25 T:139991896848128 NOTICE: (VDPAU) Total Output Surfaces Available: 2 of a max (tmp: 2 const: 4)
19:21:25 T:139991896848128 NOTICE: (VDPAU) Creating the video mixer
19:21:25 T:139991896848128 NOTICE: fps: 23.976024, pwidth: 1920, pheight: 1080, dwidth: 1920, dheight: 1080
19:21:25 T:139991896848128 DEBUG: OutputPicture - change configuration. 1920x1080. framerate: 23.98. format: VDPAU
19:21:25 T:139991896848128 NOTICE: Display resolution ADJUST : HDMI-0: 1920x1080 @ 24.00Hz (21) (weight: 0.001)
19:21:25 T:139991896848128 DEBUG: CVideoReferenceClock: Clock speed 100.100000%
19:21:25 T:139993780455360 NOTICE: Using GL_TEXTURE_2D
19:21:25 T:139993780455360 NOTICE: GL: Using VDPAU render method
19:21:25 T:139993780455360 NOTICE: GL: NPOT texture support detected
19:21:25 T:139993780455360 NOTICE: GL: Using GL_ARB_pixel_buffer_object
19:21:25 T:139993780455360 DEBUG: Activating window ID: 12005
19:21:25 T:139993780455360 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
19:21:25 T:139993780455360 DEBUG: OnLostDevice - notify display change event
19:21:25 T:139993780455360 DEBUG: Flush - flushing renderer
19:21:25 T:139993780455360 NOTICE: CVDPAU::OnLostDevice event
19:21:25 T:139993780455360 DEBUG: GLX: Destroying glPixmap
19:21:25 T:139993780455360 DEBUG: GLX: Destroying XPixmap
19:21:25 T:139993780455360 NOTICE: (VDPAU) FiniVDPAUOutput
19:21:25 T:139993780455360 INFO: XRANDR: /usr/lib/xbmc/xbmc-xrandr --output HDMI-0 --mode 0x4a
19:21:25 T:139993337231104 INFO: CAESinkALSA::Initialize - Attempting to open device "@"
19:21:25 T:139993337231104 INFO: CAESinkALSA::Initialize - Opened device "surround51"
19:21:25 T:139993337231104 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
19:21:25 T:139993337231104 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE4
19:21:25 T:139993337231104 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600
19:21:25 T:139993337231104 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600
19:21:25 T:139993337231104 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms
19:21:25 T:139993337231104 DEBUG: CSoftAE::InternalOpenSink - ALSA Initialized:
19:21:25 T:139993337231104 DEBUG: Output Device : Default (Xonar DX Multichannel)
19:21:25 T:139993337231104 DEBUG: Sample Rate : 48000
19:21:25 T:139993337231104 DEBUG: Sample Format : AE_FMT_S24NE4
19:21:25 T:139993337231104 DEBUG: Channel Count : 6
19:21:25 T:139993337231104 DEBUG: Channel Layout: FL,FR,BL,BR,FC,LFE
19:21:25 T:139993337231104 DEBUG: Frames : 2400
19:21:25 T:139993337231104 DEBUG: Frame Samples : 14400
19:21:25 T:139993337231104 DEBUG: Frame Size : 24
19:21:25 T:139993337231104 DEBUG: CSoftAE::InternalOpenSink - Using speaker layout: 5.1
19:21:25 T:139993337231104 DEBUG: CSoftAE::InternalOpenSink - Internal Buffer Size: 57600
19:21:25 T:139993337231104 DEBUG: AERemap: Downmix normalization is disabled
19:21:25 T:139992949044992 DEBUG: Previous line repeats 4 times.
19:21:25 T:139992949044992 DEBUG: CDVDPlayer::SetCaching - caching state 0
19:21:25 T:139993337231104 DEBUG: AERemap: Downmix normalization is disabled
19:21:25 T:139993337231104 DEBUG: Previous line repeats 1 times.
19:21:25 T:139993337231104 DEBUG: CSoftAEStream::CSoftAEStream - Converting from AE_FMT_S16NE to AE_FMT_FLOAT
19:21:25 T:139991888455424 DEBUG: CDVDPlayerAudio:: synctype set to 2: resample
19:21:25 T:139991888455424 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:4139.147000, should be:99.419667, error:-4039.727333
19:21:25 T:139991888455424 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(10000.000000, 1)
19:21:25 T:139992949044992 DEBUG: CDVDPlayer::HandleMessages - player started 1
19:21:25 T:139993337231104 DEBUG: CSoftAE::Run - Sink restart flagged
19:21:25 T:139993337231104 INFO: CSoftAE::LoadSettings - Stereo upmix is enabled
19:21:25 T:139993337231104 INFO: CSoftAE::InternalOpenSink - keeping old sink with : AE_FMT_FLOAT, FL,FR,FC,BL,BR,LFE, 48000hz
19:21:25 T:139993337231104 DEBUG: CSoftAE::InternalOpenSink - Using speaker layout: 5.1
19:21:25 T:139993337231104 DEBUG: CSoftAE::InternalOpenSink - Internal Buffer Size: 57600
19:21:25 T:139992957437696 DEBUG: CVideoReferenceClock: detected 1 vblanks, missed 2, refreshrate might have changed
19:21:25 T:139993780455360 ERROR: GLX: Same window as before, refreshing context
19:21:25 T:139993780455360 INFO: GL: Maximum texture width: 16384
19:21:25 T:139993780455360 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
19:21:25 T:139993780455360 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
19:21:25 T:139993780455360 INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
19:21:25 T:139993780455360 NOTICE: Using GL_TEXTURE_2D
19:21:25 T:139991896848128 NOTICE: CVDPAU::Check waiting for display reset event
19:21:25 T:139993780455360 NOTICE: GL: Using VDPAU render method
19:21:25 T:139993780455360 NOTICE: GL: NPOT texture support detected
19:21:25 T:139993780455360 NOTICE: GL: Using GL_ARB_pixel_buffer_object
19:21:25 T:139993780455360 DEBUG: CheckDisplayEvents: Received RandR event 89
19:21:25 T:139993780455360 DEBUG: CheckDisplayEvents - notify display reset event
19:21:25 T:139993780455360 NOTICE: CVDPAU::OnResetDevice event
19:21:25 T:139991896848128 NOTICE: Attempting recovery
19:21:25 T:139991896848128 NOTICE: vdp_device = 0x00000001 vdp_st = 0x00000000
19:21:25 T:139991896848128 DEBUG: CDVDPlayerVideo - video decoder was flushed
19:21:25 T:139991896848128 DEBUG: CVDPAU::FFReleaseBuffer - ignoring invalid buffer
19:21:25 T:139991896848128 DEBUG: Previous line repeats 3 times.
19:21:25 T:139991896848128 NOTICE: (VDPAU) screenWidth:1920 vidWidth:1920 surfaceWidth:1920
19:21:25 T:139991896848128 NOTICE: (VDPAU) screenHeight:1080 vidHeight:1080 surfaceHeight:1088
19:21:25 T:139992949044992 DEBUG: CDVDPlayer::HandleMessages - player started 2
19:21:25 T:139991896848128 NOTICE: Creating 1920x1080 pixmap
19:21:25 T:139991896848128 DEBUG: Found 60 fbconfigs.
19:21:25 T:139991896848128 DEBUG: Using fbconfig index 0.
19:21:25 T:139991896848128 NOTICE: (VDPAU) Total Output Surfaces Available: 2 of a max (tmp: 2 const: 4)
19:21:25 T:139991896848128 NOTICE: (VDPAU) Creating the video mixer
19:21:25 T:139991896848128 DEBUG: CVideoReferenceClock: Clock speed 104.270833%
19:21:25 T:139993780455360 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
19:21:25 T:139993780455360 ERROR: CWinSystemX11::XErrorHandler: BadDrawable (invalid Pixmap or Window parameter), type:0, serial:19101, error_code:9, request_code:152 minor_code:8
19:21:26 T:139991888455424 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:597724.273333, should be:497666.666667, error:-100057.606667
19:21:26 T:139992957437696 DEBUG: CVideoReferenceClock: Received RandR event 89
19:21:27 T:139992957437696 DEBUG: CVideoReferenceClock: Detected refreshrate: 24 hertz
19:21:27 T:139991888455424 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:941996.125667, should be:782833.402667, error:-159162.723000
19:21:27 T:139991896848128 DEBUG: CVideoReferenceClock: Clock speed 100.100000%
19:21:27 T:139991888455424 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:1096842.754667, should be:1310812.627000, error:213969.872333
19:21:27 T:139991888455424 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:1410874.922000, should be:1543306.990333, error:132432.068333
19:21:27 T:139991888455424 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:1692760.338333, should be:1830683.329000, error:137922.990667
19:21:27 T:139991888455424 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:1931490.324000, should be:2062739.533667, error:131249.209667
19:21:27 T:139991888455424 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:2212469.159667, should be:2347929.139333, error:135459.979667
19:21:30 T:139991896848128 DEBUG: CPullupCorrection: detected pattern of length 1: 41708.33, frameduration: 41708.333333
19:21:31 T:139992932259584 DEBUG: webserver: request received for /jsonrpc
19:21:31 T:139992932259584 DEBUG: JSONRPC: Incoming request: [{"id":0,"jsonrpc":"2.0","method":"Player.GetActivePlayers"},{"id":1,"jsonrpc":"2.0","method":"Application.GetProperties","params":{"properties":["volume","muted"]}}]
19:21:31 T:139992932259584 DEBUG: JSONRPC: Calling player.getactiveplayers
19:21:31 T:139992932259584 DEBUG: JSONRPC: Calling application.getproperties
19:21:36 T:139992932259584 DEBUG: webserver: request received for /jsonrpc
19:21:36 T:139992932259584 DEBUG: JSONRPC: Incoming request: [{"id":2,"jsonrpc":"2.0","method":"Player.GetProperties","params":{"playerid":1,"properties":["audiostreams","canseek","currentaudiostream","currentsubtitle","partymode","playlistid","position","repeat","shuffled","speed","subtitleenabled","subtitles","time","totaltime","type"]}},{"id":3,"jsonrpc":"2.0","method":"Player.GetItem","params":{"playerid":1,"properties":["album","albumartist","artist","director","episode","fanart","file","genre","plot","rating","season","showtitle","studio","imdbnumber","tagline","thumbnail","title","track","writer","year","streamdetails","originaltitle"]}}]
19:21:36 T:139992932259584 DEBUG: JSONRPC: Calling player.getproperties
19:21:36 T:139992932259584 DEBUG: JSONRPC: Calling player.getitem
19:21:38 T:139993780455360 DEBUG: Keyboard: scancode: ae, sym: 00b2, unicode: 0000, modifier: 0
19:21:38 T:139993780455360 DEBUG: OnKey: stop (f0bc) pressed, action is Stop
19:21:38 T:139993780455360 NOTICE: CDVDPlayer::CloseFile()
19:21:38 T:139993780455360 NOTICE: DVDPlayer: waiting for threads to exit
19:21:38 T:139992949044992 NOTICE: CDVDPlayer::OnExit()
19:21:38 T:139992949044992 NOTICE: DVDPlayer: closing audio stream
19:21:38 T:139992949044992 NOTICE: Closing audio stream
19:21:38 T:139992949044992 NOTICE: Waiting for audio thread to exit
19:21:38 T:139991888455424 NOTICE: thread end: CDVDPlayerAudio::OnExit()
19:21:38 T:139991888455424 DEBUG: Thread CDVDPlayerAudio 139991888455424 terminating
19:21:38 T:139992949044992 NOTICE: Closing audio device
19:21:38 T:139992949044992 DEBUG: CSoftAEStream::~CSoftAEStream - Destructed
19:21:38 T:139992949044992 NOTICE: Deleting audio codec
19:21:38 T:139992949044992 NOTICE: DVDPlayer: closing video stream
19:21:38 T:139992949044992 NOTICE: Closing video stream
19:21:38 T:139992949044992 NOTICE: waiting for video thread to exit
19:21:38 T:139993337231104 DEBUG: CSoftAE::Run - Sink restart flagged
19:21:38 T:139993337231104 INFO: CSoftAE::LoadSettings - Stereo upmix is enabled
19:21:38 T:139993337231104 INFO: CSoftAE::InternalOpenSink - sink incompatible, re-starting
19:21:39 T:139991896848128 NOTICE: thread end: video_thread
19:21:39 T:139991896848128 DEBUG: Thread CDVDPlayerVideo 139991896848128 terminating
19:21:39 T:139992949044992 NOTICE: deleting video codec
19:21:39 T:139992949044992 NOTICE: CDVDPlayer::OnExit() deleting demuxer
19:21:39 T:139992949044992 NOTICE: CDVDPlayer::OnExit() deleting input stream
19:21:39 T:139992949044992 DEBUG: CSmbFile::Close closing fd 10001
19:21:39 T:139992949044992 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
19:21:39 T:139992949044992 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
19:21:39 T:139992949044992 DEBUG: Thread CDVDPlayer 139992949044992 terminating
19:21:39 T:139993780455360 NOTICE: DVDPlayer: finished waiting
19:21:39 T:139993780455360 DEBUG: LinuxRendererGL: Cleaning up GL resources
19:21:39 T:139993780455360 NOTICE: (VDPAU) Close
19:21:39 T:139993780455360 DEBUG: GLX: Destroying glPixmap
19:21:39 T:139993780455360 DEBUG: GLX: Destroying XPixmap
19:21:39 T:139993780455360 NOTICE: (VDPAU) FiniVDPAUOutput
19:21:39 T:139993780455360 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
19:21:39 T:139993780455360 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
19:21:39 T:139993780455360 DEBUG: OnLostDevice - notify display change event
19:21:39 T:139993780455360 DEBUG: Flush - flushing renderer
19:21:39 T:139993780455360 INFO: XRANDR: /usr/lib/xbmc/xbmc-xrandr --output HDMI-0 --mode 0x47
19:21:39 T:139993337231104 INFO: CAESinkALSA::Initialize - Attempting to open device "@"
19:21:39 T:139993337231104 INFO: CAESinkALSA::Initialize - Opened device "surround51"
19:21:39 T:139993337231104 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
19:21:39 T:139993337231104 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE4
19:21:39 T:139993337231104 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820
19:21:39 T:139993337231104 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2204, bufferSize 8820
19:21:39 T:139993337231104 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms
19:21:39 T:139993337231104 DEBUG: CSoftAE::InternalOpenSink - ALSA Initialized:
19:21:39 T:139993337231104 DEBUG: Output Device : Default (Xonar DX Multichannel)
19:21:39 T:139993337231104 DEBUG: Sample Rate : 44100
19:21:39 T:139993337231104 DEBUG: Sample Format : AE_FMT_S24NE4
19:21:39 T:139993337231104 DEBUG: Channel Count : 6
19:21:39 T:139993337231104 DEBUG: Channel Layout: FL,FR,BL,BR,FC,LFE
19:21:39 T:139993337231104 DEBUG: Frames : 2204
19:21:39 T:139993337231104 DEBUG: Frame Samples : 13224
19:21:39 T:139993337231104 DEBUG: Frame Size : 24
19:21:39 T:139993337231104 DEBUG: CSoftAE::InternalOpenSink - Using speaker layout: 5.1
19:21:39 T:139993337231104 DEBUG: CSoftAE::InternalOpenSink - Internal Buffer Size: 52896
19:21:39 T:139993337231104 DEBUG: AERemap: Downmix normalization is disabled
19:21:39 T:139993780455360 DEBUG: Previous line repeats 5 times.
19:21:39 T:139993780455360 ERROR: GLX: Same window as before, refreshing context
19:21:39 T:139993780455360 INFO: GL: Maximum texture width: 16384
19:21:39 T:139993780455360 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
19:21:39 T:139993780455360 DEBUG: CheckDisplayEvents: Received RandR event 89
19:21:39 T:139993780455360 DEBUG: CheckDisplayEvents - notify display reset event
19:21:39 T:139993780455360 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
19:21:39 T:139993780455360 DEBUG: ------ Window Init (MyVideoNav.xml) ------
19:21:39 T:139993780455360 DEBUG: CGUIMediaWindow::GetDirectory (videodb://1/2/)
19:21:39 T:139993780455360 DEBUG: ParentPath = [videodb://1/2/]
19:21:39 T:139993780455360 DEBUG: RunQuery took 8 ms for 357 items query: select * from movieview
19:21:39 T:139992126953216 NOTICE: Thread Background Loader start, auto delete: false
19:21:39 T:139992126953216 DEBUG: Thread Background Loader 139992126953216 terminating
19:21:39 T:139992126953216 NOTICE: Thread Background Loader start, auto delete: false
19:21:39 T:139992126953216 DEBUG: Thread Background Loader 139992126953216 terminating
19:21:39 T:139993780455360 DEBUG: ExecuteXBMCAction : Translating ClearProperty(BrowseActors,home)
19:21:39 T:139993780455360 DEBUG: ExecuteXBMCAction : To ClearProperty(BrowseActors,home)
19:21:39 T:139993780455360 NOTICE: CDVDPlayer::CloseFile()
19:21:39 T:139993780455360 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
19:21:39 T:139993780455360 NOTICE: DVDPlayer: waiting for threads to exit
19:21:39 T:139993780455360 NOTICE: DVDPlayer: finished waiting
19:21:39 T:139993780455360 DEBUG: LinuxRendererGL: Cleaning up GL resources
19:21:39 T:139992957437696 DEBUG: CVideoReferenceClock: Cleaning up GLX
19:21:39 T:139992957437696 DEBUG: Thread CVideoReferenceClock 139992957437696 terminating
19:21:39 T:139991938168576 DEBUG: DoWork - Saving file state for video item smb://ANDI/xxx.mkv
19:21:39 T:139991938168576 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc
19:21:39 T:139991938168576 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate
19:21:40 T:139993069709056 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "VideoLibrary.GetMovies", "params": {"properties": ["title", "originaltitle", "playcount", "year", "genre", "studio", "country", "tagline", "plot", "runtime", "file", "plotoutline", "lastplayed", "trailer", "rating", "resume", "art", "streamdetails", "mpaa", "director"], "limits": {"end": 20}, "sort": {"order": "descending", "method": "lastplayed"}, "filter": {"field": "inprogress", "operator": "true", "value": ""}}}
19:21:40 T:139993069709056 DEBUG: JSONRPC: Calling videolibrary.getmovies
19:21:40 T:139993069709056 DEBUG: RunQuery took 1 ms for 1 items query: select * from movieview WHERE (movieview.idFile IN (select idFile from bookmark where type = 1))
19:21:40 T:139993069709056 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "VideoLibrary.GetMovies", "params": {"properties": ["title", "originaltitle", "playcount", "year", "genre", "studio", "country", "tagline", "plot", "runtime", "file", "plotoutline", "lastplayed", "trailer", "rating", "resume", "art", "streamdetails", "mpaa", "director"], "limits": {"end": 20}, "sort": {"order": "descending", "method": "dateadded"}, "filter": {"field": "playcount", "operator": "is", "value": "0"}}}
19:21:40 T:139993069709056 DEBUG: JSONRPC: Calling videolibrary.getmovies
19:21:40 T:139993069709056 DEBUG: RunQuery took 9 ms for 183 items query: select * from movieview WHERE ((movieview.playCount IS NULL OR movieview.playCount = 0))
19:21:43 T:139993780455360 DEBUG: Keyboard: scancode: 24, sym: 000d, unicode: 000d, modifier: 0
19:21:43 T:139993780455360 DEBUG: OnKey: return (f00d) pressed, action is Select
19:21:43 T:139993780455360 DEBUG: OnPlayMedia smb://ANDI/xxx.mkv
19:21:43 T:139993780455360 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
19:21:43 T:139993780455360 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
19:21:43 T:139993780455360 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
19:21:43 T:139993780455360 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
19:21:43 T:139993780455360 DEBUG: CPlayerCoreFactory::GetPlayers(smb://ANDI/xxx.mkv)
19:21:43 T:139993780455360 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
19:21:43 T:139993780455360 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
19:21:43 T:139993780455360 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
19:21:43 T:139993780455360 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp
19:21:43 T:139993780455360 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
19:21:43 T:139993780455360 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
19:21:43 T:139993780455360 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
19:21:43 T:139993780455360 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
19:21:43 T:139993780455360 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
19:21:43 T:139993780455360 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
19:21:43 T:139993780455360 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
19:21:43 T:139993780455360 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
19:21:43 T:139993780455360 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
19:21:43 T:139993780455360 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
19:21:43 T:139993780455360 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
19:21:43 T:139993780455360 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
19:21:43 T:139993780455360 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
19:21:43 T:139993780455360 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
19:21:43 T:139993780455360 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
19:21:43 T:139993780455360 NOTICE: DVDPlayer: Opening: smb://ANDI/xxx.mkv
19:21:43 T:139993780455360 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
19:21:43 T:139993780455360 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
19:21:43 T:139993780455360 DEBUG: LinuxRendererGL: Cleaning up GL resources
19:21:43 T:139993780455360 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
19:21:43 T:139992957437696 NOTICE: Thread CDVDPlayer start, auto delete: false
19:21:43 T:139992957437696 NOTICE: Creating InputStream
19:21:43 T:139992957437696 DEBUG: CSmbFile::Open - opened xxx.mkv, fd=10001
19:21:43 T:139992957437696 DEBUG: ScanForExternalSubtitles: Searching for subtitles...
19:21:43 T:139992957437696 DEBUG: OpenDir - Using authentication url smb://ANDI/Filme
19:21:43 T:139993780455360 DEBUG: ------ Window Init (DialogBusy.xml) ------
19:21:43 T:139992957437696 DEBUG: ScanForExternalSubtitles: END (total time: 648 ms)
19:21:43 T:139992957437696 NOTICE: Creating Demuxer
19:21:43 T:139992957437696 DEBUG: Open - probing detected format [matroska,webm]
19:21:43 T:139992957437696 DEBUG: Open - avformat_find_stream_info starting
19:21:44 T:139992957437696 DEBUG: Open - av_find_stream_info finished
19:21:44 T:139992957437696 INFO: ffmpeg[A67FC700]: Input #0, matroska,webm, from 'smb://ANDI/xxx.mkv':
19:21:44 T:139992957437696 INFO: ffmpeg[A67FC700]: Duration: 01:27:57.02, start: 0.000000, bitrate: 13143 kb/s
19:21:44 T:139992957437696 INFO: ffmpeg[A67FC700]: Stream #0:0(ger): Video: h264 (High), yuv420p, 1920x1080 [SAR 1:1 DAR 16:9], 23.98 fps, 23.98 tbr, 1k tbn, 47.95 tbc (default)
19:21:44 T:139992957437696 INFO: ffmpeg[A67FC700]: Stream #0:1(ger): Audio: dts (DTS), 48000 Hz, 5.1(side), s16, 1536 kb/s (default)
19:21:44 T:139992957437696 INFO: ffmpeg[A67FC700]: Metadata:
19:21:44 T:139992957437696 INFO: ffmpeg[A67FC700]: title : German
19:21:44 T:139992957437696 INFO: ffmpeg[A67FC700]: Stream #0:2(eng): Audio: dts (DTS), 48000 Hz, 5.1(side), s16, 1536 kb/s
19:21:44 T:139992957437696 INFO: ffmpeg[A67FC700]: Metadata:
19:21:44 T:139992957437696 INFO: ffmpeg[A67FC700]: title : English
19:21:44 T:139992957437696 NOTICE: Opening video stream: 0 source: 256
19:21:44 T:139992957437696 NOTICE: Creating video codec with codec id: 28
19:21:44 T:139992957437696 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:no OpenMax:no VDPAU:yes VAAPI:yes
19:21:44 T:139992957437696 DEBUG: FactoryCodec - Video: - Opening
19:21:44 T:139992957437696 NOTICE: CDVDVideoCodecFFmpeg::Open() Creating VDPAU(1920x1080, 28)
19:21:46 T:139992932259584 DEBUG: webserver: request received for /jsonrpc
19:21:46 T:139992932259584 DEBUG: JSONRPC: Incoming request: [{"id":0,"jsonrpc":"2.0","method":"Player.GetActivePlayers"},{"id":1,"jsonrpc":"2.0","method":"Application.GetProperties","params":{"properties":["volume","muted"]}}]
19:21:46 T:139992932259584 DEBUG: JSONRPC: Calling player.getactiveplayers
19:21:46 T:139992932259584 DEBUG: JSONRPC: Calling application.getproperties
19:21:47 T:139992823219968 DEBUG: webserver: request received for /image/image%3A%2F%2Fhttp%253a%252f%252fcf2.imgobject.com%252ft%252fp%252foriginal%252frbrQPjl2n4gTPsPXim9ckpRuycg.jpg%2F
19:21:48 T:139992823219968 DEBUG: webserver: request received for /jsonrpc
19:21:48 T:139992823219968 DEBUG: JSONRPC: Incoming request: [{"id":0,"jsonrpc":"2.0","method":"Player.GetActivePlayers"},{"id":1,"jsonrpc":"2.0","method":"Application.GetProperties","params":{"properties":["volume","muted"]}}]
19:21:48 T:139992823219968 DEBUG: JSONRPC: Calling player.getactiveplayers
19:21:48 T:139992823219968 DEBUG: JSONRPC: Calling application.getproperties
19:21:48 T:139992932259584 DEBUG: webserver: request received for /image/image%3A%2F%2Fhttp%253a%252f%252fcf2.imgobject.com%252ft%252fp%252foriginal%252frbrQPjl2n4gTPsPXim9ckpRuycg.jpg%2F
19:21:53 T:139993337231104 DEBUG: Previous line repeats 2 times.
19:21:53 T:139993337231104 DEBUG: Suspended the Sink
Playing Live TV with xvdr (0.9.8-1, self compiled and packaged from github) fails every time with a freeze:
19:48:04 T:140440470034368 DEBUG: ------ Window Init (MyPVR.xml) ------
19:48:04 T:140440470034368 INFO: Loading skin file: MyPVR.xml, load type: LOAD_EVERY_TIME
19:48:04 T:140440470034368 DEBUG: CGUIMediaWindow::GetDirectory ()
19:48:04 T:140440470034368 DEBUG: ParentPath = []
19:48:04 T:140440470034368 DEBUG: CGUIWindowPVRCommon - OnMessageFocus - focus set to window 'tv'
19:48:04 T:140440470034368 DEBUG: CGUIWindowPVRChannels - UpdateData - update window 'tv'. set view to 11
19:48:04 T:140440470034368 DEBUG: CGUIMediaWindow::GetDirectory (pvr://channels/tv/Alle TV-Kanäle/)
19:48:04 T:140440470034368 DEBUG: ParentPath = []
19:48:04 T:140438627669760 DEBUG: CPVRDirectory::GetDirectory(pvr://channels/tv/Alle TV-Kanäle)
19:48:04 T:140439491426048 NOTICE: Thread PVR Channel Window start, auto delete: false
19:48:04 T:140440470034368 DEBUG: CGUIWindowPVRCommon - OnMessageFocus - focus set to window 'tv'
19:48:05 T:140440470034368 DEBUG: Previous line repeats 1 times.
19:48:05 T:140440470034368 DEBUG: Keyboard: scancode: 24, sym: 000d, unicode: 000d, modifier: 0
19:48:05 T:140440470034368 DEBUG: OnKey: return (f00d) pressed, action is Select
19:48:06 T:140440470034368 DEBUG: CPlayerCoreFactory::GetPlayers(pvr://channels/tv/Alle TV-Kanäle/0.pvr)
19:48:06 T:140440470034368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
19:48:06 T:140440470034368 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
19:48:06 T:140440470034368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
19:48:06 T:140440470034368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp
19:48:06 T:140440470034368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
19:48:06 T:140440470034368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
19:48:06 T:140440470034368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
19:48:06 T:140440470034368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
19:48:06 T:140440470034368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
19:48:06 T:140440470034368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
19:48:06 T:140440470034368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
19:48:06 T:140440470034368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
19:48:06 T:140440470034368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
19:48:06 T:140440470034368 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
19:48:06 T:140440470034368 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
19:48:06 T:140440470034368 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
19:48:06 T:140440470034368 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
19:48:06 T:140440470034368 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
19:48:06 T:140440470034368 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
19:48:06 T:140440470034368 NOTICE: DVDPlayer: Opening: pvr://channels/tv/Alle TV-Kanäle/0.pvr
19:48:06 T:140440470034368 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
19:48:06 T:140440470034368 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
19:48:06 T:140440470034368 DEBUG: LinuxRendererGL: Cleaning up GL resources
19:48:06 T:140440470034368 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
19:48:06 T:140440470034368 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avutil-51-x86_64-linux.so)
19:48:06 T:140440470034368 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avutil-51-x86_64-linux.so
19:48:06 T:140440470034368 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/swscale-2-x86_64-linux.so)
19:48:06 T:140440470034368 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/swscale-2-x86_64-linux.so
19:48:06 T:140438879053568 NOTICE: Thread CDVDPlayer start, auto delete: false
19:48:06 T:140438879053568 NOTICE: Creating InputStream
19:48:06 T:140438879053568 DEBUG: PVRManager - OpenLiveStream - opening live stream on channel 'Das Erste HD'
19:48:06 T:140438879053568 DEBUG: opening live stream for channel 'Das Erste HD'
19:48:06 T:140440470034368 DEBUG: ------ Window Init (DialogBusy.xml) ------
19:48:06 T:140438879053568 DEBUG: AddOnLog: VDR XVDR Client: Possible leak caused by workaround in GetLanguageCode
19:48:06 T:140438879053568 INFO: AddOnLog: VDR XVDR Client: Logged in at '1377798483+7200' to 'VDR-XVDR Server' Version: '0.9.9' with protocol version '4'
19:48:06 T:140438879053568 INFO: AddOnLog: VDR XVDR Client: Preferred Audio Language: deu
19:48:06 T:140438879053568 DEBUG: AddOnLog: VDR XVDR Client: changing to channel 1640430212 (priority 50)
19:48:06 T:140438879053568 INFO: AddOnLog: VDR XVDR Client: sucessfully switched channel
19:48:06 T:140438879053568 DEBUG: PVRFile - Open - playback has started on filename pvr://channels/tv/Alle TV-Kanäle/0.pvr
19:48:06 T:140438879053568 DEBUG: CDVDInputStreamPVRManager::Open - stream opened: pvr://channels/tv/Alle TV-Kanäle/0.pvr
19:48:06 T:140438879053568 NOTICE: Creating Demuxer
19:48:06 T:140438879053568 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avcodec-53-x86_64-linux.so)
19:48:06 T:140438879053568 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avcodec-53-x86_64-linux.so
19:48:06 T:140438879053568 DEBUG: CDVDPlayer::SetCaching - caching state 2
19:48:06 T:140438879053568 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED
19:48:06 T:140438879053568 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED
19:48:06 T:140440470034368 DEBUG: CGUIInfoManager::SetCurrentMovie(pvr://channels/tv/Alle TV-Kanäle/0.pvr)
19:48:06 T:140440470034368 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
19:48:06 T:140440470034368 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
19:48:06 T:140440470034368 DEBUG: Building didl for object 'pvr://channels/tv/Alle TV-Kanäle/0.pvr'
19:48:06 T:140440470034368 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
19:48:06 T:140438879053568 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 0:5101 with codec_id 28
19:48:06 T:140438879053568 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 1:5106 with codec_id 86019
19:48:06 T:140438879053568 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 2:5102 with codec_id 86016
19:48:06 T:140438879053568 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 3:5103 with codec_id 86016
19:48:06 T:140438879053568 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 4:5105 with codec_id 94209
19:48:06 T:140438879053568 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 5:5104 with codec_id 94215
19:48:06 T:140438879053568 NOTICE: Opening video stream: 0 source: 256
19:48:06 T:140438879053568 NOTICE: Creating video codec with codec id: 28
19:48:06 T:140438879053568 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:no OpenMax:no VDPAU:yes VAAPI:yes
19:48:06 T:140438879053568 DEBUG: FactoryCodec - Video: - Opening
19:48:06 T:140438879053568 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/swresample-0-x86_64-linux.so)
19:48:06 T:140438879053568 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/swresample-0-x86_64-linux.so
19:48:06 T:140438879053568 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avformat-53-x86_64-linux.so)
19:48:06 T:140438879053568 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avformat-53-x86_64-linux.so
19:48:06 T:140438879053568 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avfilter-2-x86_64-linux.so)
19:48:06 T:140438879053568 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avfilter-2-x86_64-linux.so
19:48:06 T:140438879053568 NOTICE: CDVDVideoCodecFFmpeg::Open() Creating VDPAU(1280x720, 28)
19:48:16 T:140440026806016 DEBUG: Suspended the Sink
With Hardware Acceleration disabled in XBMC none of the problems described above occurs.
So does anybody here know how to approach this problem? I'd really like to use Arch on the HTPC. Is this a problem with Arch or shall I look for the cause more on the XBMC side?
Last edited by And1G (2013-08-29 18:05:38)
Offline
OK, for everyone who is interested, this problem is most likely caused by XBMC relying on the OpenGL extension GL_NV_vdpau_interop which is not available with the Radeon driver.
I'll go over to the XBMC forums with the issue, I hope they can help.
Offline
OK, for everyone who is interested, this problem is most likely caused by XBMC relying on the OpenGL extension GL_NV_vdpau_interop which is not available with the Radeon driver.
I'll go over to the XBMC forums with the issue, I hope they can help.
I followed your from the heise newsticker to here, as you have some fully wrong information.
xbmc implements as the first at all the GL vdpau interop. Which is used to use surfaces in both the decoder, vdpau and the presenter OpenGL. But - we still have all the "pixmap code" in place. So on Radeon vdpau, where this interop is not supported, we render into a pixmap and transfer that result afterwards back to OpenGL.
This works perfectly fine with e.g. mplayer - as the pixmap is the finished result, no need to transfer it any further. Xbmc is something special, cause we are fully 3D - radeon driver (be it GL part or whatever) is damn slow here, cause performance currently just sucks when rendering to pixmap and from there back to OpenGL.
We are in contact with all the AMD OSS devs to find a solution here.
Btw. nvidia ION-2 is fast enough for 1080p50 _with_ the pixmap approach, so you can imagine, that we currently search the issue somewhere else - especially in the AMD drivers.
Edit: On my current radeon vdpau oss tests with xbmc - mesa fills up with memory, leaks and dies after 20 minutes .... not a good situation to start anything.
Last edited by fritsch (2013-09-17 15:20:49)
Offline
I'm responding because I have the complete opposite experience.
Today, with kernel 3.12, everything, including HDMI Audio works.
My GPU is an AMD HD6450 (passively cooled).
The packages that bring movie magic to my screen:
mesa 9.2.3-1
mesa-libgl 9.2.3-1
ati-dri 9.2.3-1
xbmc 12.2-6
This is important untill xbmc autodetects this: add the following to /var/lib/xbmc/.bashrc:
export LIBVA_DRIVER_NAME=vdpau
export VDPAU_DRIVER=r600
No other configuration files were edited!
The settings in XBMC:
System->Settings->Video->Playback:
render method: automatic
VDPAU: enabled
VAAPI: disabled
System->Settings->System->Audio Hardware:
audio output: Digital
Speakers: 5.1
...
Device for Audio output: HD-Audio Generic, HDMI
Device for Audio pass thru: HD-Audio Generic, HDMI
I tested a DTS movie (audio pass thru) and the Radio plugin (non-pass thru audio stream) and both work.
With this, my Core 2 Duo E8400 3GHz remains under 5% under 1080p playback.
To everyone that made this possible. Thank you. I mean it. This is awesome.
**EDIT**: note that my advancedsettings.xml screwed this up today. Starting any kind of video playback would freeze XBMC. I removed the settings file, and everything worked again. It contained 0 for nofliptimeout and 3 for algorithmdirtyregions.
Last edited by rubenvb (2013-11-16 21:56:58)
Offline
I had this issue with my APU Radeon HD 7480D when i installed the free driver. This went away when I used the proprietary driver and the video is awesome.
I suggest you go this route instead of wating your time with the free driver.
Offline