18:31:31 M: 57991168 NOTICE: ----------------------------------------------------------------------- 18:31:31 M: 57970688 NOTICE: Starting XBMC, Platform: Windows. Built on Jan 14 2008 18:31:31 M: 57970688 NOTICE: Q is mapped to: E:\XBMC 18:31:31 M: 57970688 NOTICE: The executeable running is: E:\XBMC\default.xbe 18:31:31 M: 57970688 NOTICE: Log File is located: Q:\xbmc.log 18:31:31 M: 57970688 NOTICE: ----------------------------------------------------------------------- 18:31:31 M: 57970688 NOTICE: Unmapped drive T 18:31:31 M: 57970688 NOTICE: Mapping drive T to Harddisk0\Partition1\XBMC\userdata 18:31:31 M: 57970688 NOTICE: Setup DirectX 18:31:31 M: 57946112 NOTICE: Mapping drive C to Harddisk0\Partition2 18:31:31 M: 57946112 NOTICE: Mapping drive E to Harddisk0\Partition1 18:31:31 M: 57946112 NOTICE: Unmapped drive D 18:31:31 M: 57946112 NOTICE: Mapping drive D to Cdrom0 18:31:33 M: 57942016 NOTICE: Mapping drive F to Harddisk0\Partition6 18:31:37 M: 57942016 NOTICE: Mapping drive G to Harddisk0\Partition7 18:31:38 M: 57942016 NOTICE: Mapping drive X to Harddisk0\Partition3 18:31:38 M: 57942016 NOTICE: Mapping drive Y to Harddisk0\Partition4 18:31:38 M: 57942016 NOTICE: Unmapped drive Z 18:31:38 M: 57942016 NOTICE: Mapping drive Z to Harddisk0\Partition5 18:31:38 M: 57942016 NOTICE: load settings... 18:31:38 M: 57942016 NOTICE: Mapping drive P to Harddisk0\Partition1\XBMC\userdata 18:31:38 M: 57942016 NOTICE: loading T:\guisettings.xml 18:31:38 M: 57724928 NOTICE: Getting hardware information now... 18:31:38 M: 57724928 NOTICE: Checking resolution 5 18:31:38 M: 57724928 NOTICE: Per AV pack settings are off 18:31:38 M: 57876480 NOTICE: q:\userdata\sources.xml 18:31:38 M: 57876480 INFO: Key combination detected for full debug logging (X+Y) 18:31:38 M: 57876480 INFO: Checking skinpath existance, and existence of keymap.xml:Q:\skin... 18:31:38 M: 52232192 INFO: load language info file:Q:\language\english\langinfo.xml 18:31:38 M: 51707904 INFO: load language file:Q:\language\english\strings.xml 18:31:38 M: 51544064 INFO: load keymapping 18:31:38 M: 51544064 INFO: Loading P:\keymap.xml 18:31:38 M: 50077696 INFO: Checking skin version of: Project Mayhem III SE 18:31:38 M: 50085888 INFO: Skin version is: 2.1 18:31:38 M: 50085888 INFO: GUI format 720x480 NTSC 16:9 18:31:38 M: 50085888 INFO: install unhandled exception filter 18:31:38 M: 50085888 INFO: creating subdirectories 18:31:38 M: 50085888 INFO: userdata folder: q:\userdata 18:31:38 M: 50085888 INFO: recording folder: 18:31:38 M: 47996928 INFO: screenshots folder: 18:31:38 M: 47996928 INFO: thumbnails folder:q:\userdata\Thumbnails 18:31:38 M: 51474432 NOTICE: Checking the Date! 18:31:38 M: 51474432 INFO: - Current Date is: 2-2-2008 18:31:38 M: 51474432 NOTICE: start dvd mediatype detection 18:31:38 M: 51404800 NOTICE: initializing playlistplayer 18:31:38 M: 51404800 NOTICE: DONE initializing playlistplayer 18:31:38 M: 51404800 INFO: lcd not used 18:31:38 M: 51404800 NOTICE: start fancontroller 18:31:38 M: 50872320 NOTICE: load default skin:[Project Mayhem III SE] 18:31:38 M: 50872320 INFO: load skin from:Q:\skin\Project Mayhem III SE 18:31:38 M: 50872320 INFO: delete old skin... 18:31:39 M: 50872320 INFO: Default 4:3 resolution directory is Q:\skin\Project Mayhem III SE\pal 18:31:39 M: 50872320 INFO: Default 16:9 resolution directory is Q:\skin\Project Mayhem III SE\pal16x9 18:31:39 M: 50872320 INFO: Skin version is: 2.1 18:31:39 M: 50872320 INFO: Loading skin includes from Q:\skin\Project Mayhem III SE\pal\includes.xml 18:31:39 M: 49618944 NOTICE: Dismounted Cdrom0 18:31:39 M: 49999872 NOTICE: Unmapped drive D 18:31:39 M: 49999872 NOTICE: Mapping drive D to Cdrom0 18:31:39 M: 49897472 INFO: Detecting DVD-ROM media filesystem... 18:31:39 M: 49577984 INFO: load fonts for skin... 18:31:39 M: 49577984 INFO: Loading fonts from Q:\skin\Project Mayhem III SE\ntsc16x9\Font.xml 18:31:39 M: 49516544 INFO: CD Analysis Report 18:31:39 M: 49496064 INFO: __________________________________ 18:31:39 M: 49496064 DEBUG: CGUIFontTTF::Load Scaled size of font Q:\skin\Project Mayhem III SE\Fonts\FrancophilSans.ttf (10.924999): width = 13, height = 14, lineheight = 13 18:31:39 M: 49442816 INFO: CD-ROM with both UDF and ISO 9660 filesystem 18:31:39 M: 49422336 DEBUG: CGUIFontTTF::Load Scaled size of font Q:\skin\Project Mayhem III SE\Fonts\FrancophilSans.ttf (13.656249): width = 16, height = 17, lineheight = 16 18:31:39 M: 49414144 INFO: ISO 9660: 2286002 blocks, label `THE_LIFE_OF_DAVID_GALE ' 18:31:39 M: 49352704 DEBUG: CGUIFontTTF::Load Scaled size of font Q:\skin\Project Mayhem III SE\Fonts\FrancophilSans.ttf (14.566667): width = 17, height = 18, lineheight = 17 18:31:39 M: 49352704 INFO: UDF: version 1.02 18:31:39 M: 49324032 INFO: Tracks overall:1; Audio tracks:0; Data tracks:1 18:31:39 M: 49283072 DEBUG: CGUIFontTTF::Load Scaled size of font Q:\skin\Project Mayhem III SE\Fonts\FrancophilSans.ttf (15.477083): width = 18, height = 19, lineheight = 19 18:31:40 M: 49242112 INFO: Using protocol iso9660:// 18:31:40 M: 49242112 INFO: Disc label: THE_LIFE_OF_DAVID_GALE 18:31:40 M: 49242112 DEBUG: CGUIFontTTF::Load Scaled size of font Q:\skin\Project Mayhem III SE\Fonts\evt_nfo.ttf (11.835416): width = 5, height = 13, lineheight = 12 18:31:40 M: 49238016 ERROR: unable to load Q:\skin\Project Mayhem III SE\language\english\strings.xml: Failed to open file at line 0 18:31:40 M: 49254400 DEBUG: MEDIA_DETECT::CDetectDVDMedia::SetNewDVDShareUrl: looking for disc thumb:[iso9660://folder.jpg] 18:31:40 M: 49254400 INFO: load new skin... 18:31:40 M: 49254400 INFO: Skin version is: 2.1 18:31:40 M: 49254400 INFO: Loading skin file: home.xml 18:31:40 M: 48156672 INFO: Loading user windows, path Q:\skin\Project Mayhem III SE\pal16x9 18:31:40 M: 48156672 INFO: Loading user windows, path Q:\skin\Project Mayhem III SE\pal 18:31:40 M: 48156672 INFO: Loading skin file: Q:\skin\Project Mayhem III SE\pal\custom2_SkinSettings.xml 18:31:40 M: 48152576 INFO: Loading skin file: Q:\skin\Project Mayhem III SE\pal\customx_RSSFeeds.xml 18:31:40 M: 48152576 INFO: initialize new skin... 18:31:40 M: 48152576 INFO: Loading skin file: Pointer.xml 18:31:40 M: 48119808 INFO: Loading skin file: DialogVolumeBar.xml 18:31:40 M: 48111616 INFO: Loading skin file: DialogSeekbar.xml 18:31:40 M: 48074752 INFO: Loading skin file: DialogKaiToast.xml 18:31:40 M: 48074752 INFO: Loading skin file: DialogMuteBug.xml 18:31:40 M: 47677440 INFO: Loading Q:\skin\Project Mayhem III SE\sounds\sounds.xml 18:31:40 M: 47677440 INFO: skin loaded... 18:31:40 M: 47140864 INFO: Loading skin file: DialogBusy.xml 18:31:40 M: 47677440 DEBUG: Activating window ID: 12999 18:31:40 M: 47677440 DEBUG: Checking if window ID 12999 is locked. 18:31:40 M: 47247360 INFO: Loading skin file: Startup.xml 18:31:40 M: 47247360 NOTICE: use DHCP 18:31:40 M: 46931968 INFO: removing tempfiles 18:31:40 M: 46931968 NOTICE: initialize done 18:31:40 M: 46931968 INFO: Key combination detected for full debug logging (X+Y) 18:31:40 M: 46931968 NOTICE: Running the application... 18:31:40 M: 43925504 INFO: CNetwork::LogState - Link: full duplex 18:31:40 M: 43925504 INFO: CNetwork::LogState - Link: 100 mbps 18:31:40 M: 43925504 INFO: CNetwork::LogState - ip: 18:31:40 M: 43925504 INFO: CNetwork::LogState - subnet: 18:31:40 M: 43925504 INFO: CNetwork::LogState - gateway: 18:31:40 M: 43925504 DEBUG: CApplication::OnMessage : Translating ReplaceWindow(Home) 18:31:40 M: 43925504 INFO: CNetwork::LogState - dns: , 18:31:40 M: 43925504 DEBUG: CApplication::OnMessage : To ReplaceWindow(Home) 18:31:40 M: 43925504 DEBUG: Activating window ID: 10000 18:31:40 M: 43925504 DEBUG: Checking if window ID 10000 is locked. 18:31:41 M: 43294720 INFO: CNetwork::LogState - Link: full duplex 18:31:41 M: 43294720 INFO: CNetwork::LogState - Link: 100 mbps 18:31:41 M: 43294720 INFO: CNetwork::LogState - State: dns 18:31:41 M: 43294720 INFO: CNetwork::LogState - State: dhcp 18:31:41 M: 43294720 INFO: CNetwork::LogState - ip: 192.168.1.119 18:31:41 M: 43294720 INFO: CNetwork::LogState - subnet: 255.255.255.0 18:31:41 M: 43294720 INFO: CNetwork::LogState - gateway: 192.168.1.1 18:31:41 M: 43294720 INFO: CNetwork::LogState - dns: 192.168.1.1, 0.0.0.0 18:31:41 M: 43294720 INFO: WEATHER: Downloading weather 18:31:41 M: 43294720 INFO: Get URL: http://xoap.weather.com/weather/local/CAXX0539?cc=*&unit=m&dayf=4&prod=xoap&par=1004124588&key=079f24145f208494 18:31:41 M: 43294720 INFO: Get URL: http://www.xbox-hq.com/html/backend.php 18:31:41 M: 43294720 DEBUG: CNetwork::NetworkMessage - Starting network services 18:31:41 M: 43294720 NOTICE: start timeserver client 18:31:41 M: 43229184 NOTICE: XBFileZilla: Starting... 18:31:41 M: 41959424 NOTICE: XBFileZilla: Started 18:31:41 M: 41996288 DEBUG: Got rss feed: http://www.xbox-hq.com/html/backend.php 18:31:41 M: 41996288 DEBUG: RSS feed encoding: ISO-8859-1 18:31:41 M: 41996288 DEBUG: Parsed rss feed: http://www.xbox-hq.com/html/backend.php 18:31:41 M: 42098688 INFO: WEATHER: Weather download successful 18:31:44 M: 42594304 DEBUG: CApplication::OnKey: 271 pressed, action is 4 18:31:44 M: 41824256 DEBUG: CApplication::OnKey: 271 pressed, action is 4 18:31:44 M: 42569728 DEBUG: CApplication::OnKey: 271 pressed, action is 4 18:31:45 M: 42569728 DEBUG: CApplication::OnKey: 271 pressed, action is 4 18:31:45 M: 42491904 DEBUG: CApplication::OnKey: 271 pressed, action is 4 18:31:45 M: 43622400 DEBUG: CApplication::OnKey: 271 pressed, action is 4 18:31:46 M: 42876928 DEBUG: CApplication::OnKey: 256 pressed, action is 7 18:31:46 M: 42864640 DEBUG: CApplication::OnMessage : Translating ActivateWindow(Settings) 18:31:46 M: 42864640 DEBUG: CApplication::OnMessage : To ActivateWindow(Settings) 18:31:46 M: 42864640 DEBUG: Activating window ID: 10004 18:31:46 M: 42864640 DEBUG: Checking if window ID 10004 is locked. 18:31:46 M: 46641152 INFO: Loading skin file: Settings.xml 18:31:46 M: 44752896 DEBUG: CApplication::OnKey: 273 pressed, action is 2 18:31:47 M: 44752896 DEBUG: CApplication::OnKey: 271 pressed, action is 4 18:31:47 M: 44752896 DEBUG: CApplication::OnKey: 273 pressed, action is 2 18:31:48 M: 44752896 DEBUG: CApplication::OnKey: 256 pressed, action is 7 18:31:48 M: 44740608 DEBUG: CApplication::OnMessage : Translating ActivateWindow(SystemSettings) 18:31:48 M: 44740608 DEBUG: CApplication::OnMessage : To ActivateWindow(SystemSettings) 18:31:48 M: 44740608 DEBUG: Activating window ID: 10016 18:31:48 M: 44740608 DEBUG: Checking if window ID 10016 is locked. 18:31:48 M: 46637056 INFO: Loading skin file: SettingsCategory.xml 18:31:49 M: 45576192 DEBUG: CApplication::OnKey: 271 pressed, action is 4 18:31:49 M: 45551616 DEBUG: CApplication::OnKey: 271 pressed, action is 4 18:31:49 M: 45559808 DEBUG: CApplication::OnKey: 271 pressed, action is 4 18:31:50 M: 45559808 DEBUG: CApplication::OnKey: 271 pressed, action is 4 18:31:50 M: 45551616 DEBUG: CApplication::OnKey: 271 pressed, action is 4 18:31:51 M: 45584384 DEBUG: CApplication::OnKey: 273 pressed, action is 2 18:31:52 M: 45584384 DEBUG: CApplication::OnKey: 271 pressed, action is 4 18:31:55 M: 45584384 DEBUG: CApplication::OnKey: 272 pressed, action is 1 18:31:56 M: 45584384 DEBUG: CApplication::OnKey: 271 pressed, action is 4 18:31:56 M: 45584384 DEBUG: CApplication::OnKey: 256 pressed, action is 7 18:31:58 M: 45584384 DEBUG: CApplication::OnKey: 271 pressed, action is 4 18:31:58 M: 45576192 DEBUG: CApplication::OnKey: 270 pressed, action is 3 18:31:59 M: 45559808 DEBUG: CApplication::OnKey: 273 pressed, action is 2 18:31:59 M: 45584384 DEBUG: CApplication::OnKey: 271 pressed, action is 4 18:31:59 M: 45584384 DEBUG: CApplication::OnKey: 270 pressed, action is 3 18:32:00 M: 45584384 DEBUG: CApplication::OnKey: 256 pressed, action is 7 18:32:00 M: 45584384 DEBUG: CApplication::OnKey: 271 pressed, action is 4 18:32:00 M: 45559808 DEBUG: CApplication::OnKey: 271 pressed, action is 4 18:32:00 M: 45559808 DEBUG: CApplication::OnKey: 256 pressed, action is 7 18:32:02 M: 45584384 DEBUG: CApplication::OnKey: 256 pressed, action is 7 18:32:04 M: 45584384 DEBUG: CApplication::OnKey: 257 pressed, action is 10 18:32:05 M: 46641152 INFO: Loading skin file: Settings.xml 18:32:06 M: 44748800 DEBUG: CApplication::OnKey: 257 pressed, action is 10 18:32:06 M: 46641152 INFO: Loading skin file: Home.xml 18:32:08 M: 42512384 DEBUG: CApplication::OnKey: 270 pressed, action is 3 18:32:08 M: 43163648 DEBUG: CApplication::OnKey: 270 pressed, action is 3 18:32:08 M: 42442752 DEBUG: CApplication::OnKey: 270 pressed, action is 3 18:32:09 M: 41672704 DEBUG: CApplication::OnKey: 270 pressed, action is 3 18:32:09 M: 42418176 DEBUG: CApplication::OnKey: 270 pressed, action is 3 18:32:11 M: 42442752 DEBUG: CApplication::OnKey: 256 pressed, action is 7 18:32:11 M: 42430464 DEBUG: CApplication::OnMessage : Translating ActivateWindow(MyVideos) 18:32:11 M: 42430464 DEBUG: CApplication::OnMessage : To ActivateWindow(MyVideos) 18:32:11 M: 42430464 DEBUG: Activating window ID: 10024 18:32:11 M: 42430464 DEBUG: Checking if window ID 10024 is locked. 18:32:11 M: 46612480 INFO: Attempting to default to: 18:32:11 M: 46600192 INFO: Loading skin file: MyVideo.xml 18:32:11 M: 46018560 DEBUG: CGUIMediaWindow::GetDirectory () 18:32:11 M: 46063616 DEBUG: ParentPath = [] 18:32:12 M: 45080576 DEBUG: CApplication::OnKey: 256 pressed, action is 7 18:32:12 M: 45068288 DEBUG: Clearing cached fileitems [G:\Movies AVI\] 18:32:12 M: 45068288 ERROR: XFILE::CFile::Delete - Error deleting file Z:\969069cc.fi 18:32:12 M: 45146112 DEBUG: CGUIMediaWindow::GetDirectory (G:\Movies AVI\) 18:32:12 M: 45146112 DEBUG: ParentPath = [] 18:32:15 M: 44998656 DEBUG: CApplication::OnKey: 272 pressed, action is 1 18:32:15 M: 44994560 DEBUG: CApplication::OnKey: 272 pressed, action is 1 18:32:19 M: 45019136 DEBUG: CApplication::OnKey: 256 pressed, action is 7 18:32:19 M: 45006848 DEBUG: CPlayerCoreFactor::GetPlayers(G:\Movies AVI\Saw IV.avi) 18:32:19 M: 44478464 INFO: mplayer play:G:\Movies AVI\Saw IV.avi cachesize:2048 18:32:19 M: 44478464 INFO: Loading skin file: DialogProgress.xml 18:32:19 M: 44478464 DEBUG: CUtil::CacheSubtitles: START 18:32:19 M: 44478464 DEBUG: CUtil::CacheSubtitles: Checking for common subirs... 18:32:19 M: 44478464 DEBUG: CUtil::CacheSubtitles: Done (time: 21 ms) 18:32:19 M: 44478464 DEBUG: CUtil::CacheSubtitles: Searching for subtitles... 18:32:19 M: 44478464 DEBUG: CUtil::CacheSubtitles: Done (time: 5 ms) 18:32:19 M: 44478464 DEBUG: CUtil::CacheSubtitles: END (total time: 48 ms) 18:32:19 M: 38596608 DEBUG: msg: set audio functions called 18:32:19 M: 38596608 DEBUG: msg: done 18:32:19 M: 38596608 INFO: Using -subcp enca:__:CP1252 to detect the subtitle charset 18:32:19 M: 38596608 INFO: Flipping bi-directional subtitles disabled 18:32:19 M: 38596608 DEBUG: msg: mplayer_init() 18:32:19 M: 38596608 DEBUG: msg: MPlayer XBMC-SVN-r10070-4.1.2 (C) 2000-2004 MPlayer Team 18:32:19 M: 38596608 DEBUG: msg: CPU: Intel Celeron 2/Pentium III Coppermine,Geyserville 18:32:19 M: 38596608 DEBUG: msg: (Family: 6, Stepping: 10) 18:32:19 M: 38596608 DEBUG: msg: Detected cache-line size is 32 bytes 18:32:19 M: 38596608 DEBUG: msg: CPUflags: MMX: 1 MMX2: 1 3DNow: 0 3DNow2: 0 SSE: 0 SSE2: 0 18:32:19 M: 38596608 DEBUG: msg: Compiled for x86 CPU with extensions: 18:32:19 M: 38596608 DEBUG: msg: MMX 18:32:19 M: 38596608 DEBUG: msg: MMX2 18:32:19 M: 38596608 DEBUG: msg: SSE 18:32:19 M: 38596608 DEBUG: msg: 18:32:19 M: 38584320 DEBUG: msg: 18:32:19 M: 38596608 DEBUG: msg: Creating config file: Q:\system\players\mplayer\config 18:32:19 M: 38584320 DEBUG: msg: 18:32:19 M: 38596608 DEBUG: msg: get_path('codecs.conf') -> 'Q:\system\players\mplayer\codecs.conf' 18:32:19 M: 38596608 DEBUG: msg: Reading Q:\system\players\mplayer\codecs.conf: 18:32:19 M: 38268928 DEBUG: msg: 108 audio & 225 video codecs 18:32:19 M: 38268928 DEBUG: msg: CommandLine: 18:32:19 M: 38268928 DEBUG: msg: '-dr' 18:32:19 M: 38268928 DEBUG: msg: '-v' 18:32:19 M: 38268928 DEBUG: msg: '-cache-min' 18:32:19 M: 38268928 DEBUG: msg: '5.0000' 18:32:19 M: 38268928 DEBUG: msg: '-cache-prefill' 18:32:19 M: 38268928 DEBUG: msg: '5.0000' 18:32:19 M: 38268928 DEBUG: msg: '-subcp' 18:32:19 M: 38268928 DEBUG: msg: 'enca:__:CP1252' 18:32:19 M: 38268928 DEBUG: msg: '-channels' 18:32:19 M: 38268928 DEBUG: msg: '6' 18:32:19 M: 38268928 DEBUG: msg: '-a52drc' 18:32:19 M: 38268928 DEBUG: msg: '1' 18:32:19 M: 38268928 DEBUG: msg: '-noflip-hebrew' 18:32:19 M: 38268928 DEBUG: msg: '-noflip-hebrew-commas' 18:32:19 M: 38268928 DEBUG: msg: '-fs' 18:32:19 M: 38268928 DEBUG: msg: '1.avi' 18:32:19 M: 38268928 DEBUG: msg: 18:32:19 M: 38268928 DEBUG: msg: get_path('font/font.desc') -> 'Q:\system\players\mplayer\font/font.desc' 18:32:19 M: 37740544 DEBUG: msg: font: can't open file: Q:\system\players\mplayer\font/font.desc 18:32:19 M: 37740544 DEBUG: msg: font: can't open file: Q:\system\players\mplayer/font/font.desc 18:32:19 M: 38268928 DEBUG: msg: Using MMX (with tiny bit MMX2) Optimized OnScreenDisplay 18:32:19 M: 38236160 DEBUG: msg: Using XBOX High Resolution Timers for timing 18:32:19 M: 38236160 DEBUG: msg: get_path('input.conf') -> 'Q:\system\players\mplayer\input.conf' 18:32:19 M: 38236160 DEBUG: msg: Parsing input config file Q:\system\players\mplayer\input.conf 18:32:19 M: 38232064 DEBUG: msg: Input config file Q:\system\players\mplayer\input.conf parsed: 51 binds 18:32:19 M: 38232064 DEBUG: msg: mplayer_open_file(G:\Movies AVI\Saw IV.avi) 18:32:19 M: 38232064 DEBUG: msg: get_path('G:\Movies AVI\Saw IV.avi.conf') -> 'Q:\system\players\mplayer\G:\Movies AVI\Saw IV.avi.conf' 18:32:19 M: 38232064 DEBUG: msg: Playing G:\Movies AVI\Saw IV.avi. 18:32:19 M: 38232064 DEBUG: msg: find subtitles 18:32:19 M: 38232064 DEBUG: msg: auto open Z:\subtitle 18:32:19 M: 38211584 DEBUG: msg: WINSOCK2 init: 0 18:32:19 M: 38211584 DEBUG: msg: [file] File size is 733450240 bytes 18:32:19 M: 38211584 DEBUG: msg: STREAM: [file] G:\Movies AVI\Saw IV.avi 18:32:19 M: 38211584 DEBUG: msg: STREAM: Description: File 18:32:19 M: 38211584 DEBUG: msg: STREAM: Author: Albeu 18:32:19 M: 38211584 DEBUG: msg: STREAM: Comment: based on the code from ??? (probably Arpi) 18:32:19 M: 35561472 DEBUG: msg: CACHE_PRE_INIT: 0 [0] 0 pre:104857 eof:0 18:32:19 M: 35561472 DEBUG: msg: Cache fill: 0.00% (0 bytes) 18:32:20 M: 35561472 DEBUG: msg: 18:32:20 M: 35561472 DEBUG: msg: demux_open(00BE8010,0,-1,-1,-1) 18:32:20 M: 35561472 DEBUG: msg: AVI file format detected. 18:32:20 M: 35561472 DEBUG: msg: list_end=0x2286 18:32:20 M: 35561472 DEBUG: msg: ======= AVI Header ======= 18:32:20 M: 35561472 DEBUG: msg: us/frame: 40000 (fps=25.000) 18:32:20 M: 35561472 DEBUG: msg: max bytes/sec: 0 18:32:20 M: 35561472 DEBUG: msg: padding: 0 18:32:20 M: 35561472 DEBUG: msg: MainAVIHeader.dwFlags: (272) HAS_INDEX IS_INTERLEAVED 18:32:20 M: 35561472 DEBUG: msg: frames total: 133176 initial: 0 18:32:20 M: 35561472 DEBUG: msg: streams: 2 18:32:20 M: 35561472 DEBUG: msg: Suggested BufferSize: 0 18:32:20 M: 35561472 DEBUG: msg: Size: 624 x 336 18:32:20 M: 35561472 DEBUG: msg: ========================== 18:32:20 M: 35561472 DEBUG: msg: list_end=0x10F4 18:32:20 M: 35561472 DEBUG: msg: ==> Found video stream: 0 18:32:20 M: 35561472 DEBUG: msg: ====== STREAM Header ===== 18:32:20 M: 35561472 DEBUG: msg: Type: vids FCC: divx (78766964) 18:32:20 M: 35561472 DEBUG: msg: Flags: 0 18:32:20 M: 35561472 DEBUG: msg: Priority: 0 Language: 0 18:32:20 M: 35561472 DEBUG: msg: InitialFrames: 0 18:32:20 M: 35561472 DEBUG: msg: Rate: 25/1 = 25.000 18:32:20 M: 35561472 DEBUG: msg: Start: 0 Len: 133176 18:32:20 M: 35561472 DEBUG: msg: Suggested BufferSize: 161085 18:32:20 M: 35561472 DEBUG: msg: Quality 10000 18:32:20 M: 35561472 DEBUG: msg: Sample size: 0 18:32:20 M: 35561472 DEBUG: msg: ========================== 18:32:20 M: 35561472 DEBUG: msg: Found 'bih', 40 bytes of 40 18:32:20 M: 35561472 DEBUG: msg: ======= VIDEO Format ====== 18:32:20 M: 35561472 DEBUG: msg: biSize 40 18:32:20 M: 35561472 DEBUG: msg: biWidth 624 18:32:20 M: 35561472 DEBUG: msg: biHeight 336 18:32:20 M: 35561472 DEBUG: msg: biPlanes 1 18:32:20 M: 35561472 DEBUG: msg: biBitCount 24 18:32:20 M: 35561472 DEBUG: msg: biCompression 808802372='DX50' 18:32:20 M: 35561472 DEBUG: msg: biSizeImage 628992 18:32:20 M: 35561472 DEBUG: msg: =========================== 18:32:20 M: 35561472 DEBUG: msg: Regenerating keyframe table for MPEG-4 video. 18:32:20 M: 35561472 DEBUG: msg: list_end=0x217A 18:32:20 M: 35561472 DEBUG: msg: ==> Found audio stream: 1 18:32:20 M: 35561472 DEBUG: msg: ====== STREAM Header ===== 18:32:20 M: 35561472 DEBUG: msg: Type: auds FCC: (0) 18:32:20 M: 35561472 DEBUG: msg: Flags: 0 18:32:20 M: 35561472 DEBUG: msg: Priority: 0 Language: 0 18:32:20 M: 35561472 DEBUG: msg: InitialFrames: 1 18:32:20 M: 35561472 DEBUG: msg: Rate: 20000/1 = 20000.000 18:32:20 M: 35561472 DEBUG: msg: Start: 0 Len: 106539520 18:32:20 M: 35561472 DEBUG: msg: Suggested BufferSize: 10000 18:32:20 M: 35561472 DEBUG: msg: Quality -1 18:32:20 M: 35561472 DEBUG: msg: Sample size: 1 18:32:20 M: 35561472 DEBUG: msg: ========================== 18:32:20 M: 35561472 DEBUG: msg: Found 'wf', 18 bytes of 18 18:32:20 M: 35561472 DEBUG: msg: ======= WAVE Format ======= 18:32:20 M: 35561472 DEBUG: msg: Format Tag: 8192 (0x2000) 18:32:20 M: 35561472 DEBUG: msg: Channels: 2 18:32:20 M: 35561472 DEBUG: msg: Samplerate: 48000 18:32:20 M: 35561472 DEBUG: msg: avg byte/sec: 20000 18:32:20 M: 35561472 DEBUG: msg: Block align: 1 18:32:20 M: 35561472 DEBUG: msg: bits/sample: 0 18:32:20 M: 35561472 DEBUG: msg: cbSize: 0 18:32:20 M: 35561472 DEBUG: msg: =========================== 18:32:20 M: 35561472 DEBUG: msg: list_end=0x2286 18:32:20 M: 35561472 DEBUG: msg: AVI: dmlh found (size=248) (total_frames=133176) 18:32:20 M: 35561472 DEBUG: msg: list_end=0x22C6 18:32:20 M: 35561472 DEBUG: msg: hdr=Software size=44 18:32:20 M: 35561472 DEBUG: msg: Software : VirtualDubMod 1.5.10.2 (build 2540/release) 18:32:20 M: 35561472 DEBUG: msg: list_end=0x2B768470 18:32:20 M: 35561472 DEBUG: msg: Found movie at 0x280C - 0x2B768470 18:32:20 M: 35561472 DEBUG: msg: Reading INDEX block, 266339 chunks for 133176 frames (fpos=729187448). 18:32:20 M: 31293440 DEBUG: msg: AVI index offset: 0x2808 (movi=0x280C idx0=0x4 idx1=0x271C) 18:32:20 M: 31293440 DEBUG: msg: Auto-selected AVI audio ID = 1 18:32:20 M: 31293440 DEBUG: msg: Auto-selected AVI video ID = 0 18:32:20 M: 31281152 DEBUG: msg: AVI: Searching for audio stream (id:1) 18:32:20 M: 31281152 DEBUG: msg: AVI video size=620448277 (133176) audio size=106539520 (106539520) 18:32:20 M: 31281152 DEBUG: msg: [V] filefmt:3 fourcc:0x30355844 size:624x336 fps:25.00 ftime:=0.0400 18:32:20 M: 31281152 DEBUG: msg: Clip info: 18:32:20 M: 31281152 DEBUG: msg: Software: VirtualDubMod 1.5.10.2 (build 2540/release) 18:32:20 M: 31281152 DEBUG: msg: get_path('sub/') -> 'Q:\system\players\mplayer\sub/' 18:32:20 M: 31281152 DEBUG: msg: Starting lookup for subs 18:32:20 M: 31281152 DEBUG: msg: Global Subsize: 0 - VOBSUB: -1, DEMUX: -1, SUBS: -1 18:32:20 M: 31281152 DEBUG: msg: Global Subpos : -1 18:32:20 M: 31281152 DEBUG: msg: ========================================================================== 18:32:20 M: 31281152 DEBUG: msg: Opening audio decoder: [liba52] AC3 decoding with liba52 18:32:20 M: 31281152 DEBUG: msg: dec_audio: Allocating 3840 bytes for input buffer. 18:32:20 M: 31281152 DEBUG: msg: dec_audio: Allocating 18432 + 65536 = 83968 bytes for output buffer. 18:32:20 M: 31178752 DEBUG: No accelerated IMDCT transform found 18:32:20 M: 31178752 DEBUG: msg: AC3: 2.0 (stereo) 48000 Hz 160.0 kbit/s 18:32:20 M: 31178752 DEBUG: msg: A52 flags before a52_frame: 0x37 18:32:20 M: 31178752 DEBUG: msg: A52 flags after a52_frame: 0x2 18:32:20 M: 31178752 INFO: msg: Unimplemented resampler for mode 0x2 -> 6 channels conversion - Contact MPlayer developers! 18:32:20 M: 31178752 DEBUG: msg: A52 flags before a52_frame: 0x27 18:32:20 M: 31178752 DEBUG: msg: A52 flags after a52_frame: 0x2 18:32:20 M: 31178752 INFO: msg: Unimplemented resampler for mode 0x2 -> 5 channels conversion - Contact MPlayer developers! 18:32:20 M: 31178752 DEBUG: msg: A52 flags before a52_frame: 0x26 18:32:20 M: 31178752 DEBUG: msg: A52 flags after a52_frame: 0x2 18:32:20 M: 31178752 INFO: msg: Unimplemented resampler for mode 0x2 -> 4 channels conversion - Contact MPlayer developers! 18:32:20 M: 31178752 DEBUG: msg: A52 flags before a52_frame: 0x24 18:32:20 M: 31178752 DEBUG: msg: A52 flags after a52_frame: 0x2 18:32:20 M: 31178752 INFO: msg: Unimplemented resampler for mode 0x2 -> 3 channels conversion - Contact MPlayer developers! 18:32:20 M: 31178752 DEBUG: msg: A52 flags before a52_frame: 0x2A 18:32:20 M: 31178752 DEBUG: msg: A52 flags after a52_frame: 0x2 18:32:20 M: 31178752 DEBUG: Using MMX optimized resampler 18:32:20 M: 31178752 DEBUG: msg: AUDIO: 48000 Hz, 2 ch, 16 bit (0x10), ratio: 20000->192000 (160.0 kbit) 18:32:20 M: 31178752 DEBUG: msg: Selected audio codec: [a52] afm:liba52 (AC3-liba52) 18:32:20 M: 31178752 DEBUG: msg: ========================================================================== 18:32:20 M: 31178752 DEBUG: CXBoxRenderManager::PreInit - Selected RGB-Renderer 18:32:20 M: 31178752 DEBUG: msg: ========================================================================== 18:32:20 M: 31178752 DEBUG: msg: Opening video decoder: [ffmpeg] FFmpeg's libavcodec codec family 18:32:20 M: 30924800 DEBUG: msg: INFO: libavcodec init OK! 18:32:20 M: 30924800 DEBUG: msg: Selected video codec: [ffodivx] vfm:ffmpeg (FFmpeg MPEG-4) 18:32:20 M: 30924800 DEBUG: msg: ========================================================================== 18:32:20 M: 30924800 DEBUG: msg: Checking audio filter chain for 48000Hz/2ch/16bit -> 48000Hz/6ch/16bit... 18:32:20 M: 30924800 DEBUG: msg: [libaf] Adding filter dummy 18:32:20 M: 30924800 DEBUG: msg: [dummy] Was reinitialized, rate=48000Hz, nch = 2, format = 0x00000001 and bps = 2 18:32:20 M: 30924800 DEBUG: msg: AF_pre: af format: 2 bps, 2 ch, 48000 hz, little endian signed int 18:32:20 M: 30924800 DEBUG: msg: AF_pre: 48000Hz 2ch Signed 16-bit (Little-Endian) 18:32:20 M: 30887936 DEBUG: msg: AO: [win32] 48000Hz 2ch Signed 16-bit (Little-Endian) (2 bps) 18:32:20 M: 30887936 DEBUG: msg: AO: Description: Windows waveOut audio output AO: Author: SaschaSommer 18:32:20 M: 30887936 DEBUG: msg: Building audio filter chain for 48000Hz/2ch/16bit -> 48000Hz/2ch/16bit... 18:32:20 M: 30887936 DEBUG: msg: [dummy] Was reinitialized, rate=48000Hz, nch = 2, format = 0x00000001 and bps = 2 18:32:20 M: 30887936 DEBUG: msg: [dummy] Was reinitialized, rate=48000Hz, nch = 2, format = 0x00000001 and bps = 2 18:32:20 M: 30818304 DEBUG: msg: Starting playback... 18:32:20 M: 30818304 DEBUG: msg: mplayer_open_file(G:\Movies AVI\Saw IV.avi) done 1.0000 18:32:20 M: 30294016 INFO: Loading skin file: VideoOverlay.xml 18:32:20 M: 30294016 DEBUG: Playback has started 18:32:20 M: 30408704 DEBUG: msg: [ffmpeg] aspect_ratio: 1.857143 18:32:20 M: 30408704 DEBUG: CGUIInfoManager::SetCurrentMovie(G:\Movies AVI\Saw IV.avi) 18:32:20 M: 30408704 DEBUG: msg: VDec: vo config request - 624 x 336 (preferred colorspace: Planar YV12) 18:32:20 M: 30392320 DEBUG: msg: Trying filter chain: 18:32:20 M: 30367744 DEBUG: msg: vo 18:32:20 M: 30388224 DEBUG: msg: 18:32:20 M: 30347264 DEBUG: msg: VDec: using Planar YV12 as output csp (no 0) 18:32:20 M: 30347264 DEBUG: msg: Movie-Aspect is 1.86:1 - prescaling to correct movie aspect. 18:32:20 M: 30347264 DEBUG: msg: VO Config (624x336->624x336,flags=1,'MPlayer',0x32315659) 18:32:20 M: 30347264 DEBUG: msg: VO: [directx] 624x336 => 624x336 Planar YV12 [fs] 18:32:20 M: 30347264 DEBUG: msg: VO: Description: XBOX Direct3D8 YUY2 renderer 18:32:20 M: 30347264 DEBUG: msg: VO: Author: Frodo/JCMarshall 18:32:20 M: 30388224 DEBUG: CVideoDatabase::GetMovieInfo(G:\Movies AVI\Saw IV.avi), query = select idMovie from movie where idFile=44 18:32:20 M: 30347264 DEBUG: mplayer::video_config(624, 336, 624, 336, 1, 842094169) 18:32:20 M: 30347264 NOTICE: Display resolution AUTO : 480p 16:9 (3) 18:32:20 M: 29327360 DEBUG: CVideoDatabase::GetEpisodeInfo(G:\Movies AVI\Saw IV.avi), query = select idEpisode from episode where idFile=44 18:32:20 M: 29331456 DEBUG: CVideoDatabase::GetMusicVideoInfo(G:\Movies AVI\Saw IV.avi), query = select idMVideo from musicvideo where idFile=44 18:32:20 M: 29884416 DEBUG: Activating window ID: 12005 18:32:20 M: 29884416 DEBUG: Checking if window ID 12005 is locked. 18:32:20 M: 31133696 DEBUG: Setting resolution 3 18:32:20 M: 33345536 ERROR: exception in CApplication::Process()