16:28:14.491 T:140677337299072 NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM" 16:28:14.491 T:140677337299072 NOTICE: Enabled debug logging due to GUI setting. Level 2. 16:28:14.491 T:140677337299072 DEBUG: ------ Window Init () ------ 16:28:14.711 T:140676735526656 DEBUG: PushCecKeypress - received key b duration 220 16:28:15.253 T:140674282993408 DEBUG: CWebServer[8085]: request received for /jsonrpc 16:28:19.956 T:140676735526656 DEBUG: Previous line repeats 1 times. 16:28:19.956 T:140676735526656 DEBUG: PushCecKeypress - received key d8 duration 0 16:28:19.973 T:140677337299072 DEBUG: OnKey: menu (0xd8) pressed, action is PreviousMenu 16:28:19.981 T:140677337299072 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 16:28:20.284 T:140676735526656 DEBUG: PushCecKeypress - received key d8 duration 328 16:28:20.294 T:140677337299072 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------ 16:28:20.295 T:140677337299072 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 16:28:20.295 T:140677337299072 DEBUG: ------ Window Init (Settings.xml) ------ 16:28:20.295 T:140677337299072 DEBUG: CSettingsManager: requested setting (gamesgeneral.enable) was not found. 16:28:20.893 T:140676735526656 DEBUG: Previous line repeats 26 times. 16:28:20.893 T:140676735526656 DEBUG: PushCecKeypress - received key d8 duration 0 16:28:20.905 T:140677337299072 DEBUG: OnKey: menu (0xd8) pressed, action is PreviousMenu 16:28:20.905 T:140677337299072 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 16:28:20.905 T:140677337299072 DEBUG: CSettingsManager: requested setting (gamesgeneral.enable) was not found. 16:28:21.222 T:140676735526656 DEBUG: Previous line repeats 18 times. 16:28:21.222 T:140676735526656 DEBUG: PushCecKeypress - received key d8 duration 329 16:28:21.232 T:140677337299072 DEBUG: CSettingsManager: requested setting (gamesgeneral.enable) was not found. 16:28:21.459 T:140677337299072 DEBUG: Previous line repeats 13 times. 16:28:21.459 T:140677337299072 DEBUG: ------ Window Deinit (Settings.xml) ------ 16:28:21.460 T:140677337299072 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 16:28:21.460 T:140677337299072 DEBUG: ------ Window Init (Home.xml) ------ 16:28:21.460 T:140677337299072 DEBUG: CSettingsManager: requested setting (gamesgeneral.enable) was not found. 16:28:21.467 T:140674132027136 DEBUG: Thread LanguageInvoker start, auto delete: false 16:28:21.467 T:140674494109440 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update 16:28:21.467 T:140674132027136 INFO: initializing python engine. 16:28:21.467 T:140674132027136 DEBUG: CPythonInvoker(232, /storage/.kodi/addons/script.skinshortcuts/default.py): start processing 16:28:21.478 T:140674494109440 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 16:28:21.478 T:140674132027136 DEBUG: -->Python Interpreter Initialized<-- 16:28:21.478 T:140674132027136 DEBUG: CPythonInvoker(232, /storage/.kodi/addons/script.skinshortcuts/default.py): the source file to load is "/storage/.kodi/addons/script.skinshortcuts/default.py" 16:28:21.478 T:140674132027136 DEBUG: CPythonInvoker(232, /storage/.kodi/addons/script.skinshortcuts/default.py): setting the Python path to /storage/.kodi/addons/script.skinshortcuts:/storage/.kodi/addons/script.module.simplejson/lib:/storage/.kodi/addons/script.module.unidecode/lib:/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 16:28:21.478 T:140674132027136 DEBUG: CPythonInvoker(232, /storage/.kodi/addons/script.skinshortcuts/default.py): entering source directory /storage/.kodi/addons/script.skinshortcuts 16:28:21.478 T:140674132027136 DEBUG: CPythonInvoker(232, /storage/.kodi/addons/script.skinshortcuts/default.py): instantiating addon using automatically obtained id of "script.skinshortcuts" dependent on version 2.20.0 of the xbmc.python api 16:28:21.483 T:140674494109440 DEBUG: This query part contains a like, we will double backslash in the next field: select idArtist from artist where artist.strArtist like ' 16:28:21.515 T:140674494109440 DEBUG: Previous line repeats 9 times. 16:28:21.515 T:140674494109440 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 16:28:21.526 T:140674494109440 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update 16:28:21.539 T:140674494109440 DEBUG: RunQuery took 1 ms for 10 items query: select * from movie_view ORDER BY dateAdded desc, idMovie desc LIMIT 10 16:28:21.555 T:140674132027136 INFO: CPythonInvoker(232, /storage/.kodi/addons/script.skinshortcuts/default.py): script successfully run 16:28:21.558 T:140674266208000 DEBUG: Thread Timer start, auto delete: false 16:28:21.559 T:140674494109440 DEBUG: RunQuery took 1 ms for 10 items query: select * from episode_view ORDER BY dateAdded desc, idEpisode desc LIMIT 10 16:28:21.564 T:140674266208000 DEBUG: Thread Timer 140674266208000 terminating 16:28:21.565 T:140674420979456 DEBUG: Thread Timer start, auto delete: false 16:28:21.596 T:140674494109440 DEBUG: RunQuery took 1 ms for 0 items query: select * from musicvideo_view ORDER BY dateAdded desc, idMVideo desc LIMIT 10 16:28:21.597 T:140674494109440 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update 16:28:21.601 T:140674494109440 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 <> 'Różni wykonawcy') 16:28:21.655 T:140674132027136 INFO: Python script stopped 16:28:21.656 T:140674132027136 DEBUG: Thread LanguageInvoker 140674132027136 terminating 16:28:22.071 T:140674420979456 DEBUG: Thread Timer 140674420979456 terminating 16:28:23.327 T:140676735526656 DEBUG: PushCecKeypress - received key a9 duration 0 16:28:23.327 T:140677337299072 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 16:28:23.362 T:140674282993408 DEBUG: CWebServer[8085]: request received for /jsonrpc 16:28:23.557 T:140676735526656 DEBUG: PushCecKeypress - received key a9 duration 230 16:28:23.708 T:140676735526656 DEBUG: PushCecKeypress - received key a9 duration 0 16:28:23.745 T:140677337299072 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 16:28:23.833 T:140676735526656 DEBUG: PushCecKeypress - received key a9 duration 125 16:28:23.984 T:140676735526656 DEBUG: PushCecKeypress - received key a9 duration 0 16:28:23.994 T:140677337299072 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 16:28:24.104 T:140676735526656 DEBUG: PushCecKeypress - received key a9 duration 120 16:28:24.663 T:140676735526656 DEBUG: PushCecKeypress - received key a9 duration 0 16:28:24.703 T:140677337299072 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 16:28:24.893 T:140676735526656 DEBUG: PushCecKeypress - received key a9 duration 230 16:28:25.129 T:140676735526656 DEBUG: PushCecKeypress - received key a9 duration 0 16:28:25.137 T:140677337299072 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 16:28:25.363 T:140676735526656 DEBUG: PushCecKeypress - received key a9 duration 234 16:28:25.664 T:140676735526656 DEBUG: PushCecKeypress - received key a9 duration 0 16:28:25.694 T:140677337299072 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 16:28:25.888 T:140676735526656 DEBUG: PushCecKeypress - received key a9 duration 225 16:28:26.549 T:140676735526656 DEBUG: PushCecKeypress - received key b duration 0 16:28:26.586 T:140677337299072 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 16:28:26.586 T:140677337299072 DEBUG: Activating window ID: 10502 16:28:26.779 T:140676735526656 DEBUG: PushCecKeypress - received key b duration 230 16:28:27.053 T:140677337299072 DEBUG: ------ Window Deinit (Home.xml) ------ 16:28:27.059 T:140677337299072 DEBUG: ------ Window Init (MyMusicNav.xml) ------ 16:28:27.061 T:140677337299072 DEBUG: CGUIMediaWindow::GetDirectory (sources://music/) 16:28:27.061 T:140677337299072 DEBUG: ParentPath = [sources://music/] 16:28:27.071 T:140677337299072 WARNING: CreateLoader - unsupported protocol(sources) in sources://music.tbn 16:28:27.071 T:140677337299072 DEBUG: RetrieveMusicInfo() took 0 msec 16:28:27.079 T:140674420979456 DEBUG: Thread BackgroundLoader start, auto delete: false 16:28:27.087 T:140674420979456 DEBUG: Thread BackgroundLoader 140674420979456 terminating 16:28:27.435 T:140674282993408 DEBUG: CWebServer[8085]: request received for /jsonrpc 16:28:28.266 T:140676735526656 DEBUG: PushCecKeypress - received key b duration 0 16:28:28.273 T:140677337299072 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 16:28:28.274 T:140677337299072 DEBUG: CGUIMediaWindow::GetDirectory (/storage/xxx/) 16:28:28.274 T:140677337299072 DEBUG: ParentPath = [sources://music/] 16:28:28.311 T:140674420979456 DEBUG: Thread BackgroundLoader start, auto delete: false 16:28:28.329 T:140674420979456 DEBUG: Thread BackgroundLoader 140674420979456 terminating 16:28:28.329 T:140677337299072 DEBUG: RetrieveMusicInfo() took 20 msec 16:28:28.341 T:140674420979456 DEBUG: Thread BackgroundLoader start, auto delete: false 16:28:28.362 T:140674420979456 DEBUG: This query part contains a like, we will double backslash in the next field: select idArtist from artist where artist.strArtist like ' 16:28:28.490 T:140676735526656 DEBUG: Previous line repeats 1 times. 16:28:28.490 T:140676735526656 DEBUG: PushCecKeypress - received key b duration 224 16:28:29.762 T:140676735526656 DEBUG: PushCecKeypress - received key b duration 0 16:28:29.770 T:140677337299072 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 16:28:29.777 T:140674420979456 DEBUG: Thread BackgroundLoader 140674420979456 terminating 16:28:29.778 T:140677337299072 DEBUG: CGUIMediaWindow::GetDirectory (/storage/xxx/) 16:28:29.778 T:140677337299072 DEBUG: ParentPath = [/storage/xxx/] 16:28:29.796 T:140674420979456 DEBUG: Thread BackgroundLoader start, auto delete: false 16:28:29.825 T:140674420979456 DEBUG: Thread BackgroundLoader 140674420979456 terminating 16:28:29.826 T:140677337299072 DEBUG: RetrieveMusicInfo() took 30 msec 16:28:29.830 T:140674420979456 DEBUG: Thread BackgroundLoader start, auto delete: false 16:28:29.837 T:140674420979456 DEBUG: This query part contains a like, we will double backslash in the next field: select idArtist from artist where artist.strArtist like ' 16:28:29.854 T:140674420979456 DEBUG: Previous line repeats 13 times. 16:28:29.854 T:140674420979456 DEBUG: Thread BackgroundLoader 140674420979456 terminating 16:28:29.980 T:140676735526656 DEBUG: PushCecKeypress - received key b duration 218 16:28:31.491 T:140674282993408 DEBUG: CWebServer[8085]: request received for /jsonrpc 16:28:33.329 T:140676735526656 DEBUG: PushCecKeypress - received key b duration 0 16:28:33.345 T:140677337299072 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 16:28:33.346 T:140677337294592 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 16:28:33.346 T:140677337294592 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 16:28:33.347 T:140677337294592 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:28:33.347 T:140677337294592 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:28:33.347 T:140677337294592 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:28:33.347 T:140677337294592 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:28:33.347 T:140677337294592 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:28:33.347 T:140677337294592 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:28:33.347 T:140677337294592 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:28:33.347 T:140677337294592 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:28:33.348 T:140677337294592 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:28:33.348 T:140677337294592 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:28:33.348 T:140677337294592 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:28:33.348 T:140677337294592 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:28:33.348 T:140677337294592 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:28:33.348 T:140677337294592 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:28:33.348 T:140677337294592 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:28:33.348 T:140677337294592 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:28:33.348 T:140677337294592 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:28:33.348 T:140677337294592 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:28:33.348 T:140677337294592 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:28:33.348 T:140677337294592 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:28:33.348 T:140677337294592 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:28:33.349 T:140677337294592 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:28:33.349 T:140677337294592 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:28:33.349 T:140677337294592 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:28:33.349 T:140677337294592 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:28:33.349 T:140677337294592 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:28:33.349 T:140677337294592 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:28:33.349 T:140677337294592 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:28:33.356 T:140677337299072 DEBUG: Loading settings for /storage/xxx.mp3 16:28:33.358 T:140677337299072 DEBUG: CPlayerCoreFactory::GetPlayers(/storage/xxx.mp3) 16:28:33.358 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 16:28:33.358 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 16:28:33.358 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 16:28:33.358 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 16:28:33.358 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 16:28:33.358 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 16:28:33.358 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 16:28:33.358 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 16:28:33.358 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 16:28:33.358 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 16:28:33.358 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 16:28:33.358 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 16:28:33.358 T:140677337299072 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 16:28:33.358 T:140677337299072 DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (PAPlayer) 16:28:33.358 T:140677337299072 DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1 16:28:33.358 T:140677337299072 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 16:28:33.358 T:140677337299072 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: VideoPlayer 16:28:33.358 T:140677337299072 DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players 16:28:33.360 T:140676243105536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnClear - data: {"playlistid":0} 16:28:33.361 T:140676243105536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18646,"type":"song"},"playlistid":0,"position":0} 16:28:33.361 T:140676243105536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18647,"type":"song"},"playlistid":0,"position":1} 16:28:33.361 T:140676243105536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18648,"type":"song"},"playlistid":0,"position":2} 16:28:33.361 T:140676243105536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18649,"type":"song"},"playlistid":0,"position":3} 16:28:33.361 T:140676243105536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18650,"type":"song"},"playlistid":0,"position":4} 16:28:33.361 T:140676243105536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18651,"type":"song"},"playlistid":0,"position":5} 16:28:33.362 T:140676243105536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18652,"type":"song"},"playlistid":0,"position":6} 16:28:33.362 T:140676243105536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18653,"type":"song"},"playlistid":0,"position":7} 16:28:33.362 T:140676243105536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18654,"type":"song"},"playlistid":0,"position":8} 16:28:33.362 T:140676243105536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18655,"type":"song"},"playlistid":0,"position":9} 16:28:33.362 T:140676243105536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18656,"type":"song"},"playlistid":0,"position":10} 16:28:33.362 T:140676243105536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18657,"type":"song"},"playlistid":0,"position":11} 16:28:33.362 T:140676243105536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18658,"type":"song"},"playlistid":0,"position":12} 16:28:33.363 T:140676243105536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18659,"type":"song"},"playlistid":0,"position":13} 16:28:33.412 T:140677337299072 DEBUG: Open - probing detected format [mp3] 16:28:33.412 T:140677337299072 DEBUG: Open - setting usetoc to 0 for accurate VBR MP3 seek 16:28:33.413 T:140677337299072 DEBUG: Open - avformat_find_stream_info starting 16:28:33.415 T:140677337299072 DEBUG: Open - av_find_stream_info finished 16:28:33.415 T:140677337299072 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 16:28:33.415 T:140677337299072 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 16:28:33.415 T:140677337299072 DEBUG: FactoryCodec - Audio: passthrough - Opening 16:28:33.415 T:140677337299072 DEBUG: FactoryCodec - Audio: passthrough - Failed 16:28:33.415 T:140677337299072 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 16:28:33.415 T:140677337299072 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 16:28:33.416 T:140676259890944 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnClear - data: {"playlistid":0} 16:28:33.416 T:140676259890944 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18646,"type":"song"},"playlistid":0,"position":0} 16:28:33.417 T:140676259890944 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18647,"type":"song"},"playlistid":0,"position":1} 16:28:33.417 T:140676259890944 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18648,"type":"song"},"playlistid":0,"position":2} 16:28:33.418 T:140676259890944 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18649,"type":"song"},"playlistid":0,"position":3} 16:28:33.418 T:140676259890944 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18650,"type":"song"},"playlistid":0,"position":4} 16:28:33.419 T:140676259890944 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18651,"type":"song"},"playlistid":0,"position":5} 16:28:33.419 T:140676259890944 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18652,"type":"song"},"playlistid":0,"position":6} 16:28:33.419 T:140676259890944 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18653,"type":"song"},"playlistid":0,"position":7} 16:28:33.419 T:140676259890944 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18654,"type":"song"},"playlistid":0,"position":8} 16:28:33.420 T:140676259890944 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18655,"type":"song"},"playlistid":0,"position":9} 16:28:33.420 T:140676259890944 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18656,"type":"song"},"playlistid":0,"position":10} 16:28:33.420 T:140676259890944 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18657,"type":"song"},"playlistid":0,"position":11} 16:28:33.420 T:140676259890944 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18658,"type":"song"},"playlistid":0,"position":12} 16:28:33.420 T:140676259890944 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18659,"type":"song"},"playlistid":0,"position":13} 16:28:33.550 T:140676735526656 DEBUG: PushCecKeypress - received key b duration 221 16:28:35.552 T:140674282993408 DEBUG: CWebServer[8085]: request received for /jsonrpc 16:28:59.779 T:140674494109440 DEBUG: Previous line repeats 5 times. 16:28:59.779 T:140674494109440 DEBUG: Thread JobWorker 140674494109440 terminating (autodelete) 16:28:59.779 T:140674140419840 DEBUG: Thread JobWorker 140674140419840 terminating (autodelete) 16:28:59.779 T:140674367452928 DEBUG: Thread JobWorker 140674367452928 terminating (autodelete) 16:28:59.793 T:140674448869120 DEBUG: Thread JobWorker 140674448869120 terminating (autodelete) 16:28:59.912 T:140674282993408 DEBUG: CWebServer[8085]: request received for /jsonrpc 16:29:03.463 T:140677337299072 DEBUG: unrecognized ID3 frame detected: WXXX 16:29:03.465 T:140677337299072 DEBUG: ffmpeg[7FF1FEB7B880]: [mp3] Could not update timestamps for skipped samples. 16:29:03.465 T:140677337299072 DEBUG: SeekTime - seek ended up on time 0 16:29:03.465 T:140677337299072 DEBUG: Previous line repeats 1 times. 16:29:03.465 T:140677337299072 DEBUG: ffmpeg[7FF1FEB7B880]: [mp3] Could not update timestamps for skipped samples. 16:29:03.575 T:140677337299072 INFO: AudioDecoder: File is queued 16:29:03.577 T:140677337299072 DEBUG: AudioDecoder::GetReplayGain - Final Replaygain applied: 1.000000, Track/Album Gain 89.000000, Peak 1.000000 16:29:03.578 T:140677308253952 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 16:29:03.588 T:140677337299072 INFO: PAPlayer::PrepareStream - Ready 16:29:03.589 T:140677337299072 DEBUG: PlayFile: OpenFile succeed, play state 1 16:29:03.589 T:140674367452928 DEBUG: Thread PAPlayer start, auto delete: false 16:29:03.589 T:140674367452928 DEBUG: PAPlayer::Process - Playback started 16:29:03.589 T:140674367452928 DEBUG: OnPlayBackStarted: play state was 1, starting 0 16:29:03.609 T:140674448869120 DEBUG: Thread JobWorker start, auto delete: true 16:29:03.609 T:140677337294592 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 16:29:03.609 T:140674448869120 DEBUG: CGUIInfoManager::SetCurrentSong(/storage/xxx.mp3) 16:29:03.609 T:140677337294592 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 16:29:03.615 T:140674448869120 DEBUG: This query part contains a like, we will double backslash in the next field: select idArtist from artist where artist.strArtist like ' 16:29:03.615 T:140674448869120 DEBUG: Loading additional tag info for file /storage/xxx.mp3 16:29:03.616 T:140676259890944 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnPlay - data: {"item":{"id":18646,"type":"song"},"player":{"playerid":0,"speed":1}} 16:29:03.625 T:140674448869120 DEBUG: unrecognized ID3 frame detected: WXXX 16:29:03.661 T:140676243105536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnPlay - data: {"item":{"id":18646,"type":"song"},"player":{"playerid":0,"speed":1}} 16:29:03.872 T:140675664303872 DEBUG: script.toolbox: blurred img already created: /storage/.kodi/userdata/addon_data/script.toolbox/3e8f049d3a3f130b97af1c3e82cc1c775.png 16:29:03.895 T:140675664303872 DEBUG: script.toolbox: Average Color: FF818482 16:29:03.971 T:140674282993408 DEBUG: CWebServer[8085]: request received for /jsonrpc 16:29:18.155 T:140676234712832 DEBUG: Previous line repeats 7 times. 16:29:18.155 T:140676234712832 DEBUG: RunQuery took 9 ms for 105 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount < 1)) 16:29:18.264 T:140676234712832 DEBUG: RunQuery took 31 ms for 406 items query: select * from episode_view WHERE ((episode_view.playCount IS NULL OR episode_view.playCount < 1)) 16:29:18.340 T:140676234712832 DEBUG: RunQuery took 1 ms for 0 items query: select * from musicvideo_view 16:29:18.346 T:140676234712832 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album' 16:29:18.349 T:140676234712832 DEBUG: GetAlbumsByWhere - query took 4 ms 16:29:18.435 T:140676234712832 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 <> 'Różni wykonawcy') 16:29:18.444 T:140676234712832 DEBUG: Time to retrieve artists from dataset = 9 16:29:18.496 T:140676234712832 DEBUG: GetSongsFullByWhere query = SELECT sv.*, songartistview.* FROM (SELECT songview.* FROM songview WHERE ((CAST(songview.iTimesPlayed as DECIMAL(5,1)) < 1)) ORDER BY RAND() LIMIT 20) AS sv JOIN songartistview ON songartistview.idsong = sv.idsong ORDER BY songartistview.idsong, songartistview.idRole, songartistview.iOrder 16:29:18.569 T:140676234712832 DEBUG: GetSongsFullByWhere() - took 74 ms 16:29:18.876 T:140676234712832 DEBUG: Skin Widgets: Total time needed to request random queries: 0:00:00.740423 16:29:20.711 T:140674282993408 DEBUG: CWebServer[8085]: request received for /jsonrpc 16:29:36.467 T:140676735526656 DEBUG: Previous line repeats 7 times. 16:29:36.467 T:140676735526656 DEBUG: PushCecKeypress - received key e3 duration 0 16:29:36.476 T:140677337299072 DEBUG: OnKey: red (0xe3) pressed, action is skipnext 16:29:36.476 T:140677337299072 DEBUG: DoWork - Saving file state for audio item /storage/xxx.mp3 16:29:36.484 T:140677337299072 DEBUG: Loading settings for /storage/mserver/win/xxx2.mp3 16:29:36.484 T:140677337294592 DEBUG: CAnnouncementManager - Announcement: OnSpeedChanged from xbmc 16:29:36.484 T:140677337294592 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSpeedChanged 16:29:36.485 T:140677337299072 DEBUG: CPlayerCoreFactory::GetPlayers(/storage/xxx2.mp3) 16:29:36.485 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 16:29:36.485 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 16:29:36.485 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 16:29:36.485 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 16:29:36.485 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 16:29:36.485 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 16:29:36.485 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 16:29:36.485 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 16:29:36.485 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 16:29:36.485 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 16:29:36.485 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 16:29:36.485 T:140677337299072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 16:29:36.485 T:140677337299072 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 16:29:36.485 T:140677337299072 DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (PAPlayer) 16:29:36.485 T:140677337299072 DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1 16:29:36.485 T:140677337299072 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 16:29:36.485 T:140677337299072 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: VideoPlayer 16:29:36.485 T:140677337299072 DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players 16:29:36.521 T:140676259890944 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnSpeedChanged - data: {"item":{"id":18647,"type":"song"},"player":{"playerid":0,"speed":1}} 16:29:36.547 T:140676243105536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnSpeedChanged - data: {"item":{"id":18647,"type":"song"},"player":{"playerid":0,"speed":1}} 16:29:36.566 T:140677308253952 DEBUG: CActiveAE::DiscardStream - audio stream deleted 16:29:36.566 T:140677308253952 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 16:29:36.568 T:140674367452928 DEBUG: Previous line repeats 2 times. 16:29:36.568 T:140674367452928 DEBUG: OnPlayBackStopped: play state was 2, starting 1 16:29:36.568 T:140674367452928 DEBUG: Thread PAPlayer 140674367452928 terminating 16:29:36.627 T:140677337299072 DEBUG: Open - probing detected format [mp3] 16:29:36.627 T:140677337299072 DEBUG: Open - setting usetoc to 0 for accurate VBR MP3 seek 16:29:36.627 T:140677337299072 DEBUG: Open - avformat_find_stream_info starting 16:29:36.629 T:140677337299072 DEBUG: Open - av_find_stream_info finished 16:29:36.630 T:140677337299072 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 16:29:36.630 T:140677337299072 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 16:29:36.630 T:140677337299072 DEBUG: FactoryCodec - Audio: passthrough - Opening 16:29:36.630 T:140677337299072 DEBUG: FactoryCodec - Audio: passthrough - Failed 16:29:36.630 T:140677337299072 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 16:29:36.630 T:140677337299072 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 16:29:36.687 T:140676735526656 DEBUG: PushCecKeypress - received key e3 duration 220 16:29:37.032 T:140674282993408 DEBUG: CWebServer[8085]: request received for /jsonrpc 16:29:39.566 T:140677308253952 DEBUG: Previous line repeats 1 times. 16:29:39.566 T:140677308253952 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 16:29:48.759 T:140674448869120 DEBUG: Thread JobWorker 140674448869120 terminating (autodelete) 16:29:50.064 T:140674448869120 DEBUG: CWebServer[8085]: request received for /jsonrpc 16:30:06.661 T:140677337299072 DEBUG: Previous line repeats 3 times. 16:30:06.661 T:140677337299072 DEBUG: unrecognized ID3 frame detected: WXXX 16:30:06.663 T:140677337299072 DEBUG: ffmpeg[7FF1FEB7B880]: [mp3] Could not update timestamps for skipped samples. 16:30:06.663 T:140677337299072 DEBUG: SeekTime - seek ended up on time 0 16:30:06.664 T:140677337299072 DEBUG: Previous line repeats 1 times. 16:30:06.664 T:140677337299072 DEBUG: ffmpeg[7FF1FEB7B880]: [mp3] Could not update timestamps for skipped samples. 16:30:06.774 T:140677337299072 INFO: AudioDecoder: File is queued 16:30:06.776 T:140677337299072 DEBUG: AudioDecoder::GetReplayGain - Final Replaygain applied: 1.000000, Track/Album Gain 89.000000, Peak 1.000000 16:30:06.777 T:140677308253952 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 16:30:06.788 T:140677337299072 INFO: PAPlayer::PrepareStream - Ready 16:30:06.788 T:140677337299072 DEBUG: PlayFile: OpenFile succeed, play state 1 16:30:06.789 T:140674550142720 DEBUG: Thread PAPlayer start, auto delete: false 16:30:06.789 T:140674550142720 DEBUG: PAPlayer::Process - Playback started 16:30:06.789 T:140674550142720 DEBUG: OnPlayBackStarted: play state was 1, starting 0 16:30:06.804 T:140677337294592 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 16:30:06.804 T:140674494109440 DEBUG: Thread JobWorker start, auto delete: true 16:30:06.804 T:140674494109440 DEBUG: CGUIInfoManager::SetCurrentSong(/storage/xxx2.mp3) 16:30:06.804 T:140677337294592 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 16:30:06.804 T:140674140419840 DEBUG: Thread JobWorker start, auto delete: true 16:30:06.804 T:140674140419840 DEBUG: DoWork - Saving file state for audio item /storage/xxx.mp3 16:30:06.818 T:140674494109440 DEBUG: This query part contains a like, we will double backslash in the next field: select idArtist from artist where artist.strArtist like ' 16:30:06.819 T:140674494109440 DEBUG: Loading additional tag info for file /storage/xxx2.mp3 16:30:06.824 T:140676259890944 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnPlay - data: {"item":{"id":18647,"type":"song"},"player":{"playerid":0,"speed":1}} 16:30:06.827 T:140674494109440 DEBUG: unrecognized ID3 frame detected: WXXX 16:30:06.845 T:140676243105536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnPlay - data: {"item":{"id":18647,"type":"song"},"player":{"playerid":0,"speed":1}} 16:30:11.069 T:140676735526656 DEBUG: PushCecKeypress - received key e0 duration 1000 16:30:11.082 T:140677337299072 DEBUG: OnKey: guide (0xe0) pressed, action is Stop 16:30:11.111 T:140674367452928 DEBUG: CWebServer[8085]: request received for /jsonrpc 16:30:11.164 T:140677308253952 DEBUG: Previous line repeats 1 times. 16:30:11.165 T:140677308253952 DEBUG: CActiveAE::DiscardStream - audio stream deleted 16:30:11.165 T:140677308253952 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 16:30:11.170 T:140674550142720 DEBUG: Previous line repeats 2 times. 16:30:11.170 T:140674550142720 DEBUG: OnPlayBackStopped: play state was 2, starting 0 16:30:11.170 T:140674550142720 DEBUG: Thread PAPlayer 140674550142720 terminating 16:30:11.170 T:140677337294592 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 16:30:11.170 T:140677337294592 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 16:30:11.252 T:140676243105536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnStop - data: {"end":false,"item":{"id":18647,"type":"song"}} 16:30:11.255 T:140676259890944 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnStop - data: {"end":false,"item":{"id":18647,"type":"song"}} 16:30:11.382 T:140674140419840 DEBUG: DoWork - Saving file state for audio item /storage/xxx2.mp3 16:30:11.574 T:140677308253952 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 16:30:12.230 T:140676234712832 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album' 16:30:12.237 T:140676234712832 DEBUG: GetAlbumsByWhere - query took 6 ms 16:30:12.239 T:140676276676352 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album' 16:30:12.246 T:140676276676352 DEBUG: GetAlbumsByWhere - query took 7 ms 16:30:12.334 T:140676276676352 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album' 16:30:12.338 T:140676276676352 DEBUG: Previous line repeats 1 times. 16:30:12.339 T:140676276676352 DEBUG: GetAlbumsByWhere - query took 4 ms 16:30:15.366 T:140674367452928 DEBUG: Previous line repeats 1 times. 16:30:15.366 T:140674367452928 DEBUG: CWebServer[8085]: request received for /jsonrpc 16:30:41.382 T:140674140419840 DEBUG: Previous line repeats 6 times. 16:30:41.382 T:140674140419840 DEBUG: Thread JobWorker 140674140419840 terminating (autodelete) 16:30:41.382 T:140674494109440 DEBUG: Thread JobWorker 140674494109440 terminating (autodelete) 16:30:43.793 T:140674367452928 DEBUG: CWebServer[8085]: request received for /jsonrpc 16:33:11.120 T:140677337294592 DEBUG: Previous line repeats 36 times. 16:33:11.120 T:140677337294592 DEBUG: CAnnouncementManager - Announcement: OnScreensaverActivated from xbmc 16:33:11.120 T:140677337294592 DEBUG: GOT ANNOUNCEMENT, type: 4, from xbmc, message OnScreensaverActivated 16:33:11.120 T:140677337299072 DEBUG: ------ Window Init () ------ 16:33:11.134 T:140676259890944 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: GUI.OnScreensaverActivated - data: null 16:33:11.137 T:140676243105536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: GUI.OnScreensaverActivated - data: null 16:33:13.742 T:140674367452928 DEBUG: CWebServer[8085]: request received for /jsonrpc