1:39:46.838 T:139696339619136 NOTICE: VideoPlayer: finished waiting 21:40:31.168 T:139696339619136 NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM" 21:40:31.168 T:139696339619136 NOTICE: Enabled debug logging due to GUI setting. Level 2. 21:40:31.169 T:139696339619136 DEBUG: ------ Window Init () ------ 21:40:32.585 T:139696339619136 DEBUG: LIRC: Update - NEW at 102546481:ae 0 KEY_EXIT devinput (KEY_EXIT) 21:40:32.585 T:139696339619136 DEBUG: OnKey: menu (0xd8) pressed, action is PreviousMenu 21:40:32.595 T:139696339619136 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 21:40:32.906 T:139696339619136 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------ 21:40:32.917 T:139696339619136 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 21:40:32.917 T:139696339619136 DEBUG: ------ Window Init (EventLog.xml) ------ 21:40:32.918 T:139696339619136 DEBUG: CGUIMediaWindow::GetDirectory (events://) 21:40:32.918 T:139696339619136 DEBUG: ParentPath = [events://] 21:40:36.287 T:139696339619136 DEBUG: LIRC: Update - NEW at 102550183:69 0 KEY_LEFT devinput (KEY_LEFT) 21:40:36.287 T:139696339619136 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 21:40:37.205 T:139696339619136 DEBUG: LIRC: Update - NEW at 102551101:1c 0 KEY_ENTER devinput (KEY_ENTER) 21:40:37.205 T:139696339619136 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 21:40:37.205 T:139696339619136 DEBUG: Activating window ID: 10016 21:40:37.504 T:139696339619136 DEBUG: ------ Window Deinit (EventLog.xml) ------ 21:40:37.517 T:139696339619136 DEBUG: ------ Window Init (SettingsCategory.xml) ------ 21:40:38.571 T:139696339619136 DEBUG: LIRC: Update - NEW at 102552467:6a 0 KEY_RIGHT devinput (KEY_RIGHT) 21:40:38.571 T:139696339619136 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 21:40:39.535 T:139696339619136 DEBUG: LIRC: Update - NEW at 102553430:6c 0 KEY_DOWN devinput (KEY_DOWN) 21:40:39.535 T:139696339619136 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 21:40:39.951 T:139696339619136 DEBUG: LIRC: Update - NEW at 102553847:6c 0 KEY_DOWN devinput (KEY_DOWN) 21:40:39.951 T:139696339619136 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 21:40:41.101 T:139696339619136 DEBUG: LIRC: Update - NEW at 102554997:6c 0 KEY_DOWN devinput (KEY_DOWN) 21:40:41.101 T:139696339619136 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 21:40:41.934 T:139696339619136 DEBUG: LIRC: Update - NEW at 102555830:6c 0 KEY_DOWN devinput (KEY_DOWN) 21:40:41.935 T:139696339619136 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 21:40:43.668 T:139696339619136 DEBUG: LIRC: Update - NEW at 102557564:6c 0 KEY_DOWN devinput (KEY_DOWN) 21:40:43.668 T:139696339619136 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 21:40:45.601 T:139696339619136 DEBUG: LIRC: Update - NEW at 102559497:1c 0 KEY_ENTER devinput (KEY_ENTER) 21:40:45.601 T:139696339619136 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 21:40:46.934 T:139696339619136 DEBUG: LIRC: Update - NEW at 102560830:6c 0 KEY_DOWN devinput (KEY_DOWN) 21:40:46.934 T:139696339619136 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 21:40:48.514 T:139695732279040 DEBUG: RunQuery took 47 ms for 459 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount < 1)) 21:40:48.751 T:139696339619136 DEBUG: LIRC: Update - NEW at 102562647:1c 0 KEY_ENTER devinput (KEY_ENTER) 21:40:48.751 T:139696339619136 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 21:40:48.751 T:139696339619136 DEBUG: Activating window ID: 10050 21:40:49.055 T:139696339619136 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------ 21:40:49.088 T:139696339619136 DEBUG: ------ Window Init (EventLog.xml) ------ 21:40:49.089 T:139696339619136 DEBUG: CGUIMediaWindow::GetDirectory (events://) 21:40:49.089 T:139696339619136 DEBUG: ParentPath = [] 21:40:49.427 T:139695732279040 DEBUG: RunQuery took 730 ms for 12255 items query: select * from episode_view WHERE ((episode_view.playCount IS NULL OR episode_view.playCount < 1)) 21:40:50.026 T:139695732279040 DEBUG: RunQuery took 1 ms for 0 items query: select * from musicvideo_view 21:40:50.031 T:139695732279040 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album' 21:40:50.032 T:139695732279040 DEBUG: GetAlbumsByWhere - query took 1 ms 21:40:50.037 T:139695732279040 DEBUG: GetArtistsByWhere query: SELECT artistview.* FROM artistview WHERE ((EXISTS (SELECT 1 FROM song_artist WHERE song_artist.idArtist = artistview.idArtist AND song_artist.idRole = 1) OR EXISTS (SELECT 1 FROM album_artist WHERE album_artist.idArtist = artistview.idArtist)) AND (artistview.strArtist != '')) AND (artistview.strArtist <> 'Various artists') 21:40:50.038 T:139695732279040 DEBUG: Time to retrieve artists from dataset = 1 21:40:50.045 T:139695732279040 DEBUG: GetSongsFullByWhere query = SELECT sv.*, songartistview.* FROM (SELECT songview.* FROM songview WHERE ((CAST(songview.iTimesPlayed as DECIMAL(5,1)) < 1)) ORDER BY RANDOM() LIMIT 20) AS sv JOIN songartistview ON songartistview.idsong = sv.idsong ORDER BY songartistview.idsong, songartistview.idRole, songartistview.iOrder 21:40:50.236 T:139695732279040 DEBUG: Skin Widgets: Total time needed to request random queries: 0:00:01.781358 21:40:50.543 T:139696339619136 DEBUG: LIRC: Update - NEW at 102564439:69 0 KEY_LEFT devinput (KEY_LEFT) 21:40:50.543 T:139696339619136 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 21:40:51.195 T:139696339619136 DEBUG: LIRC: Update - NEW at 102565091:ae 0 KEY_EXIT devinput (KEY_EXIT) 21:40:51.195 T:139696339619136 DEBUG: OnKey: menu (0xd8) pressed, action is Back 21:40:51.195 T:139696339619136 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 21:40:51.503 T:139696339619136 DEBUG: ------ Window Deinit (EventLog.xml) ------ 21:40:51.517 T:139696339619136 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 21:40:51.517 T:139696339619136 DEBUG: ------ Window Init (Settings.xml) ------ 21:40:52.758 T:139696339619136 DEBUG: LIRC: Update - NEW at 102566654:ae 0 KEY_EXIT devinput (KEY_EXIT) 21:40:52.759 T:139696339619136 DEBUG: OnKey: menu (0xd8) pressed, action is PreviousMenu 21:40:52.759 T:139696339619136 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 21:40:53.058 T:139696339619136 DEBUG: ------ Window Deinit (Settings.xml) ------ 21:40:53.069 T:139696339619136 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 21:40:53.069 T:139696339619136 DEBUG: ------ Window Init (Home.xml) ------ 21:40:53.071 T:139696339619136 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing.. 21:40:53.071 T:139696339619136 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing.. 21:40:53.071 T:139696339619136 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing.. 21:40:53.071 T:139696339619136 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing.. 21:40:53.071 T:139696339619136 DEBUG: CDirectoryProvider[special://videoplaylists/]: refreshing.. 21:40:53.128 T:139693602207488 DEBUG: CMultiPathDirectory::GetDirectory(multipath://special%3a%2f%2fprofile%2fplaylists%2fvideo/special%3a%2f%2fprofile%2fplaylists%2fmixed/) 21:40:53.128 T:139693602207488 DEBUG: Getting Directory (special://profile/playlists/video) 21:40:53.130 T:139693602207488 DEBUG: Getting Directory (special://profile/playlists/mixed) 21:40:53.131 T:139693602207488 DEBUG: CMultiPathDirectory::MergeItems, items = 1 21:40:53.131 T:139693602207488 DEBUG: CMultiPathDirectory::MergeItems, items = 1, took 0 ms 21:40:53.147 T:139693622040320 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update 21:40:53.167 T:139693622040320 DEBUG: GetRecentlyAddedAlbumSongs() query: SELECT songview.*, songartistview.* FROM (SELECT idAlbum FROM album ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum = recentalbums.idAlbum JOIN songartistview ON songview.idSong = songartistview.idSong ORDER BY songview.idAlbum DESC, songview.idSong, songartistview.idRole, songartistview.iOrder 21:40:53.169 T:139693622040320 DEBUG: GetRecentlyAddedAlbums query: SELECT albumview.*, albumartistview.* FROM (SELECT idAlbum FROM album WHERE strAlbum != '' ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN albumview ON albumview.idAlbum = recentalbums.idAlbum JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum ORDER BY albumview.idAlbum desc, albumartistview.iOrder 21:40:53.171 T:139693622040320 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update 21:40:53.213 T:139693622040320 DEBUG: RunQuery took 25 ms for 10 items query: select * from movie_view ORDER BY dateAdded desc, idMovie desc LIMIT 10 21:40:53.703 T:139693622040320 DEBUG: RunQuery took 451 ms for 10 items query: select * from episode_view ORDER BY dateAdded desc, idEpisode desc LIMIT 10 21:40:53.773 T:139693622040320 DEBUG: RunQuery took 2 ms for 0 items query: select * from musicvideo_view ORDER BY dateAdded desc, idMVideo desc LIMIT 10 21:40:53.777 T:139693622040320 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update 21:40:53.782 T:139693622040320 DEBUG: GetArtistsByWhere query: SELECT COUNT(DISTINCT artistview.idArtist) FROM artistview WHERE ((EXISTS (SELECT 1 FROM song_artist WHERE song_artist.idArtist = artistview.idArtist AND song_artist.idRole = 1) OR EXISTS (SELECT 1 FROM album_artist WHERE album_artist.idArtist = artistview.idArtist)) AND (artistview.strArtist != '')) AND (artistview.strArtist <> 'Various artists') 21:40:54.726 T:139696339619136 DEBUG: LIRC: Update - NEW at 102568622:69 0 KEY_LEFT devinput (KEY_LEFT) 21:40:54.726 T:139696339619136 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 21:40:55.689 T:139696339619136 DEBUG: LIRC: Update - NEW at 102569585:6c 0 KEY_DOWN devinput (KEY_DOWN) 21:40:55.689 T:139696339619136 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 21:40:56.003 T:139696339619136 DEBUG: LIRC: Update - NEW at 102569898:6c 0 KEY_DOWN devinput (KEY_DOWN) 21:40:56.003 T:139696339619136 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 21:40:56.779 T:139696339619136 DEBUG: LIRC: Update - NEW at 102570675:6a 0 KEY_RIGHT devinput (KEY_RIGHT) 21:40:56.779 T:139696339619136 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 21:40:57.973 T:139696339619136 DEBUG: LIRC: Update - NEW at 102571868:1c 0 KEY_ENTER devinput (KEY_ENTER) 21:40:57.973 T:139696339619136 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 21:40:58.026 T:139696339619136 DEBUG: ------ Window Init (DialogContextMenu.xml) ------ 21:40:59.208 T:139696339619136 DEBUG: LIRC: Update - NEW at 102573103:1c 0 KEY_ENTER devinput (KEY_ENTER) 21:40:59.208 T:139696339619136 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 21:40:59.454 T:139696339619136 DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------ 21:40:59.458 T:139696339605248 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 21:40:59.458 T:139696339619136 DEBUG: DoWork - Saving file state for video item smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv 21:40:59.458 T:139696339605248 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 21:40:59.471 T:139696339619136 DEBUG: Mysql execute: update files set playCount=NULL,lastPlayed='2018-05-15 21:40:59' where idFile=19003 21:40:59.475 T:139696339605248 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc 21:40:59.475 T:139696339605248 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate 21:40:59.478 T:139696339619136 DEBUG: Mysql execute: delete from bookmark where idFile=19003 and type=1 21:40:59.480 T:139696339605248 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc 21:40:59.481 T:139696339605248 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate 21:40:59.484 T:139696339619136 DEBUG: Mysql execute: update settings set Deinterlace=1,ViewMode=0,ZoomAmount=1.000000,PixelRatio=1.000000,VerticalShift=0.000000,AudioStream=-1,SubtitleStream=-1,SubtitleDelay=0.000000,SubtitlesOn=1,Brightness=50.000000,Contrast=50.000000,Gamma=20.000000,VolumeAmplification=0.000000,AudioDelay=0.000000,OutputToAllSpeakers=0,Sharpness=0.000000,NoiseReduction=0.000000,NonLinStretch=0,PostProcess=0,ScalingMethod=1,ResumeTime=0,StereoMode=0,StereoInvert=0, VideoStream=-1 where idFile=19003 21:40:59.487 T:139695740671744 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnClear - data: {"playlistid":1} 21:40:59.487 T:139695740671744 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: VideoLibrary.OnUpdate - data: {"item":{"id":18433,"type":"episode"}} 21:40:59.490 T:139696339619136 DEBUG: Mysql Start transaction 21:40:59.490 T:139696339619136 DEBUG: Mysql execute: DELETE FROM streamdetails WHERE idFile = 19003 21:40:59.491 T:139696339619136 DEBUG: Mysql execute: INSERT INTO streamdetails (idFile, iStreamType, strVideoCodec, fVideoAspect, iVideoWidth, iVideoHeight, iVideoDuration, strStereoMode, strVideoLanguage) VALUES (19003,0,'h264',1.000000,1280,720,4322,'','eng') 21:40:59.492 T:139696339619136 DEBUG: Mysql execute: INSERT INTO streamdetails (idFile, iStreamType, strAudioCodec, iAudioChannels, strAudioLanguage) VALUES (19003,1,'eac3',6,'eng') 21:40:59.493 T:139696339619136 DEBUG: Mysql execute: INSERT INTO streamdetails (idFile, iStreamType, strSubtitleLanguage) VALUES (19003,2,'eng') 21:40:59.494 T:139696339619136 DEBUG: Previous line repeats 1 times. 21:40:59.494 T:139696339619136 DEBUG: Mysql execute: update movie set c11=4322 where idFile=19003 and c11='' 21:40:59.495 T:139696339619136 DEBUG: Mysql execute: update episode set c09=4322 where idFile=19003 and c09='' 21:40:59.495 T:139696339619136 DEBUG: Mysql execute: update musicvideo set c04=4322 where idFile=19003 and c04='' 21:40:59.499 T:139696339619136 DEBUG: Mysql commit transaction 21:40:59.520 T:139696339619136 DEBUG: Loading settings for smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv 21:40:59.523 T:139695707100928 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnClear - data: {"playlistid":1} 21:40:59.524 T:139695707100928 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: VideoLibrary.OnUpdate - data: {"item":{"id":18433,"type":"episode"}} 21:40:59.524 T:139695707100928 DEBUG: Skin Helper Widgets --> Video database changed - type: episode - refreshing widgets.... 21:40:59.525 T:139695707100928 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: VideoLibrary.OnUpdate - data: {"id":18433,"type":"episode"} 21:40:59.525 T:139695707100928 DEBUG: Skin Helper Widgets --> Video database changed - type: episode - refreshing widgets.... 21:40:59.535 T:139696339619136 DEBUG: CPlayerCoreFactory::GetPlayers(smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv) 21:40:59.535 T:139696339619136 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 21:40:59.535 T:139696339619136 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 21:40:59.535 T:139696339619136 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 21:40:59.535 T:139696339619136 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 21:40:59.535 T:139696339619136 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 21:40:59.535 T:139696339619136 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 21:40:59.535 T:139696339619136 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 21:40:59.535 T:139696339619136 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 21:40:59.535 T:139696339619136 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 21:40:59.535 T:139696339619136 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 21:40:59.536 T:139696339619136 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 21:40:59.536 T:139696339619136 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 21:40:59.536 T:139696339619136 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 21:40:59.536 T:139696339619136 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 21:40:59.536 T:139696339619136 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 21:40:59.536 T:139696339619136 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 21:40:59.536 T:139696339619136 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 21:40:59.536 T:139696339619136 NOTICE: CVideoPlayer::CloseFile() 21:40:59.536 T:139696339619136 NOTICE: VideoPlayer: waiting for threads to exit 21:40:59.536 T:139696339619136 NOTICE: VideoPlayer: finished waiting 21:40:59.536 T:139696339619136 NOTICE: VideoPlayer: Opening: smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv 21:40:59.536 T:139696339619136 DEBUG: LinuxRendererGL: Cleaning up GL resources 21:40:59.536 T:139696339619136 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16 21:40:59.537 T:139693870020352 DEBUG: Thread VideoPlayer start, auto delete: false 21:40:59.537 T:139693870020352 NOTICE: Creating InputStream 21:40:59.555 T:139693870020352 DEBUG: CSMBFile::Open - opened smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv, fd=10001 21:40:59.559 T:139693870020352 DEBUG: ScanForExternalSubtitles: Searching for subtitles... 21:40:59.566 T:139693870020352 DEBUG: ScanForExternalSubtitles: END (total time: 8 ms) 21:40:59.566 T:139693870020352 NOTICE: Creating Demuxer 21:40:59.587 T:139693870020352 DEBUG: Open - probing detected format [matroska,webm] 21:40:59.587 T:139693870020352 DEBUG: Open - avformat_find_stream_info starting 21:40:59.602 T:139693870020352 DEBUG: Open - av_find_stream_info finished 21:40:59.602 T:139693870020352 INFO: ffmpeg[7F0D037FF700]: Input #0, matroska,webm, smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mk': 21:40:59.602 T:139693870020352 INFO: ffmpeg[7F0D037FF700]: Metadata: 21:40:59.602 T:139693870020352 INFO: ffmpeg[7F0D037FF700]: encoder : libebml v1.3.5 + libmatroska v1.4.8 21:40:59.602 T:139693870020352 INFO: ffmpeg[7F0D037FF700]: creation_time : 2018-05-14 00:02:04 21:40:59.602 T:139693870020352 INFO: ffmpeg[7F0D037FF700]: Duration: 01:12:02.61, start: 0.000000, bitrate: 2776 kb/s 21:40:59.603 T:139693870020352 INFO: ffmpeg[7F0D037FF700]: Stream #0:0(eng): Video: h264 (High), yuv420p(tv, bt709), 1280x720 [SAR 1:1 DAR 16:9], 23.98 fps, 23.98 tbr, 1k tbn, 47.95 tbc (default) 21:40:59.603 T:139693870020352 INFO: ffmpeg[7F0D037FF700]: Stream #0:1(eng): Audio: eac3, 48000 Hz, 5.1(side), fltp, 640 kb/s (default) 21:40:59.603 T:139693870020352 INFO: ffmpeg[7F0D037FF700]: Stream #0:2(eng): Subtitle: subrip 21:40:59.603 T:139693870020352 INFO: ffmpeg[7F0D037FF700]: Metadata: 21:40:59.603 T:139693870020352 INFO: ffmpeg[7F0D037FF700]: title : English 21:40:59.603 T:139693870020352 INFO: ffmpeg[7F0D037FF700]: Stream #0:3(eng): Subtitle: subrip 21:40:59.603 T:139693870020352 INFO: ffmpeg[7F0D037FF700]: Metadata: 21:40:59.603 T:139693870020352 INFO: ffmpeg[7F0D037FF700]: title : English (SDH) 21:40:59.603 T:139693870020352 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 21:40:59.603 T:139693870020352 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 21:40:59.603 T:139693870020352 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 2 21:40:59.603 T:139693870020352 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 3 21:40:59.604 T:139693870020352 NOTICE: Opening stream: 0 source: 256 21:40:59.604 T:139693870020352 NOTICE: Creating video codec with codec id: 28 21:40:59.604 T:139693870020352 DEBUG: FactoryCodec - Video: - Opening 21:40:59.604 T:139693870020352 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 21:40:59.604 T:139693870020352 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264 21:40:59.604 T:139693870020352 DEBUG: FactoryCodec - Video: ff-h264 - Opened 21:40:59.604 T:139693870020352 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 28 21:40:59.604 T:139693870020352 NOTICE: Creating video thread 21:40:59.604 T:139693870020352 NOTICE: Opening stream: 1 source: 256 21:40:59.605 T:139693870020352 NOTICE: Finding audio codec for: 86057 21:40:59.605 T:139693811918592 DEBUG: Thread VideoPlayerVideo start, auto delete: false 21:40:59.605 T:139693870020352 DEBUG: FactoryCodec - Audio: passthrough - Opening 21:40:59.605 T:139693811918592 NOTICE: running thread: video_thread 21:40:59.605 T:139693870020352 DEBUG: FactoryCodec - Audio: passthrough - Failed 21:40:59.605 T:139693870020352 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 21:40:59.606 T:139693870020352 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 21:40:59.606 T:139693870020352 NOTICE: Creating audio thread 21:40:59.606 T:139693861627648 DEBUG: Thread VideoPlayerAudio start, auto delete: false 21:40:59.606 T:139693870020352 NOTICE: Opening stream: 2 source: 256 21:40:59.606 T:139693861627648 NOTICE: running thread: CVideoPlayerAudio::Process() 21:40:59.606 T:139693870020352 DEBUG: FactoryCodec - Overlay: Text Subtitle Decoder - Opening 21:40:59.606 T:139693870020352 DEBUG: FactoryCodec - Overlay: Text Subtitle Decoder - Opened 21:40:59.607 T:139693870020352 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv 21:40:59.608 T:139693870020352 DEBUG: OnPlayBackStarted: play state was 1, starting 1 21:40:59.608 T:139693870020352 DEBUG: CVideoPlayer::SetCaching - caching state 2 21:40:59.608 T:139696339619136 DEBUG: PlayFile: OpenFile succeed, play state 2 21:40:59.608 T:139696339619136 DEBUG: OnPlayBackStarted: play state was 2, starting 0 21:40:59.608 T:139693870020352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 21:40:59.609 T:139693811918592 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 21:40:59.609 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:40:59.609 T:139696339619136 DEBUG: CDirectoryProvider[videodb://inprogresstvshows]: refreshing.. 21:40:59.609 T:139696339619136 DEBUG: CDirectoryProvider[special://skin/playlists/recent_unwatched_episodes.xsp]: refreshing.. 21:40:59.610 T:139696339619136 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_tvshows.xsp]: refreshing.. 21:40:59.610 T:139693811918592 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating VDPAU(1280x720) 21:40:59.612 T:139696339619136 DEBUG: CDirectoryProvider[videodb://tvshows/genres/]: refreshing.. 21:40:59.612 T:139696339619136 DEBUG: CDirectoryProvider[videodb://tvshows/studios/]: refreshing.. 21:40:59.616 T:139693861627648 DEBUG: CDVDAudio::Pause - pausing audio stream 21:40:59.617 T:139693811918592 NOTICE: VDPAU::CreateContext - creating decoder context 21:40:59.617 T:139693861627648 NOTICE: Creating audio stream (codec id: 86057, channels: 6, sample rate: 48000, no pass-through) 21:40:59.618 T:139693861627648 DEBUG: CVideoPlayerAudio:: synctype set to 0: clock feedback 21:40:59.630 T:139696227219200 INFO: CActiveAESink::OpenSink - initialize sink 21:40:59.652 T:139693811918592 NOTICE: vdp_device = 0x00000001 vdp_st = 0x00000000 21:40:59.652 T:139693811918592 NOTICE: VDPAU Decoder capabilities: 21:40:59.652 T:139693811918592 NOTICE: name level macbs width height 21:40:59.652 T:139693811918592 NOTICE: ------------------------------------ 21:40:59.652 T:139693811918592 NOTICE: MPEG1 0 8192 2048 2048 21:40:59.652 T:139693811918592 NOTICE: MPEG2_SIMPLE 3 8192 2048 2048 21:40:59.652 T:139693811918592 NOTICE: MPEG2_MAIN 3 8192 2048 2048 21:40:59.652 T:139693811918592 NOTICE: H264_MAIN 41 8192 2048 2048 21:40:59.652 T:139693811918592 NOTICE: H264_HIGH 41 8192 2048 2048 21:40:59.652 T:139693811918592 NOTICE: VC1_SIMPLE 1 8190 2048 2048 21:40:59.652 T:139693811918592 NOTICE: VC1_MAIN 2 8190 2048 2048 21:40:59.652 T:139693811918592 NOTICE: VC1_ADVANCED 4 8190 2048 2048 21:40:59.652 T:139693811918592 NOTICE: MPEG4_PART2_ASP 5 8192 2048 2048 21:40:59.652 T:139693811918592 NOTICE: ------------------------------------ 21:40:59.652 T:139693811918592 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_NOISE_REDUCTION 21:40:59.652 T:139693811918592 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_SHARPNESS 21:40:59.652 T:139693811918592 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_DEINTERLACE_TEMPORAL 21:40:59.652 T:139693811918592 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_DEINTERLACE_TEMPORAL_SPATIAL 21:40:59.652 T:139693811918592 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_INVERSE_TELECINE 21:40:59.652 T:139693811918592 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_HIGH_QUALITY_SCALING_L1 21:40:59.677 T:139694616606464 NOTICE: Stream All The Sources: Service: Playback started 21:40:59.681 T:139696339605248 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 21:40:59.681 T:139696339605248 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 21:40:59.725 T:139695707100928 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnPlay - data: {"item":{"title":"","type":"movie"},"player":{"playerid":1,"speed":1}} 21:40:59.737 T:139696339619136 DEBUG: CDirectoryProvider[videodb://inprogresstvshows]: refreshing.. 21:40:59.746 T:139693602207488 DEBUG: RunQuery took 122 ms for 20 items query: SELECT * FROM tvshow_view WHERE watchedCount != 0 AND totalCount != watchedCount ORDER BY c00 21:40:59.749 T:139693811918592 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-vdpau 21:40:59.751 T:139693811918592 DEBUG: CVDPAU::SetWidthHeight Setting OutWidth: 1280 OutHeight: 720 21:40:59.751 T:139693811918592 NOTICE: (VDPAU) screenWidth:1280 vidWidth:1280 surfaceWidth:1280 21:40:59.751 T:139693811918592 NOTICE: (VDPAU) screenHeight:720 vidHeight:720 surfaceHeight:720 21:40:59.760 T:139693622040320 DEBUG: RunQuery took 125 ms for 133 items query: SELECT * FROM tvshow_view WHERE ((tvshow_view.watchedcount = 0)) AND ((tvshow_view.totalCount > 0)) 21:40:59.827 T:139693844842240 DEBUG: Thread Vdpau Output start, auto delete: false 21:40:59.827 T:139693844842240 NOTICE: COutput::OnStartup: Output Thread created 21:40:59.876 T:139693844842240 NOTICE: VDPAU::COutput::CreateGlxContext - created context 21:40:59.877 T:139693844842240 NOTICE: VDPAU::COutput: vdpau gl interop initialized 21:40:59.878 T:139693650130688 DEBUG: Thread Vdpau Mixer start, auto delete: false 21:40:59.878 T:139693650130688 NOTICE: CMixer::OnStartup: Output Thread created 21:40:59.878 T:139693650130688 NOTICE: (VDPAU) Creating the video mixer 21:40:59.884 T:139693844842240 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created 21:40:59.889 T:139693811918592 NOTICE: Previous line repeats 3 times. 21:40:59.889 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:40:59.911 T:139693844842240 DEBUG: Previous line repeats 3 times. 21:40:59.911 T:139693844842240 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created 21:40:59.911 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:40:59.913 T:139693844842240 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created 21:40:59.927 T:139693844842240 NOTICE: Previous line repeats 1 times. 21:40:59.927 T:139693844842240 NOTICE: VDPAU::COutput registered output surfaces 21:40:59.929 T:139693811918592 DEBUG: CRenderManager::Configure - change configuration. 1280x720. display: 1280x720. framerate: 23.98. format: VDPAU 21:40:59.930 T:139693844842240 NOTICE: VDPAU::COutput registered output surfaces 21:40:59.939 T:139696339619136 NOTICE: Previous line repeats 1 times. 21:40:59.939 T:139696339619136 DEBUG: DeleteRenderer - deleting renderer 21:40:59.939 T:139696339619136 DEBUG: LinuxRendererGL: Cleaning up GL resources 21:40:59.954 T:139693622040320 DEBUG: RunQuery took 120 ms for 53 items query: SELECT studio.studio_id, studio.name FROM studio JOIN studio_link ON studio.studio_id = studio_link.studio_id JOIN tvshow_view ON studio_link.media_id = tvshow_view.idShow AND studio_link.media_type='tvshow' GROUP BY studio.studio_id 21:40:59.962 T:139696339619136 DEBUG: LinuxRendererGL: Cleaning up GL resources 21:40:59.962 T:139696339619136 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16 21:40:59.962 T:139696339619136 NOTICE: GL: Using VDPAU render method 21:40:59.962 T:139696339619136 NOTICE: GL: NPOT texture support detected 21:40:59.962 T:139696339619136 NOTICE: GL: Using GL_ARB_pixel_buffer_object 21:40:59.962 T:139696339619136 NOTICE: Using GL_TEXTURE_2D 21:40:59.962 T:139696339619136 DEBUG: CRenderManager::Configure - 5 21:40:59.964 T:139696339619136 DEBUG: GL: Vertex Shader compilation log: 21:40:59.964 T:139696339619136 DEBUG: GL: Vertex Shader compiled successfully 21:40:59.965 T:139693870020352 DEBUG: CVideoPlayer::HandleMessages - player started 2 21:40:59.966 T:139696339619136 DEBUG: GL: Pixel Shader compilation log: 21:40:59.966 T:139696339619136 DEBUG: GL: Fragment Shader compiled successfully 21:40:59.967 T:139693602207488 DEBUG: RunQuery took 152 ms for 25 items query: SELECT genre.genre_id, genre.name FROM genre JOIN genre_link ON genre.genre_id = genre_link.genre_id JOIN tvshow_view ON genre_link.media_id = tvshow_view.idShow AND genre_link.media_type='tvshow' GROUP BY genre.genre_id 21:40:59.987 T:139696227219200 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=NVidia,DEV=1 21:40:59.987 T:139696227219200 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=NVidia,DEV=1" 21:40:59.999 T:139696227219200 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=NVidia,DEV=1,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x02" 21:41:00.000 T:139696227219200 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 21:41:00.001 T:139696227219200 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 21:41:00.001 T:139696227219200 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600 21:41:00.002 T:139696339619136 DEBUG: Activating window ID: 12005 21:41:00.002 T:139696339619136 DEBUG: ------ Window Deinit (Home.xml) ------ 21:41:00.003 T:139696339619136 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 21:41:00.041 T:139693622040320 DEBUG: CGUIInfoManager::SetCurrentMovie(smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv) 21:41:00.050 T:139693622040320 DEBUG: GetMovieId (smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv), query = select idMovie from movie where idFile=19003 21:41:00.053 T:139693622040320 DEBUG: GetEpisodeId (smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv), query = select idEpisode from episode where idFile=19003 21:41:00.118 T:139695740671744 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: VideoLibrary.OnUpdate - data: {"id":18433,"type":"episode"} 21:41:00.167 T:139696227219200 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600 21:41:00.167 T:139696227219200 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 21:41:00.169 T:139693731321600 DEBUG: RunQuery took 120 ms for 20 items query: SELECT * FROM tvshow_view WHERE watchedCount != 0 AND totalCount != watchedCount ORDER BY c00 21:41:00.297 T:139696227219200 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 21:41:00.297 T:139696227219200 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 21:41:00.297 T:139696227219200 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR) 21:41:00.297 T:139696227219200 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 21:41:00.297 T:139696227219200 DEBUG: Output Device : HDA NVidia 21:41:00.297 T:139696227219200 DEBUG: Sample Rate : 48000 21:41:00.297 T:139696227219200 DEBUG: Sample Format : AE_FMT_S32NE 21:41:00.297 T:139696227219200 DEBUG: Channel Count : 2 21:41:00.297 T:139696227219200 DEBUG: Channel Layout: FL,FR 21:41:00.297 T:139696227219200 DEBUG: Frames : 2400 21:41:00.297 T:139696227219200 DEBUG: Frame Size : 8 21:41:00.301 T:139696307889920 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 21:41:00.309 T:139693870020352 DEBUG: Previous line repeats 1 times. 21:41:00.309 T:139693870020352 DEBUG: CVideoPlayer::HandleMessages - player started 1 21:41:00.309 T:139693870020352 DEBUG: CVideoPlayer::SetCaching - caching state 3 21:41:00.309 T:139693870020352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 21:41:00.309 T:139693870020352 DEBUG: CVideoPlayer::SetCaching - caching state 0 21:41:00.309 T:139693870020352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 21:41:00.309 T:139693870020352 DEBUG: VideoPlayer::Sync - Audio - pts: 96000.000000, cache: 323360.925080, totalcache: 600000.023842 21:41:00.309 T:139693870020352 DEBUG: VideoPlayer::Sync - Video - pts: 0.000000, cache: 50000.000000, totalcache: 100000.000000 21:41:00.309 T:139693861627648 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-227360.925080) 21:41:00.309 T:139693811918592 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-227360.925080) 21:41:00.309 T:139693861627648 DEBUG: CDVDAudio::Resume - resume audio stream 21:41:00.309 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:41:00.310 T:139696307889920 DEBUG: ActiveAE - start sync of audio stream 21:41:00.313 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:41:00.317 T:139693844842240 NOTICE: VDPAU::COutput registered output surfaces 21:41:00.318 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:41:00.320 T:139693844842240 NOTICE: VDPAU::COutput registered output surfaces 21:41:00.325 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:41:00.327 T:139693844842240 NOTICE: VDPAU::COutput registered output surfaces 21:41:00.328 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:41:00.331 T:139693844842240 NOTICE: VDPAU::COutput registered output surfaces 21:41:00.337 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:41:00.457 T:139696307889920 DEBUG: ActiveAE::SyncStream - average error of 16.926177, start adjusting 21:41:00.457 T:139696307889920 DEBUG: ActiveAE::SyncStream - average error 0.926177 below threshold of 30.000000 21:41:00.473 T:139693707966208 DEBUG: RunQuery took 846 ms for 12255 items query: select * from episode_view WHERE ((episode_view.dateAdded > '1900-01-01')) AND ((episode_view.playCount IS NULL OR episode_view.playCount < 1)) 21:41:00.615 T:139693811918592 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 41727 21:41:00.775 T:139695740671744 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnPlay - data: {"item":{"title":"","type":"movie"},"player":{"playerid":1,"speed":1}} 21:41:01.508 T:139693861627648 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-29972.207000, adjusted:-29972.207000 21:41:03.430 T:139696339619136 DEBUG: LIRC: Update - NEW at 102577326:67 0 KEY_UP devinput (KEY_UP) 21:41:03.430 T:139696339619136 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is ChapterOrBigStepForward 21:41:03.433 T:139693870020352 DEBUG: CVideoPlayer::SetCaching - caching state 2 21:41:03.433 T:139693870020352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 21:41:03.433 T:139693870020352 DEBUG: demuxer seek to: 602863.000000 21:41:03.457 T:139693861627648 DEBUG: CDVDAudio::Pause - pausing audio stream 21:41:03.915 T:139693811918592 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 21:41:03.931 T:139696339619136 DEBUG: CDVDMsgGeneralSynchronize - global timeout 21:41:03.935 T:139696339619136 DEBUG: ------ Window Init (DialogSeekBar.xml) ------ 21:41:03.937 T:139696339619136 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------ 21:41:03.945 T:139696339605248 DEBUG: GetMovieId (smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv), query = select idMovie from movie where idFile=19003 21:41:03.948 T:139696339605248 DEBUG: GetEpisodeId (smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv), query = select idEpisode from episode where idFile=19003 21:41:04.448 T:139696339605248 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc 21:41:04.449 T:139696339605248 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek 21:41:04.519 T:139695707100928 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnSeek - data: {"item":{"id":18433,"type":"episode"},"player":{"playerid":1,"seekoffset":{"hours":0,"milliseconds":0,"minutes":10,"seconds":0},"speed":1,"time":{"hours":0,"milliseconds":863,"minutes":10,"seconds":2}}} 21:41:04.544 T:139695740671744 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnSeek - data: {"item":{"id":18433,"type":"episode"},"player":{"playerid":1,"seekoffset":{"hours":0,"milliseconds":0,"minutes":10,"seconds":0},"speed":1,"time":{"hours":0,"milliseconds":863,"minutes":10,"seconds":2}}} 21:41:06.250 T:139696339619136 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------ 21:41:06.251 T:139696339619136 DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------ 21:41:16.803 T:139693870020352 DEBUG: SeekTime - seek ended up on time 605814 21:41:16.803 T:139693870020352 DEBUG: demuxer seek to: 602863.000000, success 21:41:16.803 T:139693870020352 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 21:41:16.807 T:139693861627648 DEBUG: CDVDAudio::Flush - flush audio stream 21:41:16.807 T:139693861627648 DEBUG: CDVDAudio::Pause - pausing audio stream 21:41:16.807 T:139693861627648 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 21:41:16.807 T:139693811918592 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 21:41:16.807 T:139693870020352 DEBUG: CVideoPlayer - CDVDMsg::GENERAL_SYNCHRONIZE 21:41:16.814 T:139693811918592 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 21:41:16.816 T:139693811918592 NOTICE: CVDPAU::Release pre-cleanup 21:41:16.819 T:139693650130688 NOTICE: CMixer::OnExit: Output Thread terminated 21:41:16.819 T:139693650130688 DEBUG: Thread Vdpau Mixer 139693650130688 terminating 21:41:16.821 T:139693844842240 DEBUG: VDPAU::PreCleanup - released output surface 21:41:16.826 T:139693811918592 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264 21:41:16.826 T:139693811918592 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating VDPAU(1280x720) 21:41:16.827 T:139693870020352 DEBUG: CVideoPlayer::HandleMessages - player started 1 21:41:16.838 T:139696339619136 DEBUG: ------ Window Init (DialogSeekBar.xml) ------ 21:41:16.840 T:139696339619136 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------ 21:41:16.890 T:139693811918592 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-vdpau 21:41:16.892 T:139693811918592 DEBUG: CVDPAU::SetWidthHeight Setting OutWidth: 1280 OutHeight: 720 21:41:16.892 T:139693811918592 NOTICE: (VDPAU) screenWidth:1280 vidWidth:1280 surfaceWidth:1280 21:41:16.892 T:139693811918592 NOTICE: (VDPAU) screenHeight:720 vidHeight:720 surfaceHeight:720 21:41:16.985 T:139693650130688 DEBUG: Thread Vdpau Output start, auto delete: false 21:41:16.985 T:139693650130688 NOTICE: COutput::OnStartup: Output Thread created 21:41:17.054 T:139693650130688 NOTICE: VDPAU::COutput::CreateGlxContext - created context 21:41:17.055 T:139693650130688 NOTICE: VDPAU::COutput: vdpau gl interop initialized 21:41:17.055 T:139693593814784 DEBUG: Thread Vdpau Mixer start, auto delete: false 21:41:17.056 T:139693593814784 NOTICE: CMixer::OnStartup: Output Thread created 21:41:17.056 T:139693593814784 NOTICE: (VDPAU) Creating the video mixer 21:41:17.062 T:139693650130688 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created 21:41:17.068 T:139693811918592 NOTICE: Previous line repeats 3 times. 21:41:17.068 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:41:17.115 T:139693650130688 DEBUG: Previous line repeats 4 times. 21:41:17.115 T:139693650130688 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created 21:41:17.120 T:139693870020352 NOTICE: Previous line repeats 1 times. 21:41:17.120 T:139693870020352 DEBUG: CVideoPlayer::SetCaching - caching state 0 21:41:17.120 T:139693870020352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 21:41:17.120 T:139693650130688 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created 21:41:17.121 T:139693650130688 NOTICE: VDPAU::COutput registered output surfaces 21:41:17.134 T:139696339619136 NOTICE: Previous line repeats 2 times. 21:41:17.134 T:139696339619136 NOTICE: (VDPAU) Close 21:41:17.134 T:139696339619136 NOTICE: (VDPAU) FiniVDPAUOutput 21:41:17.157 T:139693844842240 NOTICE: VDPAU::COutput: vdpau gl interop finished 21:41:17.165 T:139693844842240 NOTICE: COutput::OnExit: Output Thread terminated 21:41:17.165 T:139693844842240 DEBUG: Thread Vdpau Output 139693844842240 terminating 21:41:17.197 T:139696339619136 DEBUG: CVDPAU::FiniVDPAUOutput destroying 0 video surfaces 21:41:17.201 T:139693870020352 DEBUG: CVideoPlayer::HandleMessages - player started 2 21:41:17.201 T:139693870020352 DEBUG: VideoPlayer::Sync - Audio - pts: 606112000.000000, cache: 320000.052452, totalcache: 600000.023842 21:41:17.201 T:139693870020352 DEBUG: VideoPlayer::Sync - Video - pts: 605814000.000000, cache: 50000.000000, totalcache: 100000.000000 21:41:17.201 T:139693811918592 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(605714000.000000) 21:41:17.201 T:139693861627648 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(605714000.000000) 21:41:17.201 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:41:17.201 T:139693861627648 DEBUG: CDVDAudio::Resume - resume audio stream 21:41:17.202 T:139696307889920 DEBUG: ActiveAE - start sync of audio stream 21:41:17.220 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:41:17.234 T:139693650130688 DEBUG: Previous line repeats 1 times. 21:41:17.234 T:139693650130688 NOTICE: VDPAU::COutput registered output surfaces 21:41:17.307 T:139696307889920 NOTICE: Previous line repeats 3 times. 21:41:17.307 T:139696307889920 DEBUG: ActiveAE::SyncStream - average error of -24.781059, start adjusting 21:41:17.307 T:139696307889920 DEBUG: ActiveAE::SyncStream - average error -0.781059 below threshold of 30.000000 21:41:18.358 T:139693861627648 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-29991.083000, adjusted:-29991.083000 21:41:19.014 T:139696339619136 DEBUG: LIRC: Update - NEW at 102592909:67 0 KEY_UP devinput (KEY_UP) 21:41:19.014 T:139696339619136 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is ChapterOrBigStepForward 21:41:19.020 T:139693870020352 DEBUG: CVideoPlayer::SetCaching - caching state 2 21:41:19.020 T:139693870020352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 21:41:19.020 T:139693870020352 DEBUG: demuxer seek to: 1207495.000000 21:41:19.057 T:139693861627648 DEBUG: CDVDAudio::Pause - pausing audio stream 21:41:19.500 T:139693811918592 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 21:41:19.513 T:139696339619136 DEBUG: CDVDMsgGeneralSynchronize - global timeout 21:41:19.526 T:139696339605248 DEBUG: GetMovieId (smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv), query = select idMovie from movie where idFile=19003 21:41:19.529 T:139696339605248 DEBUG: GetEpisodeId (smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv), query = select idEpisode from episode where idFile=19003 21:41:20.026 T:139696339605248 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc 21:41:20.027 T:139696339605248 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek 21:41:20.091 T:139695740671744 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnSeek - data: {"item":{"id":18433,"type":"episode"},"player":{"playerid":1,"seekoffset":{"hours":0,"milliseconds":0,"minutes":10,"seconds":0},"speed":1,"time":{"hours":0,"milliseconds":495,"minutes":20,"seconds":7}}} 21:41:20.127 T:139695707100928 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnSeek - data: {"item":{"id":18433,"type":"episode"},"player":{"playerid":1,"seekoffset":{"hours":0,"milliseconds":0,"minutes":10,"seconds":0},"speed":1,"time":{"hours":0,"milliseconds":495,"minutes":20,"seconds":7}}} 21:41:21.834 T:139696339619136 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------ 21:41:21.834 T:139696339619136 DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------ 21:41:30.021 T:139693602207488 DEBUG: Thread JobWorker 139693602207488 terminating (autodelete) 21:41:30.200 T:139693731321600 DEBUG: Thread JobWorker 139693731321600 terminating (autodelete) 21:41:30.629 T:139693622040320 DEBUG: Thread JobWorker 139693622040320 terminating (autodelete) 21:41:31.503 T:139693707966208 DEBUG: Thread JobWorker 139693707966208 terminating (autodelete) 21:41:31.529 T:139693870020352 DEBUG: SeekTime - seek ended up on time 1209792 21:41:31.529 T:139693870020352 DEBUG: demuxer seek to: 1207495.000000, success 21:41:31.529 T:139693870020352 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 21:41:31.557 T:139693861627648 DEBUG: CDVDAudio::Flush - flush audio stream 21:41:31.557 T:139693861627648 DEBUG: CDVDAudio::Pause - pausing audio stream 21:41:31.557 T:139693861627648 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 21:41:31.557 T:139693811918592 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 21:41:31.557 T:139693870020352 DEBUG: CVideoPlayer - CDVDMsg::GENERAL_SYNCHRONIZE 21:41:31.561 T:139693811918592 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 21:41:31.561 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:41:31.561 T:139693811918592 NOTICE: CVDPAU::Release pre-cleanup 21:41:31.563 T:139696339619136 DEBUG: ------ Window Init (DialogSeekBar.xml) ------ 21:41:31.565 T:139696339619136 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------ 21:41:31.566 T:139693593814784 NOTICE: CMixer::OnExit: Output Thread terminated 21:41:31.566 T:139693593814784 DEBUG: Thread Vdpau Mixer 139693593814784 terminating 21:41:31.575 T:139693870020352 DEBUG: CVideoPlayer::HandleMessages - player started 1 21:41:31.598 T:139693650130688 DEBUG: VDPAU::PreCleanup - released output surface 21:41:31.610 T:139693811918592 DEBUG: Previous line repeats 4 times. 21:41:31.610 T:139693811918592 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264 21:41:31.610 T:139693811918592 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating VDPAU(1280x720) 21:41:31.725 T:139693811918592 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-vdpau 21:41:31.727 T:139693811918592 DEBUG: CVDPAU::SetWidthHeight Setting OutWidth: 1280 OutHeight: 720 21:41:31.727 T:139693811918592 NOTICE: (VDPAU) screenWidth:1280 vidWidth:1280 surfaceWidth:1280 21:41:31.727 T:139693811918592 NOTICE: (VDPAU) screenHeight:720 vidHeight:720 surfaceHeight:720 21:41:31.817 T:139693593814784 DEBUG: Thread Vdpau Output start, auto delete: false 21:41:31.817 T:139693593814784 NOTICE: COutput::OnStartup: Output Thread created 21:41:31.855 T:139693870020352 DEBUG: CVideoPlayer::SetCaching - caching state 0 21:41:31.855 T:139693870020352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 21:41:31.872 T:139693593814784 NOTICE: VDPAU::COutput::CreateGlxContext - created context 21:41:31.873 T:139693593814784 NOTICE: VDPAU::COutput: vdpau gl interop initialized 21:41:31.873 T:139693707966208 DEBUG: Thread Vdpau Mixer start, auto delete: false 21:41:31.873 T:139693707966208 NOTICE: CMixer::OnStartup: Output Thread created 21:41:31.873 T:139693707966208 NOTICE: (VDPAU) Creating the video mixer 21:41:31.879 T:139693593814784 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created 21:41:31.883 T:139693811918592 NOTICE: Previous line repeats 3 times. 21:41:31.883 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:41:31.897 T:139693811918592 DEBUG: Previous line repeats 1 times. 21:41:31.897 T:139693811918592 WARNING: CVideoSurfaces::ClearReference - surface invalid 21:41:31.897 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:41:31.920 T:139693593814784 DEBUG: Previous line repeats 2 times. 21:41:31.920 T:139693593814784 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created 21:41:31.924 T:139693593814784 NOTICE: Previous line repeats 2 times. 21:41:31.924 T:139693593814784 NOTICE: VDPAU::COutput registered output surfaces 21:41:31.933 T:139696339619136 NOTICE: Previous line repeats 2 times. 21:41:31.933 T:139696339619136 NOTICE: (VDPAU) Close 21:41:31.933 T:139696339619136 NOTICE: (VDPAU) FiniVDPAUOutput 21:41:31.955 T:139693650130688 NOTICE: VDPAU::COutput: vdpau gl interop finished 21:41:31.959 T:139693650130688 NOTICE: COutput::OnExit: Output Thread terminated 21:41:31.959 T:139693650130688 DEBUG: Thread Vdpau Output 139693650130688 terminating 21:41:31.994 T:139696339619136 DEBUG: CVDPAU::FiniVDPAUOutput destroying 0 video surfaces 21:41:31.996 T:139693870020352 DEBUG: CVideoPlayer::HandleMessages - player started 2 21:41:31.996 T:139693870020352 DEBUG: VideoPlayer::Sync - Audio - pts: 1210080000.000000, cache: 320000.052452, totalcache: 600000.023842 21:41:31.996 T:139693870020352 DEBUG: VideoPlayer::Sync - Video - pts: 1209792000.000000, cache: 50000.000000, totalcache: 100000.000000 21:41:31.997 T:139693861627648 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(1209692000.000000) 21:41:31.997 T:139693811918592 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(1209692000.000000) 21:41:31.997 T:139693861627648 DEBUG: CDVDAudio::Resume - resume audio stream 21:41:31.999 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:41:31.999 T:139696307889920 DEBUG: ActiveAE - start sync of audio stream 21:41:32.022 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:41:32.024 T:139693593814784 DEBUG: Previous line repeats 1 times. 21:41:32.024 T:139693593814784 NOTICE: VDPAU::COutput registered output surfaces 21:41:32.033 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:41:32.034 T:139693593814784 NOTICE: VDPAU::COutput registered output surfaces 21:41:32.039 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:41:32.039 T:139693593814784 NOTICE: VDPAU::COutput registered output surfaces 21:41:32.045 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:41:32.045 T:139693593814784 NOTICE: VDPAU::COutput registered output surfaces 21:41:32.107 T:139696307889920 DEBUG: ActiveAE::SyncStream - average error of -106.767911, start adjusting 21:41:32.107 T:139696307889920 DEBUG: ActiveAE::SyncStream - average error -6.767911 below threshold of 30.000000 21:41:32.947 T:139696339619136 DEBUG: LIRC: Update - NEW at 102606842:67 0 KEY_UP devinput (KEY_UP) 21:41:32.947 T:139696339619136 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is ChapterOrBigStepForward 21:41:32.947 T:139693870020352 DEBUG: CVideoPlayer::SetCaching - caching state 2 21:41:32.947 T:139693870020352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 21:41:32.947 T:139693870020352 DEBUG: demuxer seek to: 1810641.000000 21:41:32.957 T:139693861627648 DEBUG: CDVDAudio::Pause - pausing audio stream 21:41:33.432 T:139693811918592 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 21:41:33.447 T:139696339619136 DEBUG: CDVDMsgGeneralSynchronize - global timeout 21:41:33.461 T:139696339605248 DEBUG: GetMovieId (smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv), query = select idMovie from movie where idFile=19003 21:41:33.464 T:139696339605248 DEBUG: GetEpisodeId (smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv), query = select idEpisode from episode where idFile=19003 21:41:33.985 T:139696339605248 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc 21:41:33.985 T:139696339605248 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek 21:41:33.998 T:139695740671744 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnSeek - data: {"item":{"id":18433,"type":"episode"},"player":{"playerid":1,"seekoffset":{"hours":0,"milliseconds":0,"minutes":10,"seconds":0},"speed":1,"time":{"hours":0,"milliseconds":641,"minutes":30,"seconds":10}}} 21:41:34.022 T:139695707100928 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnSeek - data: {"item":{"id":18433,"type":"episode"},"player":{"playerid":1,"seekoffset":{"hours":0,"milliseconds":0,"minutes":10,"seconds":0},"speed":1,"time":{"hours":0,"milliseconds":641,"minutes":30,"seconds":10}}} 21:41:35.768 T:139696339619136 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------ 21:41:35.768 T:139696339619136 DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------ 21:41:47.052 T:139693870020352 DEBUG: SeekTime - seek ended up on time 1813311 21:41:47.052 T:139693870020352 DEBUG: demuxer seek to: 1810641.000000, success 21:41:47.052 T:139693870020352 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 21:41:47.057 T:139693861627648 DEBUG: CDVDAudio::Flush - flush audio stream 21:41:47.057 T:139693861627648 DEBUG: CDVDAudio::Pause - pausing audio stream 21:41:47.057 T:139693861627648 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 21:41:47.057 T:139693811918592 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 21:41:47.057 T:139693870020352 DEBUG: CVideoPlayer - CDVDMsg::GENERAL_SYNCHRONIZE 21:41:47.062 T:139693811918592 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 21:41:47.063 T:139693811918592 NOTICE: CVDPAU::Release pre-cleanup 21:41:47.063 T:139696339619136 DEBUG: ------ Window Init (DialogSeekBar.xml) ------ 21:41:47.065 T:139696339619136 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------ 21:41:47.068 T:139693707966208 NOTICE: CMixer::OnExit: Output Thread terminated 21:41:47.068 T:139693707966208 DEBUG: Thread Vdpau Mixer 139693707966208 terminating 21:41:47.077 T:139693870020352 DEBUG: CVideoPlayer::HandleMessages - player started 1 21:41:47.098 T:139693593814784 DEBUG: VDPAU::PreCleanup - released output surface 21:41:47.103 T:139693811918592 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264 21:41:47.103 T:139693811918592 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating VDPAU(1280x720) 21:41:47.241 T:139693811918592 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-vdpau 21:41:47.243 T:139693811918592 DEBUG: CVDPAU::SetWidthHeight Setting OutWidth: 1280 OutHeight: 720 21:41:47.243 T:139693811918592 NOTICE: (VDPAU) screenWidth:1280 vidWidth:1280 surfaceWidth:1280 21:41:47.243 T:139693811918592 NOTICE: (VDPAU) screenHeight:720 vidHeight:720 surfaceHeight:720 21:41:47.333 T:139693707966208 DEBUG: Thread Vdpau Output start, auto delete: false 21:41:47.333 T:139693707966208 NOTICE: COutput::OnStartup: Output Thread created 21:41:47.357 T:139693870020352 DEBUG: CVideoPlayer::SetCaching - caching state 0 21:41:47.358 T:139693870020352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 21:41:47.388 T:139693707966208 NOTICE: VDPAU::COutput::CreateGlxContext - created context 21:41:47.389 T:139693707966208 NOTICE: VDPAU::COutput: vdpau gl interop initialized 21:41:47.389 T:139693622040320 DEBUG: Thread Vdpau Mixer start, auto delete: false 21:41:47.389 T:139693622040320 NOTICE: CMixer::OnStartup: Output Thread created 21:41:47.389 T:139693622040320 NOTICE: (VDPAU) Creating the video mixer 21:41:47.394 T:139693707966208 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created 21:41:47.399 T:139693811918592 NOTICE: Previous line repeats 3 times. 21:41:47.399 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:41:47.416 T:139693811918592 DEBUG: Previous line repeats 1 times. 21:41:47.416 T:139693811918592 WARNING: CVideoSurfaces::ClearReference - surface invalid 21:41:47.416 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:41:47.438 T:139693707966208 DEBUG: Previous line repeats 2 times. 21:41:47.438 T:139693707966208 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created 21:41:47.443 T:139693707966208 NOTICE: Previous line repeats 2 times. 21:41:47.443 T:139693707966208 NOTICE: VDPAU::COutput registered output surfaces 21:41:47.450 T:139696339619136 NOTICE: Previous line repeats 2 times. 21:41:47.450 T:139696339619136 NOTICE: (VDPAU) Close 21:41:47.450 T:139696339619136 NOTICE: (VDPAU) FiniVDPAUOutput 21:41:47.472 T:139693593814784 NOTICE: VDPAU::COutput: vdpau gl interop finished 21:41:47.480 T:139693593814784 NOTICE: COutput::OnExit: Output Thread terminated 21:41:47.480 T:139693593814784 DEBUG: Thread Vdpau Output 139693593814784 terminating 21:41:47.512 T:139696339619136 DEBUG: CVDPAU::FiniVDPAUOutput destroying 0 video surfaces 21:41:47.518 T:139693870020352 DEBUG: CVideoPlayer::HandleMessages - player started 2 21:41:47.518 T:139693870020352 DEBUG: VideoPlayer::Sync - Audio - pts: 1813600000.000000, cache: 320000.052452, totalcache: 600000.023842 21:41:47.518 T:139693870020352 DEBUG: VideoPlayer::Sync - Video - pts: 1813311000.000000, cache: 50000.000000, totalcache: 100000.000000 21:41:47.518 T:139693811918592 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(1813211000.000000) 21:41:47.519 T:139693861627648 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(1813211000.000000) 21:41:47.519 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:41:47.519 T:139693861627648 DEBUG: CDVDAudio::Resume - resume audio stream 21:41:47.519 T:139696307889920 DEBUG: ActiveAE - start sync of audio stream 21:41:47.538 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:41:47.540 T:139693707966208 NOTICE: VDPAU::COutput registered output surfaces 21:41:47.541 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:41:47.557 T:139693707966208 DEBUG: Previous line repeats 1 times. 21:41:47.558 T:139693707966208 NOTICE: VDPAU::COutput registered output surfaces 21:41:47.657 T:139696307889920 NOTICE: Previous line repeats 2 times. 21:41:47.657 T:139696307889920 DEBUG: ActiveAE::SyncStream - average error of -91.051240, start adjusting 21:41:47.657 T:139696307889920 DEBUG: ActiveAE::SyncStream - average error -0.051240 below threshold of 30.000000 21:41:48.658 T:139693861627648 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-29991.220714, adjusted:-29991.220714 21:41:48.930 T:139696339619136 DEBUG: LIRC: Update - NEW at 102622825:67 0 KEY_UP devinput (KEY_UP) 21:41:48.930 T:139696339619136 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is ChapterOrBigStepForward 21:41:48.940 T:139693870020352 DEBUG: CVideoPlayer::SetCaching - caching state 2 21:41:48.940 T:139693870020352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 21:41:48.940 T:139693870020352 DEBUG: demuxer seek to: 2414591.000000 21:41:48.957 T:139693861627648 DEBUG: CDVDAudio::Pause - pausing audio stream 21:41:49.416 T:139693811918592 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 21:41:49.430 T:139696339619136 DEBUG: CDVDMsgGeneralSynchronize - global timeout 21:41:49.443 T:139696339605248 DEBUG: GetMovieId (smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv), query = select idMovie from movie where idFile=19003 21:41:49.446 T:139696339605248 DEBUG: GetEpisodeId (smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv), query = select idEpisode from episode where idFile=19003 21:41:49.943 T:139696339605248 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc 21:41:49.944 T:139696339605248 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek 21:41:49.997 T:139695740671744 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnSeek - data: {"item":{"id":18433,"type":"episode"},"player":{"playerid":1,"seekoffset":{"hours":0,"milliseconds":0,"minutes":10,"seconds":0},"speed":1,"time":{"hours":0,"milliseconds":591,"minutes":40,"seconds":14}}} 21:41:50.030 T:139695707100928 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnSeek - data: {"item":{"id":18433,"type":"episode"},"player":{"playerid":1,"seekoffset":{"hours":0,"milliseconds":0,"minutes":10,"seconds":0},"speed":1,"time":{"hours":0,"milliseconds":591,"minutes":40,"seconds":14}}} 21:41:51.750 T:139696339619136 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------ 21:41:51.750 T:139696339619136 DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------ 21:42:02.588 T:139693870020352 DEBUG: SeekTime - seek ended up on time 2418416 21:42:02.588 T:139693870020352 DEBUG: demuxer seek to: 2414591.000000, success 21:42:02.588 T:139693870020352 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 21:42:02.607 T:139693861627648 DEBUG: CDVDAudio::Flush - flush audio stream 21:42:02.607 T:139693861627648 DEBUG: CDVDAudio::Pause - pausing audio stream 21:42:02.607 T:139693861627648 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 21:42:02.607 T:139693811918592 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 21:42:02.607 T:139693870020352 DEBUG: CVideoPlayer - CDVDMsg::GENERAL_SYNCHRONIZE 21:42:02.613 T:139693811918592 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 21:42:02.613 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:42:02.613 T:139693811918592 NOTICE: CVDPAU::Release pre-cleanup 21:42:02.617 T:139693622040320 NOTICE: CMixer::OnExit: Output Thread terminated 21:42:02.617 T:139693622040320 DEBUG: Thread Vdpau Mixer 139693622040320 terminating 21:42:02.626 T:139693870020352 DEBUG: CVideoPlayer::HandleMessages - player started 1 21:42:02.629 T:139693707966208 DEBUG: VDPAU::PreCleanup - released output surface 21:42:02.640 T:139696339619136 DEBUG: Previous line repeats 4 times. 21:42:02.640 T:139696339619136 DEBUG: ------ Window Init (DialogSeekBar.xml) ------ 21:42:02.641 T:139693811918592 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264 21:42:02.641 T:139693811918592 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating VDPAU(1280x720) 21:42:02.642 T:139696339619136 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------ 21:42:02.789 T:139693811918592 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-vdpau 21:42:02.791 T:139693811918592 DEBUG: CVDPAU::SetWidthHeight Setting OutWidth: 1280 OutHeight: 720 21:42:02.791 T:139693811918592 NOTICE: (VDPAU) screenWidth:1280 vidWidth:1280 surfaceWidth:1280 21:42:02.791 T:139693811918592 NOTICE: (VDPAU) screenHeight:720 vidHeight:720 surfaceHeight:720 21:42:02.867 T:139693622040320 DEBUG: Thread Vdpau Output start, auto delete: false 21:42:02.867 T:139693622040320 NOTICE: COutput::OnStartup: Output Thread created 21:42:02.924 T:139693622040320 NOTICE: VDPAU::COutput::CreateGlxContext - created context 21:42:02.925 T:139693622040320 NOTICE: VDPAU::COutput: vdpau gl interop initialized 21:42:02.926 T:139693593814784 DEBUG: Thread Vdpau Mixer start, auto delete: false 21:42:02.926 T:139693593814784 NOTICE: CMixer::OnStartup: Output Thread created 21:42:02.926 T:139693593814784 NOTICE: (VDPAU) Creating the video mixer 21:42:02.929 T:139693870020352 DEBUG: CVideoPlayer::SetCaching - caching state 0 21:42:02.929 T:139693870020352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 21:42:02.932 T:139693622040320 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created 21:42:02.936 T:139693811918592 NOTICE: Previous line repeats 3 times. 21:42:02.936 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:42:02.958 T:139693811918592 DEBUG: Previous line repeats 1 times. 21:42:02.958 T:139693811918592 WARNING: CVideoSurfaces::ClearReference - surface invalid 21:42:02.958 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:42:02.983 T:139693622040320 DEBUG: Previous line repeats 2 times. 21:42:02.983 T:139693622040320 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created 21:42:02.987 T:139693622040320 NOTICE: Previous line repeats 2 times. 21:42:02.987 T:139693622040320 NOTICE: VDPAU::COutput registered output surfaces 21:42:02.997 T:139696339619136 NOTICE: Previous line repeats 2 times. 21:42:02.997 T:139696339619136 NOTICE: (VDPAU) Close 21:42:02.997 T:139696339619136 NOTICE: (VDPAU) FiniVDPAUOutput 21:42:03.019 T:139693707966208 NOTICE: VDPAU::COutput: vdpau gl interop finished 21:42:03.023 T:139693707966208 NOTICE: COutput::OnExit: Output Thread terminated 21:42:03.023 T:139693707966208 DEBUG: Thread Vdpau Output 139693707966208 terminating 21:42:03.059 T:139696339619136 DEBUG: CVDPAU::FiniVDPAUOutput destroying 0 video surfaces 21:42:03.062 T:139693870020352 DEBUG: CVideoPlayer::HandleMessages - player started 2 21:42:03.062 T:139693870020352 DEBUG: VideoPlayer::Sync - Audio - pts: 2418720000.000000, cache: 320000.052452, totalcache: 600000.023842 21:42:03.062 T:139693870020352 DEBUG: VideoPlayer::Sync - Video - pts: 2418416000.000000, cache: 50000.000000, totalcache: 100000.000000 21:42:03.062 T:139693861627648 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(2418316000.000000) 21:42:03.062 T:139693811918592 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(2418316000.000000) 21:42:03.062 T:139693861627648 DEBUG: CDVDAudio::Resume - resume audio stream 21:42:03.062 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:42:03.064 T:139696307889920 DEBUG: ActiveAE - start sync of audio stream 21:42:03.084 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:42:03.096 T:139693622040320 DEBUG: Previous line repeats 1 times. 21:42:03.096 T:139693622040320 NOTICE: VDPAU::COutput registered output surfaces 21:42:03.097 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:42:03.098 T:139693622040320 NOTICE: VDPAU::COutput registered output surfaces 21:42:03.107 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:42:03.108 T:139693622040320 NOTICE: VDPAU::COutput registered output surfaces 21:42:03.110 T:139693811918592 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 21:42:03.111 T:139693622040320 NOTICE: VDPAU::COutput registered output surfaces 21:42:03.207 T:139696307889920 DEBUG: ActiveAE::SyncStream - average error of -91.135441, start adjusting 21:42:03.207 T:139696307889920 DEBUG: ActiveAE::SyncStream - average error -0.135441 below threshold of 30.000000 21:42:04.249 T:139693861627648 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-29973.290383, adjusted:-29973.290383 21:42:05.013 T:139696339619136 DEBUG: LIRC: Update - NEW at 102638907:6a 0 KEY_RIGHT devinput (KEY_RIGHT) 21:42:05.013 T:139696339619136 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is StepForward 21:42:05.429 T:139696339619136 DEBUG: LIRC: Update - NEW at 102639324:6a 0 KEY_RIGHT devinput (KEY_RIGHT) 21:42:05.429 T:139696339619136 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is StepForward 21:42:05.813 T:139696339619136 DEBUG: LIRC: Update - NEW at 102639707:6a 0 KEY_RIGHT devinput (KEY_RIGHT) 21:42:05.813 T:139696339619136 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is StepForward 21:42:06.513 T:139696339619136 DEBUG: LIRC: Update - NEW at 102640407:6a 0 KEY_RIGHT devinput (KEY_RIGHT) 21:42:06.513 T:139696339619136 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is StepForward 21:42:07.280 T:139693870020352 DEBUG: CVideoPlayer::SetCaching - caching state 2 21:42:07.281 T:139693870020352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 21:42:07.281 T:139693870020352 DEBUG: demuxer seek to: 2722504.000000 21:42:07.291 T:139696339605248 DEBUG: GetMovieId (smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv), query = select idMovie from movie where idFile=19003 21:42:07.293 T:139696339605248 DEBUG: GetEpisodeId (smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv), query = select idEpisode from episode where idFile=19003 21:42:07.307 T:139693861627648 DEBUG: CDVDAudio::Pause - pausing audio stream 21:42:07.346 T:139696339619136 DEBUG: LIRC: Update - NEW at 102641241:69 0 KEY_LEFT devinput (KEY_LEFT) 21:42:07.346 T:139696339619136 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is StepBack 21:42:07.806 T:139696339605248 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc 21:42:07.806 T:139696339605248 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek 21:42:07.847 T:139695707100928 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnSeek - data: {"item":{"id":18433,"type":"episode"},"player":{"playerid":1,"seekoffset":{"hours":0,"milliseconds":0,"minutes":5,"seconds":0},"speed":1,"time":{"hours":0,"milliseconds":502,"minutes":45,"seconds":22}}} 21:42:07.899 T:139695740671744 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnSeek - data: {"item":{"id":18433,"type":"episode"},"player":{"playerid":1,"seekoffset":{"hours":0,"milliseconds":0,"minutes":5,"seconds":0},"speed":1,"time":{"hours":0,"milliseconds":502,"minutes":45,"seconds":22}}} 21:42:08.108 T:139696339605248 DEBUG: GetMovieId (smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv), query = select idMovie from movie where idFile=19003 21:42:08.111 T:139696339605248 DEBUG: GetEpisodeId (smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv), query = select idEpisode from episode where idFile=19003 21:42:08.615 T:139696339605248 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc 21:42:08.615 T:139696339605248 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek 21:42:08.648 T:139695707100928 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnSeek - data: {"item":{"id":18433,"type":"episode"},"player":{"playerid":1,"seekoffset":{"hours":0,"milliseconds":0,"minutes":0,"seconds":-30},"speed":1,"time":{"hours":0,"milliseconds":870,"minutes":39,"seconds":52}}} 21:42:08.704 T:139695740671744 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnSeek - data: {"item":{"id":18433,"type":"episode"},"player":{"playerid":1,"seekoffset":{"hours":0,"milliseconds":0,"minutes":0,"seconds":-30},"speed":1,"time":{"hours":0,"milliseconds":870,"minutes":39,"seconds":52}}} 21:42:09.704 T:139696339619136 DEBUG: LIRC: Update - NEW at 102643599:6a 0 KEY_RIGHT devinput (KEY_RIGHT) 21:42:09.704 T:139696339619136 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is StepForward 21:42:10.474 T:139696339605248 DEBUG: GetMovieId (smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv), query = select idMovie from movie where idFile=19003 21:42:10.477 T:139696339605248 DEBUG: GetEpisodeId (smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv), query = select idEpisode from episode where idFile=19003 21:42:10.991 T:139696339605248 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc 21:42:10.991 T:139696339605248 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek 21:42:11.010 T:139695740671744 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnSeek - data: {"item":{"id":18433,"type":"episode"},"player":{"playerid":1,"seekoffset":{"hours":0,"milliseconds":0,"minutes":0,"seconds":30},"speed":1,"time":{"hours":0,"milliseconds":870,"minutes":40,"seconds":52}}} 21:42:11.052 T:139695707100928 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnSeek - data: {"item":{"id":18433,"type":"episode"},"player":{"playerid":1,"seekoffset":{"hours":0,"milliseconds":0,"minutes":0,"seconds":30},"speed":1,"time":{"hours":0,"milliseconds":870,"minutes":40,"seconds":52}}} 21:42:11.772 T:139696339619136 DEBUG: LIRC: Update - NEW at 102645666:6a 0 KEY_RIGHT devinput (KEY_RIGHT) 21:42:11.772 T:139696339619136 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is StepForward 21:42:12.541 T:139696339605248 DEBUG: GetMovieId (smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv), query = select idMovie from movie where idFile=19003 21:42:12.544 T:139696339605248 DEBUG: GetEpisodeId (smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv), query = select idEpisode from episode where idFile=19003 21:42:12.612 T:139696339619136 DEBUG: LIRC: Update - NEW at 102646507:6a 0 KEY_RIGHT devinput (KEY_RIGHT) 21:42:12.613 T:139696339619136 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is StepForward 21:42:13.060 T:139696339605248 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc 21:42:13.060 T:139696339605248 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek 21:42:13.096 T:139693870020352 ERROR: ffmpeg[7F0D037FF700]: [matroska,webm] Read error 21:42:13.116 T:139695740671744 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnSeek - data: {"item":{"id":18433,"type":"episode"},"player":{"playerid":1,"seekoffset":{"hours":0,"milliseconds":0,"minutes":0,"seconds":30},"speed":1,"time":{"hours":0,"milliseconds":870,"minutes":40,"seconds":52}}} 21:42:13.124 T:139695707100928 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnSeek - data: {"item":{"id":18433,"type":"episode"},"player":{"playerid":1,"seekoffset":{"hours":0,"milliseconds":0,"minutes":0,"seconds":30},"speed":1,"time":{"hours":0,"milliseconds":870,"minutes":40,"seconds":52}}} 21:42:13.195 T:139693870020352 ERROR: ffmpeg[7F0D037FF700]: [matroska,webm] Read error 21:42:13.198 T:139693870020352 DEBUG: SeekTime - seek ended up on time 2662577 21:42:13.198 T:139693870020352 DEBUG: demuxer seek to: 2722504.000000, success 21:42:13.198 T:139693870020352 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 21:42:13.207 T:139693861627648 DEBUG: CDVDAudio::Flush - flush audio stream 21:42:13.207 T:139693861627648 DEBUG: CDVDAudio::Pause - pausing audio stream 21:42:13.207 T:139693861627648 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 21:42:13.207 T:139693811918592 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 21:42:13.207 T:139693870020352 DEBUG: CVideoPlayer::SetCaching - caching state 0 21:42:13.207 T:139693870020352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 21:42:13.207 T:139693870020352 NOTICE: CVideoPlayer::OnExit() 21:42:13.207 T:139693870020352 NOTICE: VideoPlayer: eof, waiting for queues to empty 21:42:13.207 T:139693870020352 NOTICE: Closing stream player 1 21:42:13.207 T:139693870020352 NOTICE: Waiting for audio thread to exit 21:42:13.217 T:139693861627648 NOTICE: thread end: CVideoPlayerAudio::OnExit() 21:42:13.217 T:139693870020352 NOTICE: Closing audio device 21:42:13.217 T:139693861627648 DEBUG: Thread VideoPlayerAudio 139693861627648 terminating 21:42:13.217 T:139693870020352 DEBUG: CDVDAudio::Flush - flush audio stream 21:42:13.257 T:139696307889920 DEBUG: CActiveAE::DiscardStream - audio stream deleted 21:42:13.257 T:139696307889920 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 21:42:13.257 T:139693870020352 DEBUG: Previous line repeats 2 times. 21:42:13.258 T:139693870020352 NOTICE: Deleting audio codec 21:42:13.258 T:139693870020352 NOTICE: Closing stream player 2 21:42:13.258 T:139693870020352 NOTICE: waiting for video thread to exit 21:42:13.258 T:139693811918592 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true 21:42:13.258 T:139693811918592 NOTICE: thread end: video_thread 21:42:13.258 T:139693870020352 NOTICE: deleting video codec 21:42:13.258 T:139693811918592 DEBUG: Thread VideoPlayerVideo 139693811918592 terminating 21:42:13.258 T:139693870020352 NOTICE: CVDPAU::Release pre-cleanup 21:42:13.264 T:139693593814784 NOTICE: CMixer::OnExit: Output Thread terminated 21:42:13.264 T:139693593814784 DEBUG: Thread Vdpau Mixer 139693593814784 terminating 21:42:13.295 T:139693622040320 DEBUG: VDPAU::PreCleanup - released output surface 21:42:13.306 T:139693870020352 DEBUG: Previous line repeats 5 times. 21:42:13.306 T:139693870020352 NOTICE: Closing stream player 3 21:42:13.307 T:139696227219200 INFO: CActiveAESink::OpenSink - initialize sink 21:42:13.307 T:139693870020352 DEBUG: CSMBFile::Close closing fd 10001 21:42:13.309 T:139693870020352 DEBUG: OnPlayBackEnded: play state was 2, starting 0 21:42:13.310 T:139693870020352 DEBUG: Thread VideoPlayer 139693870020352 terminating 21:42:13.313 T:139696339619136 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 21:42:13.313 T:139696339619136 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 21:42:13.320 T:139696339605248 DEBUG: GetMovieId (smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv), query = select idMovie from movie where idFile=19003 21:42:13.324 T:139696339605248 DEBUG: GetEpisodeId (smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv), query = select idEpisode from episode where idFile=19003 21:42:13.325 T:139696339619136 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 21:42:13.325 T:139696339619136 DEBUG: ------ Window Init (Home.xml) ------ 21:42:13.326 T:139696339619136 DEBUG: CDirectoryProvider[special://skin/playlists/inprogress_movies.xsp]: refreshing.. 21:42:13.326 T:139696339619136 DEBUG: CDirectoryProvider[special://skin/playlists/recent_unwatched_movies.xsp]: refreshing.. 21:42:13.326 T:139693870020352 DEBUG: Thread JobWorker start, auto delete: true 21:42:13.326 T:139696339619136 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_movies.xsp]: refreshing.. 21:42:13.326 T:139693593814784 DEBUG: Thread JobWorker start, auto delete: true 21:42:13.326 T:139696339619136 DEBUG: CDirectoryProvider[special://skin/playlists/random_movies.xsp]: refreshing.. 21:42:13.326 T:139693811918592 DEBUG: Thread JobWorker start, auto delete: true 21:42:13.326 T:139696339619136 DEBUG: CDirectoryProvider[videodb://movies/genres/]: refreshing.. 21:42:13.327 T:139696339619136 DEBUG: CDirectoryProvider[videodb://movies/sets/]: refreshing.. 21:42:13.335 T:139696339619136 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing.. 21:42:13.335 T:139696339619136 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing.. 21:42:13.335 T:139696339619136 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing.. 21:42:13.335 T:139696339619136 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing.. 21:42:13.335 T:139696339619136 DEBUG: CDirectoryProvider[special://videoplaylists/]: refreshing.. 21:42:13.369 T:139693870020352 DEBUG: RunQuery took 28 ms for 3 items query: select * from movie_view WHERE (movie_view.idFile IN (SELECT DISTINCT idFile FROM bookmark WHERE type = 1)) 21:42:13.398 T:139694616606464 NOTICE: Stream All The Sources: Service: Playback completed 21:42:13.398 T:139694616606464 NOTICE: Stream All The Sources: Service: Playback Stopped 21:42:13.401 T:139693593814784 DEBUG: RunQuery took 56 ms for 459 items query: select * from movie_view WHERE ((movie_view.dateAdded > '1900-01-01')) AND ((movie_view.playCount IS NULL OR movie_view.playCount < 1)) 21:42:13.403 T:139693811918592 DEBUG: RunQuery took 62 ms for 459 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount = 0)) 21:42:13.460 T:139693870020352 DEBUG: RunQuery took 49 ms for 502 items query: select * from movie_view 21:42:13.539 T:139693811918592 DEBUG: RunQuery took 33 ms for 85 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet ORDER BY sets.idSet 21:42:13.564 T:139693593814784 DEBUG: RunQuery took 59 ms for 26 items query: SELECT genre.genre_id, genre.name, count(1), count(files.playCount) FROM genre JOIN genre_link ON genre.genre_id = genre_link.genre_id JOIN movie_view ON genre_link.media_id = movie_view.idMovie AND genre_link.media_type='movie' JOIN files ON files.idFile = movie_view.idFile GROUP BY genre.genre_id 21:42:13.603 T:139693861627648 DEBUG: Thread JobWorker start, auto delete: true 21:42:13.650 T:139696227219200 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=NVidia,DEV=1 21:42:13.650 T:139696227219200 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=NVidia,DEV=1" 21:42:13.662 T:139696227219200 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=NVidia,DEV=1,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00" 21:42:13.665 T:139696227219200 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 21:42:13.665 T:139696227219200 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 21:42:13.665 T:139696227219200 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820 21:42:13.665 T:139693593814784 DEBUG: CMultiPathDirectory::GetDirectory(multipath://special%3a%2f%2fprofile%2fplaylists%2fvideo/special%3a%2f%2fprofile%2fplaylists%2fmixed/) 21:42:13.666 T:139693593814784 DEBUG: Getting Directory (special://profile/playlists/video) 21:42:13.668 T:139693593814784 DEBUG: Getting Directory (special://profile/playlists/mixed) 21:42:13.670 T:139693593814784 DEBUG: CMultiPathDirectory::MergeItems, items = 1 21:42:13.670 T:139693593814784 DEBUG: CMultiPathDirectory::MergeItems, items = 1, took 0 ms 21:42:13.689 T:139693870020352 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update 21:42:13.703 T:139693870020352 DEBUG: GetRecentlyAddedAlbumSongs() query: SELECT songview.*, songartistview.* FROM (SELECT idAlbum FROM album ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum = recentalbums.idAlbum JOIN songartistview ON songview.idSong = songartistview.idSong ORDER BY songview.idAlbum DESC, songview.idSong, songartistview.idRole, songartistview.iOrder 21:42:13.705 T:139693870020352 DEBUG: GetRecentlyAddedAlbums query: SELECT albumview.*, albumartistview.* FROM (SELECT idAlbum FROM album WHERE strAlbum != '' ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN albumview ON albumview.idAlbum = recentalbums.idAlbum JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum ORDER BY albumview.idAlbum desc, albumartistview.iOrder 21:42:13.709 T:139693870020352 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update 21:42:13.727 T:139696339619136 NOTICE: CVideoPlayer::CloseFile() 21:42:13.727 T:139696339619136 NOTICE: VideoPlayer: waiting for threads to exit 21:42:13.727 T:139696339619136 NOTICE: VideoPlayer: finished waiting 21:42:13.727 T:139696339619136 DEBUG: DeleteRenderer - deleting renderer 21:42:13.727 T:139696339619136 NOTICE: (VDPAU) Close 21:42:13.727 T:139696339619136 NOTICE: (VDPAU) FiniVDPAUOutput 21:42:13.731 T:139693622040320 NOTICE: VDPAU::COutput: vdpau gl interop finished 21:42:13.750 T:139693870020352 DEBUG: RunQuery took 25 ms for 10 items query: select * from movie_view ORDER BY dateAdded desc, idMovie desc LIMIT 10 21:42:13.768 T:139693622040320 NOTICE: COutput::OnExit: Output Thread terminated 21:42:13.768 T:139693622040320 DEBUG: Thread Vdpau Output 139693622040320 terminating 21:42:13.805 T:139696339619136 DEBUG: CVDPAU::FiniVDPAUOutput destroying 0 video surfaces 21:42:13.805 T:139696339619136 NOTICE: VDPAU::Close - closing decoder context 21:42:13.817 T:139696339619136 DEBUG: LinuxRendererGL: Cleaning up GL resources 21:42:13.820 T:139696339619136 NOTICE: CVideoPlayer::CloseFile() 21:42:13.820 T:139696339619136 NOTICE: VideoPlayer: waiting for threads to exit 21:42:13.820 T:139696339619136 NOTICE: VideoPlayer: finished waiting 21:42:13.824 T:139696339619136 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 21:42:13.826 T:139693811918592 DEBUG: DoWork - Saving file state for video item smb://192.168.1.198/media/Drive4/Tv-Rips/Westworld/Westworld.S02E04.The.Riddle.of.the.Sphinx.720p.WEB-DL.mkv 21:42:13.826 T:139696339619136 DEBUG: LIRC: Update - NEW at 102647721:6a 0 KEY_RIGHT devinput (KEY_RIGHT) 21:42:13.826 T:139696339619136 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 21:42:13.830 T:139696227219200 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 464, bufferSize 8816 21:42:13.830 T:139696227219200 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 21:42:13.836 T:139696339619136 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------ 21:42:13.844 T:139693811918592 DEBUG: Mysql execute: update files set playCount=NULL,lastPlayed='2018-05-15 21:42:13' where idFile=19003 21:42:13.851 T:139693811918592 DEBUG: Mysql execute: insert into bookmark (idBookmark, idFile, timeInSeconds, totalTimeInSeconds, thumbNailImage, player, playerState, type) values(NULL,19003,2722.517090,4322.610352,'','VideoPlayer','', 1) 21:42:13.851 T:139696339619136 DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------ 21:42:13.857 T:139693811918592 DEBUG: Mysql execute: update settings set Deinterlace=1,ViewMode=0,ZoomAmount=1.000000,PixelRatio=1.000000,VerticalShift=0.000000,AudioStream=-1,SubtitleStream=-1,SubtitleDelay=0.000000,SubtitlesOn=1,Brightness=50.000000,Contrast=50.000000,Gamma=20.000000,VolumeAmplification=0.000000,AudioDelay=0.000000,OutputToAllSpeakers=0,Sharpness=0.000000,NoiseReduction=0.000000,NonLinStretch=0,PostProcess=0,ScalingMethod=1,ResumeTime=0,StereoMode=0,StereoInvert=0, VideoStream=-1 where idFile=19003 21:42:13.863 T:139693811918592 DEBUG: Mysql Start transaction 21:42:13.863 T:139693811918592 DEBUG: Mysql execute: DELETE FROM streamdetails WHERE idFile = 19003 21:42:13.864 T:139693811918592 DEBUG: Mysql execute: INSERT INTO streamdetails (idFile, iStreamType, strVideoCodec, fVideoAspect, iVideoWidth, iVideoHeight, iVideoDuration, strStereoMode, strVideoLanguage) VALUES (19003,0,'h264',1.777778,1280,720,4322,'','eng') 21:42:13.865 T:139693811918592 DEBUG: Mysql execute: INSERT INTO streamdetails (idFile, iStreamType, strAudioCodec, iAudioChannels, strAudioLanguage) VALUES (19003,1,'eac3',6,'eng') 21:42:13.866 T:139693811918592 DEBUG: Mysql execute: INSERT INTO streamdetails (idFile, iStreamType, strSubtitleLanguage) VALUES (19003,2,'eng') 21:42:13.867 T:139693811918592 DEBUG: Previous line repeats 1 times. 21:42:13.868 T:139693811918592 DEBUG: Mysql execute: update movie set c11=4322 where idFile=19003 and c11='' 21:42:13.869 T:139693811918592 DEBUG: Mysql execute: update episode set c09=4322 where idFile=19003 and c09='' 21:42:13.870 T:139693811918592 DEBUG: Mysql execute: update musicvideo set c04=4322 where idFile=19003 and c04='' 21:42:13.874 T:139693811918592 DEBUG: Mysql commit transaction 21:42:13.883 T:139696339605248 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 21:42:13.884 T:139696339605248 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 21:42:13.884 T:139696339605248 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc 21:42:13.884 T:139696339605248 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate 21:42:13.884 T:139696339605248 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc 21:42:13.885 T:139696339605248 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate 21:42:13.898 T:139695740671744 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnStop - data: {"end":true,"item":{"id":18433,"type":"episode"}} 21:42:13.900 T:139695707100928 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnStop - data: {"end":true,"item":{"id":18433,"type":"episode"}} 21:42:13.901 T:139695707100928 DEBUG: Skin Helper Widgets --> Video database changed - type: episode - refreshing widgets.... 21:42:13.946 T:139696339619136 DEBUG: CDirectoryProvider[videodb://inprogresstvshows]: refreshing.. 21:42:13.946 T:139696339619136 DEBUG: CDirectoryProvider[special://skin/playlists/recent_unwatched_episodes.xsp]: refreshing.. 21:42:13.947 T:139696339619136 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_tvshows.xsp]: refreshing.. 21:42:13.947 T:139696339619136 DEBUG: CDirectoryProvider[videodb://tvshows/genres/]: refreshing.. 21:42:13.947 T:139696339619136 DEBUG: CDirectoryProvider[videodb://tvshows/studios/]: refreshing.. 21:42:13.951 T:139695707100928 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: VideoLibrary.OnUpdate - data: {"item":{"id":18433,"type":"episode"}} 21:42:13.952 T:139695707100928 DEBUG: Skin Helper Widgets --> Video database changed - type: episode - refreshing widgets.... 21:42:13.952 T:139695707100928 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: VideoLibrary.OnUpdate - data: {"id":18433,"type":"episode"} 21:42:13.953 T:139695707100928 DEBUG: Skin Helper Widgets --> Video database changed - type: episode - refreshing widgets.... 21:42:13.960 T:139696227219200 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 21:42:13.960 T:139696227219200 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 21:42:13.960 T:139696227219200 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR) 21:42:13.960 T:139696227219200 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 21:42:13.960 T:139696227219200 DEBUG: Output Device : HDA NVidia 21:42:13.960 T:139696227219200 DEBUG: Sample Rate : 44100 21:42:13.960 T:139696227219200 DEBUG: Sample Format : AE_FMT_S32NE 21:42:13.960 T:139696227219200 DEBUG: Channel Count : 2 21:42:13.961 T:139696227219200 DEBUG: Channel Layout: FL,FR 21:42:13.961 T:139696227219200 DEBUG: Frames : 464 21:42:13.961 T:139696227219200 DEBUG: Frame Size : 8 21:42:13.962 T:139696307889920 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 21:42:14.011 T:139695740671744 DEBUG: Previous line repeats 1 times. 21:42:14.011 T:139695740671744 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: VideoLibrary.OnUpdate - data: {"item":{"id":18433,"type":"episode"}} 21:42:14.100 T:139693861627648 DEBUG: RunQuery took 113 ms for 20 items query: SELECT * FROM tvshow_view WHERE watchedCount != 0 AND totalCount != watchedCount ORDER BY c00 21:42:14.271 T:139693861627648 DEBUG: RunQuery took 119 ms for 133 items query: SELECT * FROM tvshow_view WHERE ((tvshow_view.watchedcount = 0)) AND ((tvshow_view.totalCount > 0)) 21:42:14.297 T:139693870020352 DEBUG: RunQuery took 514 ms for 10 items query: select * from episode_view ORDER BY dateAdded desc, idEpisode desc LIMIT 10 21:42:14.361 T:139693870020352 DEBUG: RunQuery took 1 ms for 0 items query: select * from musicvideo_view ORDER BY dateAdded desc, idMVideo desc LIMIT 10 21:42:14.362 T:139693870020352 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update 21:42:14.367 T:139693870020352 DEBUG: GetArtistsByWhere query: SELECT COUNT(DISTINCT artistview.idArtist) FROM artistview WHERE ((EXISTS (SELECT 1 FROM song_artist WHERE song_artist.idArtist = artistview.idArtist AND song_artist.idRole = 1) OR EXISTS (SELECT 1 FROM album_artist WHERE album_artist.idArtist = artistview.idArtist)) AND (artistview.strArtist != '')) AND (artistview.strArtist <> 'Various artists') 21:42:14.443 T:139693861627648 DEBUG: RunQuery took 127 ms for 25 items query: SELECT genre.genre_id, genre.name FROM genre JOIN genre_link ON genre.genre_id = genre_link.genre_id JOIN tvshow_view ON genre_link.media_id = tvshow_view.idShow AND genre_link.media_type='tvshow' GROUP BY genre.genre_id 21:42:14.651 T:139693811918592 DEBUG: RunQuery took 132 ms for 53 items query: SELECT studio.studio_id, studio.name FROM studio JOIN studio_link ON studio.studio_id = studio_link.studio_id JOIN tvshow_view ON studio_link.media_id = tvshow_view.idShow AND studio_link.media_type='tvshow' GROUP BY studio.studio_id 21:42:14.801 T:139695740671744 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: VideoLibrary.OnUpdate - data: {"id":18433,"type":"episode"} 21:42:15.005 T:139693593814784 DEBUG: RunQuery took 957 ms for 12255 items query: select * from episode_view WHERE ((episode_view.dateAdded > '1900-01-01')) AND ((episode_view.playCount IS NULL OR episode_view.playCount < 1)) 21:42:15.170 T:139695732279040 DEBUG: RunQuery took 766 ms for 23 items query: SELECT * FROM tvshow_view WHERE ( ((tvshow_view.watchedcount > 0 AND tvshow_view.watchedcount < tvshow_view.totalCount) OR (tvshow_view.watchedcount = 0 AND EXISTS (SELECT 1 FROM episode_view WHERE episode_view.idShow = tvshow_view.idShow AND episode_view.resumeTimeInSeconds > 0)))) 21:42:15.179 T:139695749064448 DEBUG: RunQuery took 778 ms for 23 items query: SELECT * FROM tvshow_view WHERE ((CASE WHEN COALESCE(tvshow_view.totalCount - tvshow_view.watchedcount, 0) > 0 THEN 0 ELSE 1 END < 1)) AND ( ((tvshow_view.watchedcount > 0 AND tvshow_view.watchedcount < tvshow_view.totalCount) OR (tvshow_view.watchedcount = 0 AND EXISTS (SELECT 1 FROM episode_view WHERE episode_view.idShow = tvshow_view.idShow AND episode_view.resumeTimeInSeconds > 0)))) 21:42:15.794 T:139695732279040 DEBUG: RunQuery took 539 ms for 1 items query: select * from episode_view WHERE (episode_view.idShow = 135) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 21:42:15.794 T:139695732279040 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:15.889 T:139695749064448 DEBUG: RunQuery took 530 ms for 1 items query: select * from episode_view WHERE (episode_view.idShow = 135) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1))) 21:42:15.889 T:139695749064448 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:16.104 T:139696339619136 DEBUG: LIRC: Update - NEW at 102649999:ae 0 KEY_EXIT devinput (KEY_EXIT) 21:42:16.104 T:139696339619136 DEBUG: OnKey: menu (0xd8) pressed, action is Back 21:42:16.105 T:139696339619136 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 21:42:16.365 T:139695732279040 DEBUG: RunQuery took 533 ms for 128 items query: select * from episode_view WHERE (episode_view.idShow = 35) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 21:42:16.365 T:139695732279040 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:16.465 T:139695749064448 DEBUG: RunQuery took 533 ms for 128 items query: select * from episode_view WHERE (episode_view.idShow = 35) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1))) 21:42:16.465 T:139695749064448 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:16.946 T:139695732279040 DEBUG: RunQuery took 541 ms for 19 items query: select * from episode_view WHERE (episode_view.idShow = 63) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 21:42:16.946 T:139695732279040 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:17.048 T:139695749064448 DEBUG: RunQuery took 538 ms for 19 items query: select * from episode_view WHERE (episode_view.idShow = 63) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1))) 21:42:17.048 T:139695749064448 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:17.321 T:139696339619136 DEBUG: LIRC: Update - NEW at 102651215:ae 0 KEY_EXIT devinput (KEY_EXIT) 21:42:17.321 T:139696339619136 DEBUG: OnKey: menu (0xd8) pressed, action is Back 21:42:17.321 T:139696339619136 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 21:42:17.512 T:139695732279040 DEBUG: RunQuery took 529 ms for 68 items query: select * from episode_view WHERE (episode_view.idShow = 69) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 21:42:17.512 T:139695732279040 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:17.609 T:139695749064448 DEBUG: RunQuery took 528 ms for 68 items query: select * from episode_view WHERE (episode_view.idShow = 69) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1))) 21:42:17.609 T:139695749064448 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:18.078 T:139695732279040 DEBUG: RunQuery took 525 ms for 49 items query: select * from episode_view WHERE (episode_view.idShow = 188) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 21:42:18.078 T:139695732279040 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:18.172 T:139695749064448 DEBUG: RunQuery took 528 ms for 49 items query: select * from episode_view WHERE (episode_view.idShow = 188) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1))) 21:42:18.172 T:139695749064448 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:18.646 T:139695732279040 DEBUG: RunQuery took 527 ms for 1 items query: select * from episode_view WHERE (episode_view.idShow = 66) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 21:42:18.646 T:139695732279040 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:18.739 T:139695749064448 DEBUG: RunQuery took 525 ms for 1 items query: select * from episode_view WHERE (episode_view.idShow = 66) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1))) 21:42:18.739 T:139695749064448 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:19.208 T:139695732279040 DEBUG: RunQuery took 533 ms for 34 items query: select * from episode_view WHERE (episode_view.idShow = 179) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 21:42:19.208 T:139695732279040 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:19.296 T:139695749064448 DEBUG: RunQuery took 525 ms for 34 items query: select * from episode_view WHERE (episode_view.idShow = 179) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1))) 21:42:19.296 T:139695749064448 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:19.769 T:139695732279040 DEBUG: RunQuery took 527 ms for 16 items query: select * from episode_view WHERE (episode_view.idShow = 190) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 21:42:19.769 T:139695732279040 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:19.852 T:139695749064448 DEBUG: RunQuery took 523 ms for 16 items query: select * from episode_view WHERE (episode_view.idShow = 190) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1))) 21:42:19.852 T:139695749064448 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:20.330 T:139695732279040 DEBUG: RunQuery took 529 ms for 18 items query: select * from episode_view WHERE (episode_view.idShow = 201) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 21:42:20.330 T:139695732279040 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:20.413 T:139695749064448 DEBUG: RunQuery took 527 ms for 18 items query: select * from episode_view WHERE (episode_view.idShow = 201) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1))) 21:42:20.413 T:139695749064448 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:20.903 T:139695732279040 DEBUG: RunQuery took 543 ms for 24 items query: select * from episode_view WHERE (episode_view.idShow = 53) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 21:42:20.903 T:139695732279040 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:20.982 T:139695749064448 DEBUG: RunQuery took 537 ms for 24 items query: select * from episode_view WHERE (episode_view.idShow = 53) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1))) 21:42:20.982 T:139695749064448 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:21.479 T:139695732279040 DEBUG: RunQuery took 535 ms for 209 items query: select * from episode_view WHERE (episode_view.idShow = 152) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 21:42:21.479 T:139695732279040 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:21.552 T:139695749064448 DEBUG: RunQuery took 531 ms for 209 items query: select * from episode_view WHERE (episode_view.idShow = 152) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1))) 21:42:21.552 T:139695749064448 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:22.107 T:139695732279040 DEBUG: RunQuery took 529 ms for 50 items query: select * from episode_view WHERE (episode_view.idShow = 172) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 21:42:22.108 T:139695732279040 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:22.136 T:139695749064448 DEBUG: RunQuery took 522 ms for 50 items query: select * from episode_view WHERE (episode_view.idShow = 172) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1))) 21:42:22.136 T:139695749064448 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:22.681 T:139695732279040 DEBUG: RunQuery took 532 ms for 231 items query: select * from episode_view WHERE (episode_view.idShow = 182) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 21:42:22.681 T:139695732279040 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:22.710 T:139695749064448 DEBUG: RunQuery took 530 ms for 231 items query: select * from episode_view WHERE (episode_view.idShow = 182) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1))) 21:42:22.710 T:139695749064448 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:23.274 T:139695732279040 DEBUG: RunQuery took 542 ms for 141 items query: select * from episode_view WHERE (episode_view.idShow = 128) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 21:42:23.274 T:139695732279040 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:23.292 T:139695749064448 DEBUG: RunQuery took 526 ms for 141 items query: select * from episode_view WHERE (episode_view.idShow = 128) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1))) 21:42:23.292 T:139695749064448 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:23.848 T:139695732279040 DEBUG: RunQuery took 527 ms for 1 items query: select * from episode_view WHERE (episode_view.idShow = 4) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 21:42:23.848 T:139695732279040 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:23.869 T:139695749064448 DEBUG: RunQuery took 526 ms for 1 items query: select * from episode_view WHERE (episode_view.idShow = 4) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1))) 21:42:23.869 T:139695749064448 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:24.410 T:139695732279040 DEBUG: RunQuery took 528 ms for 19 items query: select * from episode_view WHERE (episode_view.idShow = 111) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 21:42:24.410 T:139695732279040 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:24.424 T:139695749064448 DEBUG: RunQuery took 523 ms for 19 items query: select * from episode_view WHERE (episode_view.idShow = 111) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1))) 21:42:24.424 T:139695749064448 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:24.976 T:139695732279040 DEBUG: RunQuery took 536 ms for 103 items query: select * from episode_view WHERE (episode_view.idShow = 205) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 21:42:24.977 T:139695732279040 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:25.001 T:139695749064448 DEBUG: RunQuery took 541 ms for 103 items query: select * from episode_view WHERE (episode_view.idShow = 205) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1))) 21:42:25.001 T:139695749064448 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:25.545 T:139695732279040 DEBUG: RunQuery took 531 ms for 106 items query: select * from episode_view WHERE (episode_view.idShow = 10) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 21:42:25.545 T:139695732279040 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:25.575 T:139695749064448 DEBUG: RunQuery took 533 ms for 106 items query: select * from episode_view WHERE (episode_view.idShow = 10) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1))) 21:42:25.575 T:139695749064448 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:26.117 T:139695732279040 DEBUG: RunQuery took 525 ms for 95 items query: select * from episode_view WHERE (episode_view.idShow = 134) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 21:42:26.117 T:139695732279040 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:26.154 T:139695749064448 DEBUG: RunQuery took 524 ms for 95 items query: select * from episode_view WHERE (episode_view.idShow = 134) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1))) 21:42:26.155 T:139695749064448 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:26.689 T:139695732279040 DEBUG: RunQuery took 530 ms for 111 items query: select * from episode_view WHERE (episode_view.idShow = 173) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 21:42:26.689 T:139695732279040 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:26.730 T:139695749064448 DEBUG: RunQuery took 527 ms for 111 items query: select * from episode_view WHERE (episode_view.idShow = 173) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1))) 21:42:26.730 T:139695749064448 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 21:42:27.672 T:139695732279040 DEBUG: RunQuery took 896 ms for 12255 items query: select * from episode_view WHERE ((episode_view.playCount IS NULL OR episode_view.playCount < 1)) 21:42:27.675 T:139695749064448 DEBUG: RunQuery took 903 ms for 12255 items query: select * from episode_view WHERE ((episode_view.playCount IS NULL OR episode_view.playCount < 1)) 21:42:29.224 T:139695749064448 DEBUG: CFavourites::Load - no system favourites found, skipping 21:42:29.341 T:139695749064448 DEBUG: RunQuery took 102 ms for 153 items query: SELECT * FROM tvshow_view WHERE ((CASE WHEN COALESCE(tvshow_view.totalCount - tvshow_view.watchedcount, 0) > 0 THEN 0 ELSE 1 END < 1)) 21:42:46.113 T:139693811918592 DEBUG: Thread JobWorker 139693811918592 terminating (autodelete) 21:42:46.114 T:139693870020352 DEBUG: Thread JobWorker 139693870020352 terminating (autodelete) 21:42:46.186 T:139693861627648 DEBUG: Thread JobWorker 139693861627648 terminating (autodelete) 21:42:46.248 T:139693593814784 DEBUG: Thread JobWorker 139693593814784 terminating (autodelete)