Video stops when playing Matroska Files
Wagenius
Newbie
Posts: 5
#1
2011-06-29, 18:59
Hi!

I have this problem that when I try to play certain video files (it seems to be limited to MKV files) It will play for a while, and then suddenly the video will freeze for about 30 seconds before it plays about one second of video and dumps me back to the dashboard (videos, movies or TV shows depending on where I started from)

I am using the Dharma on a mini mac running windows 7. I have tried running the videos on other platforms from the same location (my laptop. my home computer, my ipad using air video) with no such problems. It seems to be restricted to my XBMC suite.

The problem also seems to be more persistent with some files than others.

Any hints? YOu can see the log part 1 here:
Code:
18:41:57 T:3184 M:2096406528   DEBUG: CDVDPlayer::SetCaching - caching state 2
18:41:57 T:2188 M:2096795648   DEBUG: CAnnouncementManager - Announcement: PlaybackStarted from xbmc
18:41:57 T:2188 M:2096787456   DEBUG: CGUIInfoManager::SetCurrentMovie(M:\Videos\Movies\mymovie.mkv)
18:41:57 T:3528 M:2096787456  NOTICE: running thread: video_thread
18:41:57 T:3528 M:2097659904   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
18:41:57 T:3536 M:2097659904   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(1363445000.000000, 0)
18:41:57 T:3528 M:2097659904   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(1363403000.000000, 1)
18:41:57 T:3536 M:2097631232    INFO:   msg: Stream with high frequencies VQ coding
18:41:57 T:3536 M:2097586176  NOTICE: Creating audio device with codec id: 86021, channels: 5, sample rate: 48000, no pass-through
18:41:57 T:3536 M:2097586176    INFO: AudioRendererFactory:  not a explicit device, trying to autodetect.
18:41:57 T:3536 M:2097586176    INFO: CPCMRemap: Configured speaker layout: 2.0
18:41:57 T:3536 M:2097586176    INFO: CPCMRemap: I channel map: CE,FL,FR,SL,SR
18:41:57 T:3536 M:2097586176   DEBUG: CPCMRemap: Forcing side channel map to back channels
18:41:57 T:3536 M:2097586176    INFO: CPCMRemap: O channel map: FL,FR
18:41:57 T:3536 M:2097586176   DEBUG: CPCMRemap: Downmix normalization is disabled
18:41:57 T:3536 M:2097586176   DEBUG: CPCMRemap: FL = CE(0.707107) FL(1.000000) BL(1.000000)
18:41:57 T:3536 M:2097586176   DEBUG: CPCMRemap: FR = CE(0.707107) FR(1.000000) BR(1.000000)
18:41:57 T:3536 M:2097586176   DEBUG: CWin32DirectSound::Initialize: Packet Size = 12384. Avg Bytes Per Second = 192000.
18:41:57 T:3536 M:2097586176   DEBUG: CWin32DirectSound::Initialize: secondary buffer created
18:41:57 T:3536 M:2097586176   DEBUG: CWin32DirectSound::Initialize - Channels: 5 - SampleRate: 48000 - SampleBit: 16 - Resample false - IsMusic false - IsPassthrough false - audioDevice: Default
18:41:57 T:3536 M:2097586176   DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback
18:41:57 T:3528 M:2097586176   DEBUG: DXVA - supports 'MPEG2 IDCT'
18:41:57 T:3528 M:2097586176   DEBUG: DXVA - supports 'MPEG2 VLD'
18:41:57 T:3528 M:2097586176   DEBUG: DXVA - supports 'VC-1 VLD'
18:41:57 T:3528 M:2097586176   DEBUG: DXVA - supports 'VC-1 IDCT'
18:41:57 T:3528 M:2097586176   DEBUG: DXVA - supports 'Windows Media Video 9 IDCT'
18:41:57 T:3528 M:2097586176   DEBUG: DXVA - supports 32FCFE3F-de46-4a49-861b-ac71110649d5
18:41:57 T:3528 M:2097586176   DEBUG: DXVA - supports 'H.264 VLD, no FGT'
18:41:57 T:3528 M:2097586176   DEBUG: DXVA - supports 9947EC6F-689b-11dc-a320-0019dbbc4184
18:41:57 T:3528 M:2097586176   DEBUG: DXVA - supports B194EB52-19a0-41f0-b754-cc244ac1cb20
18:41:57 T:3528 M:2097586176   DEBUG: DXVA - trying 'H.264 VLD, no FGT'
18:41:57 T:3184 M:2097586176   DEBUG: CDVDPlayer::HandleMessages - player started 1
18:41:57 T:3528 M:2097586176   DEBUG: DXVA - source requires 10 references
18:41:57 T:3528 M:2097586176   DEBUG: DXVA - bitstream type 1
18:41:57 T:3528 M:2097586176   DEBUG: DXVA - bitstream type 2
18:41:57 T:3528 M:2097586176   DEBUG: DXVA - processor found 6CB69578-7617-4637-91e5-1c02db810285
18:41:57 T:3528 M:2097586176   DEBUG: DXVA - processor found F9F19DA5-3b09-4b2f-9d89-c64753e3eaab
18:41:57 T:3528 M:2097586176   DEBUG: DXVA - processor found 5A54A0C9-c7ec-4bd9-8ede-f3c75dc4393b
18:41:57 T:3528 M:2097586176   DEBUG: DXVA - processor found 335AA36E-7884-43a4-9c91-7f87faf3e37e
18:41:57 T:3528 M:2097573888   DEBUG: DXVA - processor selected 5A54A0C9-c7ec-4bd9-8ede-f3c75dc4393b
18:41:57 T:3528 M:2097573888   DEBUG: DXVA - processor requires 0 past frames and 0 future frames
18:41:57 T:3528 M:2097573888   DEBUG: DXVA - allocating 15 surfaces
18:41:57 T:3528 M:2095316992    INFO: ffmpeg[DC8]: [h264] Cannot parallelize deblocking type 1, decoding such frames in sequential order
18:41:57 T:3528 M:2072494080  NOTICE:  fps: 23.976044, pwidth: 1280, pheight: 544, dwidth: 1280, dheight: 544
18:41:57 T:3528 M:2072494080   DEBUG: CDVDPlayerVideo::OutputPicture - change configuration. 1280x544. framerate: 23.98. format: DXVA
Find
Reply
Wagenius
Newbie
Posts: 5
#2
2011-06-29, 19:02
And the log part 2 here:
Code:
18:41:57 T:3528 M:2072494080  NOTICE: Display resolution DESKTOP : 1920x1080 @ 60.00 - Full Screen (12)
18:41:57 T:3528 M:2072494080   DEBUG: CWinRenderer::SelectRenderMethod: Selected render method 3: DXVA
18:41:57 T:2188 M:2072367104   DEBUG: Activating window ID: 12005
18:41:57 T:2188 M:2072367104   DEBUG: Checking if window ID 12005 is locked.
18:41:57 T:2188 M:2072367104   DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
18:41:57 T:2188 M:2072256512   DEBUG: ------ Window Init (VideoFullScreen.xml) ------
18:41:57 T:2188 M:2072256512    INFO: Loading skin file: VideoFullScreen.xml
18:41:57 T:3184 M:2068770816   DEBUG: CDVDPlayer::HandleMessages - player started 2
18:41:57 T:3184 M:2068770816   DEBUG: CDVDPlayer::SetCaching - caching state 3
18:41:57 T:3536 M:2068762624   DEBUG: CDVDPlayerAudio:: Discontinuity - was:1363358565.892836, should be:1363445000.000000, error:86434.107164
18:41:57 T:3184 M:2067681280   DEBUG: CDVDPlayer::SetCaching - caching state 0
18:41:57 T:3536 M:2066808832   DEBUG: CWin32DirectSound::CheckPlayStatus: Resuming Playback
18:41:57 T:2188 M:2065625088   DEBUG: created video buffer 0
18:41:57 T:2188 M:2065625088   DEBUG: created video buffer 1
18:41:57 T:2188 M:2066251776   DEBUG: ------ Window Deinit (DialogBusy.xml) ------
18:41:58 T:3536 M:2064306176   DEBUG: CDVDPlayerAudio:: Discontinuity - was:1364483717.359457, should be:1364465198.994893, error:-18518.364564
18:41:59 T:3528 M:2063994880   DEBUG: CPullupCorrection: detected pattern of length 24: 42000.00 42000.00 41000.00 42000.00 42000.00 42000.00 41000.00 42000.00 42000.00 41000.00 42000.00 42000.00 41000.00 42000.00 42000.00 42000.00 41000.00 42000.00 42000.00 41000.00 42000.00 42000.00 42000.00 41000.00
18:41:59 T:3536 M:2063994880   DEBUG: CDVDPlayerAudio:: Discontinuity - was:1365491103.140528, should be:1365433305.448384, error:-57797.692144
18:42:00 T:3536 M:2063970304 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
18:42:01 T:3528 M:2063974400 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
18:42:01 T:3528 M:2063986688    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 23.976044 fps
18:42:01 T:3528 M:2063986688   DEBUG: CPullupCorrection: pattern lost
18:42:06 T:2188 M:2063986688   DEBUG: SDLKeyboard: scancode: 56, sym: 308, unicode: 0, modifier: 0
18:42:06 T:2188 M:2063974400   DEBUG: CWinEventsWin32::WndProcWindow is active
18:42:06 T:3528 M:2051919872   DEBUG: CPullupCorrection: detected pattern of length 1: 41708.30
18:42:57 T:2832 M:2063134720  NOTICE: ES: Client  from 127.0.0.1 timed out
18:43:03 T:3184 M:2062438400   ERROR: ffmpeg[C70]: [matroska] Read error
18:43:03 T:3184 M:2062438400   DEBUG: CDVDPlayer::SetCaching - caching state 1
18:43:03 T:3184 M:2062438400   DEBUG: CDVDPlayer::HandleMessages - player started 1
18:43:03 T:3184 M:2062438400    INFO: ffmpeg[C70]: [matroska] Unknown entry 0xB7
18:43:03 T:3184 M:2062438400    INFO: ffmpeg[C70]: [matroska] Unknown entry 0xB5
18:43:03 T:3184 M:2062438400    INFO: ffmpeg[C70]: [matroska] Unknown entry 0xE1
18:43:03 T:3184 M:2062438400    INFO: ffmpeg[C70]: [matroska] Unknown entry 0xE2
18:43:03 T:3184 M:2062438400    INFO: ffmpeg[C70]: [matroska] Unknown entry 0x69AC
18:43:03 T:3184 M:2062438400    INFO: ffmpeg[C70]: [matroska] Unknown entry 0x1467FEFC
18:43:03 T:3184 M:2062438400    INFO: ffmpeg[C70]: [matroska] Unknown entry 0x1ECF7185
18:43:03 T:3184 M:2062438400    INFO: ffmpeg[C70]: [matroska] Unknown entry 0xFA
18:43:03 T:3184 M:2062438400    INFO: ffmpeg[C70]: [matroska] Unknown entry 0x83
18:43:03 T:3184 M:2062438400    INFO: ffmpeg[C70]: [matroska] Unknown entry 0x3CE8F8
18:43:03 T:3184 M:2062438400    INFO: ffmpeg[C70]: [matroska] Unknown entry 0xBC
18:43:03 T:3184 M:2062438400    INFO: ffmpeg[C70]: [matroska] Unknown entry 0x80
18:43:03 T:3184 M:2062438400    INFO: ffmpeg[C70]: [matroska] Unknown entry 0x50D9
18:43:03 T:3184 M:2062438400    INFO: ffmpeg[C70]: [matroska] Unknown entry 0x44A0
18:43:03 T:3184 M:2062438400    INFO: ffmpeg[C70]: [matroska] Unknown entry 0x3B0C77
18:43:03 T:3184 M:2062438400    INFO: ffmpeg[C70]: [matroska] Unknown entry 0x402D
18:43:03 T:3184 M:2062438400    INFO: ffmpeg[C70]: [matroska] Unknown entry 0x93
18:43:03 T:3184 M:2062438400    INFO: ffmpeg[C70]: [matroska] Unknown entry 0x6C7C
18:43:03 T:3184 M:2062438400   DEBUG: CDVDPlayer::SetCaching - caching state 0
18:43:03 T:3536 M:2062438400   DEBUG: CDVDPlayerAudio:: Discontinuity - was:1429439360.086313, should be:1367061008.099728, error:-62378351.986585
18:43:03 T:3536 M:2062438400   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_EOF
18:43:03 T:3536 M:2062438400   DEBUG: CDVDAudio::Drain - adding 15840 bytes of silence, buffer size: 15120, chunk size: 30960
18:43:03 T:3536 M:2062438400   DEBUG: CWin32DirectSound::CheckPlayStatus: Resuming Playback
18:43:03 T:3536 M:2062438400 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
18:43:03 T:3184 M:2062405632   DEBUG: CDVDPlayer::HandleMessages - player started 2
18:43:03 T:3184 M:2062405632  NOTICE: CDVDPlayer::OnExit()
18:43:03 T:3184 M:2062405632  NOTICE: DVDPlayer: eof, waiting for queues to empty
18:43:03 T:3184 M:2062405632  NOTICE: DVDPlayer: closing audio stream
18:43:03 T:3184 M:2062405632  NOTICE: Closing audio stream
18:43:03 T:3184 M:2062405632  NOTICE: CDVDMessageQueue(audio)::WaitUntilEmpty
18:43:03 T:3536 M:2062405632   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
18:43:03 T:3184 M:2062405632  NOTICE: Waiting for audio thread to exit
18:43:03 T:3536 M:2062405632   DEBUG: CDVDPlayerAudio::Process - Abort received, exiting thread
18:43:03 T:3536 M:2062405632  NOTICE: thread end: CDVDPlayerAudio::OnExit()
18:43:03 T:3536 M:2062405632   DEBUG: Thread 3536 terminating
18:43:03 T:3184 M:2062442496  NOTICE: Closing audio device
18:43:03 T:3184 M:2062430208 WARNING: CWin32DirectSound::WaitCompletion: timeout adding silence to buffer
18:43:03 T:3184 M:2062430208   DEBUG: CWin32DirectSound::WaitCompletion - timeout waiting for silence
18:43:03 T:3184 M:2062430208   DEBUG: CWin32DirectSound::Deinitialize: Cleaning up
18:43:03 T:3184 M:2062430208  NOTICE: Deleting audio codec
18:43:03 T:3184 M:2062430208  NOTICE: DVDPlayer: closing video stream
18:43:03 T:3184 M:2062430208  NOTICE: Closing video stream
18:43:03 T:3184 M:2062430208  NOTICE: CDVDMessageQueue(video)::WaitUntilEmpty
18:43:03 T:3528 M:2062442496   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
18:43:03 T:3184 M:2062442496  NOTICE: waiting for video thread to exit
18:43:03 T:3528 M:2062442496   ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
18:43:03 T:3528 M:2062442496  NOTICE: thread end: video_thread
18:43:03 T:3528 M:2062442496   DEBUG: Thread 3528 terminating
18:43:03 T:3184 M:2062458880  NOTICE: deleting video codec
18:43:03 T:3184 M:2067353600  NOTICE: CDVDPlayer::OnExit() deleting demuxer
18:43:03 T:3184 M:2067623936  NOTICE: CDVDPlayer::OnExit() deleting input stream
18:43:03 T:3184 M:2069012480   DEBUG: CAnnouncementManager - Announcement: PlaybackEnded from xbmc
18:43:03 T:3184 M:2069012480   DEBUG: Thread 3184 terminating
18:43:03 T:2188 M:2074374144   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
18:43:03 T:2188 M:2074374144   DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
18:43:03 T:2188 M:2075697152   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
18:43:03 T:2188 M:2076131328   DEBUG: ------ Window Init (MyVideoNav.xml) ------
18:43:03 T:2188 M:2076131328    INFO: Loading skin file: MyVideoNav.xml
18:43:03 T:2188 M:2075865088   DEBUG: CGUIMediaWindow::GetDirectory (videodb://1/2/)
18:43:03 T:2188 M:2075865088   DEBUG:   ParentPath = [videodb://1/2/]
18:43:03 T:2188 M:2075865088   DEBUG: CVideoDatabase::GetSetsNav query: SELECT sets.idSet,sets.strSet,COUNT(1) AS c,count(files.playCount) FROM sets JOIN setlinkmovie ON sets.idSet=setlinkmovie.idSet JOIN movie ON setlinkmovie.idMovie=movie.idMovie JOIN files ON files.idFile=movie.idFile  GROUP BY sets.idSet HAVING c>1
18:43:03 T:2188 M:2075852800   DEBUG: CVideoDatabase::GetMoviesByWhere query: select * from movieview WHERE movieview.idMovie NOT IN (SELECT idMovie FROM setlinkmovie s1 JOIN(SELECT idSet, COUNT(1) AS c FROM setlinkmovie GROUP BY idSet HAVING c>1) s2 ON s2.idSet=s1.idSet)
Find
Reply
Wagenius
Newbie
Posts: 5
#3
2011-06-29, 19:03
And the third and final piece of the log:
Code:
18:43:03 T:2188 M:2075619328   DEBUG: Time for actual SQL query = 4
18:43:04 T:2188 M:2075545600   DEBUG: Time to retrieve movies from dataset = 25
18:43:04 T:2188 M:2075545600   DEBUG: Saving fileitems [videodb://1/2/]
18:43:04 T:2188 M:2075545600   DEBUG:   -- items: 68, sort method: 25, ascending: true
18:43:04 T:3068 M:2075525120   DEBUG: thread start, auto delete: 0
18:43:04 T:2636 M:2074185728   DEBUG: thread start, auto delete: 1
18:43:04 T:2636 M:2074181632   DEBUG: CThumbExtractor::DoWork - trying to extract filestream details from video file M:\Videos\Movies\mymovie2.img
18:43:04 T:2188 M:2072096768   DEBUG: Win32DllLoader::Load(special://xbmcbin/system/ImageLib.dll)
18:43:04 T:3068 M:2068570112   DEBUG: Thread 3068 terminating
18:43:04 T:2636 M:2068582400   DEBUG: CThumbExtractor::DoWork - trying to extract filestream details from video file M:\Videos\Movies\mymovie3.img
18:43:04 T:2188 M:2058153984  NOTICE: CDVDPlayer::CloseFile()
18:43:04 T:2188 M:2058153984 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
18:43:04 T:2188 M:2058153984  NOTICE: DVDPlayer: waiting for threads to exit
18:43:04 T:2188 M:2058153984  NOTICE: DVDPlayer: finished waiting
18:43:04 T:2188 M:2064121856   DEBUG: SECTION:UnloadDelayed(SECTION: LCODE)
18:43:04 T:2636 M:2064121856   DEBUG: CSaveFileStateJob::DoWork - Saving file state for video item M:\Videos\Movies\mymovie.mkv
18:43:34 T:2188 M:2099347456   DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/players/dvdplayer/avutil-50.dll)
18:43:34 T:2188 M:2099359744   DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/players/dvdplayer/avcodec-52.dll)
18:43:34 T:2636 M:2099359744   DEBUG: Thread 2636 terminating (autodelete)
18:43:35 T:2188 M:2099417088   DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/players/dvdplayer/avformat-52.dll)
18:43:35 T:2188 M:2099417088   DEBUG: Win32DllLoader::Unload avformat-52.dll
18:43:35 T:2188 M:2099417088   DEBUG: FreeLibrary(avcodec-52.dll) -> 04FAF7E0
18:43:35 T:2188 M:2099417088   DEBUG: Win32DllLoader::Unload avcodec-52.dll
18:43:35 T:2188 M:2099417088   DEBUG: FreeLibrary(avutil-50.dll) -> 04FA9FC0
18:43:35 T:2188 M:2099417088   DEBUG: FreeLibrary(libfaad.dll) -> 04FA8FD0
18:43:35 T:2188 M:2099417088   DEBUG: Win32DllLoader::Unload libfaad.dll
18:43:35 T:2188 M:2099417088   DEBUG: FreeLibrary(avutil-50.dll) -> 04FA9FC0
18:43:35 T:2188 M:2103214080   DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/players/dvdplayer/swscale-0.6.1.dll)
18:43:35 T:2188 M:2103214080   DEBUG: Win32DllLoader::Unload swscale-0.6.1.dll
18:43:35 T:2188 M:2103214080   DEBUG: FreeLibrary(avutil-50.dll) -> 04FA9FC0
18:43:35 T:2188 M:2103214080   DEBUG: Win32DllLoader::Unload avutil-50.dll
18:43:36 T:2188 M:2103439360   DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/players/dvdplayer/libdts.dll)
18:43:36 T:2188 M:2103439360   DEBUG: Win32DllLoader::Unload libdts.dll
18:43:36 T:2188 M:2103574528   DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib.dll)
18:43:36 T:2188 M:2103574528   DEBUG: Win32DllLoader::Unload ImageLib.dll
Find
Reply
thespecialist
Senior Member
Posts: 126
#4
2011-06-30, 03:45
*edit*
Find
Reply
thespecialist
Senior Member
Posts: 126
#5
2011-06-30, 03:48
Also not sure what you mean with running it from other platforms. You mean XBMC on one computer is problematic and not on the other ? Or do you mean other software plays it fine, but just not XBMC ? What happens when you switch off hardware decoding/DXVA2 ? What happens when you run XBMC on another PC ?
Find
Reply
Wagenius
Newbie
Posts: 5
#6
2011-06-30, 14:12
What I mean is other players and computer. Or more precisely ... not XBMC. I could install xbmc on a different computer just to see if I get the same problem there ... but as it seems like the problem is with decoding the video, I am assuming that this is related to XBMC ... I will return with more info when I have tried it through XBMC on a different computer
Find
Reply
thespecialist
Senior Member
Posts: 126
#7
2011-06-30, 14:34
Wagenius Wrote:What I mean is other players and computer. Or more precisely ... not XBMC. I could install xbmc on a different computer just to see if I get the same problem there ... but as it seems like the problem is with decoding the video, I am assuming that this is related to XBMC ... I will return with more info when I have tried it through XBMC on a different computer
If the problem would be decoding of the video, then switching off hardware acceleration should solve it. So please try that and also please try running XBMC on a different PC, preferably a different OS.
Find
Reply
jhsrennie
Team-XBMC Developer
Posts: 7,256
#8
2011-06-30, 19:20
And next time please don't post the log here. Put it on pastebin.com and post the link here.

Thanks,

JR
Find
Reply

Home
Search
Help
Logout Mark Read Team Forum Stats Members Help
Video stops when playing Matroska Files0