16:36:02.320 T:139802983159552 NOTICE: CVideoPlayer::OnExit() 16:36:02.320 T:139802983159552 NOTICE: VideoPlayer: eof, waiting for queues to empty 16:36:02.320 T:139802983159552 NOTICE: Closing stream player 2 16:36:02.320 T:139802983159552 NOTICE: CDVDMessageQueue(video)::WaitUntilEmpty 16:36:02.399 T:139802964154112 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 16:36:02.399 T:139802983159552 NOTICE: waiting for video thread to exit 16:36:02.399 T:139802964154112 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true 16:36:02.399 T:139802964154112 NOTICE: thread end: video_thread 16:36:02.399 T:139802983159552 NOTICE: deleting video codec 16:36:02.399 T:139802964154112 DEBUG: Thread VideoPlayerVideo 139802964154112 terminating 16:36:02.400 T:139802983159552 DEBUG: VAAPI::Release pre-cleanup 16:36:02.401 T:139802983159552 DEBUG: OnPlayBackEnded: play state was 2, starting 0 16:36:02.401 T:139802983159552 DEBUG: Thread VideoPlayer 139802983159552 terminating 16:36:02.403 T:139805112428288 DEBUG: GetMovieId (/var/media/Backup 6TB/Proj neu 5052 (1).mkv), query = select idMovie from movie where idFile=661 16:36:02.403 T:139805112428288 DEBUG: GetEpisodeId (/var/media/Backup 6TB/Proj neu 5052 (1).mkv), query = select idEpisode from episode where idFile=661 16:36:02.403 T:139805112428288 DEBUG: GetMusicVideoId (/var/media/Backup 6TB/Proj neu 5052 (1).mkv), query = select idMVideo from musicvideo where idFile=661 16:36:02.403 T:139805112428288 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 16:36:02.403 T:139805112428288 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 16:36:02.419 T:139805112444160 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 16:36:02.419 T:139805112444160 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 16:36:02.421 T:139805112444160 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 16:36:02.424 T:139805112444160 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 16:36:02.424 T:139805112444160 DEBUG: CGUIMediaWindow::GetDirectory (/var/media/Backup 6TB/) 16:36:02.424 T:139805112444160 DEBUG: ParentPath = [/var/media/Backup 6TB/] 16:36:02.425 T:139805112444160 DEBUG: Loading items: 10, directory: /var/media/Backup 6TB/ sort method: 0, ascending: true 16:36:02.426 T:139805112444160 DEBUG: RunQuery took 0 ms for 19 items query: SELECT files.strFilename, files.playCount, bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files LEFT JOIN bookmark ON files.idFile = bookmark.idFile AND bookmark.type = 1 WHERE files.idPath=5 16:36:02.426 T:139802983159552 DEBUG: Thread BackgroundLoader start, auto delete: false 16:36:02.431 T:139802964154112 DEBUG: Thread JobWorker start, auto delete: true 16:36:02.438 T:139802983159552 DEBUG: Thread BackgroundLoader 139802983159552 terminating 16:36:02.503 T:139805112444160 NOTICE: CVideoPlayer::CloseFile() 16:36:02.503 T:139805112444160 NOTICE: VideoPlayer: waiting for threads to exit 16:36:02.503 T:139805112444160 NOTICE: VideoPlayer: finished waiting 16:36:02.503 T:139805112444160 DEBUG: DeleteRenderer - deleting renderer 16:36:02.503 T:139805112444160 NOTICE: VAAPI::Close 16:36:02.504 T:139802955761408 NOTICE: COutput::OnExit: Output Thread terminated 16:36:02.504 T:139802955761408 DEBUG: Thread Vaapi-Output 139802955761408 terminating 16:36:02.504 T:139805112444160 DEBUG: VAAPI::FiniVAAPIOutput destroying 5 video surfaces 16:36:02.504 T:139805112444160 NOTICE: VAAPI::Close - closing decoder context 16:36:02.505 T:139805112444160 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:36:02.505 T:139805112444160 NOTICE: CVideoPlayer::CloseFile() 16:36:02.505 T:139805112444160 NOTICE: VideoPlayer: waiting for threads to exit 16:36:02.505 T:139805112444160 NOTICE: VideoPlayer: finished waiting 16:36:02.506 T:139805112444160 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 16:36:02.506 T:139805112444160 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/libsse4-x86_64-linux.so) 16:36:02.506 T:139802964154112 DEBUG: DoWork - Saving file state for video item /var/media/Backup 6TB/Proj neu 5052 (1).mkv 16:36:02.508 T:139802964154112 DEBUG: DoWork - Marking video item /var/media/Backup 6TB/Proj neu 5052 (1).mkv as watched 16:36:02.517 T:139805112428288 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc 16:36:02.517 T:139805112428288 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate 16:36:02.521 T:139802964154112 DEBUG: DoWork - trying to extract thumb from video file /var/media/Backup 6TB/25 fps full hd h264.m2ts 16:36:02.521 T:139802964154112 DEBUG: Open - probing detected format [mpegts] 16:36:02.521 T:139802964154112 DEBUG: Open - avformat_find_stream_info starting 16:36:02.549 T:139802964154112 DEBUG: Open - av_find_stream_info finished 16:36:02.549 T:139802964154112 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 16:36:02.549 T:139802964154112 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 16:36:02.549 T:139802964154112 DEBUG: ScanForExternalSubtitles: Searching for subtitles... 16:36:02.549 T:139802964154112 DEBUG: ScanForExternalSubtitles: END (total time: 1 ms) 16:36:02.549 T:139802964154112 DEBUG: FactoryCodec - Video: - Opening 16:36:02.549 T:139802964154112 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 16:36:02.549 T:139802964154112 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264 16:36:02.549 T:139802964154112 DEBUG: FactoryCodec - Video: ff-h264 - Opened 16:36:02.549 T:139802964154112 DEBUG: ExtractThumb - seeking to pos 20362ms (total: 61088ms) in /var/media/Backup 6TB/25 fps full hd h264.m2ts 16:36:02.551 T:139802964154112 DEBUG: SeekTime - seek ended up on time 20359 16:36:03.055 T:139803425220352 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'Comedy Central/VIVA' from client '2025648334' 16:36:03.055 T:139803425220352 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 1370065301 start 1487259363 stop 1488036963 16:36:03.055 T:139803425220352 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 1370065301 events 0 16:36:03.055 T:139803442005760 DEBUG: CPVRTimers - Update - updating timers 16:36:03.056 T:139803442005760 DEBUG: PVRTimers - UpdateEntries - updated timer 2 on client 2025648334 16:36:03.056 T:139803442005760 DEBUG: PVRTimers - UpdateEntries - updated timer 1 on client 2025648334 16:36:03.056 T:139803442005760 DEBUG: PVRTimers - UpdateEntries - updated timer 3 on client 2025648334 16:36:03.056 T:139803442005760 DEBUG: PVRTimers - UpdateEntries - updated timer 1562949879 on client 2025648334 16:36:03.056 T:139803442005760 DEBUG: PVRTimers - UpdateEntries - updated timer 1195464325 on client 2025648334 16:36:03.056 T:139803442005760 DEBUG: PVRTimers - UpdateEntries - updated timer 1257614806 on client 2025648334 16:36:03.056 T:139803442005760 DEBUG: PVRTimers - UpdateEntries - updated timer 2140842393 on client 2025648334 16:36:03.056 T:139803442005760 DEBUG: PVRTimers - UpdateEntries - updated timer 1488905587 on client 2025648334 16:36:03.693 T:139802964154112 DEBUG: ExtractThumb - decode failed in /var/media/Backup 6TB/25 fps full hd h264.m2ts after 321 packets. 16:36:03.694 T:139802964154112 DEBUG: ExtractThumb - measured 1174 ms to extract thumb from file in 321 packets. 16:36:03.694 T:139802964154112 DEBUG: DoWork - trying to extract thumb from video file /var/media/Backup 6TB/25 fps full hd h264.ts 16:36:03.694 T:139802955761408 DEBUG: Thread JobWorker start, auto delete: true 16:36:03.695 T:139802964154112 DEBUG: Open - avformat_find_stream_info starting 16:36:03.715 T:139802964154112 DEBUG: Open - av_find_stream_info finished 16:36:03.715 T:139802964154112 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 16:36:03.715 T:139802964154112 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 16:36:03.715 T:139802964154112 DEBUG: ScanForExternalSubtitles: Searching for subtitles... 16:36:03.716 T:139802964154112 DEBUG: ScanForExternalSubtitles: END (total time: 0 ms) 16:36:03.716 T:139802964154112 DEBUG: FactoryCodec - Video: - Opening 16:36:03.716 T:139802964154112 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 16:36:03.716 T:139802964154112 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264 16:36:03.716 T:139802964154112 DEBUG: FactoryCodec - Video: ff-h264 - Opened 16:36:03.716 T:139802964154112 DEBUG: ExtractThumb - seeking to pos 20362ms (total: 61088ms) in /var/media/Backup 6TB/25 fps full hd h264.ts 16:36:03.717 T:139802964154112 DEBUG: SeekTime - seek ended up on time 20359 16:36:04.783 T:139802964154112 DEBUG: ExtractThumb - decode failed in /var/media/Backup 6TB/25 fps full hd h264.ts after 321 packets. 16:36:04.784 T:139802964154112 DEBUG: ExtractThumb - measured 1089 ms to extract thumb from file in 321 packets. 16:36:13.755 T:139805112444160 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:36:13.755 T:139805112444160 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:36:13.755 T:139805002295040 INFO: CActiveAESink::OpenSink - initialize sink 16:36:13.755 T:139805002295040 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=HDMI,DEV=0 16:36:13.755 T:139805002295040 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=HDMI,DEV=0" 16:36:13.762 T:139805002295040 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=HDMI,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00" 16:36:13.763 T:139805002295040 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 16:36:13.763 T:139805002295040 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 16:36:13.763 T:139805002295040 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820 16:36:13.772 T:139805002295040 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 464, bufferSize 8816 16:36:13.772 T:139805002295040 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 16:36:13.773 T:139805002295040 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 16:36:13.773 T:139805002295040 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 16:36:13.773 T:139805002295040 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR) 16:36:13.773 T:139805002295040 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 16:36:13.773 T:139805002295040 DEBUG: Output Device : HDA Intel 16:36:13.773 T:139805002295040 DEBUG: Sample Rate : 44100 16:36:13.773 T:139805002295040 DEBUG: Sample Format : AE_FMT_S32NE 16:36:13.773 T:139805002295040 DEBUG: Channel Count : 2 16:36:13.773 T:139805002295040 DEBUG: Channel Layout: FL,FR 16:36:13.773 T:139805002295040 DEBUG: Frames : 464 16:36:13.773 T:139805002295040 DEBUG: Frame Size : 8 16:36:13.921 T:139805112444160 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:36:14.142 T:139805112444160 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0 16:36:14.270 T:139805112444160 DEBUG: OnKey: return (0xf00d) pressed, action is Select 16:36:14.273 T:139805112444160 DEBUG: OnPlayMedia /var/media/Backup 6TB/TestPALinterlaced krnak.mkv 16:36:14.274 T:139805112428288 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 16:36:14.274 T:139805112428288 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 16:36:14.277 T:139805112428288 DEBUG: GetMovieId (/var/media/Backup 6TB/TestPALinterlaced krnak.mkv), query = select idMovie from movie where idFile=451 16:36:14.277 T:139805112428288 DEBUG: GetEpisodeId (/var/media/Backup 6TB/TestPALinterlaced krnak.mkv), query = select idEpisode from episode where idFile=451 16:36:14.277 T:139805112428288 DEBUG: GetMusicVideoId (/var/media/Backup 6TB/TestPALinterlaced krnak.mkv), query = select idMVideo from musicvideo where idFile=451 16:36:14.277 T:139805112444160 DEBUG: Loading settings for /var/media/Backup 6TB/TestPALinterlaced krnak.mkv 16:36:14.278 T:139805112428288 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:36:14.278 T:139805112428288 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:36:14.280 T:139805112444160 DEBUG: CPlayerCoreFactory::GetPlayers(/var/media/Backup 6TB/TestPALinterlaced krnak.mkv) 16:36:14.280 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 16:36:14.280 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 16:36:14.280 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 16:36:14.280 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 16:36:14.280 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 16:36:14.280 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 16:36:14.280 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 16:36:14.280 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 16:36:14.280 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 16:36:14.280 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 16:36:14.280 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 16:36:14.280 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 16:36:14.280 T:139805112444160 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 16:36:14.280 T:139805112444160 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 16:36:14.280 T:139805112444160 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 16:36:14.280 T:139805112444160 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 16:36:14.280 T:139805112444160 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 16:36:14.282 T:139805112444160 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData 16:36:14.282 T:139805112444160 NOTICE: VideoPlayer: Opening: /var/media/Backup 6TB/TestPALinterlaced krnak.mkv 16:36:14.282 T:139805112444160 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 16:36:14.282 T:139805112444160 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:36:14.282 T:139805112444160 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16 16:36:14.282 T:139802983159552 DEBUG: Thread VideoPlayer start, auto delete: false 16:36:14.282 T:139802983159552 NOTICE: Creating InputStream 16:36:14.282 T:139802983159552 DEBUG: ScanForExternalSubtitles: Searching for subtitles... 16:36:14.283 T:139802983159552 DEBUG: ScanForExternalSubtitles: END (total time: 0 ms) 16:36:14.283 T:139802983159552 NOTICE: Creating Demuxer 16:36:14.283 T:139802983159552 DEBUG: Open - probing detected format [matroska,webm] 16:36:14.299 T:139802983159552 DEBUG: Open - avformat_find_stream_info starting 16:36:14.303 T:139802983159552 DEBUG: Open - av_find_stream_info finished 16:36:14.303 T:139802983159552 INFO: ffmpeg[7F266B265700]: Input #0, matroska,webm, /var/media/Backup 6TB/TestPALinterlaced krnak.mk': 16:36:14.303 T:139802983159552 INFO: ffmpeg[7F266B265700]: Metadata: 16:36:14.303 T:139802983159552 INFO: ffmpeg[7F266B265700]: encoder : libebml v1.3.4 + libmatroska v1.4.5 16:36:14.303 T:139802983159552 INFO: ffmpeg[7F266B265700]: creation_time : 2017-01-21 22:48:54 16:36:14.303 T:139802983159552 INFO: ffmpeg[7F266B265700]: Duration: 00:05:45.56, start: 0.000000, bitrate: 6005 kb/s 16:36:14.303 T:139802983159552 INFO: ffmpeg[7F266B265700]: Stream #0:0: Video: mpeg2video (Main), yuv420p(tv), 720x576 [SAR 64:45 DAR 16:9], 50 fps, 50 tbr, 1k tbn, 50 tbc (default) 16:36:14.303 T:139802983159552 INFO: ffmpeg[7F266B265700]: Metadata: 16:36:14.303 T:139802983159552 INFO: ffmpeg[7F266B265700]: BPS : 6001648 16:36:14.303 T:139802983159552 INFO: ffmpeg[7F266B265700]: BPS-eng : 6001648 16:36:14.303 T:139802983159552 INFO: ffmpeg[7F266B265700]: DURATION : 00:05:45.560000000 16:36:14.303 T:139802983159552 INFO: ffmpeg[7F266B265700]: DURATION-eng : 00:05:45.560000000 16:36:14.303 T:139802983159552 INFO: ffmpeg[7F266B265700]: NUMBER_OF_FRAMES: 8639 16:36:14.303 T:139802983159552 INFO: ffmpeg[7F266B265700]: NUMBER_OF_FRAMES-eng: 8639 16:36:14.303 T:139802983159552 INFO: ffmpeg[7F266B265700]: NUMBER_OF_BYTES : 259241189 16:36:14.303 T:139802983159552 INFO: ffmpeg[7F266B265700]: NUMBER_OF_BYTES-eng: 259241189 16:36:14.303 T:139802983159552 INFO: ffmpeg[7F266B265700]: _STATISTICS_WRITING_APP: mkvmerge v9.7.1 ('Pandemonium') 64bit 16:36:14.303 T:139802983159552 INFO: ffmpeg[7F266B265700]: _STATISTICS_WRITING_APP-eng: mkvmerge v9.7.1 ('Pandemonium') 64bit 16:36:14.303 T:139802983159552 INFO: ffmpeg[7F266B265700]: _STATISTICS_WRITING_DATE_UTC: 2017-01-21 22:48:54 16:36:14.303 T:139802983159552 INFO: ffmpeg[7F266B265700]: _STATISTICS_WRITING_DATE_UTC-eng: 2017-01-21 22:48:54 16:36:14.303 T:139802983159552 INFO: ffmpeg[7F266B265700]: _STATISTICS_TAGS: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES 16:36:14.303 T:139802983159552 INFO: ffmpeg[7F266B265700]: _STATISTICS_TAGS-eng: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES 16:36:14.303 T:139802983159552 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 16:36:14.303 T:139802983159552 NOTICE: Opening stream: 0 source: 256 16:36:14.303 T:139802983159552 NOTICE: Creating video codec with codec id: 2 16:36:14.303 T:139802983159552 DEBUG: FactoryCodec - Video: - Opening 16:36:14.303 T:139802983159552 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video 16:36:14.303 T:139802983159552 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-mpeg2video 16:36:14.303 T:139802983159552 DEBUG: FactoryCodec - Video: ff-mpeg2video - Opened 16:36:14.303 T:139802983159552 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 2 16:36:14.303 T:139802983159552 NOTICE: Creating video thread 16:36:14.303 T:139802983159552 DEBUG: ReadEditDecisionLists - Assuming PAL interlaced content. Adjusted frames per second from 50.000 (~50.00 fps) to 25.000 16:36:14.303 T:139802442659584 DEBUG: Thread VideoPlayerVideo start, auto delete: false 16:36:14.303 T:139802983159552 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: /var/media/Backup 6TB/TestPALinterlaced krnak.mkv 16:36:14.303 T:139802442659584 NOTICE: running thread: video_thread 16:36:14.304 T:139802983159552 DEBUG: OnPlayBackStarted: play state was 1, starting 1 16:36:14.304 T:139802983159552 DEBUG: CVideoPlayer::SetCaching - caching state 2 16:36:14.304 T:139802983159552 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 16:36:14.304 T:139805112444160 DEBUG: PlayFile: OpenFile succeed, play state 2 16:36:14.304 T:139802442659584 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 16:36:14.304 T:139805112444160 DEBUG: OnPlayBackStarted: play state was 2, starting 0 16:36:14.304 T:139802442659584 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 16:36:14.304 T:139802442659584 DEBUG: VAAPI - open decoder 16:36:14.304 T:139805112444160 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x0000, modifier: 0x0 16:36:14.304 T:139802442659584 DEBUG: VAAPI - initialize version 0.39 16:36:14.304 T:139802442659584 DEBUG: VAAPI - driver in use: Intel i965 driver for Intel(R) Broadwell - 1.7.3 16:36:14.304 T:139802442659584 DEBUG: VAAPI - attrib 0 (get/set) min -100 max 100 value 0x0 16:36:14.304 T:139802442659584 DEBUG: VAAPI - attrib 1 (get/set) min 0 max 100 value 0x32 16:36:14.304 T:139802442659584 DEBUG: VAAPI - attrib 2 (get/set) min -180 max 180 value 0x0 16:36:14.304 T:139802442659584 DEBUG: VAAPI - attrib 3 (get/set) min 0 max 100 value 0x32 16:36:14.304 T:139802442659584 DEBUG: VAAPI - attrib 6 (get/set) min 0 max 3 value 0x0 16:36:14.304 T:139802442659584 DEBUG: VAAPI - profile 0 16:36:14.304 T:139802442659584 DEBUG: VAAPI - profile 1 16:36:14.304 T:139802442659584 DEBUG: VAAPI - profile 13 16:36:14.304 T:139802442659584 DEBUG: VAAPI - profile 6 16:36:14.304 T:139802442659584 DEBUG: VAAPI - profile 7 16:36:14.304 T:139802442659584 DEBUG: VAAPI - profile 15 16:36:14.304 T:139802442659584 DEBUG: VAAPI - profile 16 16:36:14.304 T:139802442659584 DEBUG: VAAPI - profile 8 16:36:14.304 T:139802442659584 DEBUG: VAAPI - profile 9 16:36:14.304 T:139802442659584 DEBUG: VAAPI - profile 10 16:36:14.304 T:139802442659584 DEBUG: VAAPI - profile -1 16:36:14.304 T:139802442659584 DEBUG: VAAPI - profile 12 16:36:14.304 T:139802442659584 DEBUG: VAAPI - profile 14 16:36:14.304 T:139802434266880 DEBUG: Thread Vaapi-Output start, auto delete: false 16:36:14.304 T:139802434266880 NOTICE: COutput::OnStartup: Output Thread created 16:36:14.307 T:139802434266880 DEBUG: VAAPI::COutput::CreateEGLContext - created context 16:36:14.307 T:139802434266880 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libsse4-x86_64-linux.so) 16:36:14.307 T:139802434266880 DEBUG: Loading: /usr/lib/kodi/system/libsse4-x86_64-linux.so 16:36:14.309 T:139802434266880 DEBUG: VAAPI::COutput::InitBufferPool - Surfaces created 16:36:14.309 T:139802442659584 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-mpeg2video-vaapi 16:36:14.310 T:139802442659584 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 16:36:14.319 T:139802964154112 DEBUG: Previous line repeats 3 times. 16:36:14.319 T:139802964154112 DEBUG: CGUIInfoManager::SetCurrentMovie(/var/media/Backup 6TB/TestPALinterlaced krnak.mkv) 16:36:14.323 T:139802442659584 DEBUG: CRenderManager::Configure - change configuration. 720x576. display: 720x405. framerate: 50.00. format: VAAPI 16:36:14.324 T:139802964154112 DEBUG: GetMovieId (/var/media/Backup 6TB/TestPALinterlaced krnak.mkv), query = select idMovie from movie where idFile=451 16:36:14.324 T:139802964154112 DEBUG: Previous line repeats 1 times. 16:36:14.324 T:139802964154112 DEBUG: GetEpisodeId (/var/media/Backup 6TB/TestPALinterlaced krnak.mkv), query = select idEpisode from episode where idFile=451 16:36:14.325 T:139805112428288 DEBUG: Previous line repeats 1 times. 16:36:14.325 T:139805112428288 DEBUG: GetMusicVideoId (/var/media/Backup 6TB/TestPALinterlaced krnak.mkv), query = select idMVideo from musicvideo where idFile=451 16:36:14.326 T:139805112428288 DEBUG: Previous line repeats 1 times. 16:36:14.326 T:139805112428288 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 16:36:14.326 T:139805112428288 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 16:36:14.344 T:139805112444160 DEBUG: DeleteRenderer - deleting renderer 16:36:14.344 T:139805112444160 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:36:14.344 T:139805112444160 DEBUG: Previous line repeats 1 times. 16:36:14.344 T:139805112444160 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16 16:36:14.344 T:139805112444160 DEBUG: GL: Requested render method: 0 16:36:14.344 T:139805112444160 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: #define XBMC_texture_rectangle 0 #define XBMC_texture_rectangle_hack 0 #define XBMC_STRETCH 0 #define XBMC_NV12_RRG 16:36:14.344 T:139805112444160 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 16:36:14.345 T:139805112444160 DEBUG: GL: Vertex Shader compilation log: 16:36:14.345 T:139805112444160 DEBUG: GL: Vertex Shader compiled successfully 16:36:14.347 T:139805112444160 DEBUG: GL: Pixel Shader compilation log: 16:36:14.347 T:139805112444160 DEBUG: GL: Fragment Shader compiled successfully 16:36:14.358 T:139805112444160 DEBUG: GL: ConvolutionFilterShader: using convolution-4x4.glsl defines: #define HAS_FLOAT_TEXTURE 1 #define XBMC_STRETCH 0 #define USE1DTEXTURE 1 16:36:14.359 T:139805112444160 DEBUG: GL: Vertex Shader compilation log: 16:36:14.359 T:139805112444160 DEBUG: GL: Vertex Shader compiled successfully 16:36:14.361 T:139805112444160 DEBUG: GL: Pixel Shader compilation log: 16:36:14.361 T:139805112444160 DEBUG: GL: Fragment Shader compiled successfully 16:36:14.389 T:139805112444160 NOTICE: GL: NPOT texture support detected 16:36:14.389 T:139805112444160 NOTICE: GL: Using GL_ARB_pixel_buffer_object 16:36:14.389 T:139805112444160 NOTICE: Using GL_TEXTURE_2D 16:36:14.389 T:139805112444160 DEBUG: CRenderManager::Configure - 4 16:36:14.389 T:139805112444160 DEBUG: GL: Requested render method: 0 16:36:14.389 T:139805112444160 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: #define XBMC_texture_rectangle 0 #define XBMC_texture_rectangle_hack 0 #define XBMC_STRETCH 0 #define XBMC_NV12_RRG 16:36:14.389 T:139805112444160 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 16:36:14.390 T:139805112444160 DEBUG: GL: Vertex Shader compilation log: 16:36:14.390 T:139805112444160 DEBUG: GL: Vertex Shader compiled successfully 16:36:14.391 T:139805112444160 DEBUG: GL: Pixel Shader compilation log: 16:36:14.391 T:139805112444160 DEBUG: GL: Fragment Shader compiled successfully 16:36:14.395 T:139802983159552 DEBUG: CVideoPlayer::HandleMessages - player started 2 16:36:14.395 T:139802983159552 DEBUG: CVideoPlayer::SetCaching - caching state 3 16:36:14.395 T:139802983159552 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 16:36:14.395 T:139802983159552 DEBUG: CVideoPlayer::SetCaching - caching state 0 16:36:14.395 T:139802983159552 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 16:36:14.395 T:139802983159552 DEBUG: VideoPlayer::Sync - Video - pts: 40000.000000, cache: 50000.000000, totalcache: 100000.000000 16:36:14.395 T:139802983159552 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 16:36:14.395 T:139802442659584 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-60000.000000) 16:36:14.395 T:139802442659584 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 16:36:14.402 T:139805112444160 DEBUG: Previous line repeats 1 times. 16:36:14.402 T:139805112444160 NOTICE: GL: NPOT texture support detected 16:36:14.402 T:139805112444160 NOTICE: GL: Using GL_ARB_pixel_buffer_object 16:36:14.407 T:139805112444160 DEBUG: Activating window ID: 12005 16:36:14.407 T:139805112444160 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------ 16:36:14.407 T:139805112444160 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 16:36:14.408 T:139805112444160 NOTICE: Display resolution ADJUST : 1920x1080@ 50.00 - Full Screen (16) (weight: 0.000) 16:36:14.599 T:139802442659584 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 40000 16:36:16.698 T:139802442659584 DEBUG: CPullupCorrection: detected pattern of length 1: 20000.00, frameduration: 20000.000000 16:36:44.319 T:139802955761408 DEBUG: Thread JobWorker 139802955761408 terminating (autodelete) 16:36:44.326 T:139802964154112 DEBUG: Thread JobWorker 139802964154112 terminating (autodelete) 16:41:59.692 T:139802983159552 NOTICE: CVideoPlayer::OnExit() 16:41:59.692 T:139802983159552 NOTICE: VideoPlayer: eof, waiting for queues to empty 16:41:59.693 T:139802983159552 NOTICE: Closing stream player 2 16:41:59.693 T:139802983159552 NOTICE: CDVDMessageQueue(video)::WaitUntilEmpty 16:41:59.798 T:139802442659584 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 16:41:59.798 T:139802983159552 NOTICE: waiting for video thread to exit 16:41:59.798 T:139802442659584 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true 16:41:59.798 T:139802442659584 NOTICE: thread end: video_thread 16:41:59.799 T:139802442659584 DEBUG: Thread VideoPlayerVideo 139802442659584 terminating 16:41:59.799 T:139802983159552 NOTICE: deleting video codec 16:41:59.799 T:139802983159552 DEBUG: VAAPI::Release pre-cleanup 16:41:59.799 T:139802983159552 DEBUG: OnPlayBackEnded: play state was 2, starting 0 16:41:59.799 T:139802983159552 DEBUG: Thread VideoPlayer 139802983159552 terminating 16:41:59.801 T:139805112428288 DEBUG: GetMovieId (/var/media/Backup 6TB/TestPALinterlaced krnak.mkv), query = select idMovie from movie where idFile=451 16:41:59.801 T:139805112428288 DEBUG: GetEpisodeId (/var/media/Backup 6TB/TestPALinterlaced krnak.mkv), query = select idEpisode from episode where idFile=451 16:41:59.801 T:139805112428288 DEBUG: GetMusicVideoId (/var/media/Backup 6TB/TestPALinterlaced krnak.mkv), query = select idMVideo from musicvideo where idFile=451 16:41:59.801 T:139805112428288 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 16:41:59.801 T:139805112428288 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 16:41:59.818 T:139805112444160 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 16:41:59.818 T:139805112444160 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 16:41:59.821 T:139805112444160 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 16:41:59.823 T:139805112444160 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 16:41:59.824 T:139805112444160 DEBUG: CGUIMediaWindow::GetDirectory (/var/media/Backup 6TB/) 16:41:59.824 T:139805112444160 DEBUG: ParentPath = [/var/media/Backup 6TB/] 16:41:59.824 T:139805112444160 DEBUG: Loading items: 10, directory: /var/media/Backup 6TB/ sort method: 0, ascending: true 16:41:59.825 T:139805112444160 DEBUG: RunQuery took 0 ms for 19 items query: SELECT files.strFilename, files.playCount, bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files LEFT JOIN bookmark ON files.idFile = bookmark.idFile AND bookmark.type = 1 WHERE files.idPath=5 16:41:59.825 T:139802983159552 DEBUG: Thread BackgroundLoader start, auto delete: false 16:41:59.829 T:139805112444160 DEBUG: Keyboard: scancode: 0x1f, sym: 0x0069, unicode: 0x0069, modifier: 0x0 16:41:59.829 T:139805112444160 DEBUG: OnKey: i (0xf049) pressed, action is Info 16:41:59.830 T:139805112444160 DEBUG: GetMovieId (/var/media/Backup 6TB/TestPALinterlaced krnak.mkv), query = select idMovie from movie where idFile=451 16:41:59.830 T:139805112444160 DEBUG: GetEpisodeId (/var/media/Backup 6TB/TestPALinterlaced krnak.mkv), query = select idEpisode from episode where idFile=451 16:41:59.830 T:139802442659584 DEBUG: Thread JobWorker start, auto delete: true 16:41:59.837 T:139802983159552 DEBUG: Thread BackgroundLoader 139802983159552 terminating 16:41:59.903 T:139805112444160 NOTICE: CVideoPlayer::CloseFile() 16:41:59.903 T:139805112444160 NOTICE: VideoPlayer: waiting for threads to exit 16:41:59.903 T:139805112444160 NOTICE: VideoPlayer: finished waiting 16:41:59.903 T:139805112444160 DEBUG: DeleteRenderer - deleting renderer 16:41:59.903 T:139805112444160 NOTICE: VAAPI::Close 16:41:59.904 T:139802434266880 NOTICE: COutput::OnExit: Output Thread terminated 16:41:59.904 T:139805112444160 DEBUG: VAAPI::FiniVAAPIOutput destroying 0 video surfaces 16:41:59.904 T:139802434266880 DEBUG: Thread Vaapi-Output 139802434266880 terminating 16:41:59.904 T:139805112444160 NOTICE: VAAPI::Close - closing decoder context 16:41:59.905 T:139805112444160 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:41:59.905 T:139805112444160 NOTICE: CVideoPlayer::CloseFile() 16:41:59.905 T:139805112444160 NOTICE: VideoPlayer: waiting for threads to exit 16:41:59.905 T:139805112444160 NOTICE: VideoPlayer: finished waiting 16:41:59.906 T:139805112444160 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 16:41:59.982 T:139805112444160 DEBUG: Keyboard: scancode: 0x1f, sym: 0x0069, unicode: 0x0000, modifier: 0x0 16:42:00.064 T:139805112444160 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/libsse4-x86_64-linux.so) 16:42:00.064 T:139802442659584 DEBUG: DoWork - Saving file state for video item /var/media/Backup 6TB/TestPALinterlaced krnak.mkv 16:42:00.067 T:139802442659584 DEBUG: DoWork - Marking video item /var/media/Backup 6TB/TestPALinterlaced krnak.mkv as watched 16:42:00.075 T:139805112428288 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc 16:42:00.075 T:139805112428288 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate 16:42:00.077 T:139802442659584 DEBUG: DoWork - trying to extract thumb from video file /var/media/Backup 6TB/25 fps full hd h264.m2ts 16:42:00.078 T:139802442659584 DEBUG: Open - probing detected format [mpegts] 16:42:00.078 T:139802442659584 DEBUG: Open - avformat_find_stream_info starting 16:42:00.092 T:139803425220352 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'Comedy Central/VIVA' from client '2025648334' 16:42:00.092 T:139803425220352 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 1370065301 start 1487259720 stop 1488037320 16:42:00.092 T:139803425220352 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 1370065301 events 0 16:42:00.093 T:139803442005760 DEBUG: CPVRTimers - Update - updating timers 16:42:00.093 T:139803442005760 DEBUG: PVRTimers - UpdateEntries - updated timer 2 on client 2025648334 16:42:00.093 T:139803442005760 DEBUG: PVRTimers - UpdateEntries - updated timer 1 on client 2025648334 16:42:00.093 T:139803442005760 DEBUG: PVRTimers - UpdateEntries - updated timer 3 on client 2025648334 16:42:00.093 T:139803442005760 DEBUG: PVRTimers - UpdateEntries - updated timer 1562949879 on client 2025648334 16:42:00.093 T:139803442005760 DEBUG: PVRTimers - UpdateEntries - updated timer 1195464325 on client 2025648334 16:42:00.093 T:139803442005760 DEBUG: PVRTimers - UpdateEntries - updated timer 1257614806 on client 2025648334 16:42:00.093 T:139803442005760 DEBUG: PVRTimers - UpdateEntries - updated timer 2140842393 on client 2025648334 16:42:00.093 T:139803442005760 DEBUG: PVRTimers - UpdateEntries - updated timer 1488905587 on client 2025648334 16:42:00.109 T:139802442659584 DEBUG: Open - av_find_stream_info finished 16:42:00.109 T:139802442659584 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 16:42:00.109 T:139802442659584 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 16:42:00.109 T:139802442659584 DEBUG: ScanForExternalSubtitles: Searching for subtitles... 16:42:00.110 T:139802442659584 DEBUG: ScanForExternalSubtitles: END (total time: 0 ms) 16:42:00.110 T:139802442659584 DEBUG: FactoryCodec - Video: - Opening 16:42:00.110 T:139802442659584 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 16:42:00.110 T:139802442659584 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264 16:42:00.110 T:139802442659584 DEBUG: FactoryCodec - Video: ff-h264 - Opened 16:42:00.110 T:139802442659584 DEBUG: ExtractThumb - seeking to pos 20362ms (total: 61088ms) in /var/media/Backup 6TB/25 fps full hd h264.m2ts 16:42:00.112 T:139802442659584 DEBUG: SeekTime - seek ended up on time 20359 16:42:01.207 T:139802442659584 DEBUG: ExtractThumb - decode failed in /var/media/Backup 6TB/25 fps full hd h264.m2ts after 321 packets. 16:42:01.209 T:139802442659584 DEBUG: ExtractThumb - measured 1131 ms to extract thumb from file in 321 packets. 16:42:01.209 T:139802442659584 DEBUG: DoWork - trying to extract thumb from video file /var/media/Backup 6TB/25 fps full hd h264.ts 16:42:01.209 T:139802434266880 DEBUG: Thread JobWorker start, auto delete: true 16:42:01.209 T:139802442659584 DEBUG: Open - avformat_find_stream_info starting 16:42:01.231 T:139802442659584 DEBUG: Open - av_find_stream_info finished 16:42:01.232 T:139802442659584 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 16:42:01.232 T:139802442659584 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 16:42:01.232 T:139802442659584 DEBUG: ScanForExternalSubtitles: Searching for subtitles... 16:42:01.232 T:139802442659584 DEBUG: ScanForExternalSubtitles: END (total time: 0 ms) 16:42:01.232 T:139802442659584 DEBUG: FactoryCodec - Video: - Opening 16:42:01.232 T:139802442659584 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 16:42:01.232 T:139802442659584 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264 16:42:01.232 T:139802442659584 DEBUG: FactoryCodec - Video: ff-h264 - Opened 16:42:01.232 T:139802442659584 DEBUG: ExtractThumb - seeking to pos 20362ms (total: 61088ms) in /var/media/Backup 6TB/25 fps full hd h264.ts 16:42:01.234 T:139802442659584 DEBUG: SeekTime - seek ended up on time 20359 16:42:02.275 T:139802442659584 DEBUG: ExtractThumb - decode failed in /var/media/Backup 6TB/25 fps full hd h264.ts after 321 packets. 16:42:02.277 T:139802442659584 DEBUG: ExtractThumb - measured 1068 ms to extract thumb from file in 321 packets. 16:42:12.586 T:139805112444160 DEBUG: Keyboard: scancode: 0x6f, sym: 0x0111, unicode: 0x0000, modifier: 0x0 16:42:12.586 T:139805112444160 DEBUG: OnKey: up (0xf080) pressed, action is Up 16:42:12.586 T:139805002295040 INFO: CActiveAESink::OpenSink - initialize sink 16:42:12.586 T:139805002295040 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=HDMI,DEV=0 16:42:12.586 T:139805002295040 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=HDMI,DEV=0" 16:42:12.589 T:139805002295040 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=HDMI,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00" 16:42:12.590 T:139805002295040 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 16:42:12.590 T:139805002295040 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 16:42:12.590 T:139805002295040 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820 16:42:12.599 T:139805002295040 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 464, bufferSize 8816 16:42:12.599 T:139805002295040 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 16:42:12.600 T:139805002295040 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 16:42:12.600 T:139805002295040 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 16:42:12.600 T:139805002295040 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR) 16:42:12.600 T:139805002295040 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 16:42:12.600 T:139805002295040 DEBUG: Output Device : HDA Intel 16:42:12.600 T:139805002295040 DEBUG: Sample Rate : 44100 16:42:12.600 T:139805002295040 DEBUG: Sample Format : AE_FMT_S32NE 16:42:12.600 T:139805002295040 DEBUG: Channel Count : 2 16:42:12.600 T:139805002295040 DEBUG: Channel Layout: FL,FR 16:42:12.600 T:139805002295040 DEBUG: Frames : 464 16:42:12.600 T:139805002295040 DEBUG: Frame Size : 8 16:42:12.721 T:139805112444160 DEBUG: Keyboard: scancode: 0x6f, sym: 0x0111, unicode: 0x0000, modifier: 0x0 16:42:13.085 T:139805112444160 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0 16:42:13.208 T:139805112444160 DEBUG: OnKey: return (0xf00d) pressed, action is Select 16:42:13.214 T:139805112444160 DEBUG: OnPlayMedia /var/media/Backup 6TB/Proj neu 5052 (1).mkv 16:42:13.214 T:139805112428288 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 16:42:13.214 T:139805112428288 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 16:42:13.218 T:139805112428288 DEBUG: GetMovieId (/var/media/Backup 6TB/Proj neu 5052 (1).mkv), query = select idMovie from movie where idFile=661 16:42:13.218 T:139805112428288 DEBUG: GetEpisodeId (/var/media/Backup 6TB/Proj neu 5052 (1).mkv), query = select idEpisode from episode where idFile=661 16:42:13.218 T:139805112428288 DEBUG: GetMusicVideoId (/var/media/Backup 6TB/Proj neu 5052 (1).mkv), query = select idMVideo from musicvideo where idFile=661 16:42:13.218 T:139805112444160 DEBUG: Loading settings for /var/media/Backup 6TB/Proj neu 5052 (1).mkv 16:42:13.219 T:139805112428288 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:42:13.219 T:139805112428288 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:42:13.221 T:139805112444160 DEBUG: CPlayerCoreFactory::GetPlayers(/var/media/Backup 6TB/Proj neu 5052 (1).mkv) 16:42:13.221 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 16:42:13.221 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 16:42:13.221 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 16:42:13.221 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 16:42:13.221 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 16:42:13.221 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 16:42:13.221 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 16:42:13.221 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 16:42:13.221 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 16:42:13.221 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 16:42:13.221 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 16:42:13.221 T:139805112444160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 16:42:13.221 T:139805112444160 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 16:42:13.221 T:139805112444160 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 16:42:13.221 T:139805112444160 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 16:42:13.221 T:139805112444160 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 16:42:13.221 T:139805112444160 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 16:42:13.222 T:139805112444160 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData 16:42:13.223 T:139805112444160 NOTICE: VideoPlayer: Opening: /var/media/Backup 6TB/Proj neu 5052 (1).mkv 16:42:13.223 T:139805112444160 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 16:42:13.223 T:139805112444160 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:42:13.223 T:139805112444160 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16 16:42:13.223 T:139802983159552 DEBUG: Thread VideoPlayer start, auto delete: false 16:42:13.223 T:139802983159552 NOTICE: Creating InputStream 16:42:13.223 T:139802983159552 DEBUG: ScanForExternalSubtitles: Searching for subtitles... 16:42:13.223 T:139802983159552 DEBUG: ScanForExternalSubtitles: END (total time: 0 ms) 16:42:13.224 T:139802983159552 NOTICE: Creating Demuxer 16:42:13.224 T:139802983159552 DEBUG: Open - probing detected format [matroska,webm] 16:42:13.239 T:139802983159552 DEBUG: Open - avformat_find_stream_info starting 16:42:13.264 T:139802983159552 DEBUG: Open - av_find_stream_info finished 16:42:13.264 T:139802983159552 INFO: ffmpeg[7F266B265700]: Input #0, matroska,webm, /var/media/Backup 6TB/Proj neu 5052 (1).mk': 16:42:13.264 T:139802983159552 INFO: ffmpeg[7F266B265700]: Metadata: 16:42:13.264 T:139802983159552 INFO: ffmpeg[7F266B265700]: encoder : libebml v1.3.4 + libmatroska v1.4.5 16:42:13.264 T:139802983159552 INFO: ffmpeg[7F266B265700]: creation_time : 2017-02-15 15:01:15 16:42:13.264 T:139802983159552 INFO: ffmpeg[7F266B265700]: Duration: 00:05:45.56, start: 0.000000, bitrate: 5725 kb/s 16:42:13.264 T:139802983159552 INFO: ffmpeg[7F266B265700]: Stream #0:0(eng): Video: h264 (High), yuv420p(tv, bt709), 1280x720 [SAR 1:1 DAR 16:9], 50 fps, 50 tbr, 1k tbn, 100 tbc (default) 16:42:13.264 T:139802983159552 INFO: ffmpeg[7F266B265700]: Metadata: 16:42:13.264 T:139802983159552 INFO: ffmpeg[7F266B265700]: BPS : 5722596 16:42:13.264 T:139802983159552 INFO: ffmpeg[7F266B265700]: BPS-eng : 5722596 16:42:13.264 T:139802983159552 INFO: ffmpeg[7F266B265700]: DURATION : 00:05:45.560000000 16:42:13.264 T:139802983159552 INFO: ffmpeg[7F266B265700]: DURATION-eng : 00:05:45.560000000 16:42:13.264 T:139802983159552 INFO: ffmpeg[7F266B265700]: NUMBER_OF_FRAMES: 17278 16:42:13.264 T:139802983159552 INFO: ffmpeg[7F266B265700]: NUMBER_OF_FRAMES-eng: 17278 16:42:13.264 T:139802983159552 INFO: ffmpeg[7F266B265700]: NUMBER_OF_BYTES : 247187558 16:42:13.264 T:139802983159552 INFO: ffmpeg[7F266B265700]: NUMBER_OF_BYTES-eng: 247187558 16:42:13.264 T:139802983159552 INFO: ffmpeg[7F266B265700]: _STATISTICS_WRITING_APP: mkvmerge v9.8.0 ('Kuglblids') 64bit 16:42:13.264 T:139802983159552 INFO: ffmpeg[7F266B265700]: _STATISTICS_WRITING_APP-eng: mkvmerge v9.8.0 ('Kuglblids') 64bit 16:42:13.264 T:139802983159552 INFO: ffmpeg[7F266B265700]: _STATISTICS_WRITING_DATE_UTC: 2017-02-15 15:01:15 16:42:13.264 T:139802983159552 INFO: ffmpeg[7F266B265700]: _STATISTICS_WRITING_DATE_UTC-eng: 2017-02-15 15:01:15 16:42:13.264 T:139802983159552 INFO: ffmpeg[7F266B265700]: _STATISTICS_TAGS: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES 16:42:13.264 T:139802983159552 INFO: ffmpeg[7F266B265700]: _STATISTICS_TAGS-eng: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES 16:42:13.264 T:139802983159552 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 16:42:13.264 T:139802983159552 NOTICE: Opening stream: 0 source: 256 16:42:13.264 T:139802983159552 NOTICE: Creating video codec with codec id: 28 16:42:13.264 T:139802983159552 DEBUG: FactoryCodec - Video: - Opening 16:42:13.264 T:139802983159552 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 16:42:13.264 T:139802983159552 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264 16:42:13.264 T:139802983159552 DEBUG: FactoryCodec - Video: ff-h264 - Opened 16:42:13.264 T:139802983159552 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 28 16:42:13.264 T:139802983159552 NOTICE: Creating video thread 16:42:13.264 T:139802983159552 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: /var/media/Backup 6TB/Proj neu 5052 (1).mkv 16:42:13.264 T:139802964154112 DEBUG: Thread VideoPlayerVideo start, auto delete: false 16:42:13.264 T:139802964154112 NOTICE: running thread: video_thread 16:42:13.264 T:139802983159552 DEBUG: OnPlayBackStarted: play state was 1, starting 1 16:42:13.264 T:139802983159552 DEBUG: CVideoPlayer::SetCaching - caching state 2 16:42:13.264 T:139802983159552 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 16:42:13.264 T:139805112444160 DEBUG: PlayFile: OpenFile succeed, play state 2 16:42:13.264 T:139805112444160 DEBUG: OnPlayBackStarted: play state was 2, starting 0 16:42:13.264 T:139802964154112 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 16:42:13.264 T:139802964154112 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 16:42:13.265 T:139805112444160 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x0000, modifier: 0x0 16:42:13.265 T:139802964154112 DEBUG: VAAPI - open decoder 16:42:13.266 T:139802964154112 DEBUG: VAAPI - initialize version 0.39 16:42:13.266 T:139802964154112 DEBUG: VAAPI - driver in use: Intel i965 driver for Intel(R) Broadwell - 1.7.3 16:42:13.266 T:139802964154112 DEBUG: VAAPI - attrib 0 (get/set) min -100 max 100 value 0x0 16:42:13.266 T:139802964154112 DEBUG: VAAPI - attrib 1 (get/set) min 0 max 100 value 0x32 16:42:13.266 T:139802964154112 DEBUG: VAAPI - attrib 2 (get/set) min -180 max 180 value 0x0 16:42:13.266 T:139802964154112 DEBUG: VAAPI - attrib 3 (get/set) min 0 max 100 value 0x32 16:42:13.266 T:139802964154112 DEBUG: VAAPI - attrib 6 (get/set) min 0 max 3 value 0x0 16:42:13.267 T:139802964154112 DEBUG: VAAPI - profile 0 16:42:13.267 T:139802964154112 DEBUG: VAAPI - profile 1 16:42:13.267 T:139802964154112 DEBUG: VAAPI - profile 13 16:42:13.267 T:139802964154112 DEBUG: VAAPI - profile 6 16:42:13.267 T:139802964154112 DEBUG: VAAPI - profile 7 16:42:13.267 T:139802964154112 DEBUG: VAAPI - profile 15 16:42:13.267 T:139802964154112 DEBUG: VAAPI - profile 16 16:42:13.267 T:139802964154112 DEBUG: VAAPI - profile 8 16:42:13.267 T:139802964154112 DEBUG: VAAPI - profile 9 16:42:13.267 T:139802964154112 DEBUG: VAAPI - profile 10 16:42:13.267 T:139802964154112 DEBUG: VAAPI - profile -1 16:42:13.267 T:139802964154112 DEBUG: VAAPI - profile 12 16:42:13.267 T:139802964154112 DEBUG: VAAPI - profile 14 16:42:13.267 T:139802955761408 DEBUG: Thread Vaapi-Output start, auto delete: false 16:42:13.267 T:139802955761408 NOTICE: COutput::OnStartup: Output Thread created 16:42:13.268 T:139802955761408 DEBUG: VAAPI::COutput::CreateEGLContext - created context 16:42:13.268 T:139802955761408 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libsse4-x86_64-linux.so) 16:42:13.268 T:139802955761408 DEBUG: Loading: /usr/lib/kodi/system/libsse4-x86_64-linux.so 16:42:13.271 T:139802955761408 DEBUG: VAAPI::COutput::InitBufferPool - Surfaces created 16:42:13.271 T:139802964154112 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-vaapi 16:42:13.273 T:139802964154112 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 16:42:13.279 T:139802442659584 DEBUG: Previous line repeats 3 times. 16:42:13.279 T:139802442659584 DEBUG: CGUIInfoManager::SetCurrentMovie(/var/media/Backup 6TB/Proj neu 5052 (1).mkv) 16:42:13.284 T:139802442659584 DEBUG: GetMovieId (/var/media/Backup 6TB/Proj neu 5052 (1).mkv), query = select idMovie from movie where idFile=661 16:42:13.284 T:139802442659584 DEBUG: GetEpisodeId (/var/media/Backup 6TB/Proj neu 5052 (1).mkv), query = select idEpisode from episode where idFile=661 16:42:13.284 T:139802964154112 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 16:42:13.284 T:139802442659584 DEBUG: GetMusicVideoId (/var/media/Backup 6TB/Proj neu 5052 (1).mkv), query = select idMVideo from musicvideo where idFile=661 16:42:13.286 T:139802964154112 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 16:42:13.286 T:139805112428288 DEBUG: Previous line repeats 1 times. 16:42:13.286 T:139805112428288 DEBUG: GetMovieId (/var/media/Backup 6TB/Proj neu 5052 (1).mkv), query = select idMovie from movie where idFile=661 16:42:13.286 T:139805112428288 DEBUG: GetEpisodeId (/var/media/Backup 6TB/Proj neu 5052 (1).mkv), query = select idEpisode from episode where idFile=661 16:42:13.287 T:139802964154112 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 16:42:13.287 T:139805112428288 DEBUG: GetMusicVideoId (/var/media/Backup 6TB/Proj neu 5052 (1).mkv), query = select idMVideo from musicvideo where idFile=661 16:42:13.288 T:139805112428288 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 16:42:13.288 T:139805112428288 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 16:42:13.288 T:139802964154112 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 16:42:13.291 T:139802964154112 DEBUG: Previous line repeats 2 times. 16:42:13.291 T:139802964154112 DEBUG: CRenderManager::Configure - change configuration. 1280x720. display: 1280x720. framerate: 50.00. format: VAAPI 16:42:13.304 T:139805112444160 DEBUG: DeleteRenderer - deleting renderer 16:42:13.304 T:139805112444160 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:42:13.304 T:139805112444160 DEBUG: Previous line repeats 1 times. 16:42:13.304 T:139805112444160 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16 16:42:13.304 T:139805112444160 DEBUG: GL: Requested render method: 0 16:42:13.304 T:139805112444160 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: #define XBMC_texture_rectangle 0 #define XBMC_texture_rectangle_hack 0 #define XBMC_STRETCH 0 #define XBMC_NV12_RRG 16:42:13.304 T:139805112444160 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 16:42:13.305 T:139805112444160 DEBUG: GL: Vertex Shader compilation log: 16:42:13.305 T:139805112444160 DEBUG: GL: Vertex Shader compiled successfully 16:42:13.306 T:139805112444160 DEBUG: GL: Pixel Shader compilation log: 16:42:13.306 T:139805112444160 DEBUG: GL: Fragment Shader compiled successfully 16:42:13.317 T:139805112444160 DEBUG: GL: ConvolutionFilterShader: using convolution-4x4.glsl defines: #define HAS_FLOAT_TEXTURE 1 #define XBMC_STRETCH 0 #define USE1DTEXTURE 1 16:42:13.318 T:139805112444160 DEBUG: GL: Vertex Shader compilation log: 16:42:13.318 T:139805112444160 DEBUG: GL: Vertex Shader compiled successfully 16:42:13.320 T:139805112444160 DEBUG: GL: Pixel Shader compilation log: 16:42:13.320 T:139805112444160 DEBUG: GL: Fragment Shader compiled successfully 16:42:13.348 T:139805112444160 NOTICE: GL: NPOT texture support detected 16:42:13.348 T:139805112444160 NOTICE: GL: Using GL_ARB_pixel_buffer_object 16:42:13.348 T:139805112444160 NOTICE: Using GL_TEXTURE_2D 16:42:13.348 T:139805112444160 DEBUG: CRenderManager::Configure - 4 16:42:13.348 T:139805112444160 DEBUG: GL: Requested render method: 0 16:42:13.348 T:139805112444160 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: #define XBMC_texture_rectangle 0 #define XBMC_texture_rectangle_hack 0 #define XBMC_STRETCH 0 #define XBMC_NV12_RRG 16:42:13.348 T:139805112444160 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 16:42:13.349 T:139805112444160 DEBUG: GL: Vertex Shader compilation log: 16:42:13.349 T:139805112444160 DEBUG: GL: Vertex Shader compiled successfully 16:42:13.350 T:139802983159552 DEBUG: CVideoPlayer::HandleMessages - player started 2 16:42:13.350 T:139802983159552 DEBUG: CVideoPlayer::SetCaching - caching state 3 16:42:13.350 T:139805112444160 DEBUG: GL: Pixel Shader compilation log: 16:42:13.350 T:139802983159552 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 16:42:13.350 T:139802983159552 DEBUG: CVideoPlayer::SetCaching - caching state 0 16:42:13.350 T:139805112444160 DEBUG: GL: Fragment Shader compiled successfully 16:42:13.350 T:139802983159552 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 16:42:13.350 T:139802983159552 DEBUG: VideoPlayer::Sync - Video - pts: 0.000000, cache: 50000.000000, totalcache: 100000.000000 16:42:13.350 T:139802983159552 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 16:42:13.361 T:139805112444160 NOTICE: GL: NPOT texture support detected 16:42:13.361 T:139805112444160 NOTICE: GL: Using GL_ARB_pixel_buffer_object 16:42:13.368 T:139805112444160 DEBUG: Activating window ID: 12005 16:42:13.368 T:139805112444160 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------ 16:42:13.368 T:139805112444160 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 16:42:13.369 T:139805112444160 NOTICE: Display resolution ADJUST : 1920x1080@ 50.00 - Full Screen (16) (weight: 0.000) 16:42:13.379 T:139802964154112 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-100000.000000) 16:42:13.379 T:139802964154112 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 20000 16:42:15.659 T:139802964154112 DEBUG: CPullupCorrection: detected pattern of length 1: 20000.00, frameduration: 20000.000000 16:42:43.279 T:139802434266880 DEBUG: Thread JobWorker 139802434266880 terminating (autodelete) 16:42:43.286 T:139802442659584 DEBUG: Thread JobWorker 139802442659584 terminating (autodelete) 16:42:44.398 T:139805112444160 DEBUG: Keyboard: scancode: 0x1f, sym: 0x0069, unicode: 0x0069, modifier: 0x0 16:42:44.398 T:139805112444160 DEBUG: OnKey: i (0xf049) pressed, action is Info 16:42:44.398 T:139805112444160 DEBUG: ------ Window Init (DialogFullScreenInfo.xml) ------ 16:42:44.399 T:139805112444160 DEBUG: ------ Window Init (DialogSeekBar.xml) ------ 16:42:44.399 T:139802442659584 DEBUG: Thread JobWorker start, auto delete: true 16:42:44.399 T:139805112444160 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------ 16:42:44.499 T:139805112444160 DEBUG: Keyboard: scancode: 0x1f, sym: 0x0069, unicode: 0x0000, modifier: 0x0 16:42:52.219 T:139805112444160 DEBUG: Keyboard: scancode: 0x35, sym: 0x0078, unicode: 0x0078, modifier: 0x0 16:42:52.219 T:139805112444160 DEBUG: OnKey: x (0xf058) pressed, action is Stop 16:42:52.219 T:139805112444160 NOTICE: CVideoPlayer::CloseFile() 16:42:52.219 T:139805112444160 NOTICE: VideoPlayer: waiting for threads to exit 16:42:52.222 T:139802983159552 NOTICE: CVideoPlayer::OnExit() 16:42:52.222 T:139802983159552 NOTICE: Closing stream player 2 16:42:52.222 T:139802983159552 NOTICE: waiting for video thread to exit 16:42:52.267 T:139802964154112 NOTICE: thread end: video_thread 16:42:52.267 T:139802964154112 DEBUG: Thread VideoPlayerVideo 139802964154112 terminating 16:42:52.268 T:139802983159552 NOTICE: deleting video codec 16:42:52.268 T:139802983159552 DEBUG: VAAPI::Release pre-cleanup 16:42:52.268 T:139802983159552 DEBUG: OnPlayBackStopped: play state was 2, starting 0 16:42:52.269 T:139802983159552 DEBUG: Thread VideoPlayer 139802983159552 terminating 16:42:52.269 T:139805112444160 NOTICE: VideoPlayer: finished waiting 16:42:52.269 T:139805112444160 DEBUG: DeleteRenderer - deleting renderer 16:42:52.269 T:139805112444160 NOTICE: VAAPI::Close 16:42:52.269 T:139802955761408 NOTICE: COutput::OnExit: Output Thread terminated 16:42:52.269 T:139802955761408 DEBUG: Thread Vaapi-Output 139802955761408 terminating 16:42:52.269 T:139805112444160 DEBUG: VAAPI::FiniVAAPIOutput destroying 5 video surfaces 16:42:52.269 T:139805112444160 NOTICE: VAAPI::Close - closing decoder context 16:42:52.270 T:139805112444160 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:42:52.270 T:139805112444160 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 16:42:52.270 T:139805112444160 DEBUG: ------ Window Deinit (DialogFullScreenInfo.xml) ------ 16:42:52.270 T:139805112444160 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 16:42:52.270 T:139805112428288 DEBUG: GetMovieId (/var/media/Backup 6TB/Proj neu 5052 (1).mkv), query = select idMovie from movie where idFile=661 16:42:52.270 T:139805112428288 DEBUG: GetEpisodeId (/var/media/Backup 6TB/Proj neu 5052 (1).mkv), query = select idEpisode from episode where idFile=661 16:42:52.270 T:139805112428288 DEBUG: GetMusicVideoId (/var/media/Backup 6TB/Proj neu 5052 (1).mkv), query = select idMVideo from musicvideo where idFile=661 16:42:52.271 T:139805112428288 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 16:42:52.271 T:139805112428288 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 16:42:52.273 T:139805112444160 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 16:42:52.275 T:139805112444160 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 16:42:52.275 T:139805112444160 DEBUG: CGUIMediaWindow::GetDirectory (/var/media/Backup 6TB/) 16:42:52.275 T:139805112444160 DEBUG: ParentPath = [/var/media/Backup 6TB/] 16:42:52.275 T:139805112444160 DEBUG: Loading items: 10, directory: /var/media/Backup 6TB/ sort method: 0, ascending: true 16:42:52.277 T:139805112444160 DEBUG: RunQuery took 0 ms for 19 items query: SELECT files.strFilename, files.playCount, bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files LEFT JOIN bookmark ON files.idFile = bookmark.idFile AND bookmark.type = 1 WHERE files.idPath=5 16:42:52.277 T:139802955761408 DEBUG: Thread BackgroundLoader start, auto delete: false 16:42:52.288 T:139802955761408 DEBUG: Thread BackgroundLoader 139802955761408 terminating 16:42:52.359 T:139805112444160 NOTICE: CVideoPlayer::CloseFile() 16:42:52.359 T:139805112444160 NOTICE: VideoPlayer: waiting for threads to exit 16:42:52.359 T:139805112444160 NOTICE: VideoPlayer: finished waiting 16:42:52.359 T:139805112444160 NOTICE: CVideoPlayer::CloseFile() 16:42:52.359 T:139805112444160 NOTICE: VideoPlayer: waiting for threads to exit 16:42:52.359 T:139805112444160 NOTICE: VideoPlayer: finished waiting 16:42:52.361 T:139805112444160 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 16:42:52.362 T:139805112444160 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------ 16:42:52.380 T:139805112444160 DEBUG: Keyboard: scancode: 0x35, sym: 0x0078, unicode: 0x0000, modifier: 0x0 16:42:52.402 T:139805112444160 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/libsse4-x86_64-linux.so) 16:42:52.402 T:139802442659584 DEBUG: DoWork - Saving file state for video item /var/media/Backup 6TB/Proj neu 5052 (1).mkv 16:42:52.424 T:139802442659584 DEBUG: DoWork - trying to extract thumb from video file /var/media/Backup 6TB/25 fps full hd h264.m2ts 16:42:52.424 T:139802442659584 DEBUG: Open - probing detected format [mpegts] 16:42:52.424 T:139802442659584 DEBUG: Open - avformat_find_stream_info starting 16:42:52.451 T:139802442659584 DEBUG: Open - av_find_stream_info finished 16:42:52.451 T:139802442659584 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 16:42:52.451 T:139802442659584 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 16:42:52.451 T:139802442659584 DEBUG: ScanForExternalSubtitles: Searching for subtitles... 16:42:52.451 T:139802442659584 DEBUG: ScanForExternalSubtitles: END (total time: 0 ms) 16:42:52.451 T:139802442659584 DEBUG: FactoryCodec - Video: - Opening 16:42:52.451 T:139802442659584 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 16:42:52.451 T:139802442659584 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264 16:42:52.451 T:139802442659584 DEBUG: FactoryCodec - Video: ff-h264 - Opened 16:42:52.451 T:139802442659584 DEBUG: ExtractThumb - seeking to pos 20362ms (total: 61088ms) in /var/media/Backup 6TB/25 fps full hd h264.m2ts 16:42:52.454 T:139802442659584 DEBUG: SeekTime - seek ended up on time 20359 16:42:52.522 T:139805112444160 DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------ 16:42:53.585 T:139802442659584 DEBUG: ExtractThumb - decode failed in /var/media/Backup 6TB/25 fps full hd h264.m2ts after 321 packets. 16:42:53.587 T:139802442659584 DEBUG: ExtractThumb - measured 1163 ms to extract thumb from file in 321 packets. 16:42:53.587 T:139802442659584 DEBUG: DoWork - trying to extract thumb from video file /var/media/Backup 6TB/25 fps full hd h264.ts 16:42:53.587 T:139802955761408 DEBUG: Thread JobWorker start, auto delete: true 16:42:53.587 T:139802442659584 DEBUG: Open - avformat_find_stream_info starting 16:42:53.613 T:139802442659584 DEBUG: Open - av_find_stream_info finished 16:42:53.613 T:139802442659584 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 16:42:53.613 T:139802442659584 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 16:42:53.613 T:139802442659584 DEBUG: ScanForExternalSubtitles: Searching for subtitles... 16:42:53.614 T:139802442659584 DEBUG: ScanForExternalSubtitles: END (total time: 1 ms) 16:42:53.614 T:139802442659584 DEBUG: FactoryCodec - Video: - Opening 16:42:53.614 T:139802442659584 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 16:42:53.614 T:139802442659584 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264 16:42:53.614 T:139802442659584 DEBUG: FactoryCodec - Video: ff-h264 - Opened 16:42:53.614 T:139802442659584 DEBUG: ExtractThumb - seeking to pos 20362ms (total: 61088ms) in /var/media/Backup 6TB/25 fps full hd h264.ts 16:42:53.616 T:139802442659584 DEBUG: SeekTime - seek ended up on time 20359 16:42:54.646 T:139802442659584 DEBUG: ExtractThumb - decode failed in /var/media/Backup 6TB/25 fps full hd h264.ts after 321 packets. 16:42:54.648 T:139802442659584 DEBUG: ExtractThumb - measured 1061 ms to extract thumb from file in 321 packets. 16:43:23.587 T:139802955761408 DEBUG: Thread JobWorker 139802955761408 terminating (autodelete) 16:43:24.648 T:139802442659584 DEBUG: Thread JobWorker 139802442659584 terminating (autodelete)