2015-03-28, 16:08
Good news. Thank you!
2015-10-22, 01:23
holly crap finally!
ive been having issues where apple trailers (only apple trailers oddly) would randomly blank out the screen (no video, just audio) for brief periods of time. same videos played just fine outside of kodi, and videos play just fine on other machines with kodi. thus something local to that box. I dont recall the problem happening prior to 15.1, and tested each 15.2rc and after the recent 15.2 final i then started going crazy turning settings on/off and testing with no luck. eventually i found the fix to be turn "sync playback to display" on. win7 64bit with GeForce GT 730 video card. tv is samsung 56inch dlp (so supported resolutions/refresh really matter)
snippet of logs when playing video with issue:
snippet of log playing video post fix 'sync playback to display':
ive been having issues where apple trailers (only apple trailers oddly) would randomly blank out the screen (no video, just audio) for brief periods of time. same videos played just fine outside of kodi, and videos play just fine on other machines with kodi. thus something local to that box. I dont recall the problem happening prior to 15.1, and tested each 15.2rc and after the recent 15.2 final i then started going crazy turning settings on/off and testing with no luck. eventually i found the fix to be turn "sync playback to display" on. win7 64bit with GeForce GT 730 video card. tv is samsung 56inch dlp (so supported resolutions/refresh really matter)
Code:
17:51:21 T:4748 NOTICE: Starting Kodi (15.2 Git:20151019-02e7013). Platform: Windows NT x86 32-bit
17:51:21 T:4748 NOTICE: Using Release Kodi x32 build
17:51:21 T:4748 NOTICE: Kodi compiled Oct 19 2015 by MSVC 180030723 for Windows NT x86 32-bit version 6.0 (0x06000000)
17:51:21 T:4748 NOTICE: Running on Gigabyte Technology Co., Ltd. P55M-UD2 with Windows 7 SP1, kernel: Windows NT x86 64-bit version 6.1
17:51:21 T:4748 NOTICE: Host CPU: Intel(R) Core(TM) i7 CPU 860 @ 2.80GHz, 8 cores available
17:51:21 T:4748 NOTICE: Desktop Resolution: 1804x1004 32Bit at 60Hz
17:51:21 T:4748 NOTICE: Running with administrator rights
17:51:21 T:4748 NOTICE: Aero is enabledsnippet of logs when playing video with issue:
Code:
17:52:22 T:4748 NOTICE: DVDPlayer: Opening: E:\trailers\The Hunger Games Mockingjay Part 2-trailer-h720p.mov
17:52:22 T:4748 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
17:52:22 T:4748 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
17:52:22 T:4744 NOTICE: Thread DVDPlayer start, auto delete: false
17:52:22 T:4744 NOTICE: Creating InputStream
17:52:22 T:4744 DEBUG: CUtil::ScanForExternalSubtitles: Searching for subtitles...
17:52:22 T:4744 DEBUG: CUtil::ScanForExternalSubtitles: END (total time: 0 ms)
17:52:22 T:4744 NOTICE: Creating Demuxer
17:52:22 T:4744 DEBUG: CDVDDemuxFFmpeg::Open - probing detected format [mov,mp4,m4a,3gp,3g2,mj2]
17:52:22 T:4744 DEBUG: CDVDDemuxFFmpeg::Open - avformat_find_stream_info starting
17:52:22 T:4744 DEBUG: CDVDDemuxFFmpeg::Open - av_find_stream_info finished
17:52:22 T:4744 INFO: ffmpeg[1288]: Input #0, mov,mp4,m4a,3gp,3g2,mj2, from 'E:\trailers\The Hunger Games Mockingjay Part 2-trailer-h720p.mov':
17:52:22 T:4744 INFO: ffmpeg[1288]: Metadata:
17:52:22 T:4744 INFO: ffmpeg[1288]: major_brand : qt
17:52:22 T:4744 INFO: ffmpeg[1288]: minor_version : 537199360
17:52:22 T:4744 INFO: ffmpeg[1288]: compatible_brands: qt
17:52:22 T:4744 INFO: ffmpeg[1288]: creation_time : 2015-06-09 19:02:48
17:52:22 T:4744 INFO: ffmpeg[1288]: timecode : 01:00:01:08
17:52:22 T:4744 INFO: ffmpeg[1288]: Duration: 00:01:42.81, start: 0.000000, bitrate: 5145 kb/s
17:52:22 T:4744 INFO: ffmpeg[1288]: Stream #0:0(eng): Video: h264 (Main) (avc1 / 0x31637661), yuv420p(tv, bt709), 1280x544, 5046 kb/s, 23.98 fps, 23.98 tbr, 24k tbn, 48k tbc (default)
17:52:22 T:4744 INFO: ffmpeg[1288]: Metadata:
17:52:22 T:4744 INFO: ffmpeg[1288]: creation_time : 2015-06-09 19:02:48
17:52:22 T:4744 INFO: ffmpeg[1288]: handler_name : Apple Video Media Handler
17:52:22 T:4744 INFO: ffmpeg[1288]: encoder : H.264
17:52:22 T:4744 INFO: ffmpeg[1288]: Stream #0:1(eng): Data: none (tmcd / 0x64636D74), 0 kb/s (default)
17:52:22 T:4744 INFO: ffmpeg[1288]: Metadata:
17:52:22 T:4744 INFO: ffmpeg[1288]: creation_time : 2015-06-09 19:02:48
17:52:22 T:4744 INFO: ffmpeg[1288]: handler_name : Time Code Media Handler
17:52:22 T:4744 INFO: ffmpeg[1288]: reel_name : 876059
17:52:22 T:4744 INFO: ffmpeg[1288]: timecode : 01:00:01:08
17:52:22 T:4744 INFO: ffmpeg[1288]: Stream #0:2(eng): Audio: aac (LC) (mp4a / 0x6134706D), 44100 Hz, stereo, fltp, 93 kb/s (default)
17:52:22 T:4744 INFO: ffmpeg[1288]: Metadata:
17:52:22 T:4744 INFO: ffmpeg[1288]: creation_time : 2015-06-09 19:02:48
17:52:22 T:4744 INFO: ffmpeg[1288]: handler_name : Apple Sound Media Handler
17:52:22 T:4744 DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0
17:52:22 T:4744 DEBUG: CDVDDemuxFFmpeg::AddStream(1, ...) -> 1
17:52:22 T:4744 DEBUG: CDVDDemuxFFmpeg::AddStream(2, ...) -> 2
17:52:22 T:4744 NOTICE: Opening stream: 0 source: 256
17:52:22 T:4744 NOTICE: Creating video codec with codec id: 28
17:52:22 T:4744 DEBUG: CDVDFactoryCodec: compiled in hardware support: AMCodec:no MediaCodec:no DXVA:yes iMXVPU:no MMAL:no
17:52:22 T:4744 DEBUG: FactoryCodec - Video: - Opening
17:52:22 T:4744 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
17:52:22 T:4744 DEBUG: FactoryCodec - Video: ff-h264 - Opened
17:52:22 T:4744 NOTICE: Creating video thread
17:52:22 T:4744 NOTICE: Opening stream: 2 source: 256
17:52:22 T:4744 NOTICE: Finding audio codec for: 86018
17:52:22 T:4744 DEBUG: FactoryCodec - Audio: passthrough - Opening
17:52:22 T:4744 DEBUG: FactoryCodec - Audio: passthrough - Failed
17:52:22 T:4744 DEBUG: FactoryCodec - Audio: FFmpeg - Opening
17:52:22 T:4108 NOTICE: Thread DVDPlayerVideo start, auto delete: false
17:52:22 T:4108 NOTICE: running thread: video_thread
17:52:22 T:4744 DEBUG: FactoryCodec - Audio: FFmpeg - Opened
17:52:22 T:4744 NOTICE: Creating audio thread
17:52:22 T:4108 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
17:52:22 T:4744 DEBUG: CEdl::ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: E:\trailers\The Hunger Games Mockingjay Part 2-trailer-h720p.mov
17:52:22 T:4128 NOTICE: Thread DVDPlayerAudio start, auto delete: false
17:52:22 T:4128 NOTICE: running thread: CDVDPlayerAudio::Process()
17:52:22 T:4128 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
17:52:22 T:4744 DEBUG: CApplication::OnPlayBackStarted: play state was 1, starting 1
17:52:22 T:4744 DEBUG: CDVDPlayer::SetCaching - caching state 3
17:52:22 T:4748 DEBUG: CApplication::PlayFile: OpenFile succeed, play state 2
17:52:22 T:4748 DEBUG: CApplication::OnPlayBackStarted: play state was 2, starting 0
17:52:22 T:4128 NOTICE: Creating audio stream (codec id: 86018, channels: 2, sample rate: 44100, no pass-through)
17:52:22 T:4128 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback
17:52:22 T:4108 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(0.000000, 0)
17:52:22 T:4108 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
17:52:22 T:4744 DEBUG: CDVDPlayer::CheckContinuity - wrapback :2, prev:83416.666667, curr:41708.333333, diff:-41708.333333
17:52:22 T:4108 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating DXVA(1280x544)
17:52:22 T:4108 DEBUG: DXVA - open decoder
17:52:22 T:4108 DEBUG: DXVA - supports 'MPEG2 IDCT'
17:52:22 T:4108 DEBUG: DXVA - supports 'MPEG1/2 VLD'
17:52:22 T:4108 DEBUG: DXVA - supports 'MPEG2 VLD'
17:52:22 T:4108 DEBUG: DXVA - supports 6F3EC719-3735-42cc-8063-65cc3cb36616
17:52:22 T:4108 DEBUG: DXVA - supports 'VC-1 VLD 2010'
17:52:22 T:4108 DEBUG: DXVA - supports 'VC-1 VLD'
17:52:22 T:4108 DEBUG: DXVA - supports 'VC-1 IDCT'
17:52:22 T:4108 DEBUG: DXVA - supports 'Windows Media Video 9 IDCT'
17:52:22 T:4108 DEBUG: DXVA - supports 32FCFE3F-de46-4a49-861b-ac71110649d5
17:52:22 T:4108 DEBUG: DXVA - supports D79BE8DA-0cf1-4c81-b82a-69a4e236f43d
17:52:22 T:4108 DEBUG: DXVA - supports F9AACCBB-c2b6-4cfc-8779-5707b1760552
17:52:22 T:4108 DEBUG: DXVA - supports 'H.264 VLD, no FGT'
17:52:22 T:4108 DEBUG: DXVA - supports 1B81BE6A-a0c7-11d3-b984-00c04f2e73c5
17:52:22 T:4108 DEBUG: DXVA - supports 8EFA5926-bd9e-4b04-8b72-8f977dc44c36
17:52:22 T:4108 DEBUG: DXVA - supports 'HEVC / H.265 variable-length decoder, main'
17:52:22 T:4108 DEBUG: DXVA - supports EFD64D74-c9e8-41d7-a5e9-e9b0e39fa319
17:52:22 T:4108 DEBUG: DXVA - supports ED418A9F-010d-4eda-9ae3-9a65358d8d2e
17:52:22 T:4108 DEBUG: DXVA - supports 9947EC6F-689b-11dc-a320-0019dbbc4184
17:52:22 T:4108 DEBUG: DXVA - supports B194EB52-19a0-41f0-b754-cc244ac1cb20
17:52:22 T:4108 DEBUG: DXVA - supports 6AFFD11E-1d96-42b1-a215-93a31f09a53d
17:52:22 T:4108 DEBUG: DXVA - trying 'H.264 VLD, no FGT'
17:52:22 T:4108 DEBUG: DXVA - source requires 2 references
17:52:22 T:4108 DEBUG: DXVA - config 0: bitstream type 1
17:52:22 T:4108 DEBUG: DXVA - config 1: bitstream type 2
17:52:22 T:4108 DEBUG: DXVA - allocating 12 surfaces
17:52:22 T:4108 NOTICE: fps: 23.976024, pwidth: 1280, pheight: 544, dwidth: 1280, dheight: 544
17:52:22 T:4108 DEBUG: CDVDPlayerVideo::OutputPicture - change configuration. 1280x544. framerate: 23.98. format: DXVA
17:52:22 T:4108 NOTICE: Display resolution DESKTOP : 1804x1004@ 60.00 - Full Screen (16)
17:52:22 T:4108 NOTICE: D3D: rendering method forced to DXVA processor
17:52:22 T:4108 DEBUG: DXVA::CProcessorHD::OpenProcessor - processor selected 95F4EDF4-6e03-4cd7-be1b-3075d665aa52.
17:52:22 T:4108 DEBUG: CWinRenderer::SelectRenderMethod: Selected render method 3: DXVA
17:52:22 T:4108 DEBUG: CXBMCRenderManager::Configure - 6
17:52:22 T:4744 DEBUG: CDVDPlayer::SetCaching - caching state 0
17:52:22 T:4748 DEBUG: created video buffer 0
17:52:22 T:4748 DEBUG: created video buffer 1
17:52:22 T:4748 DEBUG: created video buffer 2
17:52:22 T:4748 DEBUG: created video buffer 3
17:52:22 T:4748 DEBUG: created video buffer 4
17:52:22 T:4748 DEBUG: created video buffer 5
17:52:22 T:4748 WARNING: CWinRenderer::UpdateVideoFilter - chosen scaling method 1 is not supported by renderer
17:52:22 T:4748 DEBUG: CWinRenderer::CreateIntermediateRenderTarget: format 22
17:52:22 T:4108 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
17:52:22 T:4108 DEBUG: ffmpeg[100C]: [h264] Increasing reorder buffer to 1
17:52:22 T:4108 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
17:52:22 T:4984 INFO: CActiveAESink::OpenSink - initialize sink
17:52:22 T:4744 DEBUG: CDVDPlayer::HandleMessages - player started 2
17:52:22 T:4748 DEBUG: CGUIInfoManager::SetCurrentMovie(E:\trailers\The Hunger Games Mockingjay Part 2-trailer-h720p.mov)
17:52:22 T:4748 DEBUG: CVideoDatabase::GetMovieId (E:\trailers\The Hunger Games Mockingjay Part 2-trailer-h720p.mov), query = select idMovie from movie where idFile=10636
17:52:22 T:4748 DEBUG: CVideoDatabase::GetEpisodeId (E:\trailers\The Hunger Games Mockingjay Part 2-trailer-h720p.mov), query = select idEpisode from episode where idFile=10636
17:52:22 T:4748 DEBUG: CVideoDatabase::GetMusicVideoId (E:\trailers\The Hunger Games Mockingjay Part 2-trailer-h720p.mov), query = select idMVideo from musicvideo where idFile=10636
17:52:22 T:4748 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
17:52:22 T:4748 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
17:52:22 T:4748 DEBUG: Activating window ID: 12005
17:52:22 T:4748 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
17:52:22 T:4748 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
17:52:23 T:4984 DEBUG: CActiveAESink::OpenSink - trying to open device WASAPI:default
17:52:23 T:4984 INFO: CAESinkWASAPI::InitializeExclusive: WASAPI Exclusive Mode Sink Initialized using: AE_FMT_S24NE4MSB, 44100, 2
17:52:23 T:4984 DEBUG: CActiveAESink::OpenSink - WASAPI Initialized:
17:52:23 T:4984 DEBUG: Output Device : default
17:52:23 T:4984 DEBUG: Sample Rate : 44100
17:52:23 T:4984 DEBUG: Sample Format : AE_FMT_S24NE4MSB
17:52:23 T:4984 DEBUG: Channel Count : 2
17:52:23 T:4984 DEBUG: Channel Layout: FL,FR
17:52:23 T:4984 DEBUG: Frames : 2208
17:52:23 T:4984 DEBUG: Frame Samples : 4416
17:52:23 T:4984 DEBUG: Frame Size : 8
17:52:23 T:4536 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
17:52:23 T:4128 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(23219.954649, 1)
17:52:23 T:4744 DEBUG: CDVDPlayer::HandleMessages - player started 1
17:52:23 T:4128 NOTICE: CDVDPlayerAudio::OutputPacket skipping a packets of duration 23
17:52:23 T:4128 NOTICE: Previous line repeats 4 times.
17:52:23 T:4128 DEBUG: CDVDPlayerAudio::HandleSyncError - average error -31641.763040 below threshold of 50000.000000
17:52:23 T:4128 DEBUG: CDVDClock::Discontinuity - CDVDPlayerAudio::HandleSyncError2 - was:149607.882585, should be:117966.524041, error:-31641.358544
17:52:24 T:4128 DEBUG: CDVDClock::Discontinuity - CDVDPlayerAudio::HandleSyncError2 - was:1119396.010785, should be:1143434.242537, error:24038.231752
17:52:27 T:4108 DEBUG: CPullupCorrection: detected pattern of length 1: 41708.33, frameduration: 41708.333333
17:52:38 T:4748 DEBUG: Keyboard: scancode: 0x1d, sym: 0x0132, unicode: 0x0000, modifier: 0x40
17:52:38 T:4748 DEBUG: CInputManager::OnKey: ctrl-leftctrl (0x1f0d0) pressed, action is
17:52:38 T:4748 DEBUG: Keyboard: scancode: 0x2a, sym: 0x0130, unicode: 0x0000, modifier: 0x41
17:52:38 T:4748 DEBUG: CInputManager::OnKey: ctrl-shift-leftshift (0x3f0d2) pressed, action is
17:52:38 T:4748 DEBUG: Keyboard: scancode: 0x1f, sym: 0x0073, unicode: 0x0013, modifier: 0x41
17:52:38 T:4748 DEBUG: CInputManager::OnKey: ctrl-shift-s (0x3f053) pressed, action is Stop
17:52:38 T:4748 NOTICE: CDVDPlayer::CloseFile()
17:52:38 T:4748 NOTICE: DVDPlayer: waiting for threads to exit
17:52:38 T:4744 NOTICE: CDVDPlayer::OnExit()
17:52:38 T:4744 NOTICE: Closing stream player 1
17:52:38 T:4744 NOTICE: Waiting for audio thread to exit
17:52:38 T:4128 NOTICE: thread end: CDVDPlayerAudio::OnExit()
17:52:38 T:4744 NOTICE: Closing audio device
17:52:38 T:4128 DEBUG: Thread DVDPlayerAudio 4128 terminating
17:52:38 T:4536 DEBUG: CActiveAE::DiscardStream - audio stream deleted
17:52:38 T:4536 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
17:52:38 T:4744 NOTICE: Deleting audio codec
17:52:38 T:4536 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
17:52:38 T:4984 INFO: CActiveAESink::OpenSink - initialize sink
17:52:38 T:4744 NOTICE: Closing stream player 2
17:52:38 T:4744 NOTICE: waiting for video thread to exit
17:52:38 T:4108 NOTICE: thread end: video_thread
17:52:38 T:4108 DEBUG: Thread DVDPlayerVideo 4108 terminating
17:52:38 T:4744 NOTICE: deleting video codec
17:52:38 T:4744 DEBUG: DXVA::CDecoder::~CDecoder - destructing decoder, 305516992
17:52:38 T:4744 NOTICE: DXVA::CDecoder::Close - closing decoder
17:52:38 T:4744 NOTICE: DXVA::Close - closing decoder context
17:52:38 T:4744 DEBUG: CApplication::OnPlayBackStopped: play state was 2, starting 0
17:52:38 T:4744 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
17:52:38 T:4744 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStopsnippet of log playing video post fix 'sync playback to display':
Code:
18:10:23 T:2296 DEBUG: CGUIWindowVideoBase::OnPlayMedia E:\trailers\The Hunger Games Mockingjay Part 2-trailer-h720p.mov
18:10:23 T:2296 DEBUG: CVideoDatabase::GetMovieId (E:\trailers\The Hunger Games Mockingjay Part 2-trailer-h720p.mov), query = select idMovie from movie where idFile=10636
18:10:23 T:2296 DEBUG: CVideoDatabase::GetEpisodeId (E:\trailers\The Hunger Games Mockingjay Part 2-trailer-h720p.mov), query = select idEpisode from episode where idFile=10636
18:10:23 T:2296 DEBUG: CVideoDatabase::GetMusicVideoId (E:\trailers\The Hunger Games Mockingjay Part 2-trailer-h720p.mov), query = select idMVideo from musicvideo where idFile=10636
18:10:23 T:2296 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
18:10:23 T:2296 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
18:10:23 T:2296 DEBUG: Loading settings for E:\trailers\The Hunger Games Mockingjay Part 2-trailer-h720p.mov
18:10:23 T:2296 DEBUG: CPlayerCoreFactory::GetPlayers(E:\trailers\The Hunger Games Mockingjay Part 2-trailer-h720p.mov)
18:10:23 T:2296 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
18:10:23 T:2296 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
18:10:23 T:2296 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/mms/udp
18:10:23 T:2296 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
18:10:23 T:2296 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
18:10:23 T:2296 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
18:10:23 T:2296 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
18:10:23 T:2296 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
18:10:23 T:2296 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
18:10:23 T:2296 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
18:10:23 T:2296 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
18:10:23 T:2296 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
18:10:23 T:2296 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
18:10:23 T:2296 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
18:10:23 T:2296 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
18:10:23 T:2296 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
18:10:23 T:2296 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
18:10:23 T:2296 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
18:10:23 T:2296 NOTICE: DVDPlayer: Opening: E:\trailers\The Hunger Games Mockingjay Part 2-trailer-h720p.mov
18:10:23 T:2296 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
18:10:23 T:2296 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
18:10:23 T:3772 NOTICE: Thread DVDPlayer start, auto delete: false
18:10:23 T:3772 NOTICE: Creating InputStream
18:10:23 T:3772 DEBUG: CUtil::ScanForExternalSubtitles: Searching for subtitles...
18:10:23 T:3772 DEBUG: CUtil::ScanForExternalSubtitles: END (total time: 1 ms)
18:10:23 T:3772 NOTICE: Creating Demuxer
18:10:23 T:3772 DEBUG: CDVDDemuxFFmpeg::Open - probing detected format [mov,mp4,m4a,3gp,3g2,mj2]
18:10:23 T:3772 DEBUG: CDVDDemuxFFmpeg::Open - avformat_find_stream_info starting
18:10:23 T:3772 DEBUG: CDVDDemuxFFmpeg::Open - av_find_stream_info finished
18:10:23 T:3772 INFO: ffmpeg[EBC]: Input #0, mov,mp4,m4a,3gp,3g2,mj2, from 'E:\trailers\The Hunger Games Mockingjay Part 2-trailer-h720p.mov':
18:10:23 T:3772 INFO: ffmpeg[EBC]: Metadata:
18:10:23 T:3772 INFO: ffmpeg[EBC]: major_brand : qt
18:10:23 T:3772 INFO: ffmpeg[EBC]: minor_version : 537199360
18:10:23 T:3772 INFO: ffmpeg[EBC]: compatible_brands: qt
18:10:23 T:3772 INFO: ffmpeg[EBC]: creation_time : 2015-06-09 19:02:48
18:10:23 T:3772 INFO: ffmpeg[EBC]: timecode : 01:00:01:08
18:10:23 T:3772 INFO: ffmpeg[EBC]: Duration: 00:01:42.81, start: 0.000000, bitrate: 5145 kb/s
18:10:23 T:3772 INFO: ffmpeg[EBC]: Stream #0:0(eng): Video: h264 (Main) (avc1 / 0x31637661), yuv420p(tv, bt709), 1280x544, 5046 kb/s, 23.98 fps, 23.98 tbr, 24k tbn, 48k tbc (default)
18:10:23 T:3772 INFO: ffmpeg[EBC]: Metadata:
18:10:23 T:3772 INFO: ffmpeg[EBC]: creation_time : 2015-06-09 19:02:48
18:10:23 T:3772 INFO: ffmpeg[EBC]: handler_name : Apple Video Media Handler
18:10:23 T:3772 INFO: ffmpeg[EBC]: encoder : H.264
18:10:23 T:3772 INFO: ffmpeg[EBC]: Stream #0:1(eng): Data: none (tmcd / 0x64636D74), 0 kb/s (default)
18:10:23 T:3772 INFO: ffmpeg[EBC]: Metadata:
18:10:23 T:3772 INFO: ffmpeg[EBC]: creation_time : 2015-06-09 19:02:48
18:10:23 T:3772 INFO: ffmpeg[EBC]: handler_name : Time Code Media Handler
18:10:23 T:3772 INFO: ffmpeg[EBC]: reel_name : 876059
18:10:23 T:3772 INFO: ffmpeg[EBC]: timecode : 01:00:01:08
18:10:23 T:3772 INFO: ffmpeg[EBC]: Stream #0:2(eng): Audio: aac (LC) (mp4a / 0x6134706D), 44100 Hz, stereo, fltp, 93 kb/s (default)
18:10:23 T:3772 INFO: ffmpeg[EBC]: Metadata:
18:10:23 T:3772 INFO: ffmpeg[EBC]: creation_time : 2015-06-09 19:02:48
18:10:23 T:3772 INFO: ffmpeg[EBC]: handler_name : Apple Sound Media Handler
18:10:23 T:3772 DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0
18:10:23 T:3772 DEBUG: CDVDDemuxFFmpeg::AddStream(1, ...) -> 1
18:10:23 T:3772 DEBUG: CDVDDemuxFFmpeg::AddStream(2, ...) -> 2
18:10:23 T:3772 NOTICE: Opening stream: 0 source: 256
18:10:23 T:3772 NOTICE: Creating video codec with codec id: 28
18:10:23 T:3772 DEBUG: CDVDFactoryCodec: compiled in hardware support: AMCodec:no MediaCodec:no DXVA:yes iMXVPU:no MMAL:no
18:10:23 T:3772 DEBUG: FactoryCodec - Video: - Opening
18:10:23 T:3772 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
18:10:23 T:3772 DEBUG: FactoryCodec - Video: ff-h264 - Opened
18:10:23 T:3772 NOTICE: Creating video thread
18:10:23 T:3772 NOTICE: Opening stream: 2 source: 256
18:10:23 T:3772 NOTICE: Finding audio codec for: 86018
18:10:23 T:3772 DEBUG: FactoryCodec - Audio: passthrough - Opening
18:10:23 T:1108 NOTICE: Thread RefClock start, auto delete: false
18:10:23 T:3772 DEBUG: FactoryCodec - Audio: passthrough - Failed
18:10:23 T:1108 DEBUG: CVideoSyncD3D: Setting up Direct3d
18:10:23 T:4660 NOTICE: Thread DVDPlayerVideo start, auto delete: false
18:10:23 T:3772 DEBUG: FactoryCodec - Audio: FFmpeg - Opening
18:10:23 T:4660 NOTICE: running thread: video_thread
18:10:23 T:1108 DEBUG: CVideoReferenceClock: Detected refreshrate: 60.000 hertz
18:10:23 T:3772 DEBUG: FactoryCodec - Audio: FFmpeg - Opened
18:10:23 T:3772 NOTICE: Creating audio thread
18:10:23 T:4660 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
18:10:23 T:3772 DEBUG: CEdl::ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: E:\trailers\The Hunger Games Mockingjay Part 2-trailer-h720p.mov
18:10:23 T:488 NOTICE: Thread DVDPlayerAudio start, auto delete: false
18:10:23 T:488 NOTICE: running thread: CDVDPlayerAudio::Process()
18:10:23 T:488 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
18:10:23 T:3772 DEBUG: CApplication::OnPlayBackStarted: play state was 1, starting 1
18:10:23 T:3772 DEBUG: CDVDPlayer::SetCaching - caching state 3
18:10:23 T:2296 DEBUG: CApplication::PlayFile: OpenFile succeed, play state 2
18:10:23 T:2296 DEBUG: CApplication::OnPlayBackStarted: play state was 2, starting 0
18:10:23 T:488 NOTICE: Creating audio stream (codec id: 86018, channels: 2, sample rate: 44100, no pass-through)
18:10:23 T:488 DEBUG: CDVDPlayerAudio:: synctype set to 2: resample
18:10:23 T:4660 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(0.000000, 0)
18:10:23 T:4660 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
18:10:23 T:4660 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:10:23 T:3772 DEBUG: CDVDPlayer::CheckContinuity - wrapback :2, prev:83416.666667, curr:41708.333333, diff:-41708.333333
18:10:23 T:4660 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating DXVA(1280x544)
18:10:23 T:4660 DEBUG: DXVA - open decoder
18:10:23 T:4660 DEBUG: DXVA - supports 'MPEG2 IDCT'
18:10:23 T:4660 DEBUG: DXVA - supports 'MPEG1/2 VLD'
18:10:23 T:4660 DEBUG: DXVA - supports 'MPEG2 VLD'
18:10:23 T:4660 DEBUG: DXVA - supports 6F3EC719-3735-42cc-8063-65cc3cb36616
18:10:23 T:4660 DEBUG: DXVA - supports 'VC-1 VLD 2010'
18:10:23 T:4660 DEBUG: DXVA - supports 'VC-1 VLD'
18:10:23 T:4660 DEBUG: DXVA - supports 'VC-1 IDCT'
18:10:23 T:4660 DEBUG: DXVA - supports 'Windows Media Video 9 IDCT'
18:10:23 T:4660 DEBUG: DXVA - supports 32FCFE3F-de46-4a49-861b-ac71110649d5
18:10:23 T:4660 DEBUG: DXVA - supports D79BE8DA-0cf1-4c81-b82a-69a4e236f43d
18:10:23 T:4660 DEBUG: DXVA - supports F9AACCBB-c2b6-4cfc-8779-5707b1760552
18:10:23 T:4660 DEBUG: DXVA - supports 'H.264 VLD, no FGT'
18:10:23 T:4660 DEBUG: DXVA - supports 1B81BE6A-a0c7-11d3-b984-00c04f2e73c5
18:10:23 T:4660 DEBUG: DXVA - supports 8EFA5926-bd9e-4b04-8b72-8f977dc44c36
18:10:23 T:4660 DEBUG: DXVA - supports 'HEVC / H.265 variable-length decoder, main'
18:10:23 T:4660 DEBUG: DXVA - supports EFD64D74-c9e8-41d7-a5e9-e9b0e39fa319
18:10:23 T:4660 DEBUG: DXVA - supports ED418A9F-010d-4eda-9ae3-9a65358d8d2e
18:10:23 T:4660 DEBUG: DXVA - supports 9947EC6F-689b-11dc-a320-0019dbbc4184
18:10:23 T:4660 DEBUG: DXVA - supports B194EB52-19a0-41f0-b754-cc244ac1cb20
18:10:23 T:4660 DEBUG: DXVA - supports 6AFFD11E-1d96-42b1-a215-93a31f09a53d
18:10:23 T:4660 DEBUG: DXVA - trying 'H.264 VLD, no FGT'
18:10:23 T:4660 DEBUG: DXVA - source requires 2 references
18:10:23 T:4660 DEBUG: DXVA - config 0: bitstream type 1
18:10:23 T:4660 DEBUG: DXVA - config 1: bitstream type 2
18:10:23 T:4660 DEBUG: DXVA - allocating 12 surfaces
18:10:23 T:976 INFO: CActiveAESink::OpenSink - initialize sink
18:10:23 T:3772 DEBUG: CDVDPlayer::SetCaching - caching state 0
18:10:23 T:4660 NOTICE: fps: 23.976024, pwidth: 1280, pheight: 544, dwidth: 1280, dheight: 544
18:10:23 T:4660 DEBUG: CDVDPlayerVideo::OutputPicture - change configuration. 1280x544. framerate: 23.98. format: DXVA
18:10:23 T:4660 NOTICE: Display resolution DESKTOP : 1804x1004@ 60.00 - Full Screen (16)
18:10:23 T:4660 NOTICE: D3D: rendering method forced to DXVA processor
18:10:23 T:4660 DEBUG: DXVA::CProcessorHD::OpenProcessor - processor selected 95F4EDF4-6e03-4cd7-be1b-3075d665aa52.
18:10:23 T:4660 DEBUG: CWinRenderer::SelectRenderMethod: Selected render method 3: DXVA
18:10:23 T:4660 DEBUG: CXBMCRenderManager::Configure - 6
18:10:23 T:4660 DEBUG: CVideoReferenceClock: Clock speed 100.100000%
18:10:23 T:2296 DEBUG: CGUIInfoManager::SetCurrentMovie(E:\trailers\The Hunger Games Mockingjay Part 2-trailer-h720p.mov)
18:10:23 T:2296 DEBUG: CVideoDatabase::GetMovieId (E:\trailers\The Hunger Games Mockingjay Part 2-trailer-h720p.mov), query = select idMovie from movie where idFile=10636
18:10:23 T:2296 DEBUG: CVideoDatabase::GetEpisodeId (E:\trailers\The Hunger Games Mockingjay Part 2-trailer-h720p.mov), query = select idEpisode from episode where idFile=10636
18:10:23 T:2296 DEBUG: CVideoDatabase::GetMusicVideoId (E:\trailers\The Hunger Games Mockingjay Part 2-trailer-h720p.mov), query = select idMVideo from musicvideo where idFile=10636
18:10:23 T:2296 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
18:10:23 T:2296 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
18:10:23 T:2296 DEBUG: Activating window ID: 12005
18:10:23 T:2296 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
18:10:23 T:2296 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
18:10:23 T:2296 INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
18:10:23 T:2296 DEBUG: created video buffer 0
18:10:23 T:2296 DEBUG: created video buffer 1
18:10:23 T:2296 DEBUG: created video buffer 2
18:10:23 T:2296 DEBUG: created video buffer 3
18:10:23 T:2296 DEBUG: created video buffer 4
18:10:23 T:2296 DEBUG: created video buffer 5
18:10:23 T:2296 WARNING: CWinRenderer::UpdateVideoFilter - chosen scaling method 1 is not supported by renderer
18:10:23 T:2296 DEBUG: CWinRenderer::CreateIntermediateRenderTarget: format 22
18:10:23 T:4660 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
18:10:23 T:4660 DEBUG: ffmpeg[1234]: [h264] Increasing reorder buffer to 1
18:10:23 T:4660 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:10:23 T:4660 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
18:10:23 T:3772 DEBUG: CDVDPlayer::HandleMessages - player started 2
18:10:23 T:976 DEBUG: CActiveAESink::OpenSink - trying to open device WASAPI:default
18:10:23 T:976 INFO: CAESinkWASAPI::InitializeExclusive: WASAPI Exclusive Mode Sink Initialized using: AE_FMT_S24NE4MSB, 44100, 2
18:10:23 T:976 DEBUG: CActiveAESink::OpenSink - WASAPI Initialized:
18:10:23 T:976 DEBUG: Output Device : default
18:10:23 T:976 DEBUG: Sample Rate : 44100
18:10:23 T:976 DEBUG: Sample Format : AE_FMT_S24NE4MSB
18:10:23 T:976 DEBUG: Channel Count : 2
18:10:23 T:976 DEBUG: Channel Layout: FL,FR
18:10:23 T:976 DEBUG: Frames : 2208
18:10:23 T:976 DEBUG: Frame Samples : 4416
18:10:23 T:976 DEBUG: Frame Size : 8
18:10:23 T:1812 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
18:10:23 T:488 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(23219.954649, 1)
18:10:23 T:3772 DEBUG: CDVDPlayer::HandleMessages - player started 1
18:10:23 T:488 NOTICE: CDVDPlayerAudio::OutputPacket skipping a packets of duration 23
18:10:23 T:488 NOTICE: Previous line repeats 4 times.
18:10:23 T:488 DEBUG: CDVDPlayerAudio::HandleSyncError - average error -32174.525046 below threshold of 50000.000000
18:10:26 T:4724 DEBUG: CVideoDatabase::RunQuery took 62 ms for 203 items query: SELECT * FROM tvshow_view
18:10:27 T:4724 DEBUG: XFILE::CWin32SMBDirectory::ConnectAndAuthenticate: Connected to "\\husky\TV" without username and without password
18:10:28 T:4660 DEBUG: Previous line repeats 5 times.
18:10:28 T:4660 DEBUG: CPullupCorrection: detected pattern of length 1: 41708.33, frameduration: 41708.333333
18:10:28 T:4724 DEBUG: XFILE::CWin32SMBDirectory::ConnectAndAuthenticate: Connected to "\\husky\TV" without username and without password
18:10:29 T:4724 DEBUG: Previous line repeats 5 times.
18:10:29 T:4724 DEBUG: CVideoDatabase::RunQuery took 0 ms for 0 items query: select * from musicvideo_view
18:10:29 T:4724 INFO: CPythonInvoker(6, C:\Users\mel\AppData\Roaming\Kodi\addons\script.videoextras\service.py): script successfully run
18:10:29 T:4724 INFO: Python script stopped
18:10:29 T:4724 DEBUG: Thread LanguageInvoker 4724 terminating
18:10:53 T:1072 DEBUG: Thread JobWorker 1072 terminating (autodelete)
18:10:53 T:4776 DEBUG: Thread JobWorker 4776 terminating (autodelete)
18:10:53 T:4784 DEBUG: Thread JobWorker 4784 terminating (autodelete)
18:10:53 T:224 DEBUG: Thread JobWorker 224 terminating (autodelete)
18:11:07 T:2296 DEBUG: Keyboard: scancode: 0x1d, sym: 0x0132, unicode: 0x0000, modifier: 0x40
18:11:07 T:2296 DEBUG: CInputManager::OnKey: ctrl-leftctrl (0x1f0d0) pressed, action is
18:11:07 T:2296 DEBUG: Keyboard: scancode: 0x2a, sym: 0x0130, unicode: 0x0000, modifier: 0x41
18:11:07 T:2296 DEBUG: CInputManager::OnKey: ctrl-shift-leftshift (0x3f0d2) pressed, action is
18:11:07 T:2296 DEBUG: Keyboard: scancode: 0x1f, sym: 0x0073, unicode: 0x0013, modifier: 0x41
18:11:07 T:2296 DEBUG: CInputManager::OnKey: ctrl-shift-s (0x3f053) pressed, action is Stop
18:11:07 T:2296 NOTICE: CDVDPlayer::CloseFile()
18:11:07 T:2296 NOTICE: DVDPlayer: waiting for threads to exit
18:11:07 T:3772 NOTICE: CDVDPlayer::OnExit()
18:11:07 T:3772 NOTICE: Closing stream player 1
18:11:07 T:3772 NOTICE: Waiting for audio thread to exit
18:11:07 T:488 NOTICE: thread end: CDVDPlayerAudio::OnExit()
18:11:07 T:3772 NOTICE: Closing audio device
18:11:07 T:488 DEBUG: Thread DVDPlayerAudio 488 terminating
18:11:07 T:1812 DEBUG: CActiveAE::DiscardStream - audio stream deleted
18:11:07 T:1812 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
18:11:07 T:3772 NOTICE: Deleting audio codec
18:11:07 T:1812 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
18:11:07 T:976 INFO: CActiveAESink::OpenSink - initialize sink
18:11:07 T:3772 DEBUG: CVideoReferenceClock: Clock speed 100.000000%
18:11:07 T:3772 NOTICE: Closing stream player 2
18:11:07 T:3772 NOTICE: waiting for video thread to exit
18:11:07 T:4660 NOTICE: thread end: video_thread
18:11:07 T:4660 DEBUG: Thread DVDPlayerVideo 4660 terminating
18:11:07 T:3772 NOTICE: deleting video codec
18:11:07 T:3772 DEBUG: DXVA::CDecoder::~CDecoder - destructing decoder, 68010032
18:11:07 T:3772 NOTICE: DXVA::CDecoder::Close - closing decoder
18:11:07 T:3772 NOTICE: DXVA::Close - closing decoder context
18:11:07 T:3772 DEBUG: CApplication::OnPlayBackStopped: play state was 2, starting 0
18:11:07 T:3772 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
18:11:07 T:3772 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop