18:36:26.165 T:140012386412288 DEBUG: CAnnouncementManager - Announcement: OnScanFinished from xbmc 18:36:26.165 T:140012386412288 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnScanFinished 18:36:26.218 T:140012386428608 DEBUG: ------ Window Init (DialogNotification.xml) ------ 18:36:26.314 T:140011269764864 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: VideoLibrary.OnScanFinished - data: null 18:36:26.450 T:140012386428608 DEBUG: ------ Window Deinit (DialogExtendedProgressBar.xml) ------ 18:36:26.740 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:36:27.329 T:140011294942976 DEBUG: Previous line repeats 1 times. 18:36:27.329 T:140011294942976 DEBUG: RunQuery took 2 ms for 17 items query: select * from movie_view WHERE (movie_view.idFile IN (SELECT DISTINCT idFile FROM bookmark WHERE type = 1)) 18:36:27.530 T:140011294942976 DEBUG: RunQuery took 80 ms for 12 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)))) 18:36:27.573 T:140011294942976 DEBUG: RunQuery took 7 ms for 94 items query: select * from episode_view WHERE (episode_view.idShow = 7) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:36:27.573 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:36:27.609 T:140011294942976 DEBUG: RunQuery took 2 ms for 6 items query: select * from episode_view WHERE (episode_view.idShow = 1) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:36:27.609 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:36:27.644 T:140011294942976 DEBUG: RunQuery took 5 ms for 64 items query: select * from episode_view WHERE (episode_view.idShow = 34) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:36:27.644 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:36:27.680 T:140011294942976 DEBUG: RunQuery took 4 ms for 59 items query: select * from episode_view WHERE (episode_view.idShow = 4) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:36:27.680 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:36:27.745 T:140011294942976 DEBUG: RunQuery took 33 ms for 594 items query: select * from episode_view WHERE (episode_view.idShow = 8) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:36:27.745 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:36:27.817 T:140011294942976 DEBUG: RunQuery took 3 ms for 19 items query: select * from episode_view WHERE (episode_view.idShow = 84) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:36:27.817 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:36:27.849 T:140011294942976 DEBUG: RunQuery took 3 ms for 39 items query: select * from episode_view WHERE (episode_view.idShow = 41) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:36:27.849 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:36:27.889 T:140011294942976 DEBUG: RunQuery took 9 ms for 147 items query: select * from episode_view WHERE (episode_view.idShow = 24) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:36:27.889 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:36:27.935 T:140011294942976 DEBUG: RunQuery took 6 ms for 115 items query: select * from episode_view WHERE (episode_view.idShow = 32) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:36:27.935 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:36:27.972 T:140011294942976 DEBUG: RunQuery took 1 ms for 5 items query: select * from episode_view WHERE (episode_view.idShow = 31) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:36:27.973 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:36:28.003 T:140011294942976 DEBUG: RunQuery took 2 ms for 19 items query: select * from episode_view WHERE (episode_view.idShow = 59) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:36:28.003 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:36:28.034 T:140011294942976 DEBUG: RunQuery took 1 ms for 4 items query: select * from episode_view WHERE (episode_view.idShow = 29) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:36:28.034 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:36:28.100 T:140011294942976 DEBUG: RunQuery took 33 ms for 432 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount = 0)) 18:36:28.276 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:36:28.493 T:140011294942976 DEBUG: Previous line repeats 1 times. 18:36:28.493 T:140011294942976 DEBUG: RunQuery took 248 ms for 4670 items query: select * from episode_view WHERE ((episode_view.playCount IS NULL OR episode_view.playCount < 1)) 18:36:28.940 T:140011294942976 DEBUG: RunQuery took 1 ms for 0 items query: select * from musicvideo_view 18:36:29.812 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:36:31.033 T:140012386428608 DEBUG: Previous line repeats 1 times. 18:36:31.033 T:140012386428608 DEBUG: ------ Window Deinit (DialogNotification.xml) ------ 18:36:31.348 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:36:53.973 T:140009790523136 DEBUG: Previous line repeats 29 times. 18:36:53.973 T:140009790523136 NOTICE: ES: Client from ::ffff:192.168.178.66 timed out 18:36:54.389 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:36:56.168 T:140009591473920 DEBUG: Previous line repeats 3 times. 18:36:56.168 T:140009591473920 DEBUG: Thread JobWorker 140009591473920 terminating (autodelete) 18:36:56.517 T:140012386428608 INFO: CheckIdle - Closing session to https://SERVER (easy=0x7f56e4029920, multi=0x7f56e41a3bc0) 18:36:57.004 T:140011294942976 DEBUG: RunQuery took 40 ms for 432 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount < 1)) 18:36:57.397 T:140011294942976 DEBUG: RunQuery took 249 ms for 4670 items query: select * from episode_view WHERE ((episode_view.playCount IS NULL OR episode_view.playCount < 1)) 18:36:57.456 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:36:57.645 T:140011294942976 DEBUG: Previous line repeats 1 times. 18:36:57.645 T:140011294942976 DEBUG: RunQuery took 1 ms for 0 items query: select * from musicvideo_view 18:36:57.653 T:140011294942976 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album' 18:36:57.654 T:140011294942976 DEBUG: GetAlbumsByWhere - query took 0 ms 18:36:57.661 T:140011294942976 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 <> 'Verschiedene Interpreten') 18:36:57.664 T:140011294942976 DEBUG: Time to retrieve artists from dataset = 3 18:36:57.681 T:140011294942976 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 18:36:57.842 T:140009591473920 DEBUG: Thread JobWorker start, auto delete: true 18:36:58.178 T:140011294942976 DEBUG: Skin Widgets: Total time needed to request random queries: 0:00:01.225160 18:36:58.990 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:37:28.061 T:140009591473920 DEBUG: Previous line repeats 37 times. 18:37:28.061 T:140009591473920 DEBUG: Thread JobWorker 140009591473920 terminating (autodelete) 18:37:28.136 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:37:43.916 T:140012386428608 DEBUG: Previous line repeats 21 times. 18:37:43.916 T:140012386428608 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:37:43.917 T:140012279867136 INFO: CActiveAESink::OpenSink - initialize sink 18:37:43.917 T:140012279867136 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=HDMI,DEV=0 18:37:43.917 T:140012279867136 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=HDMI,DEV=0" 18:37:43.918 T:140009838188288 DEBUG: Thread JobWorker start, auto delete: true 18:37:43.924 T:140012279867136 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=HDMI,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00" 18:37:43.924 T:140012279867136 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 18:37:43.924 T:140012279867136 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 18:37:43.924 T:140012279867136 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820 18:37:43.934 T:140012279867136 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 464, bufferSize 8816 18:37:43.934 T:140012279867136 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 18:37:43.935 T:140012279867136 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 18:37:43.935 T:140012279867136 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 18:37:43.935 T:140012279867136 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR) 18:37:43.935 T:140012279867136 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 18:37:43.935 T:140012279867136 DEBUG: Output Device : HDA ATI 18:37:43.935 T:140012279867136 DEBUG: Sample Rate : 44100 18:37:43.935 T:140012279867136 DEBUG: Sample Format : AE_FMT_S32NE 18:37:43.935 T:140012279867136 DEBUG: Channel Count : 2 18:37:43.935 T:140012279867136 DEBUG: Channel Layout: FL,FR 18:37:43.935 T:140012279867136 DEBUG: Frames : 464 18:37:43.935 T:140012279867136 DEBUG: Frame Size : 8 18:37:45.022 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:37:45.286 T:140012386428608 DEBUG: Previous line repeats 1 times. 18:37:45.286 T:140012386428608 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up 18:37:46.546 T:140009482880768 DEBUG: Previous line repeats 1 times. 18:37:46.546 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:37:56.237 T:140012386428608 DEBUG: Previous line repeats 13 times. 18:37:56.237 T:140012386428608 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 18:37:56.249 T:140012386428608 DEBUG: OnPlayMedia https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi 18:37:56.249 T:140012386412288 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 18:37:56.249 T:140012386428608 INFO: easy_aquire - Created session to https://SERVER 18:37:56.249 T:140012386412288 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 18:37:56.254 T:140011269764864 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":4073,"type":"episode"},"playlistid":1,"position":0} 18:37:56.582 T:140012386428608 DEBUG: CCurlFile::GetMimeType - https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi -> video/x-msvideo 18:37:56.607 T:140012386428608 DEBUG: Loading settings for https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi 18:37:56.620 T:140012386428608 DEBUG: CPlayerCoreFactory::GetPlayers(https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi) 18:37:56.620 T:140012386428608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 18:37:56.620 T:140012386428608 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 18:37:56.620 T:140012386428608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 18:37:56.620 T:140012386428608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 18:37:56.620 T:140012386428608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 18:37:56.620 T:140012386428608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 18:37:56.620 T:140012386428608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 18:37:56.620 T:140012386428608 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams 18:37:56.620 T:140012386428608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp 18:37:56.620 T:140012386428608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2 18:37:56.621 T:140012386428608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 18:37:56.621 T:140012386428608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 18:37:56.621 T:140012386428608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 18:37:56.621 T:140012386428608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 18:37:56.621 T:140012386428608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 18:37:56.621 T:140012386428608 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 18:37:56.621 T:140012386428608 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 18:37:56.621 T:140012386428608 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 18:37:56.621 T:140012386428608 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 18:37:56.621 T:140012386428608 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: Marantz NR1604 18:37:56.621 T:140012386428608 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: [TV] Home 18:37:56.621 T:140012386428608 DEBUG: CPlayerCoreFactory::GetPlayers: added 3 players 18:37:56.621 T:140009591473920 DEBUG: Thread RefClock start, auto delete: false 18:37:56.621 T:140009591473920 DEBUG: CVideoSyncDRM::Setup - setting up DRM 18:37:56.622 T:140009591473920 DEBUG: CVideoReferenceClock: Detected refreshrate: 60.000 hertz 18:37:56.627 T:140012386428608 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData 18:37:56.628 T:140012386428608 NOTICE: VideoPlayer: Opening: https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi 18:37:56.628 T:140012386428608 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 18:37:56.660 T:140012386428608 DEBUG: CCurlFile::GetMimeType - https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi -> video/x-msvideo 18:37:56.660 T:140012386428608 DEBUG: LinuxRendererGL: Cleaning up GL resources 18:37:56.660 T:140012386428608 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16 18:37:56.660 T:140009667544832 DEBUG: Thread VideoPlayer start, auto delete: false 18:37:56.661 T:140009667544832 NOTICE: Creating InputStream 18:37:56.661 T:140009667544832 DEBUG: CurlFile::Open(0x7f568a7fb8a0) https://SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi 18:37:57.001 T:140009667544832 DEBUG: CFileCache::Open - opening using cache 18:37:57.001 T:140009667544832 DEBUG: CurlFile::Open(0x7f568c01b830) https://SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi 18:37:57.160 T:140012386428608 DEBUG: ------ Window Init (DialogBusy.xml) ------ 18:37:57.240 T:140009667544832 NOTICE: Creating Demuxer 18:37:57.240 T:140009650759424 DEBUG: Thread FileCache start, auto delete: false 18:37:57.241 T:140009667544832 DEBUG: Open - probing detected format [avi] 18:37:57.242 T:140009667544832 DEBUG: CSimpleFileCache::Seek - Attempt to seek past read data 18:37:57.263 T:140009650759424 INFO: easy_aquire - Created session to https://SERVER 18:37:57.263 T:140009650759424 DEBUG: CurlFile::CReadState::Connect - Resume from position 363571604 18:37:57.286 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:02.498 T:140009650759424 DEBUG: Previous line repeats 7 times. 18:38:02.498 T:140009650759424 INFO: CFileCache::Process - Source read didn't return any data! Hit eof(?) 18:38:02.697 T:140009667544832 DEBUG: Open - avformat_find_stream_info starting 18:38:02.761 T:140009667544832 DEBUG: Open - av_find_stream_info finished 18:38:02.762 T:140009667544832 INFO: ffmpeg[7F568A7FC700]: Input #0, avi, https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/sof-criminal.minds.s08e05.av': 18:38:02.762 T:140009667544832 INFO: ffmpeg[7F568A7FC700]: Metadata: 18:38:02.762 T:140009667544832 INFO: ffmpeg[7F568A7FC700]: encoder : MEncoder Sherpya-SVN-r31372-4.2.5 18:38:02.762 T:140009667544832 INFO: ffmpeg[7F568A7FC700]: copyright : SOF 18:38:02.762 T:140009667544832 INFO: ffmpeg[7F568A7FC700]: Duration: 00:40:29.57, start: 0.000000, bitrate: 1205 kb/s 18:38:02.762 T:140009667544832 INFO: ffmpeg[7F568A7FC700]: Stream #0:0: Video: mpeg4 (Advanced Simple Profile) (XVID / 0x44495658), yuv420p, 624x352 [SAR 1:1 DAR 39:22], 1035 kb/s, 25 fps, 25 tbr, 25 tbn, 25 tbc 18:38:02.763 T:140009667544832 INFO: ffmpeg[7F568A7FC700]: Stream #0:1: Audio: mp3 (U[0][0][0] / 0x0055), 48000 Hz, stereo, s16p, 157 kb/s 18:38:02.763 T:140009667544832 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 18:38:02.763 T:140009667544832 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 18:38:02.763 T:140009667544832 DEBUG: CDVDInputStreamFile::SetReadRate - set cache throttle rate to 165782 bytes per second 18:38:02.763 T:140009667544832 NOTICE: Opening stream: 0 source: 256 18:38:02.763 T:140009667544832 NOTICE: Creating video codec with codec id: 13 18:38:02.763 T:140009667544832 DEBUG: FactoryCodec - Video: - Opening 18:38:02.763 T:140009667544832 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-4 part 2 18:38:02.764 T:140009667544832 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-mpeg4 18:38:02.764 T:140009667544832 DEBUG: FactoryCodec - Video: ff-mpeg4 - Opened 18:38:02.764 T:140009667544832 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 13 18:38:02.764 T:140009667544832 NOTICE: Creating video thread 18:38:02.764 T:140009667544832 NOTICE: Opening stream: 1 source: 256 18:38:02.764 T:140009633974016 DEBUG: Thread VideoPlayerVideo start, auto delete: false 18:38:02.764 T:140009667544832 NOTICE: Finding audio codec for: 86017 18:38:02.764 T:140009633974016 NOTICE: running thread: video_thread 18:38:02.764 T:140009667544832 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 18:38:02.764 T:140009667544832 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 18:38:02.764 T:140009667544832 NOTICE: Creating audio thread 18:38:02.765 T:140009659152128 DEBUG: Thread VideoPlayerAudio start, auto delete: false 18:38:02.765 T:140009659152128 NOTICE: running thread: CVideoPlayerAudio::Process() 18:38:02.765 T:140009667544832 DEBUG: OnPlayBackStarted: play state was 1, starting 1 18:38:02.765 T:140009667544832 DEBUG: CVideoPlayer::SetCaching - caching state 1 18:38:02.765 T:140009667544832 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 18:38:02.765 T:140009667544832 DEBUG: CVideoPlayer::SetCaching - caching state 2 18:38:02.765 T:140009667544832 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 18:38:02.766 T:140009633974016 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:38:02.766 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 18:38:02.766 T:140009633974016 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating VDPAU(624x352) 18:38:02.766 T:140009633974016 NOTICE: (VDPAU) Close 18:38:02.769 T:140009633974016 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-4 part 2 18:38:02.769 T:140009633974016 DEBUG: CDVDVideoCodecFFmpeg - open frame threaded with 6 threads 18:38:02.769 T:140009633974016 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-mpeg4 18:38:02.769 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 18:38:02.775 T:140009659152128 DEBUG: Previous line repeats 4 times. 18:38:02.775 T:140009659152128 DEBUG: CDVDAudio::Pause - pausing audio stream 18:38:02.776 T:140009659152128 DEBUG: Previous line repeats 1 times. 18:38:02.776 T:140009659152128 NOTICE: Creating audio stream (codec id: 86017, channels: 2, sample rate: 48000, no pass-through) 18:38:02.778 T:140009659152128 DEBUG: CVideoPlayerAudio:: synctype set to 1: resample 18:38:02.779 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 18:38:02.781 T:140012279867136 INFO: CActiveAESink::OpenSink - initialize sink 18:38:02.782 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 18:38:02.788 T:140009633974016 DEBUG: Previous line repeats 1 times. 18:38:02.788 T:140009633974016 DEBUG: CRenderManager::Configure - change configuration. 624x352. display: 624x352. framerate: 25.00. format: YV12 18:38:02.791 T:140012386428608 DEBUG: PlayFile: OpenFile succeed, play state 2 18:38:02.791 T:140012386428608 DEBUG: OnPlayBackStarted: play state was 2, starting 0 18:38:02.799 T:140012386428608 DEBUG: DeleteRenderer - deleting renderer 18:38:02.799 T:140012386428608 DEBUG: LinuxRendererGL: Cleaning up GL resources 18:38:02.802 T:140012386428608 DEBUG: Previous line repeats 1 times. 18:38:02.802 T:140012386428608 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16 18:38:02.802 T:140012386428608 DEBUG: GL: Requested render method: 0 18:38:02.804 T:140012386428608 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: #define XBMC_texture_rectangle 0 #define XBMC_texture_rectangle_hack 0 #define XBMC_STRETCH 0 #define XBMC_YV12 18:38:02.804 T:140012386428608 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 18:38:02.841 T:140011278157568 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:02.842 T:140011278157568 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:02.850 T:140012386428608 DEBUG: GL: Vertex Shader compilation log: 18:38:02.851 T:140012386428608 DEBUG: GL: Vertex Shader compiled successfully 18:38:02.854 T:140011689203456 DEBUG: ### [Kodi Boblight] - PlayerChanged(start) 18:38:02.868 T:140012386428608 DEBUG: GL: Pixel Shader compilation log: 18:38:02.868 T:140012386428608 DEBUG: GL: Fragment Shader compiled successfully 18:38:02.902 T:140011278157568 DEBUG: RunQuery took 4 ms for 24 items query: select * from episode_view WHERE (episode_view.idShow = 7) AND ((episode_view.c12 = 8 or (episode_view.c12 = 0 and (episode_view.c15 = 0 or episode_view.c15 = 8)))) 18:38:02.948 T:140012386428608 DEBUG: GL: Vertex Shader compilation log: 18:38:02.948 T:140012386428608 DEBUG: GL: Vertex Shader compiled successfully 18:38:02.956 T:140012386428608 DEBUG: GL: Pixel Shader compilation log: 18:38:02.956 T:140012386428608 DEBUG: GL: Fragment Shader compiled successfully 18:38:02.988 T:140012279867136 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=HDMI,DEV=0 18:38:02.988 T:140012279867136 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=HDMI,DEV=0" 18:38:02.995 T:140012279867136 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=HDMI,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x02" 18:38:02.996 T:140012279867136 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 18:38:02.996 T:140012279867136 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 18:38:02.996 T:140012279867136 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600 18:38:03.002 T:140012386428608 NOTICE: GL: NPOT texture support detected 18:38:03.002 T:140012386428608 NOTICE: GL: Using GL_ARB_pixel_buffer_object 18:38:03.002 T:140012386428608 NOTICE: Using GL_TEXTURE_2D 18:38:03.005 T:140012279867136 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600 18:38:03.005 T:140012279867136 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 18:38:03.005 T:140012279867136 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 18:38:03.005 T:140012279867136 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 18:38:03.005 T:140012279867136 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR) 18:38:03.005 T:140012279867136 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 18:38:03.005 T:140012279867136 DEBUG: Output Device : HDA ATI 18:38:03.005 T:140012279867136 DEBUG: Sample Rate : 48000 18:38:03.005 T:140012279867136 DEBUG: Sample Format : AE_FMT_S32NE 18:38:03.005 T:140012279867136 DEBUG: Channel Count : 2 18:38:03.005 T:140012279867136 DEBUG: Channel Layout: FL,FR 18:38:03.006 T:140012279867136 DEBUG: Frames : 2400 18:38:03.006 T:140012279867136 DEBUG: Frame Size : 8 18:38:03.010 T:140012288259840 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 18:38:03.010 T:140012386428608 DEBUG: CRenderManager::Configure - 4 18:38:03.010 T:140012288259840 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 18:38:03.013 T:140012386428608 DEBUG: GL: Requested render method: 0 18:38:03.014 T:140012386428608 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: #define XBMC_texture_rectangle 0 #define XBMC_texture_rectangle_hack 0 #define XBMC_STRETCH 0 #define XBMC_YV12 18:38:03.014 T:140012386428608 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 18:38:03.014 T:140012386428608 DEBUG: GL: Vertex Shader compilation log: 18:38:03.014 T:140012386428608 DEBUG: GL: Vertex Shader compiled successfully 18:38:03.014 T:140009667544832 DEBUG: CVideoPlayer::HandleMessages - player started 2 18:38:03.014 T:140012386428608 DEBUG: GL: Pixel Shader compilation log: 18:38:03.014 T:140009667544832 DEBUG: CVideoPlayer::HandleMessages - player started 1 18:38:03.014 T:140009667544832 DEBUG: CVideoPlayer::SetCaching - caching state 3 18:38:03.014 T:140012386428608 DEBUG: GL: Fragment Shader compiled successfully 18:38:03.015 T:140009667544832 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 18:38:03.015 T:140009667544832 DEBUG: CVideoPlayer::SetCaching - caching state 0 18:38:03.015 T:140009667544832 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 18:38:03.015 T:140009667544832 DEBUG: VideoPlayer::Sync - Audio - pts: 96000.000000, cache: 316692.249435, totalcache: 600000.023842 18:38:03.015 T:140009667544832 DEBUG: VideoPlayer::Sync - Video - pts: 40000.000000, cache: 50000.000000, totalcache: 100000.000000 18:38:03.015 T:140009659152128 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-220692.249435) 18:38:03.015 T:140009659152128 DEBUG: CDVDAudio::Resume - resume audio stream 18:38:03.015 T:140012386428608 NOTICE: GL: NPOT texture support detected 18:38:03.015 T:140012386428608 NOTICE: GL: Using GL_ARB_pixel_buffer_object 18:38:03.017 T:140009633974016 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-220692.249435) 18:38:03.017 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 18:38:03.018 T:140012288259840 DEBUG: ActiveAE - start sync of audio stream 18:38:03.018 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:03.041 T:140009838188288 DEBUG: CGUIInfoManager::SetCurrentMovie(https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi) 18:38:03.042 T:140012386412288 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 18:38:03.042 T:140012386412288 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 18:38:03.042 T:140012386412288 DEBUG: UPnP: Building didl for object 'https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi' 18:38:03.044 T:140012386428608 DEBUG: Activating window ID: 12005 18:38:03.050 T:140012386428608 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------ 18:38:03.051 T:140012386428608 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 18:38:03.051 T:140012386428608 INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY 18:38:03.054 T:140011269764864 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnPlay - data: {"item":{"id":4073,"type":"episode"},"player":{"playerid":1,"speed":1}} 18:38:03.054 T:140009838188288 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:03.055 T:140009838188288 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:03.065 T:140012386428608 NOTICE: Display resolution ADJUST : HDMI-0: 1920x1080 @ 50.00Hz (18) (weight: 0.000) 18:38:03.065 T:140012386428608 NOTICE: CWinSystemX11::SetFullScreen - calling xrandr 18:38:03.065 T:140012386428608 DEBUG: OnLostDevice - notify display change event 18:38:03.065 T:140012386428608 DEBUG: Flush - flushing renderer 18:38:03.318 T:140012386428608 NOTICE: VideoPlayer: OnLostDisplay received 18:38:03.318 T:140012386428608 INFO: XRANDR: /usr/lib/kodi/kodi-xrandr --screen 0 --output HDMI-0 --mode 0x57 18:38:03.426 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:03.475 T:140012309968640 DEBUG: CALSAHControlMonitor - Monitored ALSA hctl value changed 18:38:03.497 T:140012386428608 DEBUG: Previous line repeats 1 times. 18:38:03.497 T:140012386428608 INFO: GL: Maximum texture width: 16384 18:38:03.500 T:140012386428608 DEBUG: GL: Requested render method: 0 18:38:03.500 T:140012386428608 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: #define XBMC_texture_rectangle 0 #define XBMC_texture_rectangle_hack 0 #define XBMC_STRETCH 0 #define XBMC_YV12 18:38:03.501 T:140012386428608 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 18:38:03.501 T:140012386428608 DEBUG: GL: Vertex Shader compilation log: 18:38:03.501 T:140012386428608 DEBUG: GL: Vertex Shader compiled successfully 18:38:03.501 T:140012386428608 DEBUG: GL: Pixel Shader compilation log: 18:38:03.501 T:140012386428608 DEBUG: GL: Fragment Shader compiled successfully 18:38:03.502 T:140012386428608 DEBUG: GL: Vertex Shader compilation log: 18:38:03.502 T:140012386428608 DEBUG: GL: Vertex Shader compiled successfully 18:38:03.502 T:140012386428608 DEBUG: GL: Pixel Shader compilation log: 18:38:03.502 T:140012386428608 DEBUG: GL: Fragment Shader compiled successfully 18:38:03.503 T:140012386428608 NOTICE: GL: NPOT texture support detected 18:38:03.503 T:140012386428608 NOTICE: GL: Using GL_ARB_pixel_buffer_object 18:38:03.503 T:140012386428608 NOTICE: Using GL_TEXTURE_2D 18:38:03.508 T:140009633974016 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 1 18:38:03.508 T:140012386428608 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 18:38:03.513 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:03.516 T:140012386428608 DEBUG: NotifyXRREvent - notify display reset event 18:38:03.533 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:03.595 T:140009482880768 DEBUG: Previous line repeats 1 times. 18:38:03.595 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:03.596 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:03.667 T:140012386428608 DEBUG: RecreateWindow - current output: HDMI-0, mode: 0x57, refresh: 50.000 18:38:03.667 T:140012386428608 DEBUG: OnLostDevice - notify display change event 18:38:03.667 T:140012386428608 DEBUG: Flush - flushing renderer 18:38:03.668 T:140012386428608 NOTICE: VideoPlayer: OnLostDisplay received 18:38:03.668 T:140009633974016 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 1 18:38:03.673 T:140012386428608 DEBUG: Window Manager Name: Fluxbox 18:38:03.691 T:140012386428608 DEBUG: CWinSystemX11::RefreshGlxContext: refreshing context 18:38:03.695 T:140012288259840 DEBUG: CActiveAE - display reset event 18:38:03.695 T:140012386428608 NOTICE: VideoPlayer: OnResetDisplay received 18:38:03.695 T:140009633974016 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 0 18:38:03.695 T:140012386428608 INFO: GL: Maximum texture width: 16384 18:38:03.695 T:140012279867136 INFO: CActiveAESink::OpenSink - initialize sink 18:38:03.695 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 18:38:03.695 T:140012279867136 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=HDMI,DEV=0 18:38:03.695 T:140012279867136 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=HDMI,DEV=0" 18:38:03.704 T:140012279867136 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=HDMI,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x02" 18:38:03.705 T:140012279867136 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 18:38:03.705 T:140012279867136 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 18:38:03.705 T:140012279867136 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600 18:38:03.707 T:140012386428608 DEBUG: GL: Requested render method: 0 18:38:03.708 T:140012386428608 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: #define XBMC_texture_rectangle 0 #define XBMC_texture_rectangle_hack 0 #define XBMC_STRETCH 0 #define XBMC_YV12 18:38:03.708 T:140012386428608 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 18:38:03.708 T:140012386428608 DEBUG: GL: Vertex Shader compilation log: 18:38:03.708 T:140012386428608 DEBUG: GL: Vertex Shader compiled successfully 18:38:03.708 T:140012386428608 DEBUG: GL: Pixel Shader compilation log: 18:38:03.708 T:140012386428608 DEBUG: GL: Fragment Shader compiled successfully 18:38:03.709 T:140012386428608 DEBUG: GL: Vertex Shader compilation log: 18:38:03.709 T:140012386428608 DEBUG: GL: Vertex Shader compiled successfully 18:38:03.709 T:140012386428608 DEBUG: GL: Pixel Shader compilation log: 18:38:03.709 T:140012386428608 DEBUG: GL: Fragment Shader compiled successfully 18:38:03.709 T:140012386428608 NOTICE: GL: NPOT texture support detected 18:38:03.709 T:140012386428608 NOTICE: GL: Using GL_ARB_pixel_buffer_object 18:38:03.709 T:140012386428608 NOTICE: Using GL_TEXTURE_2D 18:38:03.713 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:03.715 T:140012279867136 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600 18:38:03.715 T:140012279867136 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 18:38:03.715 T:140012279867136 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 18:38:03.715 T:140012279867136 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 18:38:03.715 T:140012279867136 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR) 18:38:03.715 T:140012279867136 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 18:38:03.715 T:140012279867136 DEBUG: Output Device : HDA ATI 18:38:03.715 T:140012279867136 DEBUG: Sample Rate : 48000 18:38:03.716 T:140012279867136 DEBUG: Sample Format : AE_FMT_S32NE 18:38:03.716 T:140012279867136 DEBUG: Channel Count : 2 18:38:03.716 T:140012279867136 DEBUG: Channel Layout: FL,FR 18:38:03.716 T:140012279867136 DEBUG: Frames : 2400 18:38:03.716 T:140012279867136 DEBUG: Frame Size : 8 18:38:03.722 T:140012386428608 INFO: GL: Enabling VSYNC 18:38:03.722 T:140012288259840 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 18:38:03.723 T:140009659152128 DEBUG: Previous line repeats 2 times. 18:38:03.723 T:140009659152128 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: 1 18:38:03.723 T:140009659152128 DEBUG: Previous line repeats 1 times. 18:38:03.723 T:140009659152128 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: 0 18:38:03.724 T:140012288259840 DEBUG: ActiveAE::SyncStream - average error of 192.064990, start adjusting 18:38:03.725 T:140012288259840 DEBUG: ActiveAE::SyncStream - average error 0.064990 below threshold of 30.000000 18:38:03.738 T:140012386428608 DEBUG: GL: Requested render method: 0 18:38:03.738 T:140012386428608 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: #define XBMC_texture_rectangle 0 #define XBMC_texture_rectangle_hack 0 #define XBMC_STRETCH 0 #define XBMC_YV12 18:38:03.739 T:140012386428608 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 18:38:03.739 T:140012386428608 DEBUG: GL: Vertex Shader compilation log: 18:38:03.739 T:140012386428608 DEBUG: GL: Vertex Shader compiled successfully 18:38:03.739 T:140012386428608 DEBUG: GL: Pixel Shader compilation log: 18:38:03.739 T:140012386428608 DEBUG: GL: Fragment Shader compiled successfully 18:38:03.740 T:140012386428608 NOTICE: GL: NPOT texture support detected 18:38:03.740 T:140012386428608 NOTICE: GL: Using GL_ARB_pixel_buffer_object 18:38:03.827 T:140009633974016 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 40000 18:38:03.857 T:140011689203456 DEBUG: ### [Kodi Boblight] - settings() - handleCategory(tvshow) 18:38:03.857 T:140011689203456 DEBUG: ### [Kodi Boblight] - settings() - handleGlobalSettings 18:38:03.857 T:140011689203456 DEBUG: ### [Kodi Boblight] - settings() - setupForTVShow 18:38:03.858 T:140011689203456 DEBUG: ### [Kodi Boblight] - changed saturation to 3.0 ret: True 18:38:03.859 T:140011689203456 DEBUG: ### [Kodi Boblight] - changed value to 10.0 ret: True 18:38:03.860 T:140011689203456 DEBUG: ### [Kodi Boblight] - changed speed to 20.0 ret: True 18:38:03.862 T:140011689203456 DEBUG: ### [Kodi Boblight] - changed autospeed to 0.0 ret: True 18:38:03.863 T:140011689203456 DEBUG: ### [Kodi Boblight] - changed interpolation to 0 ret: True 18:38:03.865 T:140011689203456 DEBUG: ### [Kodi Boblight] - changed threshold to 0.0 ret: True 18:38:03.865 T:140011689203456 DEBUG: ### [Kodi Boblight] - settings() - handleStaticBgSettings 18:38:03.865 T:140011689203456 DEBUG: ### [Kodi Boblight] - settings() - handleStereoscopic(False) - disableon3d (False) 18:38:03.867 T:140012386428608 WARNING: CRenderCaptureGL: GL_ARB_occlusion_query disabled, performance might suffer 18:38:04.451 T:140009591473920 DEBUG: CVideoSyncDRM::Setup - setting up DRM 18:38:04.451 T:140009591473920 DEBUG: CVideoReferenceClock: Detected refreshrate: 50.000 hertz 18:38:04.689 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:04.728 T:140012288259840 DEBUG: Previous line repeats 2 times. 18:38:04.728 T:140012288259840 DEBUG: ActiveAE::SyncStream - average error 464.676012 above threshold of 200.000000 18:38:04.741 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:05.128 T:140012288259840 DEBUG: ActiveAE::SyncStream - average error 14.676012, last average error: 464.676012 18:38:05.218 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:05.228 T:140012288259840 DEBUG: ActiveAE::SyncStream - average error of 34.142522, start adjusting 18:38:05.228 T:140012288259840 DEBUG: ActiveAE::SyncStream - average error 0.142522 below threshold of 30.000000 18:38:05.229 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:05.263 T:140009482880768 DEBUG: Previous line repeats 2 times. 18:38:05.263 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:05.263 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:06.824 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:06.880 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:06.880 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:06.881 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:08.298 T:140009633974016 DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00, frameduration: 40000.000000 18:38:08.446 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:08.495 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:08.495 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:08.496 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:09.356 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:10.060 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:10.060 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:10.109 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:10.109 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:10.110 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:10.905 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:10.965 T:140009633974016 DEBUG: Previous line repeats 2 times. 18:38:10.965 T:140009633974016 DEBUG: CPullupCorrection: pattern lost on diff 80000.000000, number of losses 1 18:38:11.675 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:11.725 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:11.725 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:11.725 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:11.938 T:140009633974016 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 18:38:11.946 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:12.004 T:140009633974016 DEBUG: Previous line repeats 3 times. 18:38:12.004 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 2, Bufferlevel: 3, dropped: 1 18:38:12.449 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:13.285 T:140009482880768 DEBUG: Previous line repeats 7 times. 18:38:13.285 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:13.326 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:13.326 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:13.327 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:14.894 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:14.945 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:14.945 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:14.946 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:16.078 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:16.120 T:140009633974016 DEBUG: Previous line repeats 2 times. 18:38:16.120 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 0, Bufferlevel: 3, dropped: 1 18:38:16.506 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:16.558 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:16.558 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:16.559 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:17.623 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:17.680 T:140009633974016 DEBUG: Previous line repeats 2 times. 18:38:17.680 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 0, Bufferlevel: 2, dropped: 1 18:38:18.127 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:18.135 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:18.139 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:18.177 T:140009482880768 DEBUG: Previous line repeats 2 times. 18:38:18.177 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:18.178 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:19.145 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:19.200 T:140009633974016 DEBUG: Previous line repeats 3 times. 18:38:19.200 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 2, Bufferlevel: 1, dropped: 1 18:38:19.200 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:19.202 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 2, Bufferlevel: 2, dropped: 1 18:38:19.649 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:19.741 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:19.792 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:19.792 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:19.793 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:20.662 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:20.720 T:140009633974016 DEBUG: Previous line repeats 2 times. 18:38:20.720 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 0, Bufferlevel: 2, dropped: 1 18:38:21.359 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:21.409 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:21.410 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:21.410 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:22.967 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:23.007 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:23.007 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:23.007 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:24.564 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:24.612 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:24.612 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:24.612 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:24.822 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:25.738 T:140009633974016 DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00, frameduration: 40000.000000 18:38:26.171 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:26.219 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:26.219 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:26.219 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:26.902 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:26.960 T:140009633974016 DEBUG: Previous line repeats 2 times. 18:38:26.961 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 0, Bufferlevel: 2, dropped: 1 18:38:27.411 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:27.781 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:27.833 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:27.833 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:27.834 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:28.423 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:29.401 T:140009482880768 DEBUG: Previous line repeats 2 times. 18:38:29.401 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:29.437 T:140009633974016 DEBUG: Previous line repeats 2 times. 18:38:29.437 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:29.443 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:29.459 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:29.460 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:31.026 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:31.076 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:31.076 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:31.076 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:31.518 T:140009633974016 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 18:38:31.518 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 18:38:31.520 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:31.581 T:140009633974016 DEBUG: Previous line repeats 3 times. 18:38:31.581 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 1, Bufferlevel: 1, dropped: 1 18:38:31.581 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:32.637 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:32.637 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:32.681 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:32.681 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:32.682 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:33.065 T:140009838188288 DEBUG: Thread JobWorker 140009838188288 terminating (autodelete) 18:38:34.077 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:34.251 T:140009482880768 DEBUG: Previous line repeats 4 times. 18:38:34.252 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:34.304 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:34.304 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:34.305 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:35.116 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:35.875 T:140009482880768 DEBUG: Previous line repeats 2 times. 18:38:35.875 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:35.929 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:35.929 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:35.930 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:37.494 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:37.549 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:37.549 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:37.549 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:38.237 T:140009633974016 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 18:38:38.237 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 18:38:38.238 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:38.300 T:140009633974016 DEBUG: Previous line repeats 3 times. 18:38:38.300 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 1, Bufferlevel: 1, dropped: 1 18:38:38.300 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:39.112 T:140009482880768 DEBUG: Previous line repeats 2 times. 18:38:39.112 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:39.146 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:39.146 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:39.146 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:39.250 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:39.821 T:140009633974016 DEBUG: Previous line repeats 5 times. 18:38:39.821 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 1, Bufferlevel: 1, dropped: 1 18:38:39.821 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:40.695 T:140009482880768 DEBUG: Previous line repeats 2 times. 18:38:40.695 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:40.744 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:40.744 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:40.745 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:40.772 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:42.313 T:140009482880768 DEBUG: Previous line repeats 1 times. 18:38:42.313 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:42.364 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:42.364 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:42.365 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:43.357 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:43.936 T:140009482880768 DEBUG: Previous line repeats 2 times. 18:38:43.936 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:43.986 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:43.986 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:43.986 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:44.397 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:44.823 T:140009633974016 DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00, frameduration: 40000.000000 18:38:45.436 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:45.501 T:140009633974016 DEBUG: Previous line repeats 4 times. 18:38:45.501 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 2, Bufferlevel: 2, dropped: 1 18:38:45.502 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 2, Bufferlevel: 3, dropped: 1 18:38:45.546 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:45.603 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:45.603 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:45.603 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:45.946 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:47.166 T:140009482880768 DEBUG: Previous line repeats 1 times. 18:38:47.167 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:47.216 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:47.216 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:47.217 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:47.465 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:48.773 T:140009482880768 DEBUG: Previous line repeats 5 times. 18:38:48.773 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:48.823 T:140009482880768 DEBUG: Previous line repeats 3 times. 18:38:48.823 T:140009482880768 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:48.824 T:140009482880768 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:49.013 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:49.060 T:140009633974016 DEBUG: Previous line repeats 3 times. 18:38:49.060 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 0, Bufferlevel: 1, dropped: 1 18:38:49.060 T:140009633974016 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 18:38:49.808 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:49.815 T:140012386428608 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 18:38:49.816 T:140012386428608 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 18:38:49.870 T:140012386428608 NOTICE: CWinSystemX11::SetFullScreen - calling xrandr 18:38:49.870 T:140012386428608 DEBUG: OnLostDevice - notify display change event 18:38:49.870 T:140012386428608 DEBUG: Flush - flushing renderer 18:38:50.075 T:140012386428608 NOTICE: VideoPlayer: OnLostDisplay received 18:38:50.079 T:140012386428608 INFO: XRANDR: /usr/lib/kodi/kodi-xrandr --screen 0 --output HDMI-0 --mode 0x56 18:38:50.253 T:140012386428608 INFO: GL: Maximum texture width: 16384 18:38:50.254 T:140012386428608 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 18:38:50.264 T:140012386428608 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 18:38:50.267 T:140012386428608 DEBUG: CGUIMediaWindow::GetDirectory (videodb://inprogresstvshows/7/-1/?tvshowid=7) 18:38:50.268 T:140012386428608 DEBUG: ParentPath = [videodb://inprogresstvshows/7/-1/?tvshowid=7] 18:38:50.277 T:140009633974016 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 1 18:38:50.293 T:140012386428608 DEBUG: RunQuery took 15 ms for 260 items query: select * from episode_view WHERE episode_view.idShow = 7 18:38:50.385 T:140012386428608 DEBUG: RunQuery took 6 ms for 0 items query: select * from movie_view join movielinktvshow on movielinktvshow.idMovie=movie_view.idMovie WHERE movielinktvshow.idShow = 7 18:38:50.394 T:140009838188288 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:50.405 T:140012386428608 DEBUG: WindowVideoNav::GetDirectory 18:38:50.468 T:140009337390848 DEBUG: Thread BackgroundLoader start, auto delete: false 18:38:50.527 T:140012386428608 NOTICE: CVideoPlayer::CloseFile() 18:38:50.528 T:140012386428608 NOTICE: VideoPlayer: waiting for threads to exit 18:38:50.528 T:140009667544832 NOTICE: CVideoPlayer::OnExit() 18:38:50.528 T:140009667544832 NOTICE: Closing stream player 1 18:38:50.528 T:140009667544832 NOTICE: Waiting for audio thread to exit 18:38:50.538 T:140009838188288 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:50.582 T:140009838188288 DEBUG: Previous line repeats 2 times. 18:38:50.582 T:140009838188288 DEBUG: GetMovieId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idMovie from movie where idFile=4358 18:38:50.583 T:140009838188288 DEBUG: GetEpisodeId (https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi), query = select idEpisode from episode where idFile=4358 18:38:50.639 T:140009354176256 DEBUG: Thread JobWorker start, auto delete: true 18:38:50.752 T:140009337390848 DEBUG: Thread BackgroundLoader 140009337390848 terminating 18:38:51.635 T:140009659152128 ERROR: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer 18:38:51.635 T:140009659152128 NOTICE: thread end: CVideoPlayerAudio::OnExit() 18:38:51.635 T:140009667544832 NOTICE: Closing audio device 18:38:51.636 T:140009667544832 DEBUG: CDVDAudio::Flush - flush audio stream 18:38:51.636 T:140009659152128 DEBUG: Thread VideoPlayerAudio 140009659152128 terminating 18:38:52.145 T:140009838188288 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:52.636 T:140009667544832 DEBUG: Previous line repeats 1 times. 18:38:52.636 T:140009667544832 ERROR: CActiveAE::FreeStream - failed 18:38:52.637 T:140009667544832 NOTICE: Deleting audio codec 18:38:52.638 T:140009667544832 NOTICE: Closing stream player 2 18:38:52.638 T:140009667544832 NOTICE: waiting for video thread to exit 18:38:52.638 T:140009633974016 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true 18:38:52.638 T:140009633974016 NOTICE: thread end: video_thread 18:38:52.638 T:140009633974016 DEBUG: Thread VideoPlayerVideo 140009633974016 terminating 18:38:52.639 T:140009667544832 NOTICE: deleting video codec 18:38:52.679 T:140009650759424 DEBUG: Thread FileCache 140009650759424 terminating 18:38:52.697 T:140009667544832 DEBUG: OnPlayBackStopped: play state was 2, starting 0 18:38:52.697 T:140009667544832 DEBUG: Thread VideoPlayer 140009667544832 terminating 18:38:52.697 T:140012386428608 NOTICE: VideoPlayer: finished waiting 18:38:52.697 T:140012386412288 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 18:38:52.697 T:140012386428608 DEBUG: DeleteRenderer - deleting renderer 18:38:52.697 T:140012386428608 DEBUG: LinuxRendererGL: Cleaning up GL resources 18:38:52.697 T:140012386412288 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 18:38:52.698 T:140011269764864 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnStop - data: {"end":false,"item":{"id":4073,"type":"episode"}} 18:38:52.711 T:140012386428608 DEBUG: NotifyXRREvent - notify display reset event 18:38:52.845 T:140011689203456 DEBUG: ### [Kodi Boblight] - PlayerChanged(stop) 18:38:52.860 T:140012386428608 DEBUG: RecreateWindow - current output: HDMI-0, mode: 0x56, refresh: 60.000 18:38:52.860 T:140012386428608 DEBUG: OnLostDevice - notify display change event 18:38:52.860 T:140012386428608 NOTICE: VideoPlayer: OnLostDisplay received 18:38:52.861 T:140012386428608 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 18:38:52.861 T:140012386428608 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED 18:38:52.864 T:140012386428608 DEBUG: Window Manager Name: Fluxbox 18:38:52.881 T:140012386428608 DEBUG: CWinSystemX11::RefreshGlxContext: refreshing context 18:38:52.883 T:140012386428608 NOTICE: VideoPlayer: OnResetDisplay received 18:38:52.883 T:140012288259840 DEBUG: CActiveAE - display reset event 18:38:52.883 T:140012386428608 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 18:38:52.883 T:140012386428608 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED 18:38:52.883 T:140012386428608 INFO: GL: Maximum texture width: 16384 18:38:52.884 T:140012279867136 INFO: CActiveAESink::OpenSink - initialize sink 18:38:52.884 T:140012279867136 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=HDMI,DEV=0 18:38:52.884 T:140012279867136 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=HDMI,DEV=0" 18:38:52.891 T:140012279867136 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=HDMI,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x02" 18:38:52.892 T:140012279867136 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 18:38:52.892 T:140012279867136 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 18:38:52.892 T:140012279867136 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600 18:38:52.904 T:140012279867136 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600 18:38:52.905 T:140012279867136 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 18:38:52.905 T:140012279867136 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 18:38:52.905 T:140012279867136 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 18:38:52.905 T:140012279867136 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR) 18:38:52.905 T:140012279867136 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 18:38:52.905 T:140012279867136 DEBUG: Output Device : HDA ATI 18:38:52.905 T:140012279867136 DEBUG: Sample Rate : 48000 18:38:52.905 T:140012279867136 DEBUG: Sample Format : AE_FMT_S32NE 18:38:52.905 T:140012279867136 DEBUG: Channel Count : 2 18:38:52.905 T:140012279867136 DEBUG: Channel Layout: FL,FR 18:38:52.905 T:140012279867136 DEBUG: Frames : 2400 18:38:52.905 T:140012279867136 DEBUG: Frame Size : 8 18:38:52.909 T:140012288259840 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 18:38:52.909 T:140012288259840 DEBUG: Previous line repeats 2 times. 18:38:52.909 T:140012288259840 DEBUG: CActiveAE::DiscardStream - audio stream deleted 18:38:52.909 T:140012288259840 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 18:38:53.115 T:140012279867136 DEBUG: Previous line repeats 2 times. 18:38:53.115 T:140012279867136 INFO: CActiveAESink::OpenSink - initialize sink 18:38:53.203 T:140012386428608 INFO: GL: Enabling VSYNC 18:38:53.204 T:140012386428608 NOTICE: CVideoPlayer::CloseFile() 18:38:53.204 T:140012386428608 NOTICE: VideoPlayer: waiting for threads to exit 18:38:53.204 T:140012386428608 NOTICE: VideoPlayer: finished waiting 18:38:53.205 T:140012386428608 NOTICE: CVideoPlayer::CloseFile() 18:38:53.205 T:140012386428608 NOTICE: VideoPlayer: waiting for threads to exit 18:38:53.205 T:140012386428608 NOTICE: VideoPlayer: finished waiting 18:38:53.209 T:140012386428608 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 18:38:53.213 T:140009591473920 DEBUG: Thread RefClock 140009591473920 terminating 18:38:53.214 T:140009354176256 DEBUG: DoWork - Saving file state for video item https://USERNAME:PASSWORD@SERVER:443/Serien/Criminal%20Minds/Staffel%208/s08e05.avi 18:38:53.215 T:140009591473920 DEBUG: Thread JobWorker start, auto delete: true 18:38:53.310 T:140012386412288 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc 18:38:53.310 T:140012386412288 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate 18:38:53.315 T:140012279867136 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=HDMI,DEV=0 18:38:53.315 T:140012279867136 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=HDMI,DEV=0" 18:38:53.323 T:140012279867136 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=HDMI,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00" 18:38:53.324 T:140012279867136 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 18:38:53.324 T:140012279867136 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 18:38:53.324 T:140012279867136 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820 18:38:53.334 T:140012279867136 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 464, bufferSize 8816 18:38:53.335 T:140012279867136 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 18:38:53.335 T:140012279867136 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 18:38:53.335 T:140012279867136 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 18:38:53.335 T:140012279867136 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR) 18:38:53.335 T:140012279867136 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 18:38:53.335 T:140012279867136 DEBUG: Output Device : HDA ATI 18:38:53.335 T:140012279867136 DEBUG: Sample Rate : 44100 18:38:53.335 T:140012279867136 DEBUG: Sample Format : AE_FMT_S32NE 18:38:53.335 T:140012279867136 DEBUG: Channel Count : 2 18:38:53.335 T:140012279867136 DEBUG: Channel Layout: FL,FR 18:38:53.335 T:140012279867136 DEBUG: Frames : 464 18:38:53.335 T:140012279867136 DEBUG: Frame Size : 8 18:38:53.336 T:140012288259840 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 18:38:53.465 T:140011269764864 DEBUG: Previous line repeats 1 times. 18:38:53.465 T:140011269764864 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: VideoLibrary.OnUpdate - data: {"item":{"id":4073,"type":"episode"}} 18:38:53.681 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:53.794 T:140011294942976 DEBUG: Previous line repeats 1 times. 18:38:53.794 T:140011294942976 DEBUG: RunQuery took 85 ms for 12 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)))) 18:38:53.838 T:140011294942976 DEBUG: RunQuery took 6 ms for 94 items query: select * from episode_view WHERE (episode_view.idShow = 7) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:38:53.838 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:38:53.845 T:140011689203456 DEBUG: ### [Kodi Boblight] - settings() - handleCategory(static) 18:38:53.846 T:140011689203456 DEBUG: ### [Kodi Boblight] - settings() - handleGlobalSettings 18:38:53.846 T:140011689203456 DEBUG: ### [Kodi Boblight] - settings() - setupForStatic 18:38:53.847 T:140011689203456 DEBUG: ### [Kodi Boblight] - changed saturation to 4.0 ret: True 18:38:53.848 T:140011689203456 DEBUG: ### [Kodi Boblight] - changed value to 1.0 ret: True 18:38:53.849 T:140011689203456 DEBUG: ### [Kodi Boblight] - changed speed to 50.0 ret: True 18:38:53.850 T:140011689203456 DEBUG: ### [Kodi Boblight] - changed autospeed to 0.0 ret: True 18:38:53.852 T:140011689203456 DEBUG: ### [Kodi Boblight] - changed interpolation to 1 ret: True 18:38:53.854 T:140011689203456 DEBUG: ### [Kodi Boblight] - changed threshold to 0.0 ret: True 18:38:53.854 T:140011689203456 DEBUG: ### [Kodi Boblight] - settings() - handleStaticBgSettings 18:38:53.857 T:140011689203456 DEBUG: ### [Kodi Boblight] - settings() - handleStereoscopic(False) - disableon3d (False) 18:38:53.857 T:140011689203456 DEBUG: ### [Kodi Boblight] - settings() - handleStaticBgSettings 18:38:53.881 T:140011294942976 DEBUG: RunQuery took 2 ms for 6 items query: select * from episode_view WHERE (episode_view.idShow = 1) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:38:53.881 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:38:53.920 T:140011294942976 DEBUG: RunQuery took 5 ms for 64 items query: select * from episode_view WHERE (episode_view.idShow = 34) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:38:53.921 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:38:53.957 T:140011294942976 DEBUG: RunQuery took 4 ms for 59 items query: select * from episode_view WHERE (episode_view.idShow = 4) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:38:53.957 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:38:54.023 T:140011294942976 DEBUG: RunQuery took 33 ms for 594 items query: select * from episode_view WHERE (episode_view.idShow = 8) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:38:54.023 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:38:54.095 T:140011294942976 DEBUG: RunQuery took 3 ms for 19 items query: select * from episode_view WHERE (episode_view.idShow = 84) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:38:54.095 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:38:54.128 T:140011294942976 DEBUG: RunQuery took 4 ms for 39 items query: select * from episode_view WHERE (episode_view.idShow = 41) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:38:54.128 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:38:54.168 T:140011294942976 DEBUG: RunQuery took 9 ms for 147 items query: select * from episode_view WHERE (episode_view.idShow = 24) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:38:54.168 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:38:54.215 T:140011294942976 DEBUG: RunQuery took 7 ms for 115 items query: select * from episode_view WHERE (episode_view.idShow = 32) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:38:54.215 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:38:54.253 T:140011294942976 DEBUG: RunQuery took 1 ms for 5 items query: select * from episode_view WHERE (episode_view.idShow = 31) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:38:54.253 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:38:54.285 T:140011294942976 DEBUG: RunQuery took 2 ms for 19 items query: select * from episode_view WHERE (episode_view.idShow = 59) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:38:54.285 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:38:54.316 T:140011294942976 DEBUG: RunQuery took 2 ms for 4 items query: select * from episode_view WHERE (episode_view.idShow = 29) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0))) 18:38:54.316 T:140011294942976 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30 18:38:54.593 T:140011294942976 DEBUG: RunQuery took 248 ms for 4670 items query: select * from episode_view WHERE ((episode_view.playCount IS NULL OR episode_view.playCount < 1)) 18:38:55.218 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:55.394 T:140012386428608 DEBUG: Previous line repeats 1 times. 18:38:55.394 T:140012386428608 DEBUG: OnKey: menu (0xd8) pressed, action is Back 18:38:55.394 T:140012386428608 DEBUG: CGUIMediaWindow::GetDirectory (videodb://inprogresstvshows/7/) 18:38:55.394 T:140012386428608 DEBUG: ParentPath = [videodb://inprogresstvshows/] 18:38:55.625 T:140009591473920 DEBUG: RunQuery took 219 ms for 12 items query: SELECT * FROM season_view WHERE season_view.idShow = 7 18:38:55.632 T:140009591473920 DEBUG: RunQuery took 6 ms for 0 items query: select * from movie_view join movielinktvshow on movielinktvshow.idMovie=movie_view.idMovie WHERE movielinktvshow.idShow = 7 18:38:55.654 T:140012386428608 DEBUG: WindowVideoNav::GetDirectory 18:38:55.730 T:140009692722944 DEBUG: Thread BackgroundLoader start, auto delete: false 18:38:55.749 T:140009692722944 DEBUG: Thread BackgroundLoader 140009692722944 terminating 18:38:55.752 T:140009692722944 DEBUG: Thread BackgroundLoader start, auto delete: false 18:38:55.765 T:140009692722944 DEBUG: Thread BackgroundLoader 140009692722944 terminating 18:38:55.774 T:140012386428608 DEBUG: OnKey: menu (0xd8) pressed, action is Back 18:38:55.775 T:140012386428608 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 18:38:56.093 T:140012386428608 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------ 18:38:56.095 T:140012386428608 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 18:38:56.095 T:140012386428608 DEBUG: ------ Window Init (Home.xml) ------ 18:38:56.132 T:140012386428608 DEBUG: CDirectoryProvider[special://skin/playlists/inprogress_movies.xsp]: refreshing.. 18:38:56.133 T:140012386428608 DEBUG: CDirectoryProvider[special://skin/playlists/recent_unwatched_movies.xsp]: refreshing.. 18:38:56.133 T:140012386428608 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_movies.xsp]: refreshing.. 18:38:56.133 T:140012386428608 DEBUG: CDirectoryProvider[special://skin/playlists/random_movies.xsp]: refreshing.. 18:38:56.133 T:140012386428608 DEBUG: CDirectoryProvider[videodb://movies/genres/]: refreshing.. 18:38:56.133 T:140012386428608 DEBUG: CDirectoryProvider[videodb://movies/sets/]: refreshing.. 18:38:56.134 T:140012386428608 DEBUG: CDirectoryProvider[videodb://inprogresstvshows]: refreshing.. 18:38:56.134 T:140012386428608 DEBUG: CDirectoryProvider[special://skin/playlists/recent_unwatched_episodes.xsp]: refreshing.. 18:38:56.134 T:140012386428608 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_tvshows.xsp]: refreshing.. 18:38:56.134 T:140012386428608 DEBUG: CDirectoryProvider[videodb://tvshows/genres/]: refreshing.. 18:38:56.134 T:140012386428608 DEBUG: CDirectoryProvider[videodb://tvshows/studios/]: refreshing.. 18:38:56.135 T:140012386428608 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing.. 18:38:56.135 T:140012386428608 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing.. 18:38:56.135 T:140009642465024 DEBUG: Thread JobWorker start, auto delete: true 18:38:56.136 T:140012386428608 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing.. 18:38:56.136 T:140012386428608 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing.. 18:38:56.137 T:140012386428608 DEBUG: CDirectoryProvider[pvr://channels/tv/*?view=lastplayed]: refreshing.. 18:38:56.137 T:140012386428608 DEBUG: CDirectoryProvider[pvr://recordings/tv/active?view=flat]: refreshing.. 18:38:56.137 T:140012386428608 DEBUG: CDirectoryProvider[pvr://channels/radio/*?view=lastplayed]: refreshing.. 18:38:56.137 T:140012386428608 DEBUG: CDirectoryProvider[pvr://recordings/radio/active?view=flat]: refreshing.. 18:38:56.170 T:140009354176256 DEBUG: RunQuery took 5 ms for 17 items query: select * from movie_view WHERE (movie_view.idFile IN (SELECT DISTINCT idFile FROM bookmark WHERE type = 1)) 18:38:56.188 T:140012386428608 DEBUG: OnKey: menu (0xd8) pressed, action is Back 18:38:56.188 T:140012386428608 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 18:38:56.237 T:140009642465024 DEBUG: RunQuery took 65 ms for 432 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount = 0)) 18:38:56.241 T:140009591473920 DEBUG: RunQuery took 56 ms for 432 items query: select * from movie_view WHERE ((movie_view.dateAdded > '1900-01-01')) AND ((movie_view.playCount IS NULL OR movie_view.playCount < 1)) 18:38:56.328 T:140009354176256 DEBUG: RunQuery took 76 ms for 488 items query: select * from movie_view 18:38:56.383 T:140009642465024 DEBUG: RunQuery took 46 ms for 31 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 18:38:56.400 T:140009591473920 DEBUG: RunQuery took 49 ms for 224 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet ORDER BY sets.idSet 18:38:56.510 T:140009354176256 DEBUG: RunQuery took 92 ms for 8 items query: SELECT * FROM tvshow_view WHERE watchedCount != 0 AND totalCount != watchedCount ORDER BY c00 18:38:56.636 T:140009354176256 DEBUG: RunQuery took 79 ms for 60 items query: SELECT * FROM tvshow_view WHERE ((tvshow_view.watchedcount = 0)) AND ((tvshow_view.totalCount > 0)) 18:38:56.703 T:140009591473920 DEBUG: RunQuery took 93 ms for 20 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 18:38:56.743 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:56.838 T:140009642465024 DEBUG: Previous line repeats 1 times. 18:38:56.838 T:140009642465024 DEBUG: RunQuery took 399 ms for 4670 items query: select * from episode_view WHERE ((episode_view.dateAdded > '1900-01-01')) AND ((episode_view.playCount IS NULL OR episode_view.playCount < 1)) 18:38:56.846 T:140009591473920 DEBUG: RunQuery took 101 ms for 27 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 18:38:57.029 T:140009354176256 DEBUG: RunQuery took 337 ms for 3 items query: SELECT * FROM season_view WHERE season_view.idShow = 50 18:38:57.038 T:140009354176256 DEBUG: RunQuery took 8 ms for 0 items query: select * from movie_view join movielinktvshow on movielinktvshow.idMovie=movie_view.idMovie WHERE movielinktvshow.idShow = 50 18:38:57.114 T:140009591473920 DEBUG: CPVRDirectory::GetDirectory(pvr://channels/tv/*?view=lastplayed) 18:38:57.115 T:140009591473920 DEBUG: CPVRDirectory::GetDirectory(pvr://recordings/tv/active?view=flat) 18:38:57.117 T:140009591473920 DEBUG: CPVRDirectory::GetDirectory(pvr://channels/radio/*?view=lastplayed) 18:38:57.118 T:140009591473920 DEBUG: CPVRDirectory::GetDirectory(pvr://recordings/radio/active?view=flat) 18:38:57.118 T:140009591473920 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update 18:38:57.140 T:140009591473920 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 18:38:57.143 T:140009591473920 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 18:38:57.147 T:140009591473920 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update 18:38:57.285 T:140009591473920 DEBUG: RunQuery took 103 ms for 10 items query: select * from movie_view ORDER BY dateAdded desc, idMovie desc LIMIT 10 18:38:57.313 T:140012386428608 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 18:38:57.462 T:140009354176256 DEBUG: RunQuery took 336 ms for 1 items query: SELECT * FROM season_view WHERE season_view.idShow = 67 18:38:57.469 T:140009354176256 DEBUG: RunQuery took 6 ms for 0 items query: select * from movie_view join movielinktvshow on movielinktvshow.idMovie=movie_view.idMovie WHERE movielinktvshow.idShow = 67 18:38:57.573 T:140009354176256 DEBUG: GetImageHash - unable to stat url /storage/.kodi/addons/shell.python.tvnamer/icon.png 18:38:57.589 T:140009692722944 DEBUG: Thread JobWorker start, auto delete: true 18:38:57.688 T:140009591473920 DEBUG: RunQuery took 381 ms for 10 items query: select * from episode_view ORDER BY dateAdded desc, idEpisode desc LIMIT 10 18:38:57.694 T:140009642465024 DEBUG: DoWork - took 108 ms to load special://masterprofile/Thumbnails/c/c92dbf11.jpg 18:38:57.704 T:140009692722944 DEBUG: DoWork - took 114 ms to load special://masterprofile/Thumbnails/4/4d1e88cb.jpg 18:38:57.709 T:140009591473920 DEBUG: RunQuery took 1 ms for 0 items query: select * from musicvideo_view ORDER BY dateAdded desc, idMVideo desc LIMIT 10 18:38:57.716 T:140009591473920 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update 18:38:57.721 T:140009591473920 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 <> 'Verschiedene Interpreten') 18:38:57.765 T:140009354176256 DEBUG: DoWork - took 177 ms to load special://masterprofile/Thumbnails/e/ebfb33c9.jpg 18:38:58.271 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:38:58.676 T:140012386428608 DEBUG: Previous line repeats 1 times. 18:38:58.676 T:140012386428608 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up 18:38:58.869 T:140009692722944 DEBUG: Previous line repeats 1 times. 18:38:58.869 T:140009692722944 DEBUG: DoWork - took 176 ms to load special://masterprofile/Thumbnails/0/0c80a70a.jpg 18:38:58.873 T:140009354176256 DEBUG: DoWork - took 179 ms to load special://masterprofile/Thumbnails/8/86b49b59.jpg 18:38:59.377 T:140012386428608 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 18:38:59.804 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:39:00.017 T:140012386428608 DEBUG: Previous line repeats 1 times. 18:39:00.017 T:140012386428608 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 18:39:00.017 T:140012386428608 DEBUG: Activating window ID: 10004 18:39:00.359 T:140012386428608 DEBUG: ------ Window Deinit (Home.xml) ------ 18:39:00.361 T:140012386428608 DEBUG: ------ Window Init (Settings.xml) ------ 18:39:00.361 T:140012386428608 INFO: Loading skin file: Settings.xml, load type: KEEP_IN_MEMORY 18:39:01.331 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:39:01.622 T:140012386428608 DEBUG: Previous line repeats 1 times. 18:39:01.622 T:140012386428608 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 18:39:02.854 T:140009482880768 DEBUG: Previous line repeats 2 times. 18:39:02.854 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:39:03.172 T:140012386428608 DEBUG: Previous line repeats 1 times. 18:39:03.172 T:140012386428608 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:39:03.531 T:140012386428608 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 18:39:03.531 T:140012386428608 DEBUG: Activating window ID: 10016 18:39:03.854 T:140012386428608 DEBUG: ------ Window Deinit (Settings.xml) ------ 18:39:03.855 T:140012386428608 DEBUG: ------ Window Init (SettingsCategory.xml) ------ 18:39:03.855 T:140012386428608 INFO: Loading skin file: SettingsCategory.xml, load type: KEEP_IN_MEMORY 18:39:04.377 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:39:06.455 T:140012386428608 DEBUG: Previous line repeats 3 times. 18:39:06.455 T:140012386428608 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:39:07.431 T:140009482880768 DEBUG: Previous line repeats 4 times. 18:39:07.431 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:39:07.737 T:140012386428608 DEBUG: Previous line repeats 1 times. 18:39:07.737 T:140012386428608 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:39:08.957 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:39:16.129 T:140012386428608 DEBUG: Previous line repeats 9 times. 18:39:16.129 T:140012386428608 DEBUG: OnKey: menu (0xd8) pressed, action is PreviousMenu 18:39:16.175 T:140012386428608 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 18:39:16.454 T:140012386428608 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------ 18:39:16.456 T:140012386428608 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 18:39:16.456 T:140012386428608 DEBUG: ------ Window Init (Settings.xml) ------ 18:39:16.636 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:39:16.963 T:140012386428608 DEBUG: Previous line repeats 1 times. 18:39:16.963 T:140012386428608 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 18:39:18.160 T:140009482880768 DEBUG: Previous line repeats 2 times. 18:39:18.160 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:39:18.350 T:140012386428608 DEBUG: Previous line repeats 1 times. 18:39:18.350 T:140012386428608 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:39:18.605 T:140012386428608 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 18:39:18.608 T:140009387747072 DEBUG: Thread LanguageInvoker start, auto delete: false 18:39:18.608 T:140009387747072 INFO: initializing python engine. 18:39:18.608 T:140009387747072 DEBUG: CPythonInvoker(16, /usr/share/kodi/addons/service.libreelec.settings/default.py): start processing 18:39:18.641 T:140009387747072 DEBUG: -->Python Interpreter Initialized<-- 18:39:18.641 T:140009387747072 DEBUG: CPythonInvoker(16, /usr/share/kodi/addons/service.libreelec.settings/default.py): the source file to load is "/usr/share/kodi/addons/service.libreelec.settings/default.py" 18:39:18.641 T:140009387747072 DEBUG: CPythonInvoker(16, /usr/share/kodi/addons/service.libreelec.settings/default.py): setting the Python path to /usr/share/kodi/addons/service.libreelec.settings:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages:/usr/lib/python2.7/site-packages/gtk-2.0 18:39:18.642 T:140009387747072 DEBUG: CPythonInvoker(16, /usr/share/kodi/addons/service.libreelec.settings/default.py): entering source directory /usr/share/kodi/addons/service.libreelec.settings 18:39:18.642 T:140009387747072 DEBUG: CPythonInvoker(16, /usr/share/kodi/addons/service.libreelec.settings/default.py): instantiating addon using automatically obtained id of "service.libreelec.settings" dependent on version 2.1.0 of the xbmc.python api 18:39:18.648 T:140009387747072 INFO: CPythonInvoker(16, /usr/share/kodi/addons/service.libreelec.settings/default.py): script successfully run 18:39:18.758 T:140009387747072 INFO: Python script stopped 18:39:18.758 T:140009387747072 DEBUG: Thread LanguageInvoker 140009387747072 terminating 18:39:18.759 T:140009883035392 INFO: ## LibreELEC Addon ## _service_::run ## MESSAGE:'openConfigurationWindow' 18:39:18.761 T:140009387747072 WARNING: CSkinInfo: failed to load skin settings 18:39:18.773 T:140012386428608 DEBUG: ------ Window Init (/usr/share/kodi/addons/service.libreelec.settings/resources/skins/Default/1080i/service-LibreELEC-Settings-mainWindow.xml) ------ 18:39:18.773 T:140012386428608 INFO: Loading skin file: /usr/share/kodi/addons/service.libreelec.settings/resources/skins/Default/1080i/service-LibreELEC-Settings-mainWindow.xml, load type: LOAD_ON_GUI_INIT 18:39:18.796 T:140012386428608 ERROR: Window Translator: Can't find window videolibrary 18:39:18.797 T:140012386428608 WARNING: Label Formating: $VAR[GlobalFanartVar] is not defined 18:39:18.883 T:140012386428608 DEBUG: CGUIMediaWindow::GetDirectory () 18:39:18.883 T:140012386428608 DEBUG: ParentPath = [] 18:39:18.900 T:140012386428608 ERROR: Control 1000 in window 13001 has been asked to focus, but it can't 18:39:18.901 T:140009883035392 INFO: ## LibreELEC Addon ## _service_::run ## WAITING: 18:39:19.051 T:140012386428608 DEBUG: Activating window ID: 10138 18:39:19.051 T:140012386428608 DEBUG: ------ Window Init (DialogBusy.xml) ------ 18:39:19.475 T:140012386428608 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 18:39:19.692 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:39:21.459 T:140012386428608 DEBUG: Previous line repeats 3 times. 18:39:21.459 T:140012386428608 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:39:21.511 T:140012386428608 DEBUG: Activating window ID: 10138 18:39:21.511 T:140012386428608 DEBUG: ------ Window Init (DialogBusy.xml) ------ 18:39:21.575 T:140012386428608 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 18:39:22.757 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:39:23.114 T:140012386428608 DEBUG: Previous line repeats 1 times. 18:39:23.115 T:140012386428608 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:39:23.191 T:140012386428608 DEBUG: Activating window ID: 10138 18:39:23.191 T:140012386428608 DEBUG: ------ Window Init (DialogBusy.xml) ------ 18:39:23.191 T:140012386428608 INFO: CheckIdle - Closing session to https://SERVER (easy=0x45366f0, multi=0x7f568c001810) 18:39:23.197 T:140012386428608 INFO: CheckIdle - Closing session to https://SERVER (easy=0x7f5698023e40, multi=0x7f5698004f40) 18:39:23.227 T:140012386428608 DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'lists/separator.png' 18:39:23.257 T:140012386428608 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 18:39:23.886 T:140012386428608 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:39:24.290 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:39:25.369 T:140012386428608 DEBUG: Previous line repeats 1 times. 18:39:25.369 T:140012386428608 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:39:25.812 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:39:25.953 T:140012386428608 DEBUG: Previous line repeats 1 times. 18:39:25.953 T:140012386428608 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:39:27.283 T:140012386428608 DEBUG: Previous line repeats 1 times. 18:39:27.284 T:140012386428608 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 18:39:27.331 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:39:27.616 T:140012386428608 DEBUG: Previous line repeats 1 times. 18:39:27.616 T:140012386428608 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 18:39:27.659 T:140012386428608 INFO: Loading skin file: DialogKeyboard.xml, load type: KEEP_IN_MEMORY 18:39:27.720 T:140012386428608 DEBUG: ------ Window Init (DialogKeyboard.xml) ------ 18:39:27.722 T:140012386412288 DEBUG: CAnnouncementManager - Announcement: OnInputRequested from xbmc 18:39:27.722 T:140012386412288 DEBUG: GOT ANNOUNCEMENT, type: 128, from xbmc, message OnInputRequested 18:39:27.771 T:140011269764864 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Input.OnInputRequested - data: {"title":"","type":"keyboard","value":"LibreELEC"} 18:39:28.853 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:39:28.921 T:140009591473920 DEBUG: Previous line repeats 1 times. 18:39:28.921 T:140009591473920 DEBUG: Thread JobWorker 140009591473920 terminating (autodelete) 18:39:28.928 T:140009642465024 DEBUG: Thread JobWorker 140009642465024 terminating (autodelete) 18:39:28.935 T:140009692722944 DEBUG: Thread JobWorker 140009692722944 terminating (autodelete) 18:39:28.969 T:140009354176256 DEBUG: Thread JobWorker 140009354176256 terminating (autodelete) 18:39:30.378 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:39:31.715 T:140012386428608 DEBUG: Previous line repeats 1 times. 18:39:31.715 T:140012386428608 DEBUG: OnKey: menu (0xd8) pressed, action is Back 18:39:31.899 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:39:32.163 T:140012386428608 DEBUG: Previous line repeats 1 times. 18:39:32.163 T:140012386428608 DEBUG: ------ Window Deinit (DialogKeyboard.xml) ------ 18:39:32.163 T:140012386412288 DEBUG: CAnnouncementManager - Announcement: OnInputFinished from xbmc 18:39:32.164 T:140012386412288 DEBUG: GOT ANNOUNCEMENT, type: 128, from xbmc, message OnInputFinished 18:39:32.177 T:140011269764864 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Input.OnInputFinished - data: null 18:39:32.178 T:140009387747072 INFO: ## LibreELEC Addon ## system::set_hostname ## LibreELEC 18:39:32.209 T:140012386428608 DEBUG: Activating window ID: 10138 18:39:32.209 T:140012386428608 DEBUG: ------ Window Init (DialogBusy.xml) ------ 18:39:32.242 T:140012386428608 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 18:39:32.872 T:140012386428608 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up 18:39:33.422 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc 18:39:41.003 T:140012386428608 DEBUG: Previous line repeats 9 times. 18:39:41.003 T:140012386428608 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 18:39:41.047 T:140009387747072 DEBUG: DialogProgress::Open called 18:39:41.047 T:140009387747072 DEBUG: ------ Window Init (DialogConfirm.xml) ------ 18:39:41.047 T:140009387747072 INFO: Loading skin file: DialogConfirm.xml, load type: KEEP_IN_MEMORY 18:39:41.069 T:140009482880768 DEBUG: CWebServer[80]: request received for /jsonrpc ======== /storage/.kodi/temp/dmesg.txt ============== [ 0.000000] Linux version 4.11.12 (chewitt@buildbox) (gcc version 6.2.0 (GCC) ) #1 SMP Mon Nov 13 05:12:26 GMT 2017 [ 0.000000] Command line: BOOT_IMAGE=/KERNEL boot=LABEL=System disk=LABEL=Storage ssh quiet [ 0.000000] Disabled fast string operations [ 0.000000] x86/fpu: x87 FPU will use FXSAVE [ 0.000000] e820: BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000e0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000007f946fff] usable [ 0.000000] BIOS-e820: [mem 0x000000007f947000-0x000000007f9cdfff] ACPI NVS [ 0.000000] BIOS-e820: [mem 0x000000007f9ce000-0x000000007fafcfff] reserved [ 0.000000] BIOS-e820: [mem 0x000000007fafd000-0x000000007fafdfff] usable [ 0.000000] BIOS-e820: [mem 0x000000007fafe000-0x000000007fb16fff] reserved [ 0.000000] BIOS-e820: [mem 0x000000007fb17000-0x000000007fb1cfff] ACPI data [ 0.000000] BIOS-e820: [mem 0x000000007fb1d000-0x000000007fb26fff] ACPI NVS [ 0.000000] BIOS-e820: [mem 0x000000007fb27000-0x000000007fb28fff] reserved [ 0.000000] BIOS-e820: [mem 0x000000007fb29000-0x000000007fb29fff] ACPI NVS [ 0.000000] BIOS-e820: [mem 0x000000007fb2a000-0x000000007fb4efff] reserved [ 0.000000] BIOS-e820: [mem 0x000000007fb4f000-0x000000007fd71fff] usable [ 0.000000] BIOS-e820: [mem 0x000000007fd72000-0x000000007fee3fff] reserved [ 0.000000] BIOS-e820: [mem 0x000000007fee4000-0x000000007feeffff] usable [ 0.000000] BIOS-e820: [mem 0x000000007fef0000-0x000000007fffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fec00000-0x00000000fec00fff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fed00000-0x00000000fed00fff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed8ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fee00000-0x00000000fee00fff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000ffe00000-0x00000000ffffffff] reserved [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.7 present. [ 0.000000] DMI: LENOVO 10087&3110/Tiger Hill, BIOS E6KT15AUS 06/29/2012 [ 0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved [ 0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable [ 0.000000] e820: last_pfn = 0x7fef0 max_arch_pfn = 0x400000000 [ 0.000000] MTRR default type: uncachable [ 0.000000] MTRR fixed ranges enabled: [ 0.000000] 00000-9FFFF write-back [ 0.000000] A0000-BFFFF uncachable [ 0.000000] C0000-E7FFF write-through [ 0.000000] E8000-FFFFF write-protect [ 0.000000] MTRR variable ranges enabled: [ 0.000000] 0 base 000000000 mask F80000000 write-back [ 0.000000] 1 base 07FF00000 mask FFFF00000 write-through [ 0.000000] 2 disabled [ 0.000000] 3 disabled [ 0.000000] 4 disabled [ 0.000000] 5 disabled [ 0.000000] 6 disabled [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WC UC- WT [ 0.000000] found SMP MP-table at [mem 0x000fd6f0-0x000fd6ff] mapped at [ffff8800000fd6f0] [ 0.000000] Scanning 1 areas for low memory corruption [ 0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576 [ 0.000000] BRK [0x038bc000, 0x038bcfff] PGTABLE [ 0.000000] BRK [0x038bd000, 0x038bdfff] PGTABLE [ 0.000000] BRK [0x038be000, 0x038befff] PGTABLE [ 0.000000] BRK [0x038bf000, 0x038bffff] PGTABLE [ 0.000000] BRK [0x038c0000, 0x038c0fff] PGTABLE [ 0.000000] BRK [0x038c1000, 0x038c1fff] PGTABLE [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F0490 000024 (v02 LENOVO) [ 0.000000] ACPI: XSDT 0x000000007FB17070 000064 (v01 LENOVO TC-03 00000115 AMI 00010013) [ 0.000000] ACPI: FACP 0x000000007FB1BF30 0000F4 (v04 LENOVO TC-03 00000115 AMI 00010013) [ 0.000000] ACPI: DSDT 0x000000007FB17168 004DC4 (v02 LENOVO TC-03 00000115 INTL 20051117) [ 0.000000] ACPI: FACS 0x000000007FB21F80 000040 [ 0.000000] ACPI: APIC 0x000000007FB1C028 000072 (v03 LENOVO TC-03 00000115 AMI 00010013) [ 0.000000] ACPI: MCFG 0x000000007FB1C0A0 00003C (v01 LENOVO TC-03 00000115 MSFT 00000097) [ 0.000000] ACPI: HPET 0x000000007FB1C0E0 000038 (v01 LENOVO TC-03 00000115 AMI. 00000005) [ 0.000000] ACPI: SSDT 0x000000007FB1C118 000655 (v01 LENOVO TC-03 00000115 INTL 20051117) [ 0.000000] ACPI: SSDT 0x000000007FB1C770 000259 (v01 LENOVO TC-03 00000115 INTL 20051117) [ 0.000000] ACPI: SSDT 0x000000007FB1C9D0 00020F (v01 LENOVO TC-03 00000115 INTL 20051117) [ 0.000000] ACPI: IFEU 0x000000007FB1CBE0 000042 (v01 LENOVO TC-03 00000115 00000000) [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000007feeffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x7feec000-0x7feeffff] [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x000000007feeffff] [ 0.000000] Normal empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x000000007f946fff] [ 0.000000] node 0: [mem 0x000000007fafd000-0x000000007fafdfff] [ 0.000000] node 0: [mem 0x000000007fb4f000-0x000000007fd71fff] [ 0.000000] node 0: [mem 0x000000007fee4000-0x000000007feeffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000007feeffff] [ 0.000000] On node 0 totalpages: 523029 [ 0.000000] DMA zone: 64 pages used for memmap [ 0.000000] DMA zone: 21 pages reserved [ 0.000000] DMA zone: 3998 pages, LIFO batch:0 [ 0.000000] DMA32 zone: 8124 pages used for memmap [ 0.000000] DMA32 zone: 519031 pages, LIFO batch:31 [ 0.000000] ACPI: PM-Timer IO Port: 0x408 [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] high edge lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 4, version 32, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: IRQ0 used by override. [ 0.000000] ACPI: IRQ9 used by override. [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] e820: [mem 0x80000000-0xdfffffff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on bare hardware [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370452778343963 ns [ 0.000000] setup_percpu: NR_CPUS:16 nr_cpumask_bits:16 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 35 pages/cpu @ffff88007f600000 s103896 r8192 d31272 u524288 [ 0.000000] pcpu-alloc: s103896 r8192 d31272 u524288 alloc=1*2097152 [ 0.000000] pcpu-alloc: [0] 0 1 2 3 [ 0.000000] Built 1 zonelists in Node order, mobility grouping on. Total pages: 514820 [ 0.000000] Policy zone: DMA32 [ 0.000000] Kernel command line: root=/dev/ram0 rdinit=/init usbcore.autosuspend=-1 BOOT_IMAGE=/KERNEL boot=LABEL=System disk=LABEL=Storage ssh quiet [ 0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes) [ 0.000000] Memory: 2016884K/2092116K available (11876K kernel code, 890K rwdata, 23220K rodata, 3500K init, 408K bss, 75232K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] Hierarchical RCU implementation. [ 0.000000] Build-time adjustment of leaf fanout to 64. [ 0.000000] RCU restricting CPUs from NR_CPUS=16 to nr_cpu_ids=4. [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=4 [ 0.000000] NR_IRQS:4352 nr_irqs:456 16 [ 0.000000] Console: colour VGA+ 80x25 [ 0.000000] console [tty0] enabled [ 0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 133484882848 ns [ 0.000000] hpet clockevent registered [ 0.003333] spurious 8259A interrupt: IRQ7. [ 0.003333] tsc: PIT calibration matches HPET. 1 loops [ 0.003333] tsc: Detected 1861.994 MHz processor [ 0.003333] Calibrating delay loop (skipped), value calculated using timer frequency.. 3725.15 BogoMIPS (lpj=6206646) [ 0.003333] pid_max: default: 32768 minimum: 301 [ 0.003333] ACPI: Core revision 20170119 [ 0.012884] ACPI: 4 ACPI AML tables successfully acquired and loaded [ 0.013371] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.014951] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.015706] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.015713] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.016138] Disabled fast string operations [ 0.016147] CPU: Physical Processor ID: 0 [ 0.016149] CPU: Processor Core ID: 0 [ 0.016152] process: using mwait in idle threads [ 0.016159] Last level iTLB entries: 4KB 32, 2MB 0, 4MB 0 [ 0.016161] Last level dTLB entries: 4KB 64, 2MB 0, 4MB 8, 1GB 0 [ 0.016338] Freeing SMP alternatives memory: 40K [ 0.020211] smpboot: Max logical packages: 2 [ 0.020661] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=0 pin2=0 [ 0.056666] smpboot: CPU0: Intel(R) Atom(TM) CPU D2550 @ 1.86GHz (family: 0x6, model: 0x36, stepping: 0x1) [ 0.056666] Performance Events: PEBS fmt0+, Atom events, 8-deep LBR, Intel PMU driver. [ 0.056666] ... version: 3 [ 0.056666] ... bit width: 40 [ 0.056666] ... generic registers: 2 [ 0.056666] ... value mask: 000000ffffffffff [ 0.056666] ... max period: 000000007fffffff [ 0.056666] ... fixed-purpose events: 3 [ 0.056666] ... event mask: 0000000700000003 [ 0.056666] smp: Bringing up secondary CPUs ... [ 0.056666] x86: Booting SMP configuration: [ 0.056666] .... node #0, CPUs: #1 [ 0.003333] Disabled fast string operations [ 0.138707] NMI watchdog: enabled on all CPUs, permanently consumes one hw-PMU counter. [ 0.138707] #2 [ 0.003333] Disabled fast string operations [ 0.222039] #3 [ 0.003333] Disabled fast string operations [ 0.305370] smp: Brought up 1 node, 4 CPUs [ 0.305370] smpboot: Total of 4 processors activated (14901.53 BogoMIPS) [ 0.305370] sched_clock: Marking stable (303333303, 0)->(421027811, -117694508) [ 0.304391] devtmpfs: initialized [ 0.305120] PM: Registering ACPI NVS region [mem 0x7f947000-0x7f9cdfff] (552960 bytes) [ 0.305141] PM: Registering ACPI NVS region [mem 0x7fb1d000-0x7fb26fff] (40960 bytes) [ 0.305144] PM: Registering ACPI NVS region [mem 0x7fb29000-0x7fb29fff] (4096 bytes) [ 0.305243] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370867519511994 ns [ 0.305259] futex hash table entries: 1024 (order: 4, 65536 bytes) [ 0.305343] xor: measuring software checksum speed [ 0.337855] prefetch64-sse: 7161.600 MB/sec [ 0.371188] generic_sse: 6392.400 MB/sec [ 0.371190] xor: using function: prefetch64-sse (7161.600 MB/sec) [ 0.371196] pinctrl core: initialized pinctrl subsystem [ 0.371474] NET: Registered protocol family 16 [ 0.372113] cpuidle: using governor ladder [ 0.372186] cpuidle: using governor menu [ 0.372191] PCCT header not found. [ 0.372435] ACPI: bus type PCI registered [ 0.372566] PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000) [ 0.372571] PCI: MMCONFIG at [mem 0xe0000000-0xefffffff] reserved in E820 [ 0.372600] PCI: Using configuration type 1 for base access [ 0.437931] raid6: sse2x1 gen() 174 MB/s [ 0.494540] raid6: sse2x1 xor() 785 MB/s [ 0.551223] raid6: sse2x2 gen() 506 MB/s [ 0.607871] raid6: sse2x2 xor() 1335 MB/s [ 0.664576] raid6: sse2x4 gen() 846 MB/s [ 0.721198] raid6: sse2x4 xor() 1603 MB/s [ 0.721201] raid6: using algorithm sse2x4 gen() 846 MB/s [ 0.721203] raid6: .... xor() 1603 MB/s, rmw enabled [ 0.721205] raid6: using ssse3x2 recovery algorithm [ 0.721323] ACPI: Added _OSI(Module Device) [ 0.721325] ACPI: Added _OSI(Processor Device) [ 0.721327] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.721329] ACPI: Added _OSI(Processor Aggregator Device) [ 0.721606] ACPI: Executed 1 blocks of module-level executable AML code [ 0.726710] ACPI: [Firmware Bug]: BIOS _OSI(Linux) query ignored [ 0.729047] ACPI : EC: EC started [ 0.729049] ACPI : EC: interrupt blocked [ 0.729173] ACPI: \_SB_.PCI0.LPCB.H_EC: Used as first EC [ 0.729178] ACPI: \_SB_.PCI0.LPCB.H_EC: GPE=0x11, EC_CMD/EC_SC=0x66, EC_DATA=0x62 [ 0.729181] ACPI: \_SB_.PCI0.LPCB.H_EC: Used as boot DSDT EC to handle transactions [ 0.729182] ACPI: Interpreter enabled [ 0.729216] ACPI: (supports S0 S3 S5) [ 0.729218] ACPI: Using IOAPIC for interrupt routing [ 0.729285] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.742226] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.742237] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI] [ 0.742992] acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] [ 0.743285] PCI host bridge to bus 0000:00 [ 0.743291] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.743295] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.743299] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.743302] pci_bus 0000:00: root bus resource [mem 0x000c0000-0x000dffff window] [ 0.743305] pci_bus 0000:00: root bus resource [mem 0x000e0000-0x000effff window] [ 0.743309] pci_bus 0000:00: root bus resource [mem 0x000f0000-0x000fffff window] [ 0.743312] pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window] [ 0.743316] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.743331] pci 0000:00:00.0: [8086:0bf3] type 00 class 0x060000 [ 0.743589] pci 0000:00:1b.0: [8086:27d8] type 00 class 0x040300 [ 0.743624] pci 0000:00:1b.0: reg 0x10: [mem 0xd0400000-0xd0403fff 64bit] [ 0.743769] pci 0000:00:1b.0: PME# supported from D0 D3hot D3cold [ 0.743946] pci 0000:00:1c.0: [8086:27d0] type 01 class 0x060400 [ 0.744091] pci 0000:00:1c.0: PME# supported from D0 D3hot D3cold [ 0.744270] pci 0000:00:1c.1: [8086:27d2] type 01 class 0x060400 [ 0.744416] pci 0000:00:1c.1: PME# supported from D0 D3hot D3cold [ 0.744604] pci 0000:00:1c.2: [8086:27d4] type 01 class 0x060400 [ 0.744751] pci 0000:00:1c.2: PME# supported from D0 D3hot D3cold [ 0.744857] pci 0000:00:1c.2: System wakeup disabled by ACPI [ 0.744942] pci 0000:00:1c.3: [8086:27d6] type 01 class 0x060400 [ 0.745085] pci 0000:00:1c.3: PME# supported from D0 D3hot D3cold [ 0.745188] pci 0000:00:1c.3: System wakeup disabled by ACPI [ 0.745272] pci 0000:00:1d.0: [8086:27c8] type 00 class 0x0c0300 [ 0.745342] pci 0000:00:1d.0: reg 0x20: [io 0xf080-0xf09f] [ 0.745515] pci 0000:00:1d.0: System wakeup disabled by ACPI [ 0.745597] pci 0000:00:1d.1: [8086:27c9] type 00 class 0x0c0300 [ 0.745667] pci 0000:00:1d.1: reg 0x20: [io 0xf060-0xf07f] [ 0.745832] pci 0000:00:1d.1: System wakeup disabled by ACPI [ 0.745911] pci 0000:00:1d.2: [8086:27ca] type 00 class 0x0c0300 [ 0.745981] pci 0000:00:1d.2: reg 0x20: [io 0xf040-0xf05f] [ 0.746145] pci 0000:00:1d.2: System wakeup disabled by ACPI [ 0.746224] pci 0000:00:1d.3: [8086:27cb] type 00 class 0x0c0300 [ 0.746295] pci 0000:00:1d.3: reg 0x20: [io 0xf020-0xf03f] [ 0.746458] pci 0000:00:1d.3: System wakeup disabled by ACPI [ 0.746548] pci 0000:00:1d.7: [8086:27cc] type 00 class 0x0c0320 [ 0.746587] pci 0000:00:1d.7: reg 0x10: [mem 0xd0405000-0xd04053ff] [ 0.746717] pci 0000:00:1d.7: PME# supported from D0 D3hot D3cold [ 0.746814] pci 0000:00:1d.7: System wakeup disabled by ACPI [ 0.746897] pci 0000:00:1e.0: [8086:2448] type 01 class 0x060401 [ 0.747083] pci 0000:00:1e.0: System wakeup disabled by ACPI [ 0.747165] pci 0000:00:1f.0: [8086:27bc] type 00 class 0x060100 [ 0.747465] pci 0000:00:1f.2: [8086:27c0] type 00 class 0x01018f [ 0.747491] pci 0000:00:1f.2: reg 0x10: [io 0xf0e0-0xf0e7] [ 0.747507] pci 0000:00:1f.2: reg 0x14: [io 0xf0d0-0xf0d3] [ 0.747522] pci 0000:00:1f.2: reg 0x18: [io 0xf0c0-0xf0c7] [ 0.747537] pci 0000:00:1f.2: reg 0x1c: [io 0xf0b0-0xf0b3] [ 0.747552] pci 0000:00:1f.2: reg 0x20: [io 0xf0a0-0xf0af] [ 0.747568] pci 0000:00:1f.2: reg 0x24: [mem 0xd0404000-0xd04043ff] [ 0.747631] pci 0000:00:1f.2: PME# supported from D3hot [ 0.747795] pci 0000:00:1f.3: [8086:27da] type 00 class 0x0c0500 [ 0.747869] pci 0000:00:1f.3: reg 0x20: [io 0xf000-0xf01f] [ 0.748163] pci 0000:01:00.0: [10ec:8168] type 00 class 0x020000 [ 0.748192] pci 0000:01:00.0: reg 0x10: [io 0xe000-0xe0ff] [ 0.748230] pci 0000:01:00.0: reg 0x18: [mem 0xd0304000-0xd0304fff 64bit pref] [ 0.748255] pci 0000:01:00.0: reg 0x20: [mem 0xd0300000-0xd0303fff 64bit pref] [ 0.748385] pci 0000:01:00.0: supports D1 D2 [ 0.748388] pci 0000:01:00.0: PME# supported from D0 D1 D2 D3hot D3cold [ 0.748445] pci 0000:01:00.0: System wakeup disabled by ACPI [ 0.759925] pci 0000:00:1c.0: PCI bridge to [bus 01] [ 0.759933] pci 0000:00:1c.0: bridge window [io 0xe000-0xefff] [ 0.759940] pci 0000:00:1c.0: bridge window [mem 0xd0300000-0xd03fffff] [ 0.760057] pci 0000:02:00.0: [10ec:8176] type 00 class 0x028000 [ 0.760090] pci 0000:02:00.0: reg 0x10: [io 0xd000-0xd0ff] [ 0.760133] pci 0000:02:00.0: reg 0x18: [mem 0xd0200000-0xd0203fff 64bit] [ 0.760300] pci 0000:02:00.0: supports D1 D2 [ 0.760303] pci 0000:02:00.0: PME# supported from D0 D1 D2 D3hot D3cold [ 0.760365] pci 0000:02:00.0: System wakeup disabled by ACPI [ 0.769925] pci 0000:00:1c.1: PCI bridge to [bus 02] [ 0.769932] pci 0000:00:1c.1: bridge window [io 0xd000-0xdfff] [ 0.769939] pci 0000:00:1c.1: bridge window [mem 0xd0200000-0xd02fffff] [ 0.770065] pci 0000:03:00.0: [1033:0194] type 00 class 0x0c0330 [ 0.770113] pci 0000:03:00.0: reg 0x10: [mem 0xd0100000-0xd0101fff 64bit] [ 0.770347] pci 0000:03:00.0: PME# supported from D0 D3hot D3cold [ 0.770412] pci 0000:03:00.0: System wakeup disabled by ACPI [ 0.779927] pci 0000:00:1c.2: PCI bridge to [bus 03] [ 0.779938] pci 0000:00:1c.2: bridge window [mem 0xd0100000-0xd01fffff] [ 0.780055] pci 0000:04:00.0: [1002:6770] type 00 class 0x030000 [ 0.780107] pci 0000:04:00.0: reg 0x10: [mem 0xc0000000-0xcfffffff 64bit pref] [ 0.780142] pci 0000:04:00.0: reg 0x18: [mem 0xd0020000-0xd003ffff 64bit] [ 0.780165] pci 0000:04:00.0: reg 0x20: [io 0xc000-0xc0ff] [ 0.780208] pci 0000:04:00.0: reg 0x30: [mem 0xd0000000-0xd001ffff pref] [ 0.780348] pci 0000:04:00.0: supports D1 D2 [ 0.780413] pci 0000:04:00.0: System wakeup disabled by ACPI [ 0.780512] pci 0000:04:00.1: [1002:aa98] type 00 class 0x040300 [ 0.780563] pci 0000:04:00.1: reg 0x10: [mem 0xd0040000-0xd0043fff 64bit] [ 0.780799] pci 0000:04:00.1: supports D1 D2 [ 0.789927] pci 0000:00:1c.3: PCI bridge to [bus 04] [ 0.789934] pci 0000:00:1c.3: bridge window [io 0xc000-0xcfff] [ 0.789941] pci 0000:00:1c.3: bridge window [mem 0xc0000000-0xd00fffff] [ 0.790067] pci 0000:00:1e.0: PCI bridge to [bus 05] (subtractive decode) [ 0.790084] pci 0000:00:1e.0: bridge window [io 0x0000-0x0cf7 window] (subtractive decode) [ 0.790088] pci 0000:00:1e.0: bridge window [io 0x0d00-0xffff window] (subtractive decode) [ 0.790091] pci 0000:00:1e.0: bridge window [mem 0x000a0000-0x000bffff window] (subtractive decode) [ 0.790095] pci 0000:00:1e.0: bridge window [mem 0x000c0000-0x000dffff window] (subtractive decode) [ 0.790098] pci 0000:00:1e.0: bridge window [mem 0x000e0000-0x000effff window] (subtractive decode) [ 0.790102] pci 0000:00:1e.0: bridge window [mem 0x000f0000-0x000fffff window] (subtractive decode) [ 0.790105] pci 0000:00:1e.0: bridge window [mem 0x80000000-0xfebfffff window] (subtractive decode) [ 0.790801] ACPI: PCI Interrupt Link [LNKA] (IRQs 1 3 4 5 6 7 *10 12 14 15) [ 0.790939] ACPI: PCI Interrupt Link [LNKB] (IRQs 1 3 4 *5 6 7 11 12 14 15) [ 0.791073] ACPI: PCI Interrupt Link [LNKC] (IRQs 1 *3 4 5 6 7 10 12 14 15) [ 0.791215] ACPI: PCI Interrupt Link [LNKD] (IRQs 1 3 4 5 6 *7 11 12 14 15) [ 0.791349] ACPI: PCI Interrupt Link [LNKE] (IRQs 1 3 4 5 6 7 10 12 14 15) *0, disabled. [ 0.791483] ACPI: PCI Interrupt Link [LNKF] (IRQs 1 3 4 5 6 7 11 12 14 15) *0, disabled. [ 0.791619] ACPI: PCI Interrupt Link [LNKG] (IRQs 1 3 4 5 6 7 10 12 14 15) *11 [ 0.791753] ACPI: PCI Interrupt Link [LNKH] (IRQs 1 3 *4 5 6 7 11 12 14 15) [ 0.792169] ACPI: Enabled 3 GPEs in block 00 to 1F [ 0.792268] ACPI : EC: interrupt unblocked [ 0.792279] ACPI : EC: event unblocked [ 0.792291] ACPI: \_SB_.PCI0.LPCB.H_EC: GPE=0x11, EC_CMD/EC_SC=0x66, EC_DATA=0x62 [ 0.792295] ACPI: \_SB_.PCI0.LPCB.H_EC: Used as boot DSDT EC to handle transactions and events [ 0.792481] pci 0000:04:00.0: vgaarb: setting as boot VGA device [ 0.792485] pci 0000:04:00.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none [ 0.792489] pci 0000:04:00.0: vgaarb: bridge control possible [ 0.792490] vgaarb: loaded [ 0.792619] SCSI subsystem initialized [ 0.792721] libata version 3.00 loaded. [ 0.792769] ACPI: bus type USB registered [ 0.792820] usbcore: registered new interface driver usbfs [ 0.792842] usbcore: registered new interface driver hub [ 0.792888] usbcore: registered new device driver usb [ 0.792946] pps_core: LinuxPPS API ver. 1 registered [ 0.792948] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.792955] PTP clock support registered [ 0.793061] wmi: Mapper loaded [ 0.793086] Advanced Linux Sound Architecture Driver Initialized. [ 0.793088] PCI: Using ACPI for IRQ routing [ 0.805871] PCI: pci_cache_line_size set to 64 bytes [ 0.805970] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] [ 0.805974] e820: reserve RAM buffer [mem 0x7f947000-0x7fffffff] [ 0.805978] e820: reserve RAM buffer [mem 0x7fafe000-0x7fffffff] [ 0.805980] e820: reserve RAM buffer [mem 0x7fd72000-0x7fffffff] [ 0.805983] e820: reserve RAM buffer [mem 0x7fef0000-0x7fffffff] [ 0.806505] clocksource: Switched to clocksource hpet [ 0.806673] FS-Cache: Loaded [ 0.806744] pnp: PnP ACPI init [ 0.807048] system 00:00: [mem 0xfed00000-0xfed003ff] has been reserved [ 0.807057] system 00:00: Plug and Play ACPI device, IDs PNP0103 PNP0c01 (active) [ 0.807180] system 00:01: [io 0x0680-0x069f] has been reserved [ 0.807184] system 00:01: [io 0x1000-0x100f] has been reserved [ 0.807188] system 00:01: [io 0xffff] has been reserved [ 0.807192] system 00:01: [io 0xffff] has been reserved [ 0.807196] system 00:01: [io 0x0400-0x047f] has been reserved [ 0.807199] system 00:01: [io 0x0500-0x057f] has been reserved [ 0.807203] system 00:01: [io 0x0600-0x061f] has been reserved [ 0.807210] system 00:01: Plug and Play ACPI device, IDs PNP0c02 (active) [ 0.807329] system 00:02: [io 0x06a0-0x06af] has been reserved [ 0.807333] system 00:02: [io 0x06b0-0x06ff] has been reserved [ 0.807339] system 00:02: Plug and Play ACPI device, IDs PNP0c02 (active) [ 0.807401] pnp 00:03: Plug and Play ACPI device, IDs PNP0b00 (active) [ 0.807553] system 00:04: Plug and Play ACPI device, IDs PNP0c02 (active) [ 0.807712] system 00:05: [io 0x04d0-0x04d1] has been reserved [ 0.807719] system 00:05: Plug and Play ACPI device, IDs PNP0c02 (active) [ 0.807978] system 00:06: [mem 0xfed1c000-0xfed1ffff] has been reserved [ 0.807983] system 00:06: [mem 0x00000000-0x00003fff] could not be reserved [ 0.807987] system 00:06: [mem 0x00000000-0x00000fff] could not be reserved [ 0.807991] system 00:06: [mem 0x00000000-0x00000fff] could not be reserved [ 0.807995] system 00:06: [mem 0xfed45000-0xfed8ffff] has been reserved [ 0.808001] system 00:06: Plug and Play ACPI device, IDs PNP0c02 (active) [ 0.808414] system 00:07: [mem 0xfed14000-0xfed19fff] has been reserved [ 0.808418] system 00:07: [mem 0xe0000000-0xefffffff] has been reserved [ 0.808425] system 00:07: Plug and Play ACPI device, IDs PNP0c01 (active) [ 0.808752] system 00:08: [io 0x0400-0x047f] has been reserved [ 0.808756] system 00:08: [io 0x0500-0x053f] has been reserved [ 0.808761] system 00:08: [mem 0xfec00000-0xfec00fff] could not be reserved [ 0.808765] system 00:08: [mem 0xfee00000-0xfee00fff] has been reserved [ 0.808769] system 00:08: [mem 0xfed1c000-0xfed1ffff] has been reserved [ 0.808773] system 00:08: [mem 0xfed20000-0xfed8ffff] could not be reserved [ 0.808777] system 00:08: [mem 0xffe00000-0xffffffff] has been reserved [ 0.808783] system 00:08: Plug and Play ACPI device, IDs PNP0c02 (active) [ 0.808816] pnp: PnP ACPI: found 9 devices [ 0.818270] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.818322] pci 0000:00:1c.0: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 01] add_size 200000 add_align 100000 [ 0.818340] pci 0000:00:1c.1: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 02] add_size 200000 add_align 100000 [ 0.818356] pci 0000:00:1c.2: bridge window [io 0x1000-0x0fff] to [bus 03] add_size 1000 [ 0.818361] pci 0000:00:1c.2: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 03] add_size 200000 add_align 100000 [ 0.818376] pci 0000:00:1c.3: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 04] add_size 200000 add_align 100000 [ 0.818414] pci 0000:00:1c.0: BAR 9: assigned [mem 0x80000000-0x801fffff 64bit pref] [ 0.818424] pci 0000:00:1c.1: BAR 9: assigned [mem 0x80200000-0x803fffff 64bit pref] [ 0.818434] pci 0000:00:1c.2: BAR 9: assigned [mem 0x80400000-0x805fffff 64bit pref] [ 0.818444] pci 0000:00:1c.3: BAR 9: assigned [mem 0x80600000-0x807fffff 64bit pref] [ 0.818450] pci 0000:00:1c.2: BAR 7: assigned [io 0x2000-0x2fff] [ 0.818456] pci 0000:00:1c.0: PCI bridge to [bus 01] [ 0.818461] pci 0000:00:1c.0: bridge window [io 0xe000-0xefff] [ 0.818470] pci 0000:00:1c.0: bridge window [mem 0xd0300000-0xd03fffff] [ 0.818477] pci 0000:00:1c.0: bridge window [mem 0x80000000-0x801fffff 64bit pref] [ 0.818488] pci 0000:00:1c.1: PCI bridge to [bus 02] [ 0.818492] pci 0000:00:1c.1: bridge window [io 0xd000-0xdfff] [ 0.818501] pci 0000:00:1c.1: bridge window [mem 0xd0200000-0xd02fffff] [ 0.818507] pci 0000:00:1c.1: bridge window [mem 0x80200000-0x803fffff 64bit pref] [ 0.818517] pci 0000:00:1c.2: PCI bridge to [bus 03] [ 0.818522] pci 0000:00:1c.2: bridge window [io 0x2000-0x2fff] [ 0.818530] pci 0000:00:1c.2: bridge window [mem 0xd0100000-0xd01fffff] [ 0.818537] pci 0000:00:1c.2: bridge window [mem 0x80400000-0x805fffff 64bit pref] [ 0.818548] pci 0000:00:1c.3: PCI bridge to [bus 04] [ 0.818552] pci 0000:00:1c.3: bridge window [io 0xc000-0xcfff] [ 0.818560] pci 0000:00:1c.3: bridge window [mem 0xc0000000-0xd00fffff] [ 0.818567] pci 0000:00:1c.3: bridge window [mem 0x80600000-0x807fffff 64bit pref] [ 0.818578] pci 0000:00:1e.0: PCI bridge to [bus 05] [ 0.818599] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.818602] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.818606] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.818609] pci_bus 0000:00: resource 7 [mem 0x000c0000-0x000dffff window] [ 0.818612] pci_bus 0000:00: resource 8 [mem 0x000e0000-0x000effff window] [ 0.818616] pci_bus 0000:00: resource 9 [mem 0x000f0000-0x000fffff window] [ 0.818619] pci_bus 0000:00: resource 10 [mem 0x80000000-0xfebfffff window] [ 0.818622] pci_bus 0000:01: resource 0 [io 0xe000-0xefff] [ 0.818626] pci_bus 0000:01: resource 1 [mem 0xd0300000-0xd03fffff] [ 0.818629] pci_bus 0000:01: resource 2 [mem 0x80000000-0x801fffff 64bit pref] [ 0.818633] pci_bus 0000:02: resource 0 [io 0xd000-0xdfff] [ 0.818636] pci_bus 0000:02: resource 1 [mem 0xd0200000-0xd02fffff] [ 0.818639] pci_bus 0000:02: resource 2 [mem 0x80200000-0x803fffff 64bit pref] [ 0.818643] pci_bus 0000:03: resource 0 [io 0x2000-0x2fff] [ 0.818646] pci_bus 0000:03: resource 1 [mem 0xd0100000-0xd01fffff] [ 0.818649] pci_bus 0000:03: resource 2 [mem 0x80400000-0x805fffff 64bit pref] [ 0.818652] pci_bus 0000:04: resource 0 [io 0xc000-0xcfff] [ 0.818656] pci_bus 0000:04: resource 1 [mem 0xc0000000-0xd00fffff] [ 0.818659] pci_bus 0000:04: resource 2 [mem 0x80600000-0x807fffff 64bit pref] [ 0.818663] pci_bus 0000:05: resource 4 [io 0x0000-0x0cf7 window] [ 0.818666] pci_bus 0000:05: resource 5 [io 0x0d00-0xffff window] [ 0.818669] pci_bus 0000:05: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.818673] pci_bus 0000:05: resource 7 [mem 0x000c0000-0x000dffff window] [ 0.818676] pci_bus 0000:05: resource 8 [mem 0x000e0000-0x000effff window] [ 0.818679] pci_bus 0000:05: resource 9 [mem 0x000f0000-0x000fffff window] [ 0.818682] pci_bus 0000:05: resource 10 [mem 0x80000000-0xfebfffff window] [ 0.818863] NET: Registered protocol family 2 [ 0.819137] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 0.819250] TCP bind hash table entries: 16384 (order: 6, 262144 bytes) [ 0.819377] TCP: Hash tables configured (established 16384 bind 16384) [ 0.819502] UDP hash table entries: 1024 (order: 3, 32768 bytes) [ 0.819532] UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes) [ 0.819673] NET: Registered protocol family 1 [ 0.820001] RPC: Registered named UNIX socket transport module. [ 0.820004] RPC: Registered udp transport module. [ 0.820005] RPC: Registered tcp transport module. [ 0.820007] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.821432] pci 0000:04:00.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] [ 0.821444] PCI: CLS 64 bytes, default 64 [ 0.894015] Scanning for low memory corruption every 60 seconds [ 0.894769] workingset: timestamp_bits=40 max_order=19 bucket_order=0 [ 0.898967] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 0.899079] FS-Cache: Netfs 'nfs' registered for caching [ 0.899336] NFS: Registering the id_resolver key type [ 0.899346] Key type id_resolver registered [ 0.899348] Key type id_legacy registered [ 0.899354] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 0.899525] FS-Cache: Netfs 'cifs' registered for caching [ 0.904793] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) [ 0.904797] io scheduler noop registered [ 0.904923] io scheduler cfq registered (default) [ 0.904926] io scheduler mq-deadline registered [ 0.906542] pcieport 0000:00:1c.0: Signaling PME with IRQ 24 [ 0.906594] pcieport 0000:00:1c.1: Signaling PME with IRQ 25 [ 0.906635] pcieport 0000:00:1c.2: Signaling PME with IRQ 26 [ 0.906674] pcieport 0000:00:1c.3: Signaling PME with IRQ 27 [ 0.906713] intel_idle: MWAIT substates: 0x10 [ 0.906714] intel_idle: v0.4.1 model 0x36 [ 0.906927] intel_idle: lapic_timer_reliable_states 0xffffffff [ 0.907058] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 [ 0.907064] ACPI: Power Button [PWRB] [ 0.907152] input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 [ 0.907156] ACPI: Sleep Button [SLPB] [ 0.907241] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 [ 0.907245] ACPI: Power Button [PWRF] [ 0.909257] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 0.910002] Linux agpgart interface v0.103 [ 0.910105] [drm] radeon kernel modesetting enabled. [ 0.910597] [drm] initializing kernel modesetting (CAICOS 0x1002:0x6770 0x17AA:0x3669 0x00). [ 0.910616] [drm] register mmio base: 0xD0020000 [ 0.910618] [drm] register mmio size: 131072 [ 0.910812] ATOM BIOS: C28702 [ 0.911454] radeon 0000:04:00.0: VRAM: 512M 0x0000000000000000 - 0x000000001FFFFFFF (512M used) [ 0.911458] radeon 0000:04:00.0: GTT: 1024M 0x0000000020000000 - 0x000000005FFFFFFF [ 0.911464] [drm] Detected VRAM RAM=512M, BAR=256M [ 0.911466] [drm] RAM width 64bits DDR [ 0.911559] [TTM] Zone kernel: Available graphics memory: 1008462 kiB [ 0.911561] [TTM] Initializing pool allocator [ 0.911570] [TTM] Initializing DMA pool allocator [ 0.911613] [drm] radeon: 512M of VRAM memory ready [ 0.911616] [drm] radeon: 1024M of GTT memory ready. [ 0.911632] [drm] Loading CAICOS Microcode [ 0.911659] [drm] Internal thermal controller with fan control [ 0.929577] [drm] radeon: dpm initialized [ 0.929631] [drm] GART: num cpu pages 262144, num gpu pages 262144 [ 0.963991] [drm] PCIE GART of 1024M enabled (table at 0x0000000000162000). [ 0.964338] radeon 0000:04:00.0: WB enabled [ 0.964344] radeon 0000:04:00.0: fence driver on ring 0 use gpu addr 0x0000000020000c00 and cpu addr 0xffff88007be4cc00 [ 0.964348] radeon 0000:04:00.0: fence driver on ring 3 use gpu addr 0x0000000020000c0c and cpu addr 0xffff88007be4cc0c [ 0.974771] radeon 0000:04:00.0: fence driver on ring 5 use gpu addr 0x0000000000072118 and cpu addr 0xffffc90000632118 [ 0.974775] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). [ 0.974776] [drm] Driver supports precise vblank timestamp query. [ 0.974779] radeon 0000:04:00.0: radeon: MSI limited to 32-bit [ 0.974872] radeon 0000:04:00.0: radeon: using MSI. [ 0.974944] [drm] radeon: irq initialized. [ 0.993565] [drm] ring test on 0 succeeded in 2 usecs [ 0.993588] [drm] ring test on 3 succeeded in 11 usecs [ 1.171085] [drm] ring test on 5 succeeded in 2 usecs [ 1.171103] [drm] UVD initialized successfully. [ 1.171462] [drm] ib test on ring 0 succeeded in 0 usecs [ 1.171594] [drm] ib test on ring 3 succeeded in 0 usecs [ 1.836605] [drm] ib test on ring 5 succeeded [ 1.837265] [drm] Radeon Display Connectors [ 1.837267] [drm] Connector 0: [ 1.837268] [drm] HDMI-A-1 [ 1.837270] [drm] HPD1 [ 1.837273] [drm] DDC: 0x6450 0x6450 0x6454 0x6454 0x6458 0x6458 0x645c 0x645c [ 1.837274] [drm] Encoders: [ 1.837276] [drm] DFP1: INTERNAL_UNIPHY1 [ 1.837277] [drm] Connector 1: [ 1.837278] [drm] VGA-1 [ 1.837281] [drm] DDC: 0x64d8 0x64d8 0x64dc 0x64dc 0x64e0 0x64e0 0x64e4 0x64e4 [ 1.837282] [drm] Encoders: [ 1.837283] [drm] CRT1: INTERNAL_KLDSCP_DAC1 [ 1.916538] tsc: Refined TSC clocksource calibration: 1861.999 MHz [ 1.916550] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x35ade44ba74, max_idle_ns: 881590729106 ns [ 2.022855] [drm] fb mappable at 0xC0363000 [ 2.022858] [drm] vram apper at 0xC0000000 [ 2.022859] [drm] size 8294400 [ 2.022860] [drm] fb depth is 24 [ 2.022862] [drm] pitch is 7680 [ 2.022986] fbcon: radeondrmfb (fb0) is primary device [ 2.314837] Console: switching to colour frame buffer device 240x67 [ 2.389126] radeon 0000:04:00.0: fb0: radeondrmfb frame buffer device [ 2.389324] [drm] Initialized radeon 2.49.0 20080528 for 0000:04:00.0 on minor 0 [ 2.389389] [drm] amdgpu kernel modesetting enabled. [ 2.395573] brd: module loaded [ 2.395639] loop: module loaded [ 2.405091] usbcore: registered new interface driver rtsx_usb [ 2.405119] Loading iSCSI transport class v2.0-870. [ 2.405350] iscsi: registered transport (tcp) [ 2.405353] megasas: 07.701.17.00-rc1 [ 2.405727] ata_piix 0000:00:1f.2: version 2.13 [ 2.405856] ata_piix 0000:00:1f.2: MAP [ P0 P2 P1 P3 ] [ 2.560486] scsi host0: ata_piix [ 2.560713] scsi host1: ata_piix [ 2.560806] ata1: SATA max UDMA/133 cmd 0xf0e0 ctl 0xf0d0 bmdma 0xf0a0 irq 19 [ 2.560810] ata2: SATA max UDMA/133 cmd 0xf0c0 ctl 0xf0b0 bmdma 0xf0a8 irq 19 [ 2.561826] tun: Universal TUN/TAP device driver, 1.6 [ 2.561982] pcnet32: pcnet32.c:v1.35 21.Apr.2008 tsbogend@alpha.franken.de [ 2.562034] Atheros(R) L2 Ethernet Driver - version 2.2.3 [ 2.562036] Copyright (c) 2007 Atheros Corporation. [ 2.562190] cnic: QLogic cnicDriver v2.5.22 (July 20, 2015) [ 2.562318] uli526x: ULi M5261/M5263 net driver, version 0.9.3 (2005-7-29) [ 2.562352] e100: Intel(R) PRO/100 Network Driver, 3.5.24-k2-NAPI [ 2.562354] e100: Copyright(c) 1999-2006 Intel Corporation [ 2.562385] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI [ 2.562387] e1000: Copyright (c) 1999-2006 Intel Corporation. [ 2.562418] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k [ 2.562419] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. [ 2.562455] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.4.0-k [ 2.562457] igb: Copyright (c) 2007-2014 Intel Corporation. [ 2.562490] ixgbe: Intel(R) 10 Gigabit PCI Express Network Driver - version 5.0.0-k [ 2.562492] ixgbe: Copyright (c) 1999-2016 Intel Corporation. [ 2.562613] ixgb: Intel(R) PRO/10GbE Network Driver - version 1.0.135-k2-NAPI [ 2.562615] ixgb: Copyright (c) 1999-2008 Intel Corporation. [ 2.562643] jme: JMicron JMC2XX ethernet driver version 1.0.8 [ 2.562699] sky2: driver version 1.30 [ 2.562833] r8169 Gigabit Ethernet driver 2.3LK-NAPI loaded [ 2.563569] r8169 0000:01:00.0 eth0: RTL8168evl/8111evl at 0xffffc900005dc000, 50:af:73:1f:7d:f6, XID 0c900800 IRQ 29 [ 2.563573] r8169 0000:01:00.0 eth0: jumbo features [frames: 9200 bytes, tx checksumming: ko] [ 2.563810] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 2.563812] ehci-pci: EHCI PCI platform driver [ 2.564011] ehci-pci 0000:00:1d.7: EHCI Host Controller [ 2.564024] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1 [ 2.564044] ehci-pci 0000:00:1d.7: debug port 1 [ 2.567965] ehci-pci 0000:00:1d.7: cache line size of 64 is not supported [ 2.567990] ehci-pci 0000:00:1d.7: irq 23, io mem 0xd0405000 [ 2.579875] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00 [ 2.580242] hub 1-0:1.0: USB hub found [ 2.580258] hub 1-0:1.0: 8 ports detected [ 2.580955] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 2.580967] ohci-pci: OHCI PCI platform driver [ 2.580999] uhci_hcd: USB Universal Host Controller Interface driver [ 2.581150] uhci_hcd 0000:00:1d.0: UHCI Host Controller [ 2.581164] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2 [ 2.581209] uhci_hcd 0000:00:1d.0: irq 23, io base 0x0000f080 [ 2.581560] hub 2-0:1.0: USB hub found [ 2.581576] hub 2-0:1.0: 2 ports detected [ 2.581905] uhci_hcd 0000:00:1d.1: UHCI Host Controller [ 2.581917] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3 [ 2.581957] uhci_hcd 0000:00:1d.1: irq 19, io base 0x0000f060 [ 2.582309] hub 3-0:1.0: USB hub found [ 2.582324] hub 3-0:1.0: 2 ports detected [ 2.582647] uhci_hcd 0000:00:1d.2: UHCI Host Controller [ 2.582660] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4 [ 2.582711] uhci_hcd 0000:00:1d.2: irq 18, io base 0x0000f040 [ 2.583053] hub 4-0:1.0: USB hub found [ 2.583068] hub 4-0:1.0: 2 ports detected [ 2.583449] uhci_hcd 0000:00:1d.3: UHCI Host Controller [ 2.583462] uhci_hcd 0000:00:1d.3: new USB bus registered, assigned bus number 5 [ 2.583515] uhci_hcd 0000:00:1d.3: irq 16, io base 0x0000f020 [ 2.583857] hub 5-0:1.0: USB hub found [ 2.583873] hub 5-0:1.0: 2 ports detected [ 2.584269] xhci_hcd 0000:03:00.0: xHCI Host Controller [ 2.584283] xhci_hcd 0000:03:00.0: new USB bus registered, assigned bus number 6 [ 2.584508] xhci_hcd 0000:03:00.0: hcc params 0x014042cb hci version 0x96 quirks 0x00000004 [ 2.585107] hub 6-0:1.0: USB hub found [ 2.585128] hub 6-0:1.0: 2 ports detected [ 2.585330] xhci_hcd 0000:03:00.0: xHCI Host Controller [ 2.585339] xhci_hcd 0000:03:00.0: new USB bus registered, assigned bus number 7 [ 2.587912] usb usb7: We don't know the algorithms for LPM for this host, disabling LPM. [ 2.588188] hub 7-0:1.0: USB hub found [ 2.588211] hub 7-0:1.0: 2 ports detected [ 2.588453] usbcore: registered new interface driver uas [ 2.588525] usbcore: registered new interface driver usb-storage [ 2.588587] i8042: PNP: No PS/2 controller found. [ 2.588588] i8042: Probing ports directly. [ 2.592736] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.592746] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.592963] mousedev: PS/2 mouse device common for all mice [ 2.593560] rtc_cmos 00:03: RTC can wake from S4 [ 2.593782] rtc_cmos 00:03: rtc core: registered rtc_cmos as rtc0 [ 2.593825] rtc_cmos 00:03: alarms up to one month, y3k, 242 bytes nvram, hpet irqs [ 2.593856] i2c /dev entries driver [ 2.594648] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt [ 2.595083] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input3 [ 2.596096] sdhci: Secure Digital Host Controller Interface driver [ 2.596098] sdhci: Copyright(c) Pierre Ossman [ 2.596211] sdhci-pltfm: SDHCI platform and OF driver helper [ 2.596215] No iBFT detected. [ 2.596243] hidraw: raw HID events driver (C) Jiri Kosina [ 2.596903] usbcore: registered new interface driver usbhid [ 2.596904] usbhid: USB HID core driver [ 2.597554] NET: Registered protocol family 10 [ 2.598093] Segment Routing with IPv6 [ 2.598126] NET: Registered protocol family 17 [ 2.598162] Key type dns_resolver registered [ 2.598466] microcode: sig=0x30661, pf=0x4, revision=0x10d [ 2.598574] microcode: Microcode Update Driver: v2.2. [ 2.599679] Btrfs loaded, crc32c=crc32c-generic [ 2.600339] console [netcon0] enabled [ 2.600341] netconsole: network logging started [ 2.600409] rtc_cmos 00:03: setting system clock to 2017-11-24 17:16:05 UTC (1511543765) [ 2.600788] ALSA device list: [ 2.600790] No soundcards found. [ 2.728721] ata1.00: ATA-8: WDC WD3200BPVT-24JJ5T0, 01.01A01, max UDMA/133 [ 2.728725] ata1.00: 625142448 sectors, multi 16: LBA48 NCQ (depth 0/32) [ 2.733663] ata1.00: configured for UDMA/133 [ 2.733970] scsi 0:0:0:0: Direct-Access ATA WDC WD3200BPVT-2 1A01 PQ: 0 ANSI: 5 [ 2.734453] sd 0:0:0:0: Attached scsi generic sg0 type 0 [ 2.734506] sd 0:0:0:0: [sda] 625142448 512-byte logical blocks: (320 GB/298 GiB) [ 2.734511] sd 0:0:0:0: [sda] 4096-byte physical blocks [ 2.734546] sd 0:0:0:0: [sda] Write Protect is off [ 2.734552] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00 [ 2.734607] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 2.768568] sda: sda1 sda2 [ 2.769192] sd 0:0:0:0: [sda] Attached SCSI disk [ 2.774933] Freeing unused kernel memory: 3500K [ 2.774939] Write protecting the kernel read-only data: 36864k [ 2.776095] Freeing unused kernel memory: 396K [ 2.785824] Freeing unused kernel memory: 1356K [ 2.930034] clocksource: Switched to clocksource tsc [ 3.330316] random: fast init done [ 3.363194] usb 3-1: new full-speed USB device number 2 using uhci_hcd [ 3.813190] usb 1-5: new high-speed USB device number 3 using ehci-pci [ 5.722301] EXT4-fs (sda1): couldn't mount as ext3 due to feature incompatibilities [ 5.722659] EXT4-fs (sda1): couldn't mount as ext2 due to feature incompatibilities [ 5.736356] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null) [ 5.986699] EXT4-fs (sda2): couldn't mount as ext3 due to feature incompatibilities [ 5.987031] EXT4-fs (sda2): couldn't mount as ext2 due to feature incompatibilities [ 6.062116] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: (null) [ 8.960668] random: crng init done [ 9.119825] systemd[1]: systemd 232 running in system mode. (-PAM -AUDIT -SELINUX -IMA -APPARMOR -SMACK -SYSVINIT -UTMP -LIBCRYPTSETUP -GCRYPT -GNUTLS -ACL -XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN) [ 9.120249] systemd[1]: Detected architecture x86-64. [ 9.120396] systemd[1]: Set hostname to . [ 9.356161] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe. [ 9.356454] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ 9.356683] systemd[1]: Listening on Journal Socket. [ 9.356762] systemd[1]: Reached target Remote File Systems. [ 9.356918] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ 9.356964] systemd[1]: Reached target Paths. [ 9.357071] systemd[1]: Listening on Journal Socket (/dev/log). [ 9.712933] systemd-journald[250]: Received request to flush runtime journal from PID 1 [ 12.638863] snd_hda_intel 0000:04:00.1: Handle vga_switcheroo audio client [ 12.672398] cdc_acm 3-1:1.0: ttyACM0: USB ACM device [ 12.680807] usbcore: registered new interface driver cdc_acm [ 12.680812] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters [ 12.701217] snd_hda_codec_realtek hdaudioC0D0: autoconfig for ALC662 rev1: line_outs=1 (0x1b/0x0/0x0/0x0/0x0) type:hp [ 12.701225] snd_hda_codec_realtek hdaudioC0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) [ 12.701230] snd_hda_codec_realtek hdaudioC0D0: hp_outs=0 (0x0/0x0/0x0/0x0/0x0) [ 12.701234] snd_hda_codec_realtek hdaudioC0D0: mono: mono_out=0x0 [ 12.701237] snd_hda_codec_realtek hdaudioC0D0: dig-out=0x1e/0x0 [ 12.701241] snd_hda_codec_realtek hdaudioC0D0: inputs: [ 12.701246] snd_hda_codec_realtek hdaudioC0D0: Mic=0x18 [ 12.710715] input: HDA ATI HDMI HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:1c.3/0000:04:00.1/sound/card1/input6 [ 12.738650] input: HDA Digital PCBeep as /devices/pci0000:00/0000:00:1b.0/sound/card0/input7 [ 12.739242] input: HDA Intel Mic as /devices/pci0000:00/0000:00:1b.0/sound/card0/input8 [ 12.739437] input: HDA Intel Front Headphone as /devices/pci0000:00/0000:00:1b.0/sound/card0/input9 [ 13.022365] wl: loading out-of-tree module taints kernel. [ 13.022373] wl: module license 'MIXED/Proprietary' taints kernel. [ 13.022374] Disabling lock debugging due to kernel taint [ 13.068158] rtl8192ce: Chip Version ID: B_CHIP_88C [ 13.079121] rtl8192ce: Using firmware rtlwifi/rtl8192cfw.bin [ 13.083679] ieee80211 phy0: Selected rate control algorithm 'rtl_rc' [ 13.084252] rtlwifi: rtlwifi: wireless switch is on [ 13.773745] r8169 0000:01:00.0 eth0: link down [ 13.774671] r8169 0000:01:00.0 eth0: link down [ 13.775299] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 15.376449] r8169 0000:01:00.0 eth0: link up [ 15.376464] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 15.381890] 8021q: 802.1Q VLAN Support v1.8 [ 17.580195] IPv6: eth0: IPv6 duplicate address 2003:f8:3bc2:9400:52af:73ff:fe1f:7df6 detected! [ 20.204257] snd_hda_intel 0000:04:00.1: IRQ timing workaround is activated for card #1. Suggest a bigger bdl_pos_adj. [ 242.736498] perf: interrupt took too long (2514 > 2500), lowering kernel.perf_event_max_sample_rate to 79500 [ 353.622952] perf: interrupt took too long (3149 > 3142), lowering kernel.perf_event_max_sample_rate to 63300 [ 553.450869] perf: interrupt took too long (3946 > 3936), lowering kernel.perf_event_max_sample_rate to 50400 [ 899.039418] perf: interrupt took too long (4933 > 4932), lowering kernel.perf_event_max_sample_rate to 40500