Hi all,
a MKV video playback crashes back to the Kodi menu, always at the same time.
Also, it's impossible to fast-forward beyond this time. The MKV file is stored on
the local SSD so it's probably not a network issue. VLC plays the MKV without errors.
There are some errors in the log (see below)
ERROR: ffmpeg[7FA46BFFF700]: [matroska,webm] Read error
ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
Hardware is a Intel NUC. Already tried without VAAPI but this doesn't help.
Any further ideas?
Display Spoiler
2019-06-01 05:50:15.312 T:140344473233536 NOTICE: Starting Kodi (18.2 Git:18.2-Leia). Platform: Linux x86 64-bit
2019-06-01 05:50:15.312 T:140344473233536 NOTICE: Using Release Kodi x64 build
2019-06-01 05:50:15.312 T:140344473233536 NOTICE: Kodi compiled 2019-05-04 by GCC 8.2.0 for Linux x86 64-bit version 4.19.36 (267044)
2019-06-01 05:50:15.312 T:140344473233536 NOTICE: Running on LibreELEC (official): 9.0.2, kernel: Linux x86 64-bit version 4.19.36
2019-06-01 05:50:15.312 T:140344473233536 NOTICE: FFmpeg version/source: 4.0.3-Kodi
2019-06-01 05:50:15.312 T:140344473233536 NOTICE: Host CPU: Intel(R) Core(TM) i3-5010U CPU @ 2.10GHz, 4 cores available
...
2019-06-01 05:57:37.888 T:140344306992896 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libsse4-x86_64-linux.so)
2019-06-01 05:57:37.888 T:140344306992896 DEBUG: Loading: /usr/lib/kodi/system/libsse4-x86_64-linux.so
2019-06-01 05:57:37.898 T:140344446191360 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=HDMI,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x02"
2019-06-01 05:57:37.899 T:140344446191360 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
2019-06-01 05:57:37.899 T:140344446191360 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S16NE
2019-06-01 05:57:37.899 T:140344446191360 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600
2019-06-01 05:57:37.902 T:140344163301120 ERROR: ffmpeg[7FA46BFFF700]: [matroska,webm] Read error
2019-06-01 05:57:37.903 T:140344163301120 DEBUG: CVideoPlayer::SetCaching - caching state 0
2019-06-01 05:57:37.903 T:140344163301120 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2019-06-01 05:57:37.908 T:140342393480960 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-vaapi
2019-06-01 05:57:37.908 T:140344446191360 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600
2019-06-01 05:57:37.908 T:140344446191360 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms
2019-06-01 05:57:37.909 T:140344446191360 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
2019-06-01 05:57:37.909 T:140344446191360 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL, FR
2019-06-01 05:57:37.909 T:140344446191360 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL, FR (ALSA: FL FR)
2019-06-01 05:57:37.909 T:140344446191360 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
2019-06-01 05:57:37.909 T:140344446191360 DEBUG: Output Device : HDA Intel
2019-06-01 05:57:37.909 T:140344446191360 DEBUG: Sample Rate : 48000
2019-06-01 05:57:37.909 T:140344446191360 DEBUG: Sample Format : AE_FMT_S16NE
2019-06-01 05:57:37.909 T:140344446191360 DEBUG: Channel Count : 2
2019-06-01 05:57:37.909 T:140344446191360 DEBUG: Channel Layout: FL, FR
2019-06-01 05:57:37.909 T:140344446191360 DEBUG: Frames : 2400
2019-06-01 05:57:37.909 T:140344446191360 DEBUG: Frame Size : 4
2019-06-01 05:57:37.918 T:140344454584064 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2019-06-01 05:57:37.940 T:140342393480960 DEBUG: Previous line repeats 1 times.
2019-06-01 05:57:37.940 T:140342393480960 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
2019-06-01 05:57:37.948 T:140344306992896 DEBUG: Previous line repeats 4 times.
2019-06-01 05:57:37.948 T:140344306992896 DEBUG: VAAPI output: Initializing skip postproc
2019-06-01 05:57:37.950 T:140342393480960 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
2019-06-01 05:57:37.955 T:140342393480960 DEBUG: CRenderManager::Configure - change configuration. 1920x1080. display: 1920x1080. framerate: 23.98.
2019-06-01 05:57:37.967 T:140344473233536 DEBUG: DeleteRenderer - deleting renderer
2019-06-01 05:57:37.967 T:140344473233536 DEBUG: LinuxRendererGL: Cleaning up GL resources
2019-06-01 05:57:37.981 T:140344473233536 DEBUG: GL: Requested render method: 0
2019-06-01 05:57:37.981 T:140344473233536 DEBUG: GL: BaseYUV2RGBGLSLShader: defines:
#define XBMC_texture_rectangle 0
#define XBMC_texture_rectangle_hack 0
#define XBMC_STRETCH 0
#define XBMC_NV12
2019-06-01 05:57:37.981 T:140344473233536 NOTICE: GL: Selecting YUV 2 RGB shader
2019-06-01 05:57:37.982 T:140344473233536 NOTICE: GL: NPOT texture support detected
2019-06-01 05:57:37.982 T:140344473233536 NOTICE: GL: Using GL_ARB_pixel_buffer_object
2019-06-01 05:57:37.982 T:140344473233536 NOTICE: Using GL_TEXTURE_2D
2019-06-01 05:57:37.982 T:140344473233536 DEBUG: CRenderManager::Configure - 4
2019-06-01 05:57:37.982 T:140342393480960 DEBUG: CVideoReferenceClock: Clock speed 100.00 %
2019-06-01 05:57:38.002 T:140344473233536 DEBUG: GL: Requested render method: 0
2019-06-01 05:57:38.002 T:140344473233536 DEBUG: GL: BaseYUV2RGBGLSLShader: defines:
#define XBMC_texture_rectangle 0
#define XBMC_texture_rectangle_hack 0
#define XBMC_STRETCH 0
#define XBMC_NV12
2019-06-01 05:57:38.002 T:140344473233536 NOTICE: GL: Selecting YUV 2 RGB shader
2019-06-01 05:57:38.003 T:140344473233536 NOTICE: GL: NPOT texture support detected
2019-06-01 05:57:38.003 T:140344473233536 NOTICE: GL: Using GL_ARB_pixel_buffer_object
2019-06-01 05:57:38.004 T:140344163301120 DEBUG: CVideoPlayer::HandleMessages - player started 1
2019-06-01 05:57:38.004 T:140344163301120 DEBUG: CVideoPlayer::HandleMessages - player started 2
2019-06-01 05:57:38.004 T:140342385088256 DEBUG: OnAVChange: CApplication::OnAVChange
2019-06-01 05:57:38.004 T:140344163301120 DEBUG: VideoPlayer::Sync - Audio - pts: 7483157000.000000, cache: 493851.284704, totalcache: 800000.011921
2019-06-01 05:57:38.004 T:140344163301120 DEBUG: VideoPlayer::Sync - Video - pts: 7482808708.333333, cache: 50000.000000, totalcache: 100000.000000
2019-06-01 05:57:38.004 T:140342015817472 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(7482663148.715297), level: 16, cache: 469893.375037
2019-06-01 05:57:38.004 T:140344473229056 DEBUG: CAnnouncementManager - Announcement: OnAVChange from xbmc
2019-06-01 05:57:38.005 T:140342393480960 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(7482663148.715297)
2019-06-01 05:57:38.005 T:140342015817472 DEBUG: CDVDAudio::Resume - resume audio stream
2019-06-01 05:57:38.006 T:140342393480960 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
2019-06-01 05:57:38.006 T:140344454584064 DEBUG: ActiveAE - start sync of audio stream
2019-06-01 05:57:38.006 T:140342367254272 DEBUG: Thread JobWorker start, auto delete: true
2019-06-01 05:57:38.007 T:140342385088256 DEBUG: OnAVChange: CApplication::OnAVChange
2019-06-01 05:57:38.007 T:140344473229056 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnAVChange
2019-06-01 05:57:38.008 T:140342385088256 DEBUG: OnAVStarted: CApplication::OnAVStarted
2019-06-01 05:57:38.008 T:140344473229056 DEBUG: CAnnouncementManager - Announcement: OnAVChange from xbmc
2019-06-01 05:57:38.008 T:140344473229056 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnAVChange
2019-06-01 05:57:38.008 T:140344473229056 DEBUG: CAnnouncementManager - Announcement: OnAVStart from xbmc
2019-06-01 05:57:38.008 T:140344473229056 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnAVStart
2019-06-01 05:57:38.010 T:140342393480960 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
2019-06-01 05:57:38.025 T:140344473233536 DEBUG: Previous line repeats 2 times.
2019-06-01 05:57:38.025 T:140344473233536 DEBUG: Activating window ID: 12005
2019-06-01 05:57:38.025 T:140344473233536 DEBUG: ------ Window Deinit (Home.xml) ------
2019-06-01 05:57:38.025 T:140344473233536 DEBUG: FreeVisualisation() done
2019-06-01 05:57:38.025 T:140344473233536 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
2019-06-01 05:57:38.025 T:140344473233536 INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
2019-06-01 05:57:38.029 T:140344473233536 NOTICE: Whitelist search for: width: 1920, height: 1080, fps: 23.976, 3D: false
2019-06-01 05:57:38.029 T:140344473233536 DEBUG: Whitelist is empty using default one
2019-06-01 05:57:38.029 T:140344473233536 DEBUG: Trying to find exact refresh rate
2019-06-01 05:57:38.029 T:140344473233536 DEBUG: No exact whitelisted resolution matched, trying double refresh rate
2019-06-01 05:57:38.029 T:140344473233536 DEBUG: No double whitelisted resolution matched, trying 3:2 pullback
2019-06-01 05:57:38.029 T:140344473233536 DEBUG: No 3:2 pullback refresh rate whitelisted resolution matched, trying current resolution
2019-06-01 05:57:38.029 T:140344473233536 DEBUG: Matched current Resolution HDMI1: 3840x2160 @ 23.98Hz (21)
2019-06-01 05:57:38.029 T:140344473233536 NOTICE: Display resolution ADJUST : HDMI1: 3840x2160 @ 23.98Hz (21) (w. 0.000)
2019-06-01 05:57:38.030 T:140344473233536 DEBUG: GL: Requested render method: 0
2019-06-01 05:57:38.031 T:140344473233536 DEBUG: GL: BaseYUV2RGBGLSLShader: defines:
#define XBMC_texture_rectangle 0
#define XBMC_texture_rectangle_hack 0
#define XBMC_STRETCH 0
#define XBMC_NV12
2019-06-01 05:57:38.031 T:140342393480960 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1
2019-06-01 05:57:38.031 T:140344473233536 NOTICE: GL: Selecting YUV 2 RGB shader
2019-06-01 05:57:38.031 T:140344473233536 NOTICE: GL: NPOT texture support detected
2019-06-01 05:57:38.031 T:140344473233536 NOTICE: GL: Using GL_ARB_pixel_buffer_object
2019-06-01 05:57:38.032 T:140342393480960 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1
2019-06-01 05:57:38.044 T:140344473233536 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
2019-06-01 05:57:38.105 T:140342385088256 DEBUG: OnAVChange: CApplication::OnAVChange
2019-06-01 05:57:38.105 T:140344473229056 DEBUG: CAnnouncementManager - Announcement: OnAVChange from xbmc
2019-06-01 05:57:38.105 T:140344473229056 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnAVChange
2019-06-01 05:57:38.122 T:140344454584064 DEBUG: ActiveAE::SyncStream - average error of 15.332663, start adjusting
2019-06-01 05:57:38.122 T:140344454584064 DEBUG: ActiveAE::SyncStream - average error 0.332663 below threshold of 30.000000
2019-06-01 05:57:38.128 T:140342393480960 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1
2019-06-01 05:57:38.172 T:140342393480960 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 41727
2019-06-01 05:57:39.024 T:140342015817472 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_EOF
2019-06-01 05:57:39.106 T:140344163301120 NOTICE: CVideoPlayer::OnExit()
2019-06-01 05:57:39.107 T:140344163301120 NOTICE: VideoPlayer: eof, waiting for queues to empty
2019-06-01 05:57:39.107 T:140344163301120 DEBUG: CVideoPlayer: updating file item stream details with current streams
2019-06-01 05:57:39.107 T:140344163301120 NOTICE: Closing stream player 1
2019-06-01 05:57:39.107 T:140344163301120 NOTICE: CDVDMessageQueue(audio)::WaitUntilEmpty
2019-06-01 05:57:39.107 T:140342015817472 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
2019-06-01 05:57:39.107 T:140344163301120 NOTICE: Waiting for audio thread to exit
2019-06-01 05:57:39.107 T:140342015817472 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
2019-06-01 05:57:39.107 T:140342015817472 NOTICE: thread end: CVideoPlayerAudio::OnExit()
2019-06-01 05:57:39.107 T:140342015817472 DEBUG: Thread VideoPlayerAudio 140342015817472 terminating
2019-06-01 05:57:39.107 T:140344163301120 NOTICE: Closing audio device
2019-06-01 05:57:39.422 T:140344454584064 DEBUG: CActiveAE::DiscardStream - audio stream deleted
2019-06-01 05:57:39.422 T:140344454584064 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2019-06-01 05:57:39.422 T:140344163301120 DEBUG: Previous line repeats 2 times.
2019-06-01 05:57:39.422 T:140344163301120 NOTICE: Deleting audio codec
2019-06-01 05:57:39.422 T:140344163301120 NOTICE: Closing stream player 2
2019-06-01 05:57:39.422 T:140344163301120 NOTICE: CDVDMessageQueue(video)::WaitUntilEmpty
2019-06-01 05:57:39.504 T:140342393480960 DEBUG: CDVDVideoCodecFFmpeg::GetPicture - eof hw accel
2019-06-01 05:57:39.504 T:140342393480960 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps
2019-06-01 05:57:39.545 T:140342393480960 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
2019-06-01 05:57:39.545 T:140344163301120 NOTICE: waiting for video thread to exit
2019-06-01 05:57:39.545 T:140342393480960 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true