02:27:30 M: 57032704  NOTICE: -----------------------------------------------------------------------
02:27:30 M: 57012224  NOTICE: Starting XBoxMediaCenter.  Built on Aug 27 2007
02:27:30 M: 57012224  NOTICE: Q is mapped to: E:\XBMC
02:27:30 M: 57012224  NOTICE: The executeable running is: E:\XBMC\default.xbe
02:27:30 M: 57012224  NOTICE: Log File is located: Q:\xbmc.log
02:27:30 M: 57012224  NOTICE: -----------------------------------------------------------------------
02:27:30 M: 57012224  NOTICE: Unmapped drive T
02:27:30 M: 57012224  NOTICE: Mapping drive T to Harddisk0\Partition1\XBMC\userdata
02:27:30 M: 57012224  NOTICE: Setup DirectX
02:27:30 M: 56987648  NOTICE: Mapping drive C to Harddisk0\Partition2
02:27:30 M: 56987648  NOTICE: Mapping drive E to Harddisk0\Partition1
02:27:30 M: 56987648  NOTICE: Unmapped drive D
02:27:30 M: 56987648  NOTICE: Mapping drive D to Cdrom0
02:27:30 M: 56987648  NOTICE: Mapping drive F to Harddisk0\Partition6
02:27:30 M: 56987648  NOTICE: Mapping drive X to Harddisk0\Partition3
02:27:30 M: 56987648  NOTICE: Mapping drive Y to Harddisk0\Partition4
02:27:30 M: 56987648  NOTICE: Unmapped drive Z
02:27:30 M: 56987648  NOTICE: Mapping drive Z to Harddisk0\Partition5
02:27:30 M: 56987648  NOTICE: load settings...
02:27:30 M: 56987648  NOTICE: Mapping drive P to Harddisk0\Partition1\XBMC\userdata
02:27:30 M: 56987648  NOTICE: loading T:\guisettings.xml
02:27:30 M: 56819712  NOTICE: Getting hardware information now...
02:27:30 M: 56819712  NOTICE: Checking resolution 10
02:27:30 M: 56819712  NOTICE: Setting autoresolution mode 7
02:27:30 M: 56819712   DEBUG:   loglevel: 1
02:27:30 M: 56819712   DEBUG:   songinfoduration: 2
02:27:30 M: 56819712   DEBUG:   busydialogdelay: 2000
02:27:30 M: 56819712   DEBUG:   remoterepeat: 480
02:27:30 M: 56819712   DEBUG:   controllerdeadzone: 0.300000
02:27:30 M: 56819712   DEBUG:   thumbsize: 192
02:27:30 M: 56819712  NOTICE: Getting hardware information now...
02:27:30 M: 56819712    INFO: Using analog output
02:27:30 M: 56819712    INFO: AC3 pass through is disabled
02:27:30 M: 56819712    INFO: DTS pass through is disabled
02:27:30 M: 56819712  NOTICE: Checking resolution 10
02:27:30 M: 56819712    INFO: AV Pack: Scart
02:27:30 M: 56819712    INFO: AV Flags: Widescreen,Pal60
02:27:30 M: 56819712  NOTICE: Setting autoresolution mode 7
02:27:30 M: 56819712  NOTICE: Per AV pack settings are off
02:27:30 M: 56934400  NOTICE: q:\userdata\sources.xml
02:27:30 M: 56934400   DEBUG:   Parsing <myprograms> tag
02:27:31 M: 56934400   DEBUG:     Setting <default> source to : Games
02:27:31 M: 56934400   DEBUG:     ---- SOURCE START ----
02:27:31 M: 56934400   DEBUG:     Found name: Games
02:27:31 M: 56934400   DEBUG:     Found path: F:\Games\
02:27:31 M: 56934400   DEBUG:       Adding source:
02:27:31 M: 56934400   DEBUG:         Name: Games
02:27:31 M: 56934400   DEBUG:         Path: F:\Games\
02:27:31 M: 56934400   DEBUG:     ---- SOURCE START ----
02:27:31 M: 56934400   DEBUG:     Found name: Programs
02:27:31 M: 56934400   DEBUG:     Found path: E:\Apps\
02:27:31 M: 56934400   DEBUG:       Adding source:
02:27:31 M: 56934400   DEBUG:         Name: Programs
02:27:31 M: 56934400   DEBUG:         Path: E:\Apps\
02:27:31 M: 56934400   DEBUG:     ---- SOURCE START ----
02:27:31 M: 56934400   DEBUG:     Found name: Emulators
02:27:31 M: 56934400   DEBUG:     Found path: E:\Emulators\
02:27:31 M: 56934400   DEBUG:       Adding source:
02:27:31 M: 56934400   DEBUG:         Name: Emulators
02:27:31 M: 56934400   DEBUG:         Path: E:\Emulators\
02:27:31 M: 56934400   DEBUG:   Parsing <pictures> tag
02:27:31 M: 56934400   DEBUG:     Setting <default> source to : Pictures
02:27:31 M: 56934400   DEBUG:     ---- SOURCE START ----
02:27:31 M: 56934400   DEBUG:     Found name: Pictures
02:27:31 M: 56934400   DEBUG:     Found path: smb://SUNCLUB/share/pictures/
02:27:31 M: 56934400   DEBUG:       Adding source:
02:27:31 M: 56934400   DEBUG:         Name: Pictures
02:27:31 M: 56934400   DEBUG:         Path: smb://SUNCLUB/share/pictures/
02:27:31 M: 56934400   DEBUG:     ---- SOURCE START ----
02:27:31 M: 56934400   DEBUG:     Found name: Pictures (2)
02:27:31 M: 56934400   DEBUG:     Found path: F:\Pictures\
02:27:31 M: 56934400   DEBUG:       Adding source:
02:27:31 M: 56934400   DEBUG:         Name: Pictures (2)
02:27:31 M: 56934400   DEBUG:         Path: F:\Pictures\
02:27:31 M: 56934400   DEBUG:   Parsing <files> tag
02:27:31 M: 56934400   DEBUG:   Parsing <music> tag
02:27:31 M: 56934400   DEBUG:     ---- SOURCE START ----
02:27:31 M: 56934400   DEBUG:     Found name: Modules
02:27:31 M: 56934400   DEBUG:     Found path: smb://SUNCLUB/share/modules/
02:27:31 M: 56934400   DEBUG:       Adding source:
02:27:31 M: 56934400   DEBUG:         Name: Modules
02:27:31 M: 56934400   DEBUG:         Path: smb://SUNCLUB/share/modules/
02:27:31 M: 56934400   DEBUG:     ---- SOURCE START ----
02:27:31 M: 56934400   DEBUG:     Found name: Music
02:27:31 M: 56934400   DEBUG:     Found path: smb://SUNCLUB/share/music/
02:27:31 M: 56934400   DEBUG:       Adding source:
02:27:31 M: 56934400   DEBUG:         Name: Music
02:27:31 M: 56934400   DEBUG:         Path: smb://SUNCLUB/share/music/
02:27:31 M: 56934400   DEBUG:     ---- SOURCE START ----
02:27:31 M: 56934400   DEBUG:     Found name: Streams
02:27:31 M: 56934400   DEBUG:     Found path: F:\Music\
02:27:31 M: 56934400   DEBUG:       Adding source:
02:27:31 M: 56934400   DEBUG:         Name: Streams
02:27:31 M: 56934400   DEBUG:         Path: F:\Music\
02:27:31 M: 56934400   DEBUG:   Parsing <video> tag
02:27:31 M: 56934400   DEBUG:     Setting <default> source to : Movies
02:27:31 M: 56934400   DEBUG:     ---- SOURCE START ----
02:27:31 M: 56934400   DEBUG:     Found name: Movies
02:27:31 M: 56934400   DEBUG:     Found path: smb://SUNCLUB/share/movies/
02:27:31 M: 56934400   DEBUG:       Adding source:
02:27:31 M: 56934400   DEBUG:         Name: Movies
02:27:31 M: 56934400   DEBUG:         Path: smb://SUNCLUB/share/movies/
02:27:31 M: 56934400   DEBUG:     ---- SOURCE START ----
02:27:31 M: 56934400   DEBUG:     Found name: Streams
02:27:31 M: 56934400   DEBUG:     Found path: F:\Videos\
02:27:31 M: 56934400   DEBUG:       Adding source:
02:27:31 M: 56934400   DEBUG:         Name: Streams
02:27:31 M: 56934400   DEBUG:         Path: F:\Videos\
02:27:31 M: 56934400   DEBUG:     ---- SOURCE START ----
02:27:31 M: 56934400   DEBUG:     Found name: Series
02:27:31 M: 56934400   DEBUG:     Found path: smb://SUNCLUB/share/series/
02:27:31 M: 56934400   DEBUG:       Adding source:
02:27:31 M: 56934400   DEBUG:         Name: Series
02:27:31 M: 56934400   DEBUG:         Path: smb://SUNCLUB/share/series/
02:27:31 M: 56934400    INFO: Checking skinpath existance, and existence of keymap.xml:Q:\skin...
02:27:31 M: 51490816    INFO: load language info file:Q:\language\english\langinfo.xml
02:27:31 M: 50962432    INFO: load language file:Q:\language\english\strings.xml
02:27:31 M: 50774016    INFO: load keymapping
02:27:31 M: 50794496    INFO: Loading P:\keymap.xml
02:27:32 M: 50724864    INFO: Checking skin version of: Project Mayhem III
02:27:32 M: 50724864    INFO: Skin version is: 2.1
02:27:32 M: 50724864    INFO:  GUI format 720x576 PAL 16:9
02:27:32 M: 50724864    INFO: install unhandled exception filter
02:27:32 M: 50724864    INFO: creating subdirectories
02:27:32 M: 50724864    INFO: userdata folder: q:\userdata
02:27:32 M: 50724864    INFO:   recording folder:
02:27:32 M: 50724864    INFO:   screenshots folder:F:\Pictures\
02:27:32 M: 50724864    INFO:   thumbnails folder:q:\userdata\Thumbnails
02:27:32 M: 50712576  NOTICE: Checking the Date!
02:27:32 M: 50712576    INFO: - Current Date is: 24-9-2007
02:27:32 M: 50712576  NOTICE: start dvd mediatype detection
02:27:32 M: 50647040  NOTICE: initializing playlistplayer
02:27:32 M: 50647040  NOTICE: DONE initializing playlistplayer
02:27:32 M: 50647040    INFO: lcd not used
02:27:32 M: 50647040  NOTICE: start fancontroller
02:27:32 M: 50114560  NOTICE: load default skin:[Project Mayhem III]
02:27:32 M: 50114560    INFO:   load skin from:Q:\skin\Project Mayhem III
02:27:32 M: 50114560    INFO:   delete old skin...
02:27:32 M: 50114560    INFO: Default 4:3 resolution directory is Q:\skin\Project Mayhem III\pal
02:27:32 M: 50114560    INFO: Default 16:9 resolution directory is Q:\skin\Project Mayhem III\pal16x9
02:27:32 M: 50114560    INFO: Skin version is: 2.1
02:27:32 M: 50114560    INFO: Loading skin includes from Q:\skin\Project Mayhem III\pal\includes.xml
02:27:32 M: 48959488    INFO:   load fonts for skin...
02:27:32 M: 48959488    INFO: Loading fonts from Q:\skin\Project Mayhem III\pal16x9\font.xml
02:27:32 M: 48898048   DEBUG: CGUIFontTTF::Load Scaled size of font Q:\skin\Project Mayhem III\Fonts\FrancophilSans.ttf (14): width = 16, height = 17
02:27:32 M: 48828416   DEBUG: CGUIFontTTF::Load Scaled size of font Q:\skin\Project Mayhem III\Fonts\FrancophilSans.ttf (14): width = 16, height = 17
02:27:32 M: 48779264   DEBUG: CGUIFontTTF::Load Scaled size of font Q:\skin\Project Mayhem III\Fonts\FrancophilSans.ttf (16): width = 18, height = 20
02:27:32 M: 48701440   DEBUG: CGUIFontTTF::Load Scaled size of font Q:\skin\Project Mayhem III\Fonts\FrancophilSans.ttf (17): width = 19, height = 21
02:27:32 M: 48644096   DEBUG: CGUIFontTTF::Load Scaled size of font Q:\skin\Project Mayhem III\Fonts\FrancophilSans.ttf (19): width = 19, height = 23
02:27:32 M: 48570368   DEBUG: CGUIFontTTF::Load Scaled size of font Q:\skin\Project Mayhem III\Fonts\FrancophilSans.ttf (19): width = 18, height = 23
02:27:32 M: 48537600    INFO:   load new skin...
02:27:32 M: 48537600    INFO: Skin version is: 2.1
02:27:32 M: 48537600    INFO: Loading skin file: home.xml
02:27:32 M: 47710208    INFO: Loading user windows, path Q:\skin\Project Mayhem III\pal16x9
02:27:32 M: 47710208    INFO: Loading user windows, path Q:\skin\Project Mayhem III\pal
02:27:32 M: 47710208    INFO: Loading skin file: Q:\skin\Project Mayhem III\pal\custom2_SkinSettings.xml
02:27:32 M: 47710208    INFO:   initialize new skin...
02:27:32 M: 47710208    INFO: Loading skin file: Pointer.xml
02:27:32 M: 47677440    INFO: Loading skin file: DialogVolumeBar.xml
02:27:32 M: 47669248    INFO: Loading skin file: DialogSeekbar.xml
02:27:32 M: 47632384    INFO: Loading skin file: DialogKaiToast.xml
02:27:32 M: 47632384    INFO: Loading skin file: DialogMuteBug.xml
02:27:33 M: 47239168    INFO:   skin loaded...
02:27:33 M: 46661632    INFO: Loading skin file: DialogBusy.xml
02:27:33 M: 47235072   DEBUG: Activating window ID: 12999
02:27:33 M: 47235072   DEBUG: Checking if window ID 12999 is locked.
02:27:33 M: 47235072    INFO: Loading skin file: Startup.xml
02:27:33 M: 47235072  NOTICE: use DHCP
02:27:33 M: 46915584    INFO: removing tempfiles
02:27:33 M: 46915584  NOTICE: initialize done
02:27:33 M: 46915584  NOTICE: Running the application...
02:27:33 M: 44748800    INFO: CNetwork::LogState - Link: full duplex
02:27:33 M: 44589056    INFO: CNetwork::LogState - Link: 100 mbps
02:27:33 M: 43909120   DEBUG: CApplication::OnMessage : Translating ReplaceWindow(Home)
02:27:33 M: 43909120    INFO: CNetwork::LogState - ip:
02:27:33 M: 43909120   DEBUG: CApplication::OnMessage : To ReplaceWindow(Home)
02:27:33 M: 43909120    INFO: CNetwork::LogState - subnet:
02:27:33 M: 43909120   DEBUG: Activating window ID: 10000
02:27:33 M: 43909120    INFO: CNetwork::LogState - gateway:
02:27:33 M: 43909120   DEBUG: Checking if window ID 10000 is locked.
02:27:33 M: 43909120    INFO: CNetwork::LogState - dns: ,
02:27:34 M: 43364352    INFO: CNetwork::LogState - Link: full duplex
02:27:34 M: 43364352   DEBUG: CNetwork::NetworkMessage - Starting network services
02:27:34 M: 43364352    INFO: CNetwork::LogState - Link: 100 mbps
02:27:34 M: 43364352    INFO: WEATHER: Downloading weather
02:27:34 M: 43364352  NOTICE: start timeserver client
02:27:34 M: 43298816    INFO: CNetwork::LogState - State: dns
02:27:34 M: 43298816    INFO: Get URL: http://xoap.weather.com/weather/local/SWXX0022?cc=*&unit=m&dayf=4&prod=xoap&par=1004124588&key=079f24145f208494
02:27:35 M: 43364352  NOTICE: Webserver: Starting...
02:27:35 M: 43253760    INFO: CNetwork::LogState - State: dhcp
02:27:35 M: 43253760   DEBUG: xbmcHttpShim starts
02:27:35 M: 43253760    INFO: CNetwork::LogState - ip: 192.168.1.3
02:27:35 M: 43122688  NOTICE: XBFileZilla: Starting...
02:27:35 M: 43122688    INFO: WebServer: Server starting using Q:\web on 192.168.1.3:80
02:27:35 M: 42520576    INFO: CNetwork::LogState - subnet: 255.255.255.0
02:27:35 M: 42520576   DEBUG: DB: Registering database table <users>
02:27:35 M: 42520576    INFO: CNetwork::LogState - gateway: 192.168.1.1
02:27:35 M: 42520576   DEBUG: DB: Registering database table <groups>
02:27:35 M: 42524672    INFO: CNetwork::LogState - dns: 192.168.1.1, 0.0.0.0
02:27:35 M: 42500096   DEBUG: DB: Registering database table <access>
02:27:35 M: 42500096    INFO: Get URL: http://feeds.feedburner.com/XboxScene
02:27:35 M: 42500096   DEBUG: UM: Loading User Configuration from file <umconfig.txt>
02:27:35 M: 42467328   DEBUG: DB: About to read data file <Q:\web\umconfig.txt>
02:27:35 M: 42471424   DEBUG: DB: Failed to stat persistent data file.
02:27:35 M: 42471424   DEBUG: webs: Listening for HTTP requests at address 192.168.1.3
02:27:35 M: 42467328  NOTICE: Webserver: Started
02:27:35 M: 42479616   DEBUG: Got rss feed: http://feeds.feedburner.com/XboxScene
02:27:35 M: 42479616   DEBUG: RSS feed encoding: UTF-8
02:27:35 M: 42500096    INFO: Get URL: http://www.gp32x.com/board/index.php?act=rssoutid=2
02:27:35 M: 42487808    INFO: WEATHER: Weather download successful
02:27:36 M: 37740544   DEBUG: Got rss feed: http://www.gp32x.com/board/index.php?act=rssoutid=2
02:27:36 M: 37638144   DEBUG: RSS feed encoding: UTF-8
02:27:36 M: 37998592   DEBUG: CApplication::OnKey: 166 pressed, action is 3
02:27:37 M: 37986304   DEBUG: CApplication::OnKey: 11 pressed, action is 7
02:27:37 M: 37720064   DEBUG: CApplication::OnMessage : Translating ActivateWindow(4)
02:27:37 M: 37699584   DEBUG: CApplication::OnMessage : To ActivateWindow(4)
02:27:37 M: 37965824   DEBUG: Activating window ID: 10004
02:27:37 M: 37699584   DEBUG: Checking if window ID 10004 is locked.
02:27:37 M: 41287680    INFO: Loading skin file: Settings.xml
02:27:38 M: 38481920  NOTICE: XBFileZilla: Started
02:27:40 M: 39317504   DEBUG: CApplication::OnKey: 11 pressed, action is 7
02:27:40 M: 39317504   DEBUG: CApplication::OnMessage : Translating ActivateWindow(MyVideosSettings)
02:27:40 M: 39317504   DEBUG: CApplication::OnMessage : To ActivateWindow(MyVideosSettings)
02:27:40 M: 39317504   DEBUG: Activating window ID: 10017
02:27:40 M: 39317504   DEBUG: Checking if window ID 10017 is locked.
02:27:40 M: 40906752    INFO: Loading skin file: SettingsCategory.xml
02:27:40 M: 40529920    INFO: Loading skin file: MyVideo.xml
02:27:45 M: 44666880   DEBUG: CApplication::OnKey: 169 pressed, action is 1
02:27:46 M: 44666880   DEBUG: CApplication::OnKey: 169 pressed, action is 1
02:27:46 M: 44666880   DEBUG: CApplication::OnKey: 167 pressed, action is 4
02:27:49 M: 44666880   DEBUG: CApplication::OnKey: 168 pressed, action is 2
02:27:50 M: 44666880   DEBUG: CApplication::OnKey: 167 pressed, action is 4
02:27:50 M: 44666880   DEBUG: CApplication::OnKey: 167 pressed, action is 4
02:27:58 M: 44666880   DEBUG: CApplication::OnKey: 167 pressed, action is 4
02:27:58 M: 44666880   DEBUG: CApplication::OnKey: 167 pressed, action is 4
02:27:59 M: 44666880   DEBUG: CApplication::OnKey: 167 pressed, action is 4
02:27:59 M: 44666880   DEBUG: CApplication::OnKey: 167 pressed, action is 4
02:27:59 M: 44666880   DEBUG: CApplication::OnKey: 167 pressed, action is 4
02:27:59 M: 44666880   DEBUG: CApplication::OnKey: 167 pressed, action is 4
02:27:59 M: 44666880   DEBUG: CApplication::OnKey: 167 pressed, action is 4
02:28:00 M: 44666880   DEBUG: CApplication::OnKey: 167 pressed, action is 4
02:28:00 M: 44666880   DEBUG: CApplication::OnKey: 167 pressed, action is 4
02:28:01 M: 44666880   DEBUG: CApplication::OnKey: 166 pressed, action is 3
02:28:02 M: 44666880   DEBUG: CApplication::OnKey: 11 pressed, action is 7
02:28:02 M: 44666880   DEBUG: CApplication::OnKey: 11 pressed, action is 7
02:28:03 M: 44666880   DEBUG: CApplication::OnKey: 167 pressed, action is 4
02:28:03 M: 44666880   DEBUG: CApplication::OnKey: 167 pressed, action is 4
02:28:04 M: 44666880   DEBUG: CApplication::OnKey: 167 pressed, action is 4
02:28:04 M: 44666880   DEBUG: CApplication::OnKey: 11 pressed, action is 7
02:28:06 M: 44666880   DEBUG: CApplication::OnKey: 168 pressed, action is 2
02:28:06 M: 44666880   DEBUG: CApplication::OnKey: 11 pressed, action is 7
02:28:07 M: 44666880   DEBUG: CApplication::OnKey: 11 pressed, action is 7
02:28:09 M: 44666880   DEBUG: CApplication::OnKey: 11 pressed, action is 7
02:28:10 M: 44666880   DEBUG: CApplication::OnKey: 11 pressed, action is 7
02:28:15 M: 44666880   DEBUG: CApplication::OnKey: 216 pressed, action is 10
02:28:16 M: 45707264    INFO: Loading skin file: Settings.xml
02:28:19 M: 44187648   DEBUG: CApplication::OnKey: 216 pressed, action is 10
02:28:19 M: 45715456    INFO: Loading skin file: Home.xml
02:28:20 M: 41881600   DEBUG: CApplication::OnKey: 166 pressed, action is 3
02:28:20 M: 41775104   DEBUG: CApplication::OnKey: 166 pressed, action is 3
02:28:20 M: 41775104   DEBUG: CApplication::OnKey: 166 pressed, action is 3
02:28:21 M: 41775104   DEBUG: CApplication::OnKey: 11 pressed, action is 7
02:28:21 M: 41775104   DEBUG: CApplication::OnMessage : Translating ActivateWindow(MyVideos)
02:28:21 M: 41775104   DEBUG: CApplication::OnMessage : To ActivateWindow(MyVideos)
02:28:21 M: 41775104   DEBUG: Activating window ID: 10024
02:28:21 M: 41775104   DEBUG: Checking if window ID 10024 is locked.
02:28:21 M: 45715456    INFO: Attempting to default to: Movies
02:28:21 M: 45715456   DEBUG: CURL::CURL - Url has no protocol Movies, empty CURL created
02:28:21 M: 45715456    INFO:   Success! Opened destination path: Movies
02:28:21 M: 45703168    INFO: Loading skin file: MyVideo.xml
02:28:21 M: 45105152   DEBUG: CGUIMediaWindow::GetDirectory (smb://SUNCLUB/share/movies)
02:28:21 M: 45105152   DEBUG:   ParentPath = [smb://SUNCLUB/share/movies/]
02:28:21 M: 44765184   DEBUG: DIRECTORY::CSMBDirectory::OpenDir - Using authentication url smb://SUNCLUB/share/movies
02:28:24 M: 41381888   DEBUG: CApplication::OnKey: 167 pressed, action is 4
02:28:24 M: 41381888   DEBUG: CApplication::OnKey: 167 pressed, action is 4
02:28:24 M: 41381888   DEBUG: CApplication::OnKey: 167 pressed, action is 4
02:28:24 M: 41381888   DEBUG: CApplication::OnKey: 167 pressed, action is 4
02:28:24 M: 41381888   DEBUG: CApplication::OnKey: 167 pressed, action is 4
02:28:24 M: 41381888   DEBUG: CApplication::OnKey: 167 pressed, action is 4
02:28:24 M: 41381888   DEBUG: CApplication::OnKey: 167 pressed, action is 4
02:28:25 M: 41381888   DEBUG: CApplication::OnKey: 11 pressed, action is 7
02:28:25 M: 41381888   DEBUG: Clearing cached fileitems [smb://SUNCLUB/share/movies/dvd/]
02:28:25 M: 41381888   ERROR: XFILE::CFile::Delete - Error deleting file Z:\d50398e7.fi
02:28:25 M: 43589632   DEBUG: CGUIMediaWindow::GetDirectory (smb://SUNCLUB/share/movies/dvd/)
02:28:25 M: 43589632   DEBUG:   ParentPath = [smb://SUNCLUB/share/movies]
02:28:25 M: 43589632   DEBUG: DIRECTORY::CSMBDirectory::OpenDir - Using authentication url smb://SUNCLUB/share/movies/dvd
02:28:27 M: 41615360   DEBUG: CApplication::OnKey: 11 pressed, action is 7
02:28:27 M: 41615360   DEBUG: CPlayerCoreFactor::GetPlayers(smb://SUNCLUB/share/movies/dvd/Paprika.iso)
02:28:27 M: 41615360  NOTICE: DVDPlayer: Opening: smb://SUNCLUB/share/movies/dvd/Paprika.iso
02:28:27 M: 41615360  NOTICE: CDVDPlayer::CloseFile()
02:28:27 M: 41615360  NOTICE: DVDPlayer: waiting for threads to exit
02:28:27 M: 41615360  NOTICE: DVDPlayer: finished waiting
02:28:27 M: 41091072  NOTICE: Creating InputStream
02:28:27 M: 40833024    INFO:   msg: libdvdnav: Using dvdnav version 0.2.0cvs from http://dvd.sf.net
02:28:27 M: 40833024    INFO:   msg: libdvdread: Using libdvdcss version 1.2.9 for DVD access
02:28:27 M: 40833024    INFO:   msg: libdvdcss debug:
02:28:28 M: 40833024    INFO:   msg: opening target `smb://SUNCLUB/share/movies/dvd/Paprika.iso'
02:28:28 M: 40833024    INFO:   msg:
02:28:28 M: 40833024    INFO:   msg: libdvdcss debug:
02:28:28 M: 40833024    INFO:   msg: using libc for access
02:28:28 M: 40833024    INFO:   msg:
02:28:29 M: 40833024    INFO:   msg: libdvdcss debug:
02:28:29 M: 40833024    INFO:   msg: using CSS key cache dir: T:\cache/PAPRIKA/2007070700483300-0000000000/
02:28:29 M: 40833024    INFO:   msg:
02:28:29 M: 40833024    INFO:   msg: libdvdnav: vm: DVD Title: PAPRIKA
02:28:29 M: 40833024    INFO:   msg: libdvdnav: Unable to find map file 'Q:\dvdplayer/.dvdnav/PAPRIKA.map'
02:28:29 M: 40787968    INFO: Loading skin file: DialogBusy.xml
02:28:38 M: 40570880    INFO:   msg: libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions:
02:28:38 M: 40640512    INFO:   msg:  1
02:28:38 M: 40640512    INFO:   msg:  2
02:28:38 M: 40640512    INFO:   msg:  3
02:28:38 M: 40640512    INFO:   msg:  4
02:28:38 M: 40640512    INFO:   msg:  5
02:28:38 M: 40640512    INFO:   msg:  6
02:28:38 M: 40640512    INFO:   msg:  7
02:28:38 M: 40570880    INFO:   msg:  8
02:28:38 M: 40640512   DEBUG:   msg:
02:28:38 M: 40640512   DEBUG: CDVDInputStreamNavigator::Open - Setting region mask ff
02:28:39 M: 40640512    INFO:   msg: 
                             *** libdvdread: CHECK_VALUE failed in ifo_read.c:1419 ***
                             *** for c_adt->cell_adr_table[i].start_sector < c_adt->cell_adr_table[i].last_sector ***
02:28:40 M: 40583168  NOTICE: DVDPlayer: playing a dvd with menu's
02:28:40 M: 40583168  NOTICE: Creating Demuxer
02:28:40 M: 36610048    INFO: ffmpeg: Input #0, mpeg, from 'smb://SUNCLUB/share/movies/dvd/Paprika.iso':
02:28:40 M: 36610048    INFO: ffmpeg:   Duration: N/A, bitrate: N/A
02:28:40 M: 36610048   DEBUG: Playback has started
02:28:40 M: 36655104    INFO: Loading skin file: VideoOverlay.xml
02:28:40 M: 36655104   DEBUG: DVDNAV_HOP_CHANNEL
02:28:40 M: 36651008   ERROR: Error reading data from demuxer
02:28:40 M: 36646912   DEBUG: CGUIInfoManager::SetCurrentMovie(smb://SUNCLUB/share/movies/dvd/Paprika.iso)
02:28:40 M: 36646912 WARNING: CDVDMessageQueue::Put MSGQ_NOT_INITIALIZED
02:28:40 M: 36638720   DEBUG: clear
02:28:40 M: 36610048   DEBUG:  - DVDNAV_VTS_CHANGE (HOLDING)
02:28:40 M: 36593664   DEBUG: DVDNAV_VTS_CHANGE
02:28:40 M: 36634624   DEBUG: clear
02:28:40 M: 36593664    INFO: CDVDInputStreamNavigator::GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 1
02:28:40 M: 36593664 WARNING: CDVDMessageQueue::Put MSGQ_NOT_INITIALIZED
02:28:40 M: 36593664    INFO: CDVDInputStreamNavigator::GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 1
02:28:41 M: 36593664   ERROR: Error reading data from demuxer
02:28:41 M: 36573184    INFO: ffmpeg: Input #0, mpeg, from 'smb://SUNCLUB/share/movies/dvd/Paprika.iso':
02:28:41 M: 36573184    INFO: ffmpeg:   Duration: N/A, bitrate: N/A
02:28:41 M: 36634624   DEBUG: CVideoDatabase::GetMovieInfo(smb://SUNCLUB/share/movies/dvd/Paprika.iso), query = select idMovie from movie where idFile=955
02:28:41 M: 36593664   DEBUG: CDVDInputStreamNavigator::ProcessBlock - Cell change: Title 0, Chapter 0
02:28:41 M: 36593664   DEBUG: CDVDInputStreamNavigator::ProcessBlock - At position 0% inside the feature
02:28:41 M: 36634624   DEBUG: DVDNAV_CELL_CHANGE
02:28:41 M: 36634624   DEBUG: CVideoDatabase::GetMovieInfo(smb://SUNCLUB/share/movies/dvd/Paprika.iso), query = select idMovie from movie where idFile=955
02:28:41 M: 36593664   DEBUG: DVDNAV_SPU_CLUT_CHANGE
02:28:41 M: 36593664   DEBUG: DVDNAV_SPU_STREAM_CHANGE
02:28:41 M: 36573184   DEBUG: DVDNAV_AUDIO_STREAM_CHANGE
02:28:41 M: 36532224    INFO:   msg: libdvdcss debug:
02:28:41 M: 36540416    INFO:   msg: cracking title key at block 424
02:28:41 M: 36540416    INFO:   msg:
02:28:41 M: 36634624   DEBUG: CGUIInfoManager::SetCurrentMovie, got movie info!
02:28:41 M: 36634624   DEBUG:   Title = Paprika
02:28:43 M: 36786176   DEBUG: CApplication::OnKey: 213 pressed, action is 18
02:28:43 M: 36786176   DEBUG: Activating window ID: 12005
02:28:43 M: 36786176   DEBUG: Checking if window ID 12005 is locked.
02:28:43 M: 39874560   ERROR: The screen resolution requested is not valid, resetting to a valid mode
02:28:43 M: 39874560   DEBUG: Setting resolution 6
02:28:44 M: 42528768   DEBUG: We set resolution 6
02:28:44 M: 42528768    INFO: Loading skin file: VideoFullscreen.xml
02:28:44 M: 42528768    INFO: Loading skin file: VideoOSD.xml
02:28:44 M: 41934848    INFO: Loading skin file: VideoOSDSettings.xml
02:28:44 M: 41226240    INFO: Loading skin file: VideoOSDSettings.xml
02:28:44 M: 41193472   DEBUG: CGUIFontTTF::Load Scaled size of font Q:\Media\Fonts\arial.ttf (22): width = 60, height = 31
02:28:49 M: 41168896   DEBUG: CApplication::OnKey: 213 pressed, action is 18
02:28:50 M: 42471424   DEBUG: Setting resolution 7
02:28:50 M: 39817216    INFO: Loading skin file: DialogBusy.xml
02:28:50 M: 39817216   DEBUG: We set resolution 7
02:28:50 M: 39825408    INFO: Loading skin file: DialogBusy.xml
02:28:50 M: 39645184    INFO: Loading skin file: MyVideo.xml
02:28:50 M: 39141376   DEBUG: CGUIMediaWindow::GetDirectory (smb://SUNCLUB/share/movies/dvd/)
02:28:50 M: 39182336   DEBUG:   ParentPath = [smb://SUNCLUB/share/movies/dvd/]
02:28:51 M: 39198720   DEBUG: DIRECTORY::CSMBDirectory::OpenDir - Using authentication url smb://SUNCLUB/share/movies/dvd
02:29:36 M: 36216832   DEBUG: CApplication::OnKey: 213 pressed, action is 18
02:29:36 M: 36216832   DEBUG: Activating window ID: 12005
02:29:37 M: 36216832   DEBUG: Checking if window ID 12005 is locked.
02:29:37 M: 39559168   ERROR: The screen resolution requested is not valid, resetting to a valid mode
02:29:37 M: 39559168   DEBUG: Setting resolution 6
02:29:37 M: 42213376   DEBUG: We set resolution 6
02:29:37 M: 42213376    INFO: Loading skin file: VideoFullscreen.xml
02:29:37 M: 42213376    INFO: Loading skin file: VideoOSD.xml
02:29:37 M: 41619456    INFO: Loading skin file: VideoOSDSettings.xml
02:29:37 M: 40910848    INFO: Loading skin file: VideoOSDSettings.xml
02:29:37 M: 40878080   DEBUG: CGUIFontTTF::Load Scaled size of font Q:\Media\Fonts\arial.ttf (22): width = 60, height = 31
02:39:27 M: 40857600    INFO:   msg: libdvdcss debug:
02:39:27 M: 40857600    INFO:   msg: end of title reached
02:39:27 M: 40857600    INFO:   msg:
02:39:27 M: 40857600    INFO:   msg: libdvdcss debug:
02:39:27 M: 40857600    INFO:   msg: successful attempts 0/0, scrambled blocks 0/2000
02:39:27 M: 40857600    INFO:   msg:
02:39:27 M: 40857600    INFO:   msg: libdvdcss debug:
02:39:27 M: 40857600    INFO:   msg: no scrambled sectors found
02:39:27 M: 40857600    INFO:   msg:
02:39:27 M: 40857600    INFO:   msg: libdvdcss debug:
02:39:27 M: 40857600    INFO:   msg: title key is 00:00:00:00:00
02:39:27 M: 40857600    INFO:   msg:
02:39:27 M: 40857600    INFO:   msg: libdvdcss debug:
02:39:27 M: 40857600    INFO:   msg: unencrypted title
02:39:27 M: 40857600    INFO:   msg:
02:39:27 M: 39829504   DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:0 TO:287266 DIFF:287266
02:39:28 M: 39829504  NOTICE: Opening Subtitle stream: 1
02:39:28 M: 39673856    INFO: CDVDDemuxSPU::ParseRLE - ignoring blank alpha palette, using default
02:39:28 M: 39673856   DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet
02:39:30 M: 39657472  NOTICE: Opening video stream: 0
02:39:30 M: 39657472  NOTICE: Creating video codec with codec id: 2
02:39:30 M: 39657472   DEBUG: FactoryCodec - Video: libmpeg2 - Opening
02:39:30 M: 38281216   DEBUG: FactoryCodec - Video: libmpeg2 - Opened
02:39:30 M: 38285312  NOTICE: Creating video thread
02:39:30 M: 37761024    INFO: CDVDInputStreamNavigator::GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 1
02:39:30 M: 37761024   DEBUG: CXBoxRenderManager::PreInit - Selected LQShader-Renderer
02:39:30 M: 37765120  NOTICE: running thread: video_thread
02:39:30 M: 37765120   DEBUG: CDVDPlayerVideo - CDVDMsg::VIDEO_SET_ASPECT
02:39:30 M: 37765120   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC
02:39:30 M: 37765120   DEBUG: CDVDPlayerVideo:: Resync - clock:-39999, delay:0
02:39:30 M: 35885056    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25 fps
02:39:32 M: 35729408    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
02:39:32 M: 35631104  NOTICE: Opening audio stream: 3
02:39:32 M: 35672064  NOTICE:  fps: 25.000000, pwidth: 720, pheight: 576, dwidth: 1024, dheight: 576
02:39:32 M: 35672064  NOTICE: Finding audio codec for: 86020
02:39:32 M: 35655680  NOTICE: Display resolution USER : PAL 16:9 (7)
02:39:32 M: 35655680   DEBUG: FactoryCodec - Audio: passthrough - Opening
02:39:32 M: 35655680   DEBUG: FactoryCodec - Audio: passthrough - Failed
02:39:32 M: 35532800   DEBUG: FactoryCodec - Audio: liba52 - Opening
02:39:32 M: 34869248   DEBUG: Created YV12 texture 0
02:39:32 M: 34168832   DEBUG: FactoryCodec - Audio: liba52 - Opened
02:39:32 M: 33742848  NOTICE: Creating audio thread
02:39:32 M: 33218560  NOTICE: running thread: CDVDPlayerAudio::Process()
02:39:32 M: 32894976  NOTICE: Creating audio device with codec id: 86020, channels: 2, sample rate: 48000, no pass-through
02:39:33 M: 31830016   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
02:39:33 M: 31830016   DEBUG: CDVDPlayerAudio:: Resync - clock:32001, delay:7644
02:39:33 M: 31834112   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC
02:39:33 M: 31391744   DEBUG: CDVDPlayerAudio:: Discontinuty - was:294404, should be:288672, error:-5732