10:47:50.377 T:140332021717120 NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM" 10:47:50.377 T:140332021717120 NOTICE: Enabled debug logging due to GUI setting. Level 2. 10:47:50.378 T:140332021717120 DEBUG: ------ Window Init () ------ 10:47:50.593 T:140331278399232 DEBUG: PushCecKeypress - received key b duration 226 10:47:52.223 T:140329592284928 DEBUG: CWebServer[8085]: request received for /jsonrpc 10:47:55.816 T:140331278399232 DEBUG: PushCecKeypress - received key d8 duration 0 10:47:55.827 T:140332021717120 DEBUG: OnKey: menu (0xd8) pressed, action is PreviousMenu 10:47:55.838 T:140332021717120 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 10:47:56.143 T:140332021717120 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------ 10:47:56.144 T:140332021717120 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 10:47:56.144 T:140332021717120 DEBUG: ------ Window Init (Settings.xml) ------ 10:47:56.144 T:140332021717120 DEBUG: CSettingsManager: requested setting (gamesgeneral.enable) was not found. 10:47:56.145 T:140331278399232 DEBUG: PushCecKeypress - received key d8 duration 328 10:47:56.145 T:140332021717120 DEBUG: CSettingsManager: requested setting (gamesgeneral.enable) was not found. 10:47:56.145 T:140329298605824 DEBUG: Thread JobWorker start, auto delete: true 10:47:56.159 T:140332021717120 DEBUG: CSettingsManager: requested setting (gamesgeneral.enable) was not found. 10:47:56.273 T:140329592284928 DEBUG: Previous line repeats 5 times. 10:47:56.273 T:140329592284928 DEBUG: CWebServer[8085]: request received for /jsonrpc 10:47:56.275 T:140332021717120 DEBUG: CSettingsManager: requested setting (gamesgeneral.enable) was not found. 10:47:56.816 T:140331278399232 DEBUG: Previous line repeats 20 times. 10:47:56.817 T:140331278399232 DEBUG: PushCecKeypress - received key d8 duration 0 10:47:56.817 T:140332021717120 DEBUG: OnKey: menu (0xd8) pressed, action is PreviousMenu 10:47:56.817 T:140332021717120 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 10:47:56.817 T:140332021717120 DEBUG: CSettingsManager: requested setting (gamesgeneral.enable) was not found. 10:47:57.145 T:140331278399232 DEBUG: Previous line repeats 19 times. 10:47:57.145 T:140331278399232 DEBUG: PushCecKeypress - received key d8 duration 328 10:47:57.150 T:140332021717120 DEBUG: CSettingsManager: requested setting (gamesgeneral.enable) was not found. 10:47:57.398 T:140332021717120 DEBUG: Previous line repeats 14 times. 10:47:57.398 T:140332021717120 DEBUG: ------ Window Deinit (Settings.xml) ------ 10:47:57.398 T:140332021717120 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 10:47:57.398 T:140332021717120 DEBUG: ------ Window Init (Home.xml) ------ 10:47:57.399 T:140332021717120 DEBUG: CSettingsManager: requested setting (gamesgeneral.enable) was not found. 10:47:57.406 T:140329298605824 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update 10:47:57.406 T:140329399318272 DEBUG: Thread LanguageInvoker start, auto delete: false 10:47:57.406 T:140329399318272 INFO: initializing python engine. 10:47:57.406 T:140329399318272 DEBUG: CPythonInvoker(35, /storage/.kodi/addons/script.skinshortcuts/default.py): start processing 10:47:57.417 T:140329399318272 DEBUG: -->Python Interpreter Initialized<-- 10:47:57.417 T:140329399318272 DEBUG: CPythonInvoker(35, /storage/.kodi/addons/script.skinshortcuts/default.py): the source file to load is "/storage/.kodi/addons/script.skinshortcuts/default.py" 10:47:57.417 T:140329399318272 DEBUG: CPythonInvoker(35, /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 10:47:57.417 T:140329399318272 DEBUG: CPythonInvoker(35, /storage/.kodi/addons/script.skinshortcuts/default.py): entering source directory /storage/.kodi/addons/script.skinshortcuts 10:47:57.417 T:140329399318272 DEBUG: CPythonInvoker(35, /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 10:47:57.419 T:140329298605824 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 10:47:57.424 T:140329298605824 DEBUG: This query part contains a like, we will double backslash in the next field: select idArtist from artist where artist.strArtist like ' 10:47:57.458 T:140329298605824 DEBUG: Previous line repeats 9 times. 10:47:57.458 T:140329298605824 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 10:47:57.470 T:140329298605824 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update 10:47:57.483 T:140329298605824 DEBUG: RunQuery took 2 ms for 10 items query: select * from movie_view ORDER BY dateAdded desc, idMovie desc LIMIT 10 10:47:57.491 T:140329382536960 DEBUG: Thread Timer start, auto delete: false 10:47:57.495 T:140329399318272 INFO: CPythonInvoker(35, /storage/.kodi/addons/script.skinshortcuts/default.py): script successfully run 10:47:57.497 T:140329382536960 DEBUG: Thread Timer 140329382536960 terminating 10:47:57.497 T:140329179932416 DEBUG: Thread Timer start, auto delete: false 10:47:57.503 T:140329298605824 DEBUG: RunQuery took 0 ms for 10 items query: select * from episode_view ORDER BY dateAdded desc, idEpisode desc LIMIT 10 10:47:57.540 T:140329298605824 DEBUG: RunQuery took 1 ms for 0 items query: select * from musicvideo_view ORDER BY dateAdded desc, idMVideo desc LIMIT 10 10:47:57.541 T:140329298605824 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update 10:47:57.547 T:140329298605824 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') 10:47:57.617 T:140329399318272 INFO: Python script stopped 10:47:57.617 T:140329399318272 DEBUG: Thread LanguageInvoker 140329399318272 terminating 10:47:58.003 T:140329179932416 DEBUG: Thread Timer 140329179932416 terminating 10:48:00.104 T:140331278399232 DEBUG: PushCecKeypress - received key a9 duration 0 10:48:00.141 T:140332021717120 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 10:48:00.314 T:140329592284928 DEBUG: CWebServer[8085]: request received for /jsonrpc 10:48:00.335 T:140331278399232 DEBUG: PushCecKeypress - received key a9 duration 230 10:48:00.614 T:140331278399232 DEBUG: PushCecKeypress - received key a9 duration 0 10:48:00.628 T:140332021717120 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 10:48:00.839 T:140331278399232 DEBUG: PushCecKeypress - received key a9 duration 225 10:48:01.169 T:140331278399232 DEBUG: PushCecKeypress - received key a9 duration 0 10:48:01.182 T:140332021717120 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 10:48:01.399 T:140331278399232 DEBUG: PushCecKeypress - received key a9 duration 231 10:48:01.658 T:140331278399232 DEBUG: PushCecKeypress - received key a9 duration 0 10:48:01.674 T:140332021717120 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 10:48:01.877 T:140331278399232 DEBUG: PushCecKeypress - received key a9 duration 220 10:48:02.145 T:140331278399232 DEBUG: PushCecKeypress - received key a9 duration 0 10:48:02.180 T:140332021717120 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 10:48:02.370 T:140331278399232 DEBUG: PushCecKeypress - received key a9 duration 226 10:48:02.738 T:140331278399232 DEBUG: PushCecKeypress - received key a9 duration 0 10:48:02.745 T:140332021717120 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 10:48:02.968 T:140331278399232 DEBUG: PushCecKeypress - received key a9 duration 230 10:48:03.948 T:140331278399232 DEBUG: PushCecKeypress - received key b duration 0 10:48:03.964 T:140332021717120 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 10:48:03.964 T:140332021717120 DEBUG: Activating window ID: 10502 10:48:04.173 T:140331278399232 DEBUG: PushCecKeypress - received key b duration 225 10:48:04.330 T:140329592284928 DEBUG: CWebServer[8085]: request received for /jsonrpc 10:48:04.441 T:140332021717120 DEBUG: ------ Window Deinit (Home.xml) ------ 10:48:04.512 T:140332021717120 DEBUG: ------ Window Init (MyMusicNav.xml) ------ 10:48:04.512 T:140332021717120 INFO: Loading skin file: MyMusicNav.xml, load type: KEEP_IN_MEMORY 10:48:04.615 T:140332021717120 DEBUG: CGUIMediaWindow::GetDirectory (sources://music/) 10:48:04.615 T:140332021717120 DEBUG: ParentPath = [sources://music/] 10:48:04.624 T:140332021717120 WARNING: CreateLoader - unsupported protocol(sources) in sources://music.tbn 10:48:04.624 T:140332021717120 DEBUG: RetrieveMusicInfo() took 0 msec 10:48:04.632 T:140329179932416 DEBUG: Thread BackgroundLoader start, auto delete: false 10:48:04.639 T:140329179932416 DEBUG: Thread BackgroundLoader 140329179932416 terminating 10:48:05.958 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 0 10:48:05.960 T:140332021717120 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:48:06.184 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 226 10:48:06.363 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 0 10:48:06.378 T:140332021717120 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:48:06.585 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 222 10:48:06.875 T:140331278399232 DEBUG: PushCecKeypress - received key b duration 0 10:48:06.875 T:140332021717120 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 10:48:06.876 T:140332021717120 DEBUG: CGUIMediaWindow::GetDirectory (/storage/xxx/) 10:48:06.876 T:140332021717120 DEBUG: ParentPath = [sources://music/] 10:48:06.910 T:140329179932416 DEBUG: Thread BackgroundLoader start, auto delete: false 10:48:06.929 T:140329179932416 DEBUG: Thread BackgroundLoader 140329179932416 terminating 10:48:06.930 T:140332021717120 DEBUG: RetrieveMusicInfo() took 20 msec 10:48:06.942 T:140329179932416 DEBUG: Thread BackgroundLoader start, auto delete: false 10:48:06.960 T:140329179932416 DEBUG: This query part contains a like, we will double backslash in the next field: select idArtist from artist where artist.strArtist like ' 10:48:07.103 T:140331278399232 DEBUG: Previous line repeats 1 times. 10:48:07.103 T:140331278399232 DEBUG: PushCecKeypress - received key b duration 229 10:48:08.065 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 0 10:48:08.104 T:140332021717120 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:48:08.289 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 225 10:48:08.361 T:140329592284928 DEBUG: CWebServer[8085]: request received for /jsonrpc 10:48:08.722 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 0 10:48:08.733 T:140332021717120 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:48:08.946 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 225 10:48:09.699 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 0 10:48:09.729 T:140332021717120 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:48:09.929 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 230 10:48:10.044 T:140329179932416 DEBUG: Thread BackgroundLoader 140329179932416 terminating 10:48:10.250 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 0 10:48:10.254 T:140332021717120 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:48:10.481 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 232 10:48:10.718 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 0 10:48:10.754 T:140332021717120 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:48:10.944 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 226 10:48:11.282 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 0 10:48:11.312 T:140332021717120 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:48:11.508 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 226 10:48:11.666 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 0 10:48:11.669 T:140332021717120 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:48:11.890 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 223 10:48:12.381 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 0 10:48:12.382 T:140329592284928 DEBUG: CWebServer[8085]: request received for /jsonrpc 10:48:12.416 T:140332021717120 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:48:12.611 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 230 10:48:13.297 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 0 10:48:13.319 T:140332021717120 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:48:13.337 T:140329179932416 DEBUG: Thread JobWorker start, auto delete: true 10:48:13.521 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 225 10:48:14.304 T:140331278399232 DEBUG: PushCecKeypress - received key b duration 0 10:48:14.304 T:140332021717120 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 10:48:14.305 T:140332021717120 DEBUG: CGUIMediaWindow::GetDirectory (/storage/xxx/) 10:48:14.305 T:140332021717120 DEBUG: ParentPath = [/storage/xxx/] 10:48:14.318 T:140329222694656 DEBUG: Thread BackgroundLoader start, auto delete: false 10:48:14.353 T:140329222694656 DEBUG: Thread BackgroundLoader 140329222694656 terminating 10:48:14.353 T:140332021717120 DEBUG: RetrieveMusicInfo() took 36 msec 10:48:14.355 T:140329222694656 DEBUG: Thread BackgroundLoader start, auto delete: false 10:48:14.363 T:140329222694656 DEBUG: This query part contains a like, we will double backslash in the next field: select idArtist from artist where artist.strArtist like ' 10:48:14.382 T:140329222694656 DEBUG: Previous line repeats 13 times. 10:48:14.382 T:140329222694656 DEBUG: Thread BackgroundLoader 140329222694656 terminating 10:48:14.532 T:140331278399232 DEBUG: PushCecKeypress - received key b duration 228 10:48:15.390 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 0 10:48:15.413 T:140332021717120 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:48:15.610 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 220 10:48:16.403 T:140329592284928 DEBUG: CWebServer[8085]: request received for /jsonrpc 10:48:40.660 T:140331278399232 DEBUG: Previous line repeats 6 times. 10:48:40.660 T:140331278399232 DEBUG: PushCecKeypress - received key b duration 0 10:48:40.670 T:140332021717120 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 10:48:40.671 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:48:40.671 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:48:40.671 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:48:40.671 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:48:40.671 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:48:40.672 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:48:40.672 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:48:40.672 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:48:40.672 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:48:40.672 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:48:40.672 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:48:40.672 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:48:40.672 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:48:40.672 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:48:40.672 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:48:40.672 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:48:40.672 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:48:40.672 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:48:40.672 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:48:40.673 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:48:40.673 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:48:40.673 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:48:40.673 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:48:40.673 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:48:40.673 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:48:40.673 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:48:40.673 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:48:40.673 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:48:40.679 T:140332021717120 DEBUG: Loading settings for /storage/xxx/xxx.mp3 10:48:40.681 T:140332021717120 DEBUG: CPlayerCoreFactory::GetPlayers(/storage/xxx/xxx.mp3) 10:48:40.681 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 10:48:40.681 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 10:48:40.681 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 10:48:40.681 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 10:48:40.681 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 10:48:40.681 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 10:48:40.681 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 10:48:40.681 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 10:48:40.681 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 10:48:40.681 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 10:48:40.681 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 10:48:40.681 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 10:48:40.681 T:140332021717120 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 10:48:40.681 T:140332021717120 DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (PAPlayer) 10:48:40.681 T:140332021717120 DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1 10:48:40.681 T:140332021717120 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 10:48:40.681 T:140332021717120 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: VideoPlayer 10:48:40.681 T:140332021717120 DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players 10:48:40.682 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18646,"type":"song"},"playlistid":0,"position":0} 10:48:40.682 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18647,"type":"song"},"playlistid":0,"position":1} 10:48:40.683 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18648,"type":"song"},"playlistid":0,"position":2} 10:48:40.683 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18649,"type":"song"},"playlistid":0,"position":3} 10:48:40.683 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18650,"type":"song"},"playlistid":0,"position":4} 10:48:40.683 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18651,"type":"song"},"playlistid":0,"position":5} 10:48:40.683 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18652,"type":"song"},"playlistid":0,"position":6} 10:48:40.683 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18653,"type":"song"},"playlistid":0,"position":7} 10:48:40.683 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18654,"type":"song"},"playlistid":0,"position":8} 10:48:40.683 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18655,"type":"song"},"playlistid":0,"position":9} 10:48:40.684 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18656,"type":"song"},"playlistid":0,"position":10} 10:48:40.684 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18657,"type":"song"},"playlistid":0,"position":11} 10:48:40.684 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18658,"type":"song"},"playlistid":0,"position":12} 10:48:40.684 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18659,"type":"song"},"playlistid":0,"position":13} 10:48:40.706 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18646,"type":"song"},"playlistid":0,"position":0} 10:48:40.707 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18647,"type":"song"},"playlistid":0,"position":1} 10:48:40.708 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18648,"type":"song"},"playlistid":0,"position":2} 10:48:40.708 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18649,"type":"song"},"playlistid":0,"position":3} 10:48:40.709 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18650,"type":"song"},"playlistid":0,"position":4} 10:48:40.709 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18651,"type":"song"},"playlistid":0,"position":5} 10:48:40.710 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18652,"type":"song"},"playlistid":0,"position":6} 10:48:40.710 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18653,"type":"song"},"playlistid":0,"position":7} 10:48:40.711 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18654,"type":"song"},"playlistid":0,"position":8} 10:48:40.711 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18655,"type":"song"},"playlistid":0,"position":9} 10:48:40.711 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18656,"type":"song"},"playlistid":0,"position":10} 10:48:40.712 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18657,"type":"song"},"playlistid":0,"position":11} 10:48:40.712 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18658,"type":"song"},"playlistid":0,"position":12} 10:48:40.713 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18659,"type":"song"},"playlistid":0,"position":13} 10:48:40.728 T:140332021717120 DEBUG: Open - probing detected format [mp3] 10:48:40.728 T:140332021717120 DEBUG: Open - setting usetoc to 0 for accurate VBR MP3 seek 10:48:40.728 T:140332021717120 DEBUG: Open - avformat_find_stream_info starting 10:48:40.733 T:140332021717120 DEBUG: Open - av_find_stream_info finished 10:48:40.733 T:140332021717120 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 10:48:40.733 T:140332021717120 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 10:48:40.733 T:140332021717120 DEBUG: FactoryCodec - Audio: passthrough - Opening 10:48:40.733 T:140332021717120 DEBUG: FactoryCodec - Audio: passthrough - Failed 10:48:40.733 T:140332021717120 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 10:48:40.733 T:140332021717120 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 10:48:40.881 T:140331278399232 DEBUG: PushCecKeypress - received key b duration 221 10:48:44.305 T:140329298605824 DEBUG: Thread JobWorker 140329298605824 terminating (autodelete) 10:48:44.312 T:140329179932416 DEBUG: Thread JobWorker 140329179932416 terminating (autodelete) 10:48:44.589 T:140329592284928 DEBUG: CWebServer[8085]: request received for /jsonrpc 10:49:10.792 T:140332021717120 DEBUG: Previous line repeats 6 times. 10:49:10.793 T:140332021717120 DEBUG: unrecognized ID3 frame detected: WXXX 10:49:10.794 T:140332021717120 DEBUG: ffmpeg[7FA1984E1880]: [mp3] Could not update timestamps for skipped samples. 10:49:10.795 T:140332021717120 DEBUG: SeekTime - seek ended up on time 0 10:49:10.795 T:140332021717120 DEBUG: Previous line repeats 1 times. 10:49:10.795 T:140332021717120 DEBUG: ffmpeg[7FA1984E1880]: [mp3] Could not update timestamps for skipped samples. 10:49:10.905 T:140332021717120 INFO: AudioDecoder: File is queued 10:49:10.907 T:140332021717120 DEBUG: AudioDecoder::GetReplayGain - Final Replaygain applied: 1.000000, Track/Album Gain 89.000000, Peak 1.000000 10:49:10.908 T:140331992667904 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 10:49:10.918 T:140332021717120 INFO: PAPlayer::PrepareStream - Ready 10:49:10.919 T:140332021717120 DEBUG: PlayFile: OpenFile succeed, play state 1 10:49:10.919 T:140329298605824 DEBUG: Thread PAPlayer start, auto delete: false 10:49:10.919 T:140329298605824 DEBUG: PAPlayer::Process - Playback started 10:49:10.919 T:140329298605824 DEBUG: OnPlayBackStarted: play state was 1, starting 0 10:49:10.938 T:140329179932416 DEBUG: Thread JobWorker start, auto delete: true 10:49:10.938 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 10:49:10.938 T:140329179932416 DEBUG: CGUIInfoManager::SetCurrentSong(/storage/xxx/xxx.mp3) 10:49:10.938 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 10:49:10.949 T:140329179932416 DEBUG: This query part contains a like, we will double backslash in the next field: select idArtist from artist where artist.strArtist like ' 10:49:10.950 T:140329179932416 DEBUG: Loading additional tag info for file /storage/xxx/xxx.mp3 10:49:10.958 T:140329179932416 DEBUG: unrecognized ID3 frame detected: WXXX 10:49:10.984 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnPlay - data: {"item":{"id":18646,"type":"song"},"player":{"playerid":0,"speed":1}} 10:49:11.010 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnPlay - data: {"item":{"id":18646,"type":"song"},"player":{"playerid":0,"speed":1}} 10:49:11.046 T:140330699564800 DEBUG: script.toolbox: blurred img already created: /storage/.kodi/userdata/addon_data/script.toolbox/3e8f049d3a3f130b97af1c3e82cc1c775.png 10:49:11.076 T:140330699564800 DEBUG: script.toolbox: Average Color: FF818482 10:49:12.958 T:140329592284928 DEBUG: CWebServer[8085]: request received for /jsonrpc 10:49:26.097 T:140331278399232 DEBUG: Previous line repeats 7 times. 10:49:26.097 T:140331278399232 DEBUG: PushCecKeypress - received key e0 duration 1000 10:49:26.134 T:140332021717120 DEBUG: OnKey: guide (0xe0) pressed, action is Stop 10:49:26.219 T:140331992667904 DEBUG: CActiveAE::DiscardStream - audio stream deleted 10:49:26.219 T:140331992667904 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 10:49:26.226 T:140329298605824 DEBUG: Previous line repeats 2 times. 10:49:26.226 T:140329298605824 DEBUG: OnPlayBackStopped: play state was 2, starting 0 10:49:26.226 T:140329298605824 DEBUG: Thread PAPlayer 140329298605824 terminating 10:49:26.226 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 10:49:26.227 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 10:49:26.277 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnStop - data: {"end":false,"item":{"id":18646,"type":"song"}} 10:49:26.319 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnStop - data: {"end":false,"item":{"id":18646,"type":"song"}} 10:49:26.521 T:140329179932416 DEBUG: DoWork - Saving file state for audio item /storage/xxx/xxx.mp3 10:49:26.627 T:140331992667904 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 10:49:27.295 T:140331030894336 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album' 10:49:27.302 T:140331030894336 DEBUG: GetAlbumsByWhere - query took 7 ms 10:49:27.313 T:140330724742912 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album' 10:49:27.318 T:140330724742912 DEBUG: GetAlbumsByWhere - query took 5 ms 10:49:27.392 T:140331030894336 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album' 10:49:27.398 T:140331030894336 DEBUG: GetAlbumsByWhere - query took 6 ms 10:49:27.407 T:140330724742912 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album' 10:49:27.412 T:140330724742912 DEBUG: GetAlbumsByWhere - query took 5 ms 10:49:28.380 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 0 10:49:28.388 T:140332021717120 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:49:28.606 T:140331278399232 DEBUG: PushCecKeypress - received key a7 duration 225 10:49:29.301 T:140329592284928 DEBUG: CWebServer[8085]: request received for /jsonrpc 10:49:29.380 T:140331278399232 DEBUG: PushCecKeypress - received key b duration 0 10:49:29.396 T:140332021717120 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 10:49:29.396 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 10:49:29.397 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 10:49:29.397 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:49:29.397 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:49:29.398 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:49:29.398 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:49:29.398 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:49:29.398 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:49:29.398 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:49:29.398 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:49:29.398 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:49:29.398 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:49:29.398 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:49:29.399 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:49:29.399 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:49:29.399 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:49:29.399 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:49:29.399 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:49:29.399 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:49:29.399 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:49:29.399 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:49:29.399 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:49:29.399 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:49:29.400 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:49:29.400 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:49:29.400 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:49:29.400 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:49:29.400 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:49:29.400 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:49:29.400 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:49:29.407 T:140332021717120 DEBUG: Loading settings for /storage/xxx/xxx1.mp3 10:49:29.408 T:140332021717120 DEBUG: CPlayerCoreFactory::GetPlayers(/storage/xxx/xxx1.mp3) 10:49:29.408 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 10:49:29.408 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 10:49:29.408 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 10:49:29.408 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 10:49:29.408 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 10:49:29.408 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 10:49:29.408 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 10:49:29.408 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 10:49:29.408 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 10:49:29.408 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 10:49:29.408 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 10:49:29.408 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 10:49:29.408 T:140332021717120 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 10:49:29.408 T:140332021717120 DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (PAPlayer) 10:49:29.408 T:140332021717120 DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1 10:49:29.408 T:140332021717120 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 10:49:29.408 T:140332021717120 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: VideoPlayer 10:49:29.408 T:140332021717120 DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players 10:49:29.409 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnClear - data: {"playlistid":0} 10:49:29.409 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18646,"type":"song"},"playlistid":0,"position":0} 10:49:29.410 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18647,"type":"song"},"playlistid":0,"position":1} 10:49:29.410 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18648,"type":"song"},"playlistid":0,"position":2} 10:49:29.411 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18649,"type":"song"},"playlistid":0,"position":3} 10:49:29.411 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18650,"type":"song"},"playlistid":0,"position":4} 10:49:29.412 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18651,"type":"song"},"playlistid":0,"position":5} 10:49:29.412 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18652,"type":"song"},"playlistid":0,"position":6} 10:49:29.413 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18653,"type":"song"},"playlistid":0,"position":7} 10:49:29.413 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18654,"type":"song"},"playlistid":0,"position":8} 10:49:29.414 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18655,"type":"song"},"playlistid":0,"position":9} 10:49:29.414 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18656,"type":"song"},"playlistid":0,"position":10} 10:49:29.415 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18657,"type":"song"},"playlistid":0,"position":11} 10:49:29.415 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18658,"type":"song"},"playlistid":0,"position":12} 10:49:29.415 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18659,"type":"song"},"playlistid":0,"position":13} 10:49:29.443 T:140332021717120 DEBUG: Open - probing detected format [mp3] 10:49:29.443 T:140332021717120 DEBUG: Open - setting usetoc to 0 for accurate VBR MP3 seek 10:49:29.443 T:140332021717120 DEBUG: Open - avformat_find_stream_info starting 10:49:29.445 T:140332021717120 DEBUG: Open - av_find_stream_info finished 10:49:29.446 T:140332021717120 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 10:49:29.446 T:140332021717120 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 10:49:29.446 T:140332021717120 DEBUG: FactoryCodec - Audio: passthrough - Opening 10:49:29.446 T:140332021717120 DEBUG: FactoryCodec - Audio: passthrough - Failed 10:49:29.446 T:140332021717120 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 10:49:29.446 T:140332021717120 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 10:49:29.482 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnClear - data: {"playlistid":0} 10:49:29.483 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18646,"type":"song"},"playlistid":0,"position":0} 10:49:29.483 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18647,"type":"song"},"playlistid":0,"position":1} 10:49:29.483 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18648,"type":"song"},"playlistid":0,"position":2} 10:49:29.483 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18649,"type":"song"},"playlistid":0,"position":3} 10:49:29.483 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18650,"type":"song"},"playlistid":0,"position":4} 10:49:29.483 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18651,"type":"song"},"playlistid":0,"position":5} 10:49:29.483 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18652,"type":"song"},"playlistid":0,"position":6} 10:49:29.484 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18653,"type":"song"},"playlistid":0,"position":7} 10:49:29.484 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18654,"type":"song"},"playlistid":0,"position":8} 10:49:29.484 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18655,"type":"song"},"playlistid":0,"position":9} 10:49:29.484 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18656,"type":"song"},"playlistid":0,"position":10} 10:49:29.484 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18657,"type":"song"},"playlistid":0,"position":11} 10:49:29.484 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18658,"type":"song"},"playlistid":0,"position":12} 10:49:29.484 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"id":18659,"type":"song"},"playlistid":0,"position":13} 10:49:29.606 T:140331278399232 DEBUG: PushCecKeypress - received key b duration 226 10:49:33.331 T:140329592284928 DEBUG: CWebServer[8085]: request received for /jsonrpc 10:49:56.521 T:140329179932416 DEBUG: Previous line repeats 5 times. 10:49:56.521 T:140329179932416 DEBUG: Thread JobWorker 140329179932416 terminating (autodelete) 10:49:57.480 T:140329592284928 DEBUG: CWebServer[8085]: request received for /jsonrpc 10:49:59.501 T:140332021717120 DEBUG: unrecognized ID3 frame detected: WXXX 10:49:59.503 T:140332021717120 DEBUG: ffmpeg[7FA1984E1880]: [mp3] Could not update timestamps for skipped samples. 10:49:59.503 T:140332021717120 DEBUG: SeekTime - seek ended up on time 0 10:49:59.504 T:140332021717120 DEBUG: Previous line repeats 1 times. 10:49:59.504 T:140332021717120 DEBUG: ffmpeg[7FA1984E1880]: [mp3] Could not update timestamps for skipped samples. 10:49:59.612 T:140332021717120 INFO: AudioDecoder: File is queued 10:49:59.613 T:140332021717120 DEBUG: AudioDecoder::GetReplayGain - Final Replaygain applied: 1.000000, Track/Album Gain 89.000000, Peak 1.000000 10:49:59.614 T:140331992667904 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 10:49:59.625 T:140332021717120 INFO: PAPlayer::PrepareStream - Ready 10:49:59.625 T:140332021717120 DEBUG: PlayFile: OpenFile succeed, play state 1 10:49:59.625 T:140329382536960 DEBUG: Thread PAPlayer start, auto delete: false 10:49:59.625 T:140329382536960 DEBUG: PAPlayer::Process - Playback started 10:49:59.625 T:140329382536960 DEBUG: OnPlayBackStarted: play state was 1, starting 0 10:49:59.642 T:140329179932416 DEBUG: Thread JobWorker start, auto delete: true 10:49:59.642 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 10:49:59.642 T:140329179932416 DEBUG: CGUIInfoManager::SetCurrentSong(/storage/xxx/xxx1.mp3) 10:49:59.642 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 10:49:59.649 T:140329179932416 DEBUG: This query part contains a like, we will double backslash in the next field: select idArtist from artist where artist.strArtist like ' 10:49:59.650 T:140329179932416 DEBUG: Loading additional tag info for file /storage/xxx/xxx1.mp3 10:49:59.661 T:140329179932416 DEBUG: unrecognized ID3 frame detected: WXXX 10:49:59.683 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnPlay - data: {"item":{"id":18647,"type":"song"},"player":{"playerid":0,"speed":1}} 10:49:59.720 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnPlay - data: {"item":{"id":18647,"type":"song"},"player":{"playerid":0,"speed":1}} 10:49:59.724 T:140330699564800 DEBUG: script.toolbox: blurred img already created: /storage/.kodi/userdata/addon_data/script.toolbox/3e8f049d3a3f130b97af1c3e82cc1c775.png 10:49:59.742 T:140330699564800 DEBUG: script.toolbox: Average Color: FF818482 10:50:01.527 T:140329592284928 DEBUG: CWebServer[8085]: request received for /jsonrpc 10:50:04.543 T:140331278399232 DEBUG: Previous line repeats 1 times. 10:50:04.543 T:140331278399232 DEBUG: PushCecKeypress - received key e3 duration 0 10:50:04.576 T:140332021717120 DEBUG: OnKey: red (0xe3) pressed, action is skipnext 10:50:04.576 T:140332021717120 DEBUG: DoWork - Saving file state for audio item /storage/xxx/xxx1.mp3 10:50:04.577 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnSpeedChanged from xbmc 10:50:04.577 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSpeedChanged 10:50:04.582 T:140332021717120 DEBUG: Loading settings for /storage/xxx/xxx2.mp3 10:50:04.583 T:140332021717120 DEBUG: CPlayerCoreFactory::GetPlayers(/storage/xxx/xxx2.mp3) 10:50:04.583 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 10:50:04.583 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 10:50:04.583 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 10:50:04.583 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 10:50:04.583 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 10:50:04.583 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 10:50:04.583 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 10:50:04.583 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 10:50:04.583 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 10:50:04.583 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 10:50:04.583 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 10:50:04.584 T:140332021717120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 10:50:04.584 T:140332021717120 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 10:50:04.584 T:140332021717120 DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (PAPlayer) 10:50:04.584 T:140332021717120 DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1 10:50:04.584 T:140332021717120 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 10:50:04.584 T:140332021717120 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: VideoPlayer 10:50:04.584 T:140332021717120 DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players 10:50:04.635 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnSpeedChanged - data: {"item":{"id":18648,"type":"song"},"player":{"playerid":0,"speed":1}} 10:50:04.664 T:140331992667904 DEBUG: CActiveAE::DiscardStream - audio stream deleted 10:50:04.664 T:140331992667904 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 10:50:04.670 T:140329382536960 DEBUG: Previous line repeats 2 times. 10:50:04.670 T:140329382536960 DEBUG: OnPlayBackStopped: play state was 2, starting 1 10:50:04.670 T:140329382536960 DEBUG: Thread PAPlayer 140329382536960 terminating 10:50:04.675 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnSpeedChanged - data: {"item":{"id":18648,"type":"song"},"player":{"playerid":0,"speed":1}} 10:50:04.693 T:140332021717120 DEBUG: Open - probing detected format [mp3] 10:50:04.693 T:140332021717120 DEBUG: Open - setting usetoc to 0 for accurate VBR MP3 seek 10:50:04.694 T:140332021717120 DEBUG: Open - avformat_find_stream_info starting 10:50:04.696 T:140332021717120 DEBUG: Open - av_find_stream_info finished 10:50:04.696 T:140332021717120 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 10:50:04.696 T:140332021717120 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 10:50:04.696 T:140332021717120 DEBUG: FactoryCodec - Audio: passthrough - Opening 10:50:04.696 T:140332021717120 DEBUG: FactoryCodec - Audio: passthrough - Failed 10:50:04.696 T:140332021717120 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 10:50:04.697 T:140332021717120 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 10:50:04.763 T:140331278399232 DEBUG: PushCecKeypress - received key e3 duration 221 10:50:05.678 T:140329592284928 DEBUG: CWebServer[8085]: request received for /jsonrpc 10:50:07.664 T:140331992667904 DEBUG: Previous line repeats 1 times. 10:50:07.664 T:140331992667904 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 10:50:18.720 T:140329382536960 DEBUG: CWebServer[8085]: request received for /jsonrpc 10:50:29.662 T:140329179932416 DEBUG: Previous line repeats 1 times. 10:50:29.662 T:140329179932416 DEBUG: Thread JobWorker 140329179932416 terminating (autodelete) 10:50:31.797 T:140329179932416 DEBUG: CWebServer[8085]: request received for /jsonrpc 10:50:34.761 T:140332021717120 DEBUG: Previous line repeats 1 times. 10:50:34.761 T:140332021717120 DEBUG: unrecognized ID3 frame detected: WXXX 10:50:34.762 T:140332021717120 DEBUG: ffmpeg[7FA1984E1880]: [mp3] Could not update timestamps for skipped samples. 10:50:34.763 T:140332021717120 DEBUG: SeekTime - seek ended up on time 0 10:50:34.763 T:140332021717120 DEBUG: Previous line repeats 1 times. 10:50:34.763 T:140332021717120 DEBUG: ffmpeg[7FA1984E1880]: [mp3] Could not update timestamps for skipped samples. 10:50:34.873 T:140332021717120 INFO: AudioDecoder: File is queued 10:50:34.875 T:140332021717120 DEBUG: AudioDecoder::GetReplayGain - Final Replaygain applied: 1.000000, Track/Album Gain 89.000000, Peak 1.000000 10:50:34.876 T:140331992667904 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 10:50:34.887 T:140332021717120 INFO: PAPlayer::PrepareStream - Ready 10:50:34.888 T:140332021717120 DEBUG: PlayFile: OpenFile succeed, play state 1 10:50:34.888 T:140330590525184 DEBUG: Thread PAPlayer start, auto delete: false 10:50:34.888 T:140330590525184 DEBUG: PAPlayer::Process - Playback started 10:50:34.888 T:140330590525184 DEBUG: OnPlayBackStarted: play state was 1, starting 0 10:50:34.901 T:140329298605824 DEBUG: Thread JobWorker start, auto delete: true 10:50:34.901 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 10:50:34.901 T:140329298605824 DEBUG: CGUIInfoManager::SetCurrentSong(/storage/xxx/xxx2.mp3) 10:50:34.902 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 10:50:34.902 T:140330582132480 DEBUG: Thread JobWorker start, auto delete: true 10:50:34.902 T:140330582132480 DEBUG: DoWork - Saving file state for audio item /storage/xxx/xxx3.mp3 10:50:34.912 T:140329298605824 DEBUG: This query part contains a like, we will double backslash in the next field: select idArtist from artist where artist.strArtist like ' 10:50:34.913 T:140329298605824 DEBUG: Loading additional tag info for file /storage/xxx/xxx2.mp3 10:50:34.921 T:140329298605824 DEBUG: unrecognized ID3 frame detected: WXXX 10:50:34.931 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnPlay - data: {"item":{"id":18648,"type":"song"},"player":{"playerid":0,"speed":1}} 10:50:34.976 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnPlay - data: {"item":{"id":18648,"type":"song"},"player":{"playerid":0,"speed":1}} 10:50:38.977 T:140329179932416 DEBUG: CWebServer[8085]: request received for /jsonrpc 10:50:41.357 T:140331278399232 DEBUG: Previous line repeats 1 times. 10:50:41.358 T:140331278399232 DEBUG: PushCecKeypress - received key e0 duration 1000 10:50:41.360 T:140332021717120 DEBUG: OnKey: guide (0xe0) pressed, action is Stop 10:50:41.441 T:140331992667904 DEBUG: CActiveAE::DiscardStream - audio stream deleted 10:50:41.441 T:140331992667904 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 10:50:41.443 T:140330590525184 DEBUG: Previous line repeats 2 times. 10:50:41.443 T:140330590525184 DEBUG: OnPlayBackStopped: play state was 2, starting 0 10:50:41.443 T:140330590525184 DEBUG: Thread PAPlayer 140330590525184 terminating 10:50:41.443 T:140332021712640 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 10:50:41.443 T:140332021712640 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 10:50:41.486 T:140330733135616 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnStop - data: {"end":false,"item":{"id":18648,"type":"song"}} 10:50:41.527 T:140330607310592 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnStop - data: {"end":false,"item":{"id":18648,"type":"song"}} 10:50:41.646 T:140330582132480 DEBUG: DoWork - Saving file state for audio item /storage/xxx/xxx2.mp3 10:50:41.846 T:140331992667904 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 10:50:42.500 T:140331030894336 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album' 10:50:42.506 T:140331030894336 DEBUG: GetAlbumsByWhere - query took 6 ms 10:50:42.537 T:140330724742912 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album' 10:50:42.540 T:140330724742912 DEBUG: GetAlbumsByWhere - query took 4 ms 10:50:42.585 T:140331030894336 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album' 10:50:42.588 T:140331030894336 DEBUG: GetAlbumsByWhere - query took 3 ms 10:50:42.621 T:140330724742912 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album' 10:50:42.625 T:140330724742912 DEBUG: GetAlbumsByWhere - query took 4 ms 10:50:43.057 T:140329179932416 DEBUG: CWebServer[8085]: request received for /jsonrpc 10:51:11.646 T:140329298605824 DEBUG: Previous line repeats 7 times. 10:51:11.646 T:140329298605824 DEBUG: Thread JobWorker 140329298605824 terminating (autodelete) 10:51:11.646 T:140330582132480 DEBUG: Thread JobWorker 140330582132480 terminating (autodelete) 10:51:15.221 T:140329179932416 DEBUG: CWebServer[8085]: request received for /jsonrpc 10:52:01.777 T:140330724742912 DEBUG: Previous line repeats 11 times. 10:52:01.777 T:140330724742912 DEBUG: RunQuery took 10 ms for 104 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount < 1)) 10:52:01.885 T:140330724742912 DEBUG: RunQuery took 33 ms for 406 items query: select * from episode_view WHERE ((episode_view.playCount IS NULL OR episode_view.playCount < 1)) 10:52:01.963 T:140330724742912 DEBUG: RunQuery took 2 ms for 0 items query: select * from musicvideo_view 10:52:01.969 T:140330724742912 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album' 10:52:01.971 T:140330724742912 DEBUG: GetAlbumsByWhere - query took 3 ms 10:52:02.053 T:140330724742912 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') 10:52:02.061 T:140330724742912 DEBUG: Time to retrieve artists from dataset = 7 10:52:02.114 T:140330724742912 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 10:52:02.187 T:140330724742912 DEBUG: GetSongsFullByWhere() - took 75 ms 10:52:02.329 T:140329298605824 DEBUG: Thread JobWorker start, auto delete: true 10:52:02.466 T:140330724742912 DEBUG: Skin Widgets: Total time needed to request random queries: 0:00:00.709960 10:52:03.723 T:140329179932416 DEBUG: CWebServer[8085]: request received for /jsonrpc 10:52:32.348 T:140329298605824 DEBUG: Previous line repeats 7 times. 10:52:32.348 T:140329298605824 DEBUG: Thread JobWorker 140329298605824 terminating (autodelete) 10:52:35.974 T:140329179932416 DEBUG: CWebServer[8085]: request received for /jsonrpc