19:49:20 T:2952 NOTICE: ----------------------------------------------------------------------- 19:49:20 T:2952 NOTICE: Starting XBMC (11.0-HD-Audio Git:HD-audio-20120325), Platform: Windows 7, 64-bit (WoW) Service Pack 1 build 7601. Built on Mar 25 2012 (compiler 1600) 19:49:20 T:2952 NOTICE: AMD E-350 Processor 19:49:20 T:2952 NOTICE: Desktop Resolution: 1280x720 32Bit at 50Hz 19:49:20 T:2952 NOTICE: Running with restricted rights 19:49:20 T:2952 NOTICE: Aero is enabled 19:49:20 T:2952 NOTICE: special://xbmc/ is mapped to: C:\Program Files (x86)\XBMC 19:49:20 T:2952 NOTICE: special://xbmcbin/ is mapped to: C:\Program Files (x86)\XBMC 19:49:20 T:2952 NOTICE: special://masterprofile/ is mapped to: C:\Users\Stefan David\AppData\Roaming\XBMC\userdata 19:49:20 T:2952 NOTICE: special://home/ is mapped to: C:\Users\Stefan David\AppData\Roaming\XBMC\ 19:49:20 T:2952 NOTICE: special://temp/ is mapped to: C:\Users\Stefan David\AppData\Roaming\XBMC\cache 19:49:20 T:2952 NOTICE: The executable running is: C:\Program Files (x86)\XBMC\XBMC.exe 19:49:21 T:2952 NOTICE: Local hostname: HTPC 19:49:21 T:2952 NOTICE: Log File is located: C:\Users\Stefan David\AppData\Roaming\XBMC\xbmc.log 19:49:21 T:2952 NOTICE: ----------------------------------------------------------------------- 19:49:21 T:2952 NOTICE: Setup SDL 19:49:21 T:2952 NOTICE: Found screen: PnP-Monitor (Standard) on AMD Radeon HD 6310 Graphics, adapter 0. 19:49:21 T:2952 NOTICE: Primary mode: 1280x720 @ 50.00 - Full Screen 19:49:21 T:2952 NOTICE: Additional mode: 640x480 @ 60.00 - Full Screen 19:49:21 T:2952 NOTICE: Additional mode: 640x480 @ 59.94 - Full Screen 19:49:21 T:2952 NOTICE: Additional mode: 720x480 @ 59.94 - Full Screen 19:49:21 T:2952 NOTICE: Additional mode: 720x480 @ 60.00 - Full Screen 19:49:21 T:2952 NOTICE: Additional mode: 720x576 @ 50.00 - Full Screen 19:49:21 T:2952 NOTICE: Additional mode: 720x576 @ 60.00 - Full Screen 19:49:21 T:2952 NOTICE: Previous line repeats 2 times. 19:49:21 T:2952 NOTICE: Additional mode: 800x480 @ 60.00 - Full Screen 19:49:21 T:2952 NOTICE: Previous line repeats 2 times. 19:49:21 T:2952 NOTICE: Additional mode: 800x600 @ 60.00 - Full Screen 19:49:21 T:2952 NOTICE: Additional mode: 1024x600 @ 60.00 - Full Screen 19:49:21 T:2952 NOTICE: Previous line repeats 2 times. 19:49:21 T:2952 NOTICE: Additional mode: 1024x768 @ 60.00 - Full Screen 19:49:21 T:2952 NOTICE: Additional mode: 1152x648 @ 50.00 - Full Screen 19:49:21 T:2952 NOTICE: Additional mode: 1280x720 @ 50.00 - Full Screen 19:49:21 T:2952 NOTICE: Additional mode: 1280x720 @ 60.00 - Full Screen 19:49:21 T:2952 NOTICE: Additional mode: 1280x720 @ 59.94 - Full Screen 19:49:21 T:2952 NOTICE: Additional mode: 1280x768 @ 25.00i - Full Screen 19:49:21 T:2952 NOTICE: Previous line repeats 2 times. 19:49:21 T:2952 NOTICE: Additional mode: 1280x1024 @ 25.00i - Full Screen 19:49:21 T:2952 NOTICE: Previous line repeats 2 times. 19:49:21 T:2952 NOTICE: Additional mode: 1400x1050 @ 25.00i - Full Screen 19:49:21 T:2952 NOTICE: Previous line repeats 2 times. 19:49:21 T:2952 NOTICE: Additional mode: 1680x1050 @ 25.00i - Full Screen 19:49:21 T:2952 NOTICE: Previous line repeats 2 times. 19:49:21 T:2952 NOTICE: Additional mode: 1776x1000 @ 25.00i - Full Screen 19:49:21 T:2952 NOTICE: Additional mode: 1920x1080 @ 25.00i - Full Screen 19:49:21 T:2952 NOTICE: load settings... 19:49:21 T:2952 NOTICE: special://profile/ is mapped to: special://masterprofile/ 19:49:21 T:2952 NOTICE: loading special://masterprofile/guisettings.xml 19:49:21 T:2952 NOTICE: Getting hardware information now... 19:49:21 T:2952 NOTICE: Checking resolution 12 19:49:21 T:2952 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml. 19:49:21 T:2952 NOTICE: Loaded playercorefactory configuration 19:49:21 T:2952 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml. 19:49:21 T:2952 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping. 19:49:21 T:2952 NOTICE: Loaded settings file from special://xbmc/system/advancedsettings.xml 19:49:21 T:2952 NOTICE: Contents of special://xbmc/system/advancedsettings.xml are... 3 0 19:49:21 T:2952 NOTICE: Getting hardware information now... 19:49:21 T:2952 NOTICE: Checking resolution 12 19:49:21 T:2952 NOTICE: Loaded settings file from special://profile/advancedsettings.xml 19:49:21 T:2952 NOTICE: Contents of special://profile/advancedsettings.xml are... mysql 192.168.220.50 3306 xbmc xbmc xbmc_video mysql 192.168.220.50 3306 xbmc xbmc xbmc_music special://masterprofile/Thumbnails smb://Administrator:*****@192.168.220.50/Thumbnails/ .mp4 19:49:21 T:2952 WARNING: VIDEO database configuration is experimental. 19:49:21 T:2952 NOTICE: Getting hardware information now... 19:49:21 T:2952 NOTICE: Checking resolution 12 19:49:21 T:2952 NOTICE: Default DVD Player: dvdplayer 19:49:21 T:2952 NOTICE: Default Video Player: dvdplayer 19:49:21 T:2952 NOTICE: Default Audio Player: paplayer 19:49:21 T:2952 NOTICE: Disabled debug logging due to GUI setting. Level 0. 19:49:21 T:2952 NOTICE: Log level changed to 0 19:49:21 T:2952 NOTICE: Loading media sources from special://masterprofile/sources.xml 19:49:23 T:684 ERROR: CRemoteControl::Connect - failed to connect 19:49:24 T:2952 NOTICE: initializing playlistplayer 19:49:24 T:2952 NOTICE: DONE initializing playlistplayer 19:49:25 T:2952 NOTICE: initialize done 19:49:25 T:2952 NOTICE: Running the application... 19:49:25 T:2952 NOTICE: Webserver: Starting... 19:49:25 T:2952 NOTICE: WebServer: Started the webserver 19:49:25 T:2952 NOTICE: ES: Starting event server 19:49:25 T:2456 NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777 19:49:25 T:2456 NOTICE: UDP: Listening on port 9777 19:49:25 T:3704 NOTICE: -->Python Interpreter Initialized<-- 19:49:25 T:3412 NOTICE: -->Python Interpreter Initialized<-- 19:49:26 T:3412 NOTICE: StorageServer Module loaded RUN 19:49:26 T:3412 NOTICE: StorageClient-1.0.0 Starting server 19:49:26 T:3412 NOTICE: StorageServer-1.0.0 Storage Server starting C:\Users\Stefan David\AppData\Roaming\XBMC\userdata\Database\commoncache.db 19:49:27 T:3040 NOTICE: -->Python Interpreter Initialized<-- 19:49:39 T:2952 NOTICE: Log level changed to 2 19:49:39 T:2952 NOTICE: Enabled debug logging due to GUI setting. Level 2. 19:49:39 T:2952 DEBUG: ------ Window Init () ------ 19:49:44 T:2952 DEBUG: WinEventsWin32.cpp: APPCOMMAND 1 19:49:44 T:2952 DEBUG: CApplication::OnAppCommand: appcommand 1, trying action Back 19:49:44 T:2952 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 19:49:45 T:2952 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------ 19:49:45 T:2952 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 19:49:45 T:2952 DEBUG: ------ Window Init (Settings.xml) ------ 19:49:45 T:2952 INFO: Loading skin file: Settings.xml 19:49:45 T:2952 DEBUG: SDLKeyboard: scancode: 00, sym: 0000, unicode: 0000, modifier: 0 19:49:45 T:2952 DEBUG: CApplication::OnKey: 0 (f200) pressed, action is 19:49:45 T:3096 DEBUG: CImageLoader::DoWork - took 421 ms to load special://skin/backgrounds/system.jpg 19:49:46 T:2952 DEBUG: WinEventsWin32.cpp: APPCOMMAND 1 19:49:46 T:2952 DEBUG: CApplication::OnAppCommand: appcommand 1, trying action Back 19:49:46 T:2952 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 19:49:46 T:2952 DEBUG: ------ Window Deinit (Settings.xml) ------ 19:49:46 T:2952 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 19:49:46 T:2952 DEBUG: ------ Window Init (Home.xml) ------ 19:49:46 T:2952 INFO: Loading skin file: Home.xml 19:49:46 T:3096 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update 19:49:46 T:2952 DEBUG: SDLKeyboard: scancode: 00, sym: 0000, unicode: 0000, modifier: 0 19:49:46 T:2952 DEBUG: CApplication::OnKey: 0 (f200) pressed, action is 19:49:46 T:3096 DEBUG: GetRecentlyAddedAlbumSongs() query: SELECT songview.* FROM (SELECT idAlbum FROM albumview ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum=recentalbums.idAlbum 19:49:47 T:3096 DEBUG: CMusicDatabase::GetRecentlyAddedAlbums query: select * from albumview order by idAlbum desc limit 10 19:49:47 T:3096 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update 19:49:47 T:3096 DEBUG: CVideoDatabase::RunQuery took 2 ms for 10 items query: select * from movieview order by idMovie desc limit 10 19:49:47 T:3096 DEBUG: CVideoDatabase::RunQuery took 1 ms for 10 items query: select * from episodeview order by idEpisode desc limit 10 19:49:47 T:3096 DEBUG: CVideoDatabase::GetMusicVideosByWhere query = select * from musicvideoview order by idMVideo desc limit 10 19:49:47 T:3096 DEBUG: CVideoDatabase::GetMusicVideosByWhere time for actual SQL query = 1 19:49:47 T:3096 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update 19:49:48 T:2952 DEBUG: SDLKeyboard: scancode: 4b, sym: 0114, unicode: 0000, modifier: 0 19:49:48 T:2952 DEBUG: CApplication::OnKey: left (f082) pressed, action is Left 19:49:49 T:2952 DEBUG: SDLKeyboard: scancode: 4b, sym: 0114, unicode: 0000, modifier: 0 19:49:49 T:2952 DEBUG: CApplication::OnKey: left (f082) pressed, action is Left 19:49:49 T:2952 DEBUG: SDLKeyboard: scancode: 4b, sym: 0114, unicode: 0000, modifier: 0 19:49:49 T:2952 DEBUG: CApplication::OnKey: left (f082) pressed, action is Left 19:49:50 T:2952 DEBUG: SDLKeyboard: scancode: 4d, sym: 0113, unicode: 0000, modifier: 0 19:49:50 T:2952 DEBUG: CApplication::OnKey: right (f083) pressed, action is Right 19:49:51 T:2952 DEBUG: SDLKeyboard: scancode: 50, sym: 0112, unicode: 0000, modifier: 0 19:49:51 T:2952 DEBUG: CApplication::OnKey: down (f081) pressed, action is Down 19:49:52 T:2952 DEBUG: SDLKeyboard: scancode: 1c, sym: 000d, unicode: 000d, modifier: 0 19:49:52 T:2952 DEBUG: CApplication::OnKey: return (f00d) pressed, action is Select 19:49:52 T:2952 DEBUG: CApplication::ExecuteXBMCAction : Translating ActivateWindow(MusicLibrary,Artists,return) 19:49:52 T:2952 DEBUG: CApplication::ExecuteXBMCAction : To ActivateWindow(MusicLibrary,Artists,return) 19:49:52 T:2952 DEBUG: Activating window ID: 10502 19:49:52 T:2952 DEBUG: ------ Window Deinit (Home.xml) ------ 19:49:52 T:2952 DEBUG: ------ Window Init (MyMusicNav.xml) ------ 19:49:52 T:2952 INFO: Loading skin file: MyMusicNav.xml 19:49:52 T:2952 DEBUG: CGUIMediaWindow::GetDirectory (musicdb://2/) 19:49:52 T:2952 DEBUG: ParentPath = [musicdb://2/] 19:49:52 T:2952 DEBUG: CMusicDatabase::GetArtistsNav query: select * from artist where (idArtist IN (select song.idArtist from song) or idArtist IN (select exartistsong.idArtist from exartistsong) or idArtist IN (select album.idArtist from album) or idArtist IN (select exartistalbum.idArtist from exartistalbum )) and artist.strArtist != "" and artist.idArtist<>9 19:49:53 T:2952 DEBUG: Time to retrieve artists from dataset = 211 19:49:53 T:2952 DEBUG: Album format left = [%B] 19:49:53 T:2952 DEBUG: Album format right = [%A] 19:49:53 T:2952 DEBUG: Album format left = [%B] 19:49:53 T:2952 DEBUG: Album format right = [%A] 19:49:53 T:2952 DEBUG: Album format left = [%B] 19:49:53 T:2952 DEBUG: Album format right = [%A] 19:49:54 T:2952 DEBUG: SDLKeyboard: scancode: 48, sym: 0111, unicode: 0000, modifier: 0 19:49:54 T:2952 DEBUG: CApplication::OnKey: up (f080) pressed, action is Up 19:49:55 T:2952 DEBUG: WinEventsWin32.cpp: APPCOMMAND 51 19:49:55 T:2952 DEBUG: CApplication::OnAppCommand: appcommand 51, trying action PageUp 19:49:55 T:2952 INFO: XCURL::DllLibCurlGlobal::CheckIdle - Closing session to http://feeds.feedburner.com (easy=004E1E98, multi=004EC5F0) 19:49:56 T:2952 DEBUG: WinEventsWin32.cpp: APPCOMMAND 51 19:49:56 T:2952 DEBUG: CApplication::OnAppCommand: appcommand 51, trying action PageUp 19:49:57 T:2952 DEBUG: SDLKeyboard: scancode: 50, sym: 0112, unicode: 0000, modifier: 0 19:49:57 T:2952 DEBUG: CApplication::OnKey: down (f081) pressed, action is Down 19:49:57 T:2952 DEBUG: SDLKeyboard: scancode: 50, sym: 0112, unicode: 0000, modifier: 0 19:49:57 T:2952 DEBUG: CApplication::OnKey: down (f081) pressed, action is Down 19:49:58 T:2952 DEBUG: SDLKeyboard: scancode: 50, sym: 0112, unicode: 0000, modifier: 0 19:49:58 T:2952 DEBUG: CApplication::OnKey: down (f081) pressed, action is Down 19:49:58 T:2952 DEBUG: SDLKeyboard: scancode: 50, sym: 0112, unicode: 0000, modifier: 0 19:49:58 T:2952 DEBUG: CApplication::OnKey: down (f081) pressed, action is Down 19:49:59 T:2952 DEBUG: SDLKeyboard: scancode: 50, sym: 0112, unicode: 0000, modifier: 0 19:49:59 T:2952 DEBUG: CApplication::OnKey: down (f081) pressed, action is Down 19:50:00 T:2952 DEBUG: SDLKeyboard: scancode: 1c, sym: 000d, unicode: 000d, modifier: 0 19:50:00 T:2952 DEBUG: CApplication::OnKey: return (f00d) pressed, action is Select 19:50:00 T:2952 DEBUG: CGUIMediaWindow::GetDirectory (musicdb://2/53/) 19:50:00 T:2952 DEBUG: ParentPath = [musicdb://2/] 19:50:00 T:3096 DEBUG: CMusicDatabase::GetAlbumsByWhere query: select * from albumview where (idAlbum IN (select song.idAlbum from song where song.idArtist=53) or idAlbum IN (select song.idAlbum from song join exartistsong on song.idSong=exartistsong.idSong where exartistsong.idArtist=53) or idAlbum IN (select album.idAlbum from album where album.idArtist=53) or idAlbum IN (select exartistalbum.idAlbum from exartistalbum where exartistalbum.idArtist=53)) 19:50:00 T:3096 DEBUG: CMusicDatabase::GetAlbumsByWhere - query took 2 ms 19:50:00 T:2952 DEBUG: Album format left = [%B] 19:50:00 T:2952 DEBUG: Album format right = [%A] 19:50:00 T:2952 DEBUG: Album format left = [%B] 19:50:00 T:2952 DEBUG: Album format right = [%A] 19:50:00 T:2952 DEBUG: Album format left = [%B] 19:50:00 T:2952 DEBUG: Album format right = [%A] 19:50:02 T:2952 DEBUG: SDLKeyboard: scancode: 50, sym: 0112, unicode: 0000, modifier: 0 19:50:02 T:2952 DEBUG: CApplication::OnKey: down (f081) pressed, action is Down 19:50:02 T:2812 DEBUG: CImageLoader::DoWork - took 128 ms to load special://masterprofile/Thumbnails/Music/9/96aa393e.tbn 19:50:02 T:2952 DEBUG: SDLKeyboard: scancode: 50, sym: 0112, unicode: 0000, modifier: 0 19:50:02 T:2952 DEBUG: CApplication::OnKey: down (f081) pressed, action is Down 19:50:03 T:2952 DEBUG: SDLKeyboard: scancode: 50, sym: 0112, unicode: 0000, modifier: 0 19:50:03 T:2952 DEBUG: CApplication::OnKey: down (f081) pressed, action is Down 19:50:04 T:2952 DEBUG: SDLKeyboard: scancode: 50, sym: 0112, unicode: 0000, modifier: 0 19:50:04 T:2952 DEBUG: CApplication::OnKey: down (f081) pressed, action is Down 19:50:04 T:2952 DEBUG: SDLKeyboard: scancode: 1c, sym: 000d, unicode: 000d, modifier: 0 19:50:04 T:2952 DEBUG: CApplication::OnKey: return (f00d) pressed, action is Select 19:50:04 T:2952 DEBUG: CGUIMediaWindow::GetDirectory (musicdb://2/53/390/) 19:50:04 T:2952 DEBUG: ParentPath = [musicdb://2/53/] 19:50:04 T:2812 DEBUG: CMusicDatabase::GetSongsByWhere query = select * from songview where (idAlbum=390) and (idArtist=53 or idSong IN (select exartistsong.idSong from exartistsong where exartistsong.idArtist=53)or idSong IN (select song.idSong from song join album on song.idAlbum=album.idAlbum where album.idArtist=53)or idSong IN (select song.idSong from song join exartistalbum on song.idAlbum=exartistalbum.idAlbum join album on song.idAlbum=album.idAlbum where exartistalbum.idArtist=53 and album.strExtraArtists != '')) 19:50:04 T:2812 DEBUG: CMusicDatabase::GetSongsByWhere(where (idAlbum=390) and (idArtist=53 or idSong IN (select exartistsong.idSong from exartistsong where exartistsong.idArtist=53)or idSong IN (select song.idSong from song join album on song.idAlbum=album.idAlbum where album.idArtist=53)or idSong IN (select song.idSong from song join exartistalbum on song.idAlbum=exartistalbum.idAlbum join album on song.idAlbum=album.idAlbum where exartistalbum.idArtist=53 and album.strExtraArtists != '')) ) - took 2 ms 19:50:04 T:2952 DEBUG: Album format left = [%B] 19:50:04 T:2952 DEBUG: Album format right = [%A] 19:50:04 T:2952 DEBUG: Album format left = [%B] 19:50:04 T:2952 DEBUG: Album format right = [%A] 19:50:04 T:2952 DEBUG: Album format left = [%B] 19:50:04 T:2952 DEBUG: Album format right = [%A] 19:50:06 T:2952 DEBUG: SDLKeyboard: scancode: 1c, sym: 000d, unicode: 000d, modifier: 0 19:50:06 T:2952 DEBUG: CApplication::OnKey: return (f00d) pressed, action is Select 19:50:06 T:2952 DEBUG: PLAYLIST::CPlayList::Shuffle shuffling at pos:0 19:50:06 T:2952 DEBUG: CPlayerCoreFactory::GetPlayers(musicdb://2/53/390/10155.ac3) 19:50:06 T:2952 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 19:50:06 T:2952 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 19:50:06 T:2952 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv 19:50:06 T:2952 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp 19:50:06 T:2952 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 19:50:06 T:2952 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 19:50:06 T:2952 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 19:50:06 T:2952 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 19:50:06 T:2952 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile 19:50:06 T:2952 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 19:50:06 T:2952 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 19:50:06 T:2952 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 19:50:06 T:2952 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 19:50:06 T:2952 DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (3) 19:50:06 T:2952 DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1 19:50:06 T:2952 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: PAPlayer (3) 19:50:06 T:2952 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 19:50:06 T:2952 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1) 19:50:06 T:2952 DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players 19:50:06 T:2952 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avutil-50.dll) 19:50:06 T:2952 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avcore-0.dll) 19:50:06 T:2952 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avcodec-52.dll) 19:50:06 T:2952 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avformat-52.dll) 19:50:06 T:2952 DEBUG: CDVDDemuxFFmpeg::Open - probing detected format [ac3] 19:50:06 T:2952 DEBUG: CDVDDemuxFFmpeg::Open - av_find_stream_info starting 19:50:06 T:2952 DEBUG: ffmpeg[B88]: [ac3] max_analyze_duration reached 19:50:06 T:2952 DEBUG: ffmpeg[B88]: [ac3] Estimating duration from bitrate, this may be inaccurate 19:50:06 T:2952 DEBUG: CDVDDemuxFFmpeg::Open - av_find_stream_info finished 19:50:06 T:2952 INFO: ffmpeg[B88]: Input #0, ac3, from 'musicdb://2/53/390/10155.ac3': 19:50:06 T:2952 INFO: ffmpeg[B88]: Duration: 00:37:51.45, bitrate: 448 kb/s 19:50:06 T:2952 INFO: ffmpeg[B88]: Stream #0.0: Audio: ac3, 48000 Hz, 5.1, s16, 448 kb/s 19:50:06 T:2952 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 19:50:06 T:2952 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 19:50:06 T:2952 INFO: PAPlayer: Playing musicdb://2/53/390/10155.ac3 19:50:06 T:2952 DEBUG: PAPlayer: Creating new audio renderer 19:50:06 T:2952 INFO: CAudioRendererFactory: no input channel map specified assume windows 19:50:06 T:2952 DEBUG: CWin32WASAPI::Initialize: endpoint device AMD HDMI Output (2- AMD High Definition Audio Device) 19:50:06 T:2952 INFO: CPCMRemap: Configured speaker layout: 5.1 19:50:06 T:2952 INFO: CPCMRemap: I channel map: FL,FR,CE,LFE,BL,BR 19:50:06 T:2952 INFO: CPCMRemap: O channel map: FL,FR,CE,LFE,BL,BR 19:50:06 T:2952 DEBUG: CPCMRemap: Downmix normalization is disabled 19:50:06 T:2952 DEBUG: CPCMRemap: FL = FL(1.000000*) 19:50:06 T:2952 DEBUG: CPCMRemap: FR = FR(1.000000*) 19:50:06 T:2952 DEBUG: CPCMRemap: CE = CE(1.000000*) 19:50:06 T:2952 DEBUG: CPCMRemap: LFE = LFE(1.000000*) 19:50:06 T:2952 DEBUG: CPCMRemap: BL = BL(1.000000*) 19:50:06 T:2952 DEBUG: CPCMRemap: BR = BR(1.000000*) 19:50:06 T:2952 DEBUG: CAudioContext::SetActiveDevice - SetActiveDevice from 2 to 0 19:50:06 T:2952 DEBUG: CAudioContext::RemoveActiveDevice - Removing device 2 19:50:06 T:2952 DEBUG: CGUIAudioManager::DeInitialize 19:50:07 T:2952 DEBUG: CWin32WASAPI::Initialize: Packet Size = 6144. Avg Bytes Per Second = 576000. 19:50:07 T:2952 DEBUG: CWin32WASAPI::Initialize - Channels: 6 - SampleRate: 48000 - SampleBit: 16 - Resample false - IsMusic true - IsPassthrough 0 - audioDevice: AMD HDMI Output (2- AMD High Definition Audio Device) 19:50:07 T:3788 DEBUG: Thread PAPlayer start, auto delete: 0 19:50:07 T:3788 DEBUG: PAPlayer: Thread started 19:50:07 T:3788 INFO: AudioDecoder: File is queued 19:50:07 T:2952 DEBUG: CGUIInfoManager::SetCurrentSong(musicdb://2/53/390/10155.ac3) 19:50:07 T:2952 DEBUG: Loading additional tag info for file smb://WHS2011/Musik_1/Rush/(1975) Fly By Night (Dolby Digital 5.1)/Rush - Fly By Night.ac3 19:50:07 T:2952 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 19:50:07 T:2952 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 19:50:17 T:2952 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib.dll) 19:50:21 T:2952 DEBUG: WinEventsWin32.cpp: APPCOMMAND 13 19:50:21 T:2952 DEBUG: CApplication::OnAppCommand: appcommand 13, trying action Stop 19:50:21 T:3788 INFO: PAPlayer: End of playback reached 19:50:21 T:3788 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 19:50:21 T:3788 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 19:50:21 T:3788 DEBUG: PAPlayer: Thread end 19:50:21 T:3788 DEBUG: Thread PAPlayer 3788 terminating 19:50:21 T:2952 DEBUG: CWin32WASAPI::Deinitialize: Cleaning up 19:50:21 T:2952 DEBUG: CAudioContext::SetActiveDevice - SetActiveDevice from 0 to 2 19:50:21 T:2952 DEBUG: CAudioContext::RemoveActiveDevice - Removing device 0 19:50:21 T:2952 DEBUG: CWDSound::direct_sound_enumerator_member_callback - found Device: Prim�rer Soundtreiber 19:50:21 T:2952 DEBUG: CWDSound::direct_sound_enumerator_member_callback - found Device: AMD HDMI Output (2- AMD High Definition Audio Device) 19:50:21 T:2952 DEBUG: CAudioContext::SetActiveDevice - selecting AMD HDMI Output (2- AMD High Definition Audio Device) as output devices 19:50:21 T:2952 DEBUG: SDLKeyboard: scancode: 00, sym: 0000, unicode: 0000, modifier: 0 19:50:21 T:2952 DEBUG: CApplication::OnKey: 0 (f200) pressed, action is 19:50:21 T:2952 DEBUG: CGUIAudioManager::Initialize 19:50:21 T:2812 DEBUG: CSaveFileStateJob::DoWork - Saving file state for audio item musicdb://2/53/390/10155.ac3 19:50:22 T:2952 DEBUG: WinEventsWin32.cpp: APPCOMMAND 1 19:50:22 T:2952 DEBUG: CApplication::OnAppCommand: appcommand 1, trying action Back 19:50:22 T:2952 DEBUG: CGUIMediaWindow::GetDirectory (musicdb://2/53/) 19:50:22 T:2952 DEBUG: ParentPath = [musicdb://2/] 19:50:22 T:2812 DEBUG: CMusicDatabase::GetAlbumsByWhere query: select * from albumview where (idAlbum IN (select song.idAlbum from song where song.idArtist=53) or idAlbum IN (select song.idAlbum from song join exartistsong on song.idSong=exartistsong.idSong where exartistsong.idArtist=53) or idAlbum IN (select album.idAlbum from album where album.idArtist=53) or idAlbum IN (select exartistalbum.idAlbum from exartistalbum where exartistalbum.idArtist=53)) 19:50:22 T:2812 DEBUG: CMusicDatabase::GetAlbumsByWhere - query took 2 ms 19:50:22 T:2952 DEBUG: Album format left = [%B] 19:50:22 T:2952 DEBUG: Album format right = [%A] 19:50:22 T:2952 DEBUG: Album format left = [%B] 19:50:22 T:2952 DEBUG: Album format right = [%A] 19:50:22 T:2952 DEBUG: Album format left = [%B] 19:50:22 T:2952 DEBUG: Album format right = [%A] 19:50:22 T:2952 DEBUG: SDLKeyboard: scancode: 00, sym: 0000, unicode: 0000, modifier: 0 19:50:22 T:2952 DEBUG: CApplication::OnKey: 0 (f200) pressed, action is 19:50:22 T:2952 DEBUG: WinEventsWin32.cpp: APPCOMMAND 1 19:50:22 T:2952 DEBUG: CApplication::OnAppCommand: appcommand 1, trying action Back 19:50:23 T:2952 DEBUG: CGUIMediaWindow::GetDirectory (musicdb://2/) 19:50:23 T:2952 DEBUG: ParentPath = [] 19:50:23 T:2812 DEBUG: CMusicDatabase::GetArtistsNav query: select * from artist where (idArtist IN (select song.idArtist from song) or idArtist IN (select exartistsong.idArtist from exartistsong) or idArtist IN (select album.idArtist from album) or idArtist IN (select exartistalbum.idArtist from exartistalbum )) and artist.strArtist != "" and artist.idArtist<>9 19:50:23 T:2812 DEBUG: Time to retrieve artists from dataset = 214 19:50:23 T:2952 DEBUG: Album format left = [%B] 19:50:23 T:2952 DEBUG: Album format right = [%A] 19:50:23 T:2952 DEBUG: Album format left = [%B] 19:50:23 T:2952 DEBUG: Album format right = [%A] 19:50:23 T:2952 DEBUG: Album format left = [%B] 19:50:23 T:2952 DEBUG: Album format right = [%A] 19:50:23 T:2952 DEBUG: SDLKeyboard: scancode: 00, sym: 0000, unicode: 0000, modifier: 0 19:50:23 T:2952 DEBUG: CApplication::OnKey: 0 (f200) pressed, action is 19:50:23 T:2952 DEBUG: WinEventsWin32.cpp: APPCOMMAND 1 19:50:23 T:2952 DEBUG: CApplication::OnAppCommand: appcommand 1, trying action Back 19:50:23 T:2952 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 19:50:23 T:2952 DEBUG: ------ Window Deinit (MyMusicNav.xml) ------ 19:50:23 T:2952 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 19:50:23 T:2952 DEBUG: ------ Window Init (Home.xml) ------ 19:50:23 T:2952 INFO: Loading skin file: Home.xml 19:50:23 T:2812 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update 19:50:23 T:2952 DEBUG: SDLKeyboard: scancode: 00, sym: 0000, unicode: 0000, modifier: 0 19:50:23 T:2952 DEBUG: CApplication::OnKey: 0 (f200) pressed, action is 19:50:23 T:2952 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib.dll) 19:50:23 T:2812 DEBUG: GetRecentlyAddedAlbumSongs() query: SELECT songview.* FROM (SELECT idAlbum FROM albumview ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum=recentalbums.idAlbum 19:50:23 T:2812 DEBUG: CMusicDatabase::GetRecentlyAddedAlbums query: select * from albumview order by idAlbum desc limit 10 19:50:24 T:2812 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update 19:50:24 T:2812 DEBUG: CVideoDatabase::RunQuery took 3 ms for 10 items query: select * from movieview order by idMovie desc limit 10 19:50:24 T:2812 DEBUG: CVideoDatabase::RunQuery took 1 ms for 10 items query: select * from episodeview order by idEpisode desc limit 10 19:50:24 T:2812 DEBUG: CVideoDatabase::GetMusicVideosByWhere query = select * from musicvideoview order by idMVideo desc limit 10 19:50:24 T:2812 DEBUG: CVideoDatabase::GetMusicVideosByWhere time for actual SQL query = 1 19:50:24 T:2812 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update 19:50:25 T:2952 DEBUG: SDLKeyboard: scancode: 50, sym: 0112, unicode: 0000, modifier: 0 19:50:25 T:2952 DEBUG: CApplication::OnKey: down (f081) pressed, action is Down 19:50:25 T:2952 DEBUG: SDLKeyboard: scancode: 50, sym: 0112, unicode: 0000, modifier: 0 19:50:25 T:2952 DEBUG: CApplication::OnKey: down (f081) pressed, action is Down 19:50:26 T:2952 DEBUG: SDLKeyboard: scancode: 1c, sym: 000d, unicode: 000d, modifier: 0 19:50:26 T:2952 DEBUG: CApplication::OnKey: return (f00d) pressed, action is Select 19:50:26 T:2952 DEBUG: CApplication::ExecuteXBMCAction : Translating ActivateWindow(ShutdownMenu) 19:50:26 T:2952 DEBUG: CApplication::ExecuteXBMCAction : To ActivateWindow(ShutdownMenu) 19:50:26 T:2952 DEBUG: Activating window ID: 10111 19:50:26 T:2952 DEBUG: ------ Window Init (DialogButtonMenu.xml) ------ 19:50:26 T:2952 INFO: Loading skin file: DialogButtonMenu.xml 19:50:26 T:2952 DEBUG: CApplication::ExecuteXBMCAction : Translating down 19:50:26 T:2952 DEBUG: CApplication::ExecuteXBMCAction : To down 19:50:28 T:2952 DEBUG: SDLKeyboard: scancode: 1c, sym: 000d, unicode: 000d, modifier: 0 19:50:28 T:2952 DEBUG: CApplication::OnKey: return (f00d) pressed, action is Select 19:50:28 T:2952 DEBUG: CApplication::ExecuteXBMCAction : Translating XBMC.Quit() 19:50:28 T:2952 DEBUG: CApplication::ExecuteXBMCAction : To XBMC.Quit() 19:50:28 T:2952 DEBUG: CAnnouncementManager - Announcement: OnQuit from xbmc 19:50:28 T:2952 DEBUG: GOT ANNOUNCEMENT, type: 4, from xbmc, message OnQuit 19:50:28 T:2812 DEBUG: Thread Jobworker 2812 terminating (autodelete) 19:50:28 T:3096 DEBUG: Thread Jobworker 3096 terminating (autodelete) 19:50:28 T:2952 NOTICE: Storing total System Uptime 19:50:28 T:2952 NOTICE: Saving settings 19:50:28 T:2952 NOTICE: stop all 19:50:28 T:2952 DEBUG: CNetwork::NetworkMessage - Signaling network services to stop 19:50:28 T:2952 NOTICE: ES: Stopping event server 19:50:28 T:2952 DEBUG: CNetwork::NetworkMessage - Waiting for network services to stop 19:50:28 T:2952 NOTICE: stopping zeroconf publishing 19:50:28 T:2952 NOTICE: Webserver: Stopping... 19:50:28 T:2952 NOTICE: WebServer: Stopped the webserver 19:50:28 T:2952 NOTICE: Webserver: Stopped... 19:50:28 T:2456 NOTICE: ES: UDP Event server stopped 19:50:28 T:2456 DEBUG: Thread CEventServer 2456 terminating 19:50:28 T:2116 DEBUG: Thread JSONRPC::CTCPServer 2116 terminating 19:50:28 T:1164 DEBUG: Thread XBMC Peripherals 1164 terminating 19:50:28 T:2952 NOTICE: stop sap announcement listener 19:50:28 T:2952 NOTICE: clean cached files! 19:50:28 T:2952 NOTICE: unload skin 19:50:28 T:2952 INFO: Unloading old skin ... 19:50:28 T:2952 DEBUG: CGUIAudioManager::DeInitialize 19:50:28 T:2952 DEBUG: ------ Window Deinit () ------ 19:50:28 T:2952 DEBUG: ------ Window Deinit (Home.xml) ------ 19:50:28 T:2952 DEBUG: CTextureBundleXBT::Cleanup - Closed bundle 19:50:28 T:2952 DEBUG: ADDON: Stopping service addons. 19:50:28 T:2952 INFO: Stopping script with id: 2 19:50:28 T:3412 NOTICE: StorageServer-1.0.0 Closed down 19:50:28 T:3412 INFO: Scriptresult: Success 19:50:28 T:2952 NOTICE: stop python 19:50:28 T:2952 DEBUG: waiting for python thread 2 to stop 19:50:28 T:3412 INFO: Python script interrupted by user 19:50:28 T:3412 DEBUG: Thread XBPyThread 3412 terminating 19:50:28 T:2952 DEBUG: python thread 2 destructed 19:50:28 T:2952 NOTICE: stopped 19:50:28 T:2952 NOTICE: destroy 19:50:28 T:2952 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 038FAA00 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 19:50:28 T:2952 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0391B308 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 19:50:28 T:2952 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 038FAEC8 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 19:50:28 T:2952 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 038FB150 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 19:50:28 T:2952 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 038FAD18 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 19:50:28 T:2952 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 038FAAD8 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 19:50:28 T:2952 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 038FAFA0 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 19:50:28 T:2952 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 038FA928 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 19:50:28 T:2952 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 038FA970 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 19:50:28 T:2952 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 038FAE38 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 19:50:28 T:2952 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 038FABF8 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 19:50:28 T:2952 NOTICE: closing down remote control service 19:50:28 T:684 DEBUG: Thread CRemoteControl 684 terminating 19:50:28 T:2952 NOTICE: unload sections 19:50:28 T:2952 INFO: lastfm destroyed 19:50:28 T:2952 NOTICE: destroy 19:50:28 T:2952 WARNING: Attempted to remove window 10013 from the window manager when it didn't exist 19:50:28 T:2952 WARNING: Attempted to remove window 10014 from the window manager when it didn't exist 19:50:28 T:2952 WARNING: Attempted to remove window 10015 from the window manager when it didn't exist 19:50:28 T:2952 WARNING: Attempted to remove window 10016 from the window manager when it didn't exist 19:50:28 T:2952 WARNING: Attempted to remove window 10017 from the window manager when it didn't exist 19:50:28 T:2952 WARNING: Attempted to remove window 10018 from the window manager when it didn't exist 19:50:28 T:2952 WARNING: Attempted to remove window 10019 from the window manager when it didn't exist 19:50:28 T:2952 WARNING: Attempted to remove window 10107 from the window manager when it didn't exist 19:50:28 T:2952 WARNING: Attempted to remove window 10115 from the window manager when it didn't exist 19:50:28 T:2952 WARNING: Attempted to remove window 10104 from the window manager when it didn't exist 19:50:28 T:2952 NOTICE: closing down remote control service 19:50:28 T:2952 NOTICE: unload sections 19:50:28 T:2952 NOTICE: application stopped... 19:50:28 T:2952 DEBUG: SECTION:UnloadDll(special://xbmcbin/system/libcurl.dll) 19:50:28 T:2952 DEBUG: SECTION:UnloadAll(DLL: special://xbmcbin/system/cpluff.dll) 19:50:28 T:2952 DEBUG: SECTION:UnloadAll(DLL: special://xbmcbin/system/players/dvdplayer/avutil-50.dll) 19:50:28 T:2952 DEBUG: SECTION:UnloadAll(DLL: special://xbmcbin/system/players/dvdplayer/avcore-0.dll) 19:50:28 T:2952 DEBUG: SECTION:UnloadAll(DLL: special://xbmcbin/system/players/dvdplayer/avcodec-52.dll) 19:50:28 T:2952 DEBUG: SECTION:UnloadAll(DLL: special://xbmcbin/system/players/dvdplayer/avformat-52.dll) 19:50:28 T:2952 DEBUG: SECTION:UnloadAll(DLL: special://xbmcbin/system/ImageLib.dll)