19:30:36 M: 56016896  NOTICE: -----------------------------------------------------------------------
19:30:36 M: 55996416  NOTICE: Starting XBMC, Platform: Xbox.  Built on May 19 2008
19:30:36 M: 55996416  NOTICE: Q is mapped to: E:\apps\xbmc
19:30:36 M: 55996416  NOTICE: The executeable running is: E:\apps\xbmc\default.xbe
19:30:36 M: 55996416  NOTICE: Log File is located: Q:\xbmc.log
19:30:36 M: 55996416  NOTICE: -----------------------------------------------------------------------
19:30:36 M: 55996416  NOTICE: Unmapped drive T
19:30:36 M: 55996416  NOTICE: Mapping drive T to Harddisk0\Partition1\apps\xbmc\UserData
19:30:36 M: 55996416  NOTICE: Setup DirectX
19:30:36 M: 55975936  NOTICE: Mapping drive C to Harddisk0\Partition2
19:30:36 M: 55975936  NOTICE: Mapping drive E to Harddisk0\Partition1
19:30:36 M: 55975936  NOTICE: Unmapped drive D
19:30:36 M: 55975936  NOTICE: Mapping drive D to Cdrom0
19:30:36 M: 55975936  NOTICE: Mapping drive F to Harddisk0\Partition6
19:30:36 M: 55975936  NOTICE: Mapping drive X to Harddisk0\Partition3
19:30:36 M: 55975936  NOTICE: Mapping drive Y to Harddisk0\Partition4
19:30:36 M: 55975936  NOTICE: Unmapped drive Z
19:30:36 M: 55975936  NOTICE: Mapping drive Z to Harddisk0\Partition5
19:30:36 M: 55975936  NOTICE: load settings...
19:30:36 M: 55975936  NOTICE: Mapping drive P to Harddisk0\Partition1\apps\xbmc\UserData
19:30:36 M: 55975936  NOTICE: loading T:\guisettings.xml
19:30:36 M: 55652352  NOTICE: Getting hardware information now...
19:30:36 M: 55652352  NOTICE: Checking resolution 1
19:30:37 M: 55652352  NOTICE: Getting hardware information now...
19:30:37 M: 55631872    INFO: Using digital output
19:30:37 M: 55631872    INFO: AC3 pass through is enabled
19:30:37 M: 55631872    INFO: DTS pass through is enabled
19:30:37 M: 55631872  NOTICE: Checking resolution 1
19:30:37 M: 55631872    INFO: AV Pack: HDTV
19:30:37 M: 55631872    INFO: AV Flags: Widescreen,Pal60,480p,720p,1080i
19:30:37 M: 55631872  NOTICE: Per AV pack settings are off
19:30:37 M: 55894016  NOTICE: q:\UserData\sources.xml
19:30:37 M: 55889920   DEBUG:     Setting <default> source to : Games
19:30:37 M: 55869440    INFO: Checking skinpath existance, and existence of keymap.xml:Q:\skin...
19:30:37 M: 55869440   DEBUG: We set resolution 1
19:30:37 M: 47362048    INFO: load language info file:Q:\language\German\langinfo.xml
19:30:37 M: 46837760    INFO: load language file:Q:\language\German\strings.xml
19:30:37 M: 46665728    INFO: load keymapping
19:30:37 M: 46665728    INFO: Loading P:\Keymap.xml
19:30:37 M: 46469120   ERROR: Window Translator: Can't find window webbrowser
19:30:37 M: 46448640   ERROR: Window Translator: Can't find window webbrowserosd
19:30:37 M: 46448640   ERROR: Window Translator: Can't find window webbookmarks
19:30:37 M: 46448640   ERROR: Window Translator: Can't find window webhistory
19:30:37 M: 46608384    INFO: Checking skin version of: Project Mayhem III
19:30:37 M: 46620672    INFO: Skin version is: 2.1
19:30:37 M: 46620672    INFO:  GUI format 1280x720 720p 16:9
19:30:37 M: 46620672    INFO: install unhandled exception filter
19:30:37 M: 46620672    INFO: creating subdirectories
19:30:37 M: 46620672    INFO: userdata folder: q:\UserData
19:30:37 M: 46620672    INFO:   recording folder:F:\audio
19:30:37 M: 46620672    INFO:   screenshots folder:E:\screenshots
19:30:37 M: 46620672    INFO:   thumbnails folder:q:\UserData\Thumbnails
19:30:37 M: 46608384  NOTICE: Checking the Date!
19:30:37 M: 46608384    INFO: - Current Date is: 5-6-2008
19:30:37 M: 46608384  NOTICE: start dvd mediatype detection
19:30:37 M: 46542848  NOTICE: initializing playlistplayer
19:30:37 M: 46542848   DEBUG: Compiled with libcdio Version 0.72
19:30:37 M: 46542848  NOTICE: DONE initializing playlistplayer
19:30:37 M: 46542848    INFO: lcd not used
19:30:37 M: 46542848  NOTICE: start fancontroller
19:30:37 M: 46010368  NOTICE: load default skin:[Project Mayhem III]
19:30:37 M: 46010368    INFO:   load skin from:Q:\skin\Project Mayhem III
19:30:37 M: 46010368    INFO:   delete old skin...
19:30:37 M: 46010368    INFO: Default 4:3 resolution directory is Q:\skin\Project Mayhem III\PAL
19:30:37 M: 46010368    INFO: Default 16:9 resolution directory is Q:\skin\Project Mayhem III\PAL16x9
19:30:37 M: 46010368    INFO: Skin version is: 2.1
19:30:37 M: 46010368    INFO: Loading skin includes from Q:\skin\Project Mayhem III\PAL\includes.xml
19:30:38 M: 44548096    INFO:   load fonts for skin...
19:30:38 M: 44556288    INFO: Loading colors from Q:\skin\Project Mayhem III\colors\SKINDEFAULT.xml
19:30:38 M: 44556288    INFO: Loading fonts from Q:\skin\Project Mayhem III\720p\Font.xml
19:30:38 M: 44457984   DEBUG: CGUIFontTTF::Load Scaled size of font Q:\media\Fonts\Arial.ttf (18.722223): width = 52, height = 26, lineheight = 22
19:30:38 M: 44380160   DEBUG: CGUIFontTTF::Load Scaled size of font Q:\media\Fonts\Arial.ttf (13.105556): width = 36, height = 19, lineheight = 15
19:30:38 M: 44294144   DEBUG: CGUIFontTTF::Load Scaled size of font Q:\media\Fonts\Arial.ttf (17.786112): width = 49, height = 25, lineheight = 20
19:30:38 M: 44191744   DEBUG: CGUIFontTTF::Load Scaled size of font Q:\media\Fonts\Arial.ttf (19.658335): width = 54, height = 28, lineheight = 23
19:30:38 M: 44154880   ERROR: unable to load Q:\skin\Project Mayhem III\language\German\strings.xml: Failed to open file at line 0
19:30:38 M: 44154880   ERROR: unable to load Q:\skin\Project Mayhem III\language\English\strings.xml: Failed to open file at line 0
19:30:38 M: 44154880    INFO:   load new skin...
19:30:38 M: 44154880    INFO: Skin version is: 2.1
19:30:38 M: 44154880    INFO: Loading skin file: Home.xml
19:30:38 M: 43163648   DEBUG: Load Home.xml: 93.52ms (21.59 ms xml load)
19:30:38 M: 43298816    INFO: Loading user windows, path Q:\skin\Project Mayhem III\PAL16x9
19:30:38 M: 43319296    INFO: Loading user windows, path Q:\skin\Project Mayhem III\PAL
19:30:38 M: 43319296    INFO: Loading skin file: Q:\skin\Project Mayhem III\PAL\custom2_SkinSettings.xml
19:30:38 M: 43319296   DEBUG: Load Skin XML: 124.15ms
19:30:38 M: 43319296    INFO:   initialize new skin...
19:30:38 M: 43319296    INFO: Loading skin file: Pointer.xml
19:30:38 M: 43319296   DEBUG: Load Pointer.xml: 10.77ms (9.29 ms xml load)
19:30:38 M: 43286528   DEBUG: Alloc resources: 76.10ms (12.08 ms skin load, 54.70 ms preload)
19:30:38 M: 43286528    INFO: Loading skin file: DialogVolumeBar.xml
19:30:38 M: 43286528   DEBUG: Load DialogVolumeBar.xml: 9.16ms (7.10 ms xml load)
19:30:38 M: 43278336   DEBUG: Alloc resources: 22.07ms (10.15 ms skin load, 0.15 ms preload)
19:30:38 M: 43278336    INFO: Loading skin file: DialogSeekBar.xml
19:30:38 M: 43274240   DEBUG: Load DialogSeekBar.xml: 13.70ms (4.80 ms xml load)
19:30:38 M: 43241472   DEBUG: Alloc resources: 16.62ms (15.02 ms skin load, 0.26 ms preload)
19:30:38 M: 43241472    INFO: Loading skin file: DialogKaiToast.xml
19:30:38 M: 43241472   DEBUG: Load DialogKaiToast.xml: 21.10ms (19.56 ms xml load)
19:30:38 M: 43241472   DEBUG: Alloc resources: 21.85ms (21.84 ms skin load, 0.00 ms preload)
19:30:38 M: 43241472    INFO: Loading skin file: DialogMuteBug.xml
19:30:38 M: 43241472   DEBUG: Load DialogMuteBug.xml: 1.64ms (1.01 ms xml load)
19:30:38 M: 43241472   DEBUG: Alloc resources: 2.20ms (2.20 ms skin load, 0.00 ms preload)
19:30:38 M: 42848256    INFO:   skin loaded...
19:30:38 M: 42311680    INFO: Loading skin file: DialogBusy.xml
19:30:38 M: 42319872   DEBUG: Load DialogBusy.xml: 21.64ms (20.71 ms xml load)
19:30:38 M: 42844160   DEBUG: Activating window ID: 12999
19:30:38 M: 42844160   DEBUG: Checking if window ID 12999 is locked.
19:30:38 M: 42844160    INFO: Loading skin file: Startup.xml
19:30:38 M: 42844160   DEBUG: Load Startup.xml: 12.28ms (8.75 ms xml load)
19:30:38 M: 42844160   DEBUG: Alloc resources: 13.04ms (13.02 ms skin load, 0.01 ms preload)
19:30:38 M: 42844160  NOTICE: use DHCP
19:30:38 M: 42528768    INFO: removing tempfiles
19:30:38 M: 42528768  NOTICE: initialize done
19:30:38 M: 42528768  NOTICE: Running the application...
19:30:38 M: 40361984    INFO: CNetwork::LogState - Link: full duplex
19:30:38 M: 40361984    INFO: CNetwork::LogState - Link: 100 mbps
19:30:38 M: 40361984    INFO: CNetwork::LogState - ip:
19:30:38 M: 40361984    INFO: CNetwork::LogState - subnet:
19:30:38 M: 40673280    INFO: CNetwork::LogState - gateway:
19:30:38 M: 40148992    INFO:  - dns: CNetwork::LogState,
19:30:38 M: 39522304   DEBUG: CApplication::OnMessage : Translating ReplaceWindow(Home)
19:30:38 M: 39522304   DEBUG: CApplication::OnMessage : To ReplaceWindow(Home)
19:30:38 M: 39522304   DEBUG: Activating window ID: 10000
19:30:38 M: 39522304   DEBUG: Checking if window ID 10000 is locked.
19:30:39 M: 42254336   DEBUG: Alloc resources: 34.37ms (0.00 ms skin load, 1.86 ms preload)
19:30:40 M: 38977536    INFO: CNetwork::LogState - Link: full duplex
19:30:40 M: 38977536    INFO: CNetwork::LogState - Link: 100 mbps
19:30:40 M: 38977536    INFO: CNetwork::LogState - State: dns
19:30:40 M: 38977536    INFO: CNetwork::LogState - State: dhcp
19:30:40 M: 38977536   DEBUG: CNetwork::NetworkMessage - Starting network services
19:30:40 M: 38977536    INFO: CNetwork::LogState - ip: 192.168.0.100
19:30:40 M: 38977536   DEBUG: SECTION:Section SNTP loaded count:1 size:7120
19:30:40 M: 38977536    INFO: CNetwork::LogState - subnet: 255.255.255.0
19:30:40 M: 38977536  NOTICE: start timeserver client
19:30:40 M: 38977536    INFO: CNetwork::LogState - gateway: 192.168.0.1
19:30:40 M: 38912000  NOTICE: Webserver: Starting...
19:30:40 M: 38838272    INFO: 192.168.0.1 - dns: CNetwork::LogState, 0.0.0.0
19:30:40 M: 38903808    INFO: WEATHER: Downloading weather
19:30:40 M: 38903808   DEBUG: SECTION:Section LIBHTTP loaded count:1 size:80384
19:30:40 M: 38903808    INFO: Get URL: http://xoap.weather.com/weather/local/GMXX0263?cc=*&unit=m&dayf=4&prod=xoap&link=xoap&par=1004124588&key=079f24145f208494
19:30:40 M: 38903808   DEBUG: xbmcHttpShim starts
19:30:40 M: 38838272  NOTICE: XBFileZilla: Starting...
19:30:40 M: 38772736    INFO: WebServer: Server starting using Q:\web on 192.168.0.100:80
19:30:40 M: 38694912    INFO: Get URL: http://feeds.feedburner.com/XboxScene
19:30:40 M: 38694912  NOTICE: starting upnp client
19:30:40 M: 38674432   DEBUG: DB: Registering database table <users>
19:30:40 M: 38686720   DEBUG: .\Platinum\Source\Core\PltUPnP.cpp(125): [platinum.core.upnp] 1212687040:783 INFO: Starting UPnP...
19:30:40 M: 38686720   DEBUG: DB: Registering database table <groups>
19:30:40 M: 38608896   DEBUG: .\Platinum\Source\Core\PltUPnP.cpp(210): [platinum.core.upnp] 1212687040:806 INFO: Starting Ctrlpoint...
19:30:40 M: 38522880   DEBUG: DB: Registering database table <access>
19:30:40 M: 38514688   DEBUG: .\Platinum\Source\Core\PltHttpServer.cpp(74): [platinum.core.http.server] 1212687040:808 INFO: HttpServer listening on 0.0.0.0:60387
19:30:40 M: 38514688   DEBUG: UM: Loading User Configuration from file <umconfig.txt>
19:30:40 M: 38146048  NOTICE: starting upnp server
19:30:40 M: 38141952   DEBUG: DB: About to read data file <Q:\web\umconfig.txt>
19:30:40 M: 38060032   ERROR:   <music> tag is missing or sources.xml is malformed
19:30:40 M: 38060032   ERROR:   <video> tag is missing or sources.xml is malformed
19:30:40 M: 38060032   DEBUG: DB: Failed to stat persistent data file.
19:30:40 M: 38060032   ERROR:   <pictures> tag is missing or sources.xml is malformed
19:30:40 M: 38060032   DEBUG: webs: Listening for HTTP requests at address 192.168.0.100
19:30:40 M: 38047744   DEBUG: .\Platinum\Source\Core\PltUPnP.cpp(178): [platinum.core.upnp] 1212687040:867 INFO: Starting Device...
19:30:40 M: 37974016  NOTICE: Webserver: Started
19:30:40 M: 37974016   DEBUG: .\Platinum\Source\Core\PltHttpServer.cpp(74): [platinum.core.http.server] 1212687040:868 INFO: HttpServer listening on 0.0.0.0:81
19:30:40 M: 37904384   DEBUG: .\Platinum\Source\Core\PltHttpServer.cpp(74): [platinum.core.http.server] 1212687040:872 INFO: HttpServer listening on 0.0.0.0:56697
19:30:40 M: 37789696  NOTICE: starting upnp renderer
19:30:40 M: 37789696   ERROR:   <music> tag is missing or sources.xml is malformed
19:30:40 M: 37781504   ERROR:   <video> tag is missing or sources.xml is malformed
19:30:40 M: 37781504   ERROR:   <pictures> tag is missing or sources.xml is malformed
19:30:40 M: 37683200   DEBUG: .\Platinum\Source\Core\PltUPnP.cpp(178): [platinum.core.upnp] 1212687040:955 INFO: Starting Device...
19:30:40 M: 37625856   DEBUG: .\Platinum\Source\Core\PltHttpServer.cpp(74): [platinum.core.http.server] 1212687040:956 INFO: HttpServer listening on 0.0.0.0:62955
19:30:41 M: 37511168  NOTICE: ES: Starting event server
19:30:41 M: 36458496   DEBUG: AudioScrobbler: load cache
19:30:41 M: 36454400  NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777
19:30:41 M: 36458496   DEBUG: AudioScrobbler: ...
19:30:41 M: 36450304  NOTICE: UDP: Listening on port 9777
19:30:41 M: 36450304    INFO: Get URL: http://post.audioscrobbler.com/?hs=true&p=1.1&c=xbm&v=0.1&u=redacted
19:30:41 M: 35782656  NOTICE: XBFileZilla: Started
19:30:41 M: 35819520   DEBUG: AudioScrobbler: Handshaking: Client up to date.
19:30:41 M: 35819520   DEBUG: AudioScrobbler: Submit interval set to 1 seconds.
19:30:41 M: 35819520  NOTICE: AudioScrobbler: Handshake successful.
19:30:41 M: 35848192    INFO: WEATHER: Weather download successful
19:30:42 M: 36024320   DEBUG: CApplication::OnKey: 168 pressed, action is 2
19:30:42 M: 35962880   DEBUG: CApplication::OnKey: 11 pressed, action is 7
19:30:42 M: 35962880   DEBUG: CApplication::OnMessage : Translating ActivateWindow(Favourites)
19:30:42 M: 35962880   DEBUG: CApplication::OnMessage : To ActivateWindow(Favourites)
19:30:42 M: 35962880   DEBUG: Activating window ID: 10134
19:30:42 M: 35962880   DEBUG: Checking if window ID 10134 is locked.
19:30:42 M: 35962880    INFO: Loading skin file: DialogFavourites.xml
19:30:42 M: 35962880   DEBUG: Load DialogFavourites.xml: 37.24ms (33.04 ms xml load)
19:30:42 M: 35942400   DEBUG: Alloc resources: 38.49ms (38.48 ms skin load, 0.00 ms preload)
19:30:43 M: 33771520   DEBUG: Got rss feed: http://feeds.feedburner.com/XboxScene
19:30:43 M: 33718272   DEBUG: RSS feed encoding: UTF-8
19:30:43 M: 33697792   DEBUG: Parsed rss feed: http://feeds.feedburner.com/XboxScene
19:30:43 M: 33804288    INFO: Get URL: http://bitspace.dyndns.org:6560/rss.xml
19:30:43 M: 33755136   DEBUG: .\Platinum\Source\Devices\MediaServer\PltMediaBrowser.cpp(58): [platinum.media.server.browser] 1212687043:707 WARNING: Service urn:schemas-upnp-org:service:ContentDirectory:1 not found
19:30:43 M: 33800192   ERROR: Server returned: 404 Not Found
19:30:43 M: 33837056    INFO: Get URL: http://bitspace.dyndns.org:6560/rss.xml
19:30:44 M: 33837056   DEBUG: CApplication::OnKey: 166 pressed, action is 3
19:30:44 M: 33083392   DEBUG: CApplication::OnKey: 166 pressed, action is 3
19:30:45 M: 33083392   DEBUG: CApplication::OnKey: 11 pressed, action is 7
19:30:45 M: 33083392   DEBUG: CApplication::OnMessage : Translating RunScript(Q:\scripts\VeohProxy\default.py)
19:30:45 M: 33083392   DEBUG: CApplication::OnMessage : To RunScript(Q:\scripts\VeohProxy\default.py)
19:30:45 M: 33083392   DEBUG: LoadLibraryA('python24.dll')
19:30:45 M: 31952896   DEBUG: LoadLibrary('python24.dll') returning: 00C208E0
19:30:45 M: 31932416   DEBUG: dllGetProcAddress(007B8A10(kernel32.dll), 'InterlockedCompareExchange') => 000C8644
19:30:45 M: 31666176   DEBUG: GetModuleFileNameA(00000000, 1E102490, 256) => 'xbmc.xbe'
19:30:45 M: 31645696   DEBUG: advapi32.dll fake function RegQueryInfoKeyA called
19:30:45 M: 31645696   DEBUG: advapi32.dll fake function RegQueryInfoKeyA called
19:30:45 M: 31563776   DEBUG: LoadLibraryExA called with flags: 
                              - LOAD_WITH_ALTERED_SEARCH_PATH
19:30:45 M: 31543296   DEBUG: LoadLibraryA('zlib.pyd')
19:30:45 M: 31473664   DEBUG: kernel32.dll fake function DisableThreadLibraryCalls called
19:30:45 M: 31473664   DEBUG: LoadLibrary('zlib.pyd') returning: 00A82480
19:30:45 M: 31473664   DEBUG: dllGetProcAddress(1E1B0000(zlib.pyd), 'initzlib') => 1E1B2150
19:30:46 M: 30384128    INFO: -->Python Initialized<--
19:30:46 M: 30363648    INFO: 
19:30:46 M: 29462528   DEBUG: kernel32.dll fake function SetConsoleCtrlHandler called
19:30:46 M: 31559680   DEBUG: LoadLibraryExA called with flags: 
                              - LOAD_WITH_ALTERED_SEARCH_PATH
19:30:46 M: 31559680   DEBUG: LoadLibraryA('_socket.pyd')
19:30:46 M: 31510528   DEBUG: kernel32.dll fake function DisableThreadLibraryCalls called
19:30:46 M: 31510528   DEBUG: LoadLibrary('_socket.pyd') returning: 009DC700
19:30:46 M: 31510528   DEBUG: dllGetProcAddress(1E1D0000(_socket.pyd), 'init_socket') => 1E1D4BF0
19:30:46 M: 31485952   DEBUG: LoadLibraryExA called with flags: 
                              - LOAD_WITH_ALTERED_SEARCH_PATH
19:30:46 M: 31485952   DEBUG: LoadLibraryA('_ssl.pyd')
19:30:46 M: 30986240   DEBUG: Unable to resolve msvcr71.dll _getch
19:30:46 M: 30986240   DEBUG: Unable to resolve kernel32.dll FlushConsoleInputBuffer
19:30:46 M: 30986240   DEBUG: kernel32.dll fake function DisableThreadLibraryCalls called
19:30:46 M: 30986240   DEBUG: LoadLibrary('_ssl.pyd') returning: 00A40100
19:30:46 M: 30986240   DEBUG: dllGetProcAddress(10000000(_ssl.pyd), 'init_ssl') => 10001820
19:30:47 M: 30679040   DEBUG: dllGetProcAddress(007B9230(advapi32.dll), 'CryptAcquireContextA') => 0023EA73
19:30:47 M: 30679040   DEBUG: dllGetProcAddress(007B9230(advapi32.dll), 'CryptGenRandom') => 0023BEC0
19:30:47 M: 30679040   DEBUG: advapi32.dll fake function dllCryptAcquireContext() called
19:30:47 M: 30679040   DEBUG: dllbind: family:2 port:64652 ip:127.0.0.1
19:30:47 M: 30679040   DEBUG: LoadLibraryA('ws2_32')
19:30:47 M: 30679040   DEBUG: LoadLibrary('ws2_32.dll') returning: 007B89A8
19:30:47 M: 30679040   DEBUG: dllGetProcAddress(007B89A8(ws2_32.dll), 'getaddrinfo') => 0023BE9B
19:30:47 M: 30679040   DEBUG: dllGetProcAddress(007B89A8(ws2_32.dll), 'getaddrinfo') => 0023BE9B
19:30:47 M: 30679040   DEBUG: dllGetProcAddress(007B89A8(ws2_32.dll), 'getnameinfo') => 0023B580
19:30:47 M: 30679040   DEBUG: dllGetProcAddress(007B89A8(ws2_32.dll), 'freeaddrinfo') => 0023B101
19:30:47 M: 30679040 WARNING: Untested function dllgethostbyaddr called!
19:30:47 M: 30679040    INFO: Thu Jun 05 19:30:47 2008
19:30:47 M: 30679040    INFO: 
19:30:47 M: 30679040    INFO: VeohProxy Starts - 127.0.0.1:64652
19:30:47 M: 30679040    INFO: 
19:30:47 M: 30679040   DEBUG: CApplication::OnKey: 169 pressed, action is 1
19:30:48 M: 30732288   DEBUG: CApplication::OnKey: 167 pressed, action is 4
19:30:48 M: 30732288   DEBUG: CApplication::OnKey: 167 pressed, action is 4
19:30:49 M: 30732288   DEBUG: CApplication::OnKey: 11 pressed, action is 7
19:30:49 M: 30740480   DEBUG: CApplication::OnMessage : Translating ActivateWindow(MyVideos)
19:30:49 M: 30740480   DEBUG: CApplication::OnMessage : To ActivateWindow(MyVideos)
19:30:49 M: 30740480   DEBUG: Activating window ID: 10024
19:30:49 M: 30740480   DEBUG: Checking if window ID 10024 is locked.
19:30:49 M: 34230272    INFO: Attempting to default to:
19:30:49 M: 34217984    INFO: Loading skin file: MyVideo.xml
19:30:49 M: 33521664   DEBUG: Load MyVideo.xml: 122.28ms (32.46 ms xml load)
19:30:50 M: 33533952   DEBUG: Alloc resources: 194.51ms (128.56 ms skin load, 0.85 ms preload)
19:30:50 M: 33583104   DEBUG: CGUIMediaWindow::GetDirectory ()
19:30:50 M: 33583104   DEBUG:   ParentPath = []
19:30:50 M: 30142464   DEBUG: CApplication::OnKey: 167 pressed, action is 4
19:30:51 M: 30142464   DEBUG: CApplication::OnKey: 167 pressed, action is 4
<Stuff removed, pastebin size limit>
19:30:52 M: 29487104   DEBUG: CApplication::OnKey: 167 pressed, action is 4
19:30:52 M: 29880320   DEBUG: CApplication::OnKey: 167 pressed, action is 4
19:30:52 M: 29741056   DEBUG: CApplication::OnKey: 166 pressed, action is 3
19:30:53 M: 29741056   DEBUG: CApplication::OnKey: 166 pressed, action is 3
19:30:53 M: 29741056   DEBUG: CApplication::OnKey: 166 pressed, action is 3
19:30:53 M: 29741056   DEBUG: CApplication::OnKey: 166 pressed, action is 3
19:30:54 M: 29741056   DEBUG: CApplication::OnKey: 11 pressed, action is 7
19:30:54 M: 29741056   DEBUG: Clearing cached fileitems [plugin://video/Ninja Video/]
19:30:54 M: 32358400   DEBUG: CGUIMediaWindow::GetDirectory (plugin://video/Ninja Video/)
19:30:54 M: 32358400   DEBUG:   ParentPath = []
19:30:54 M: 32358400   DEBUG: DIRECTORY::CPluginDirectory::GetDirectory - URL for plugin settings: Ninja Video/
19:30:54 M: 32358400   ERROR: Unable to load: Q:\plugins\video\Ninja Video\resources\settings.xml, Line 0
                             Failed to open file
19:30:54 M: 32358400   ERROR: unable to load Q:\plugins\video\Ninja Video\resources\language\German\strings.xml: Failed to open file at line 0
19:30:54 M: 32358400   ERROR: unable to load Q:\plugins\video\Ninja Video\resources\language\english\strings.xml: Failed to open file at line 0
19:30:54 M: 32358400   DEBUG: DIRECTORY::CPluginDirectory::GetDirectory - calling plugin Q:\plugins\video\Ninja Video\default.py('plugin://video/Ninja Video/','0','')
19:30:54 M: 31834112   DEBUG: DIRECTORY::CPluginDirectory::WaitOnScriptResult - waiting on the Ninja Video plugin...
19:30:55 M: 30912512    INFO: Mode: None
19:30:55 M: 30912512    INFO: 
19:30:55 M: 30912512    INFO: URL: None
19:30:55 M: 30912512    INFO: 
19:30:55 M: 30912512    INFO: Name: None
19:30:55 M: 30912512    INFO: 
19:30:55 M: 30912512    INFO: main category index :
19:30:55 M: 30912512    INFO: 
19:30:55 M: 30912512    INFO: Scriptresult: Succes
19:30:55 M: 30912512   DEBUG: DIRECTORY::CPluginDirectory::WaitOnScriptResult- plugin returned successfully
19:30:55 M: 30912512    INFO: Python script stopped
19:30:56 M: 30720000   DEBUG: CApplication::OnKey: 168 pressed, action is 2
19:30:56 M: 30720000   DEBUG: CApplication::OnKey: 167 pressed, action is 4
19:30:57 M: 30720000   DEBUG: CApplication::OnKey: 11 pressed, action is 7
19:30:57 M: 30720000   DEBUG: Clearing cached fileitems [plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Ftvshows&mode=1&name=3.+TV+SHOWS]
19:30:57 M: 31436800   DEBUG: CGUIMediaWindow::GetDirectory (plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Ftvshows&mode=1&name=3.+TV+SHOWS)
19:30:57 M: 31436800   DEBUG:   ParentPath = [plugin://video/Ninja Video/]
19:30:57 M: 31436800   DEBUG: DIRECTORY::CPluginDirectory::GetDirectory - URL for plugin settings: Ninja Video/
19:30:57 M: 31436800   ERROR: Unable to load: Q:\plugins\video\Ninja Video\resources\settings.xml, Line 0
                             Failed to open file
19:30:57 M: 31436800   ERROR: unable to load Q:\plugins\video\Ninja Video\resources\language\German\strings.xml: Failed to open file at line 0
19:30:57 M: 31436800   ERROR: unable to load Q:\plugins\video\Ninja Video\resources\language\english\strings.xml: Failed to open file at line 0
19:30:57 M: 31436800   DEBUG: DIRECTORY::CPluginDirectory::GetDirectory - calling plugin Q:\plugins\video\Ninja Video\default.py('plugin://video/Ninja Video/','1','?url=http%3A%2F%2Fwww.ninjavideo.net%2Ftvshows&mode=1&name=3.+TV+SHOWS')
19:30:57 M: 30912512   DEBUG: DIRECTORY::CPluginDirectory::WaitOnScriptResult - waiting on the Ninja Video plugin...
19:30:57 M: 30912512    INFO: Mode: 1
19:30:57 M: 30912512    INFO: 
19:30:57 M: 30912512    INFO: URL: http://www.ninjavideo.net/tvshows
19:30:57 M: 30912512    INFO: 
19:30:57 M: 30912512    INFO: Name: 3. TV SHOWS
19:30:57 M: 30912512    INFO: 
19:30:57 M: 30912512    INFO: index of shows : http://www.ninjavideo.net/tvshows
19:30:57 M: 30912512    INFO: 
19:30:57 M: 30855168   DEBUG: DIRECTORY::CPluginDirectory::WaitOnScriptResult- plugin returned successfully
19:30:57 M: 30834688    INFO: Scriptresult: Succes
19:30:57 M: 30814208    INFO: Python script stopped
19:31:00 M: 31186944   DEBUG: CApplication::OnKey: 167 pressed, action is 4
19:31:00 M: 31166464   DEBUG: CApplication::OnKey: 167 pressed, action is 4
<Stuff removed, pastebin size limit>
19:31:02 M: 31113216   DEBUG: CApplication::OnKey: 167 pressed, action is 4
19:31:02 M: 31096832   DEBUG: CApplication::OnKey: 167 pressed, action is 4
19:31:02 M: 31117312   DEBUG: CApplication::OnKey: 166 pressed, action is 3
19:31:03 M: 31117312   DEBUG: CApplication::OnKey: 166 pressed, action is 3
19:31:03 M: 31117312   DEBUG: CApplication::OnKey: 166 pressed, action is 3
19:31:03 M: 31117312   DEBUG: CApplication::OnKey: 166 pressed, action is 3
19:31:03 M: 31096832   DEBUG: CApplication::OnKey: 166 pressed, action is 3
19:31:03 M: 31096832   DEBUG: CApplication::OnKey: 166 pressed, action is 3
19:31:03 M: 31096832   DEBUG: CApplication::OnKey: 166 pressed, action is 3
19:31:03 M: 31096832   DEBUG: CApplication::OnKey: 166 pressed, action is 3
19:31:04 M: 31059968   DEBUG: CApplication::OnKey: 11 pressed, action is 7
19:31:04 M: 31059968   DEBUG: Clearing cached fileitems [plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Fcat%2F294&mode=2&name=Bones]
19:31:04 M: 31059968   ERROR: Server returned: 404 Not Found
19:31:04 M: 31408128   DEBUG: CGUIMediaWindow::GetDirectory (plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Fcat%2F294&mode=2&name=Bones)
19:31:04 M: 31408128    INFO: Get URL: http://bitspace.dyndns.org:6560/rss.xml
19:31:04 M: 31408128   DEBUG:   ParentPath = [plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Ftvshows&mode=1&name=3.+TV+SHOWS]
19:31:04 M: 31408128   DEBUG: DIRECTORY::CPluginDirectory::GetDirectory - URL for plugin settings: Ninja Video/
19:31:04 M: 31408128   ERROR: Unable to load: Q:\plugins\video\Ninja Video\resources\settings.xml, Line 0
                             Failed to open file
19:31:04 M: 31408128   ERROR: unable to load Q:\plugins\video\Ninja Video\resources\language\German\strings.xml: Failed to open file at line 0
19:31:04 M: 31408128   ERROR: unable to load Q:\plugins\video\Ninja Video\resources\language\english\strings.xml: Failed to open file at line 0
19:31:04 M: 31408128   DEBUG: DIRECTORY::CPluginDirectory::GetDirectory - calling plugin Q:\plugins\video\Ninja Video\default.py('plugin://video/Ninja Video/','1','?url=http%3A%2F%2Fwww.ninjavideo.net%2Fcat%2F294&mode=2&name=Bones')
19:31:04 M: 30883840   DEBUG: DIRECTORY::CPluginDirectory::WaitOnScriptResult - waiting on the Ninja Video plugin...
19:31:04 M: 30883840    INFO: Mode: 2
19:31:04 M: 30883840    INFO: 
19:31:04 M: 30883840    INFO: URL: http://www.ninjavideo.net/cat/294
19:31:04 M: 30883840    INFO: 
19:31:04 M: 30883840    INFO: Name: Bones
19:31:04 M: 30883840    INFO: 
19:31:04 M: 30883840    INFO: index of seasons : http://www.ninjavideo.net/cat/294
19:31:04 M: 30883840    INFO: 
19:31:04 M: 30846976    INFO: Scriptresult: Succes
19:31:04 M: 30846976   DEBUG: DIRECTORY::CPluginDirectory::WaitOnScriptResult- plugin returned successfully
19:31:04 M: 30846976    INFO: Python script stopped
19:31:04 M: 31371264   ERROR: Server returned: 404 Not Found
19:31:05 M: 31342592   DEBUG: CApplication::OnKey: 167 pressed, action is 4
19:31:06 M: 31342592   DEBUG: CApplication::OnKey: 167 pressed, action is 4
19:31:06 M: 31342592   DEBUG: CApplication::OnKey: 167 pressed, action is 4
19:31:06 M: 31342592   DEBUG: CApplication::OnKey: 167 pressed, action is 4
19:31:06 M: 31342592   DEBUG: CApplication::OnKey: 167 pressed, action is 4
19:31:07 M: 31342592   DEBUG: CApplication::OnKey: 11 pressed, action is 7
19:31:07 M: 31342592   DEBUG: Clearing cached fileitems [plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Fvideo%2F2873&mode=4&name=Bones+3x13+The+Verdict+In+The+Story]
19:31:07 M: 31473664   DEBUG: CGUIMediaWindow::GetDirectory (plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Fvideo%2F2873&mode=4&name=Bones+3x13+The+Verdict+In+The+Story)
19:31:07 M: 31473664   DEBUG:   ParentPath = [plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Fcat%2F294&mode=2&name=Bones]
19:31:07 M: 31473664   DEBUG: DIRECTORY::CPluginDirectory::GetDirectory - URL for plugin settings: Ninja Video/
19:31:07 M: 31473664   ERROR: Unable to load: Q:\plugins\video\Ninja Video\resources\settings.xml, Line 0
                             Failed to open file
19:31:07 M: 31473664   ERROR: unable to load Q:\plugins\video\Ninja Video\resources\language\German\strings.xml: Failed to open file at line 0
19:31:07 M: 31473664   ERROR: unable to load Q:\plugins\video\Ninja Video\resources\language\english\strings.xml: Failed to open file at line 0
19:31:07 M: 31473664   DEBUG: DIRECTORY::CPluginDirectory::GetDirectory - calling plugin Q:\plugins\video\Ninja Video\default.py('plugin://video/Ninja Video/','1','?url=http%3A%2F%2Fwww.ninjavideo.net%2Fvideo%2F2873&mode=4&name=Bones+3x13+The+Verdict+In+The+Story')
19:31:07 M: 30949376   DEBUG: DIRECTORY::CPluginDirectory::WaitOnScriptResult - waiting on the Ninja Video plugin...
19:31:07 M: 30953472    INFO: Mode: 4
19:31:07 M: 30953472    INFO: 
19:31:07 M: 30953472    INFO: URL: http://www.ninjavideo.net/video/2873
19:31:07 M: 30953472    INFO: 
19:31:07 M: 30953472    INFO: Name: Bones 3x13 The Verdict In The Story
19:31:07 M: 30953472    INFO: 
19:31:07 M: 30953472    INFO: index of videolinks : http://www.ninjavideo.net/video/2873
19:31:07 M: 30953472    INFO: 
19:31:07 M: 30953472   DEBUG: DIRECTORY::CPluginDirectory::WaitOnScriptResult- plugin returned successfully
19:31:07 M: 30932992    INFO: Scriptresult: Succes
19:31:07 M: 30932992    INFO: Python script stopped
19:31:08 M: 31350784   DEBUG: CApplication::OnKey: 167 pressed, action is 4
19:31:08 M: 31350784   DEBUG: CApplication::OnKey: 11 pressed, action is 7
19:31:08 M: 30867456   DEBUG: Unable to resolve kernel32.dll PeekNamedPipe
19:31:08 M: 30867456   DEBUG: Dll MSVCP71.dll was not found in path
19:31:08 M: 30867456   DEBUG: Unable to load referenced dll MSVCP71.dll - Dll: Q:\system\libcurl.dll
19:31:08 M: 30867456   DEBUG: Unable to resolve MSVCP71.dll ?_Nomemory@std@@YAXXZ
19:31:08 M: 30867456   DEBUG: kernel32.dll fake function DisableThreadLibraryCalls called
19:31:08 M: 30830592    INFO: XCURL::DllLibCurlGlobal::easy_aquire - Created session to http://127.0.0.1
19:31:08 M: 30830592   DEBUG: Curl:: Debug About to connect() to 127.0.0.1 port 64652 (#0)
19:31:08 M: 30830592   DEBUG: Curl:: Debug   Trying 127.0.0.1...
19:31:08 M: 30306304   DEBUG: Curl:: Debug connected
19:31:08 M: 30294016   DEBUG: Curl:: Debug Connected to 127.0.0.1 (127.0.0.1) port 64652 (#0)
19:31:08 M: 30306304   DEBUG: Curl:: Debug HEAD /2873?.avi HTTP/1.1

                             User-Agent: XBMC/pre-2.1 (compatible; MSIE 6.0; Windows NT 5.1; WinampMPEG/5.09)

                             Host: 127.0.0.1:64652

                             Accept: */*
19:31:08 M: 30306304    INFO: Thu Jun 05 19:31:08 2008
19:31:08 M: 30306304    INFO: 
19:31:08 M: 30306304    INFO: Serving HEAD request...
19:31:08 M: 30306304    INFO: 
19:31:08 M: 30306304    INFO: Thu Jun 05 19:31:08 2008
19:31:08 M: 30306304    INFO: 
19:31:08 M: 30306304    INFO: Getting infos from NinjaVideo.net for Video ID
19:31:08 M: 30306304    INFO: 
19:31:08 M: 30306304    INFO: 2873
19:31:08 M: 30306304    INFO: 
19:31:08 M: 30306304    INFO: ...
19:31:08 M: 30306304    INFO: 
19:31:09 M: 30298112 WARNING: Untested function dllgethostbyaddr called!
19:31:09 M: 30298112    INFO: 127.0.0.1 - - [05/Jun/2008 19:31:09] "HEAD /2873?.avi HTTP/1.1" 200 -
19:31:09 M: 30298112   DEBUG: Curl:: Debug HTTP 1.0, assume close after body
19:31:09 M: 30298112    INFO: Thu Jun 05 19:31:09 2008
19:31:09 M: 30298112   DEBUG: Curl:: Debug HTTP/1.0 200 OK
19:31:09 M: 30298112    INFO: 
19:31:09 M: 30298112   DEBUG: Curl:: Debug Server: BaseHTTP/0.3 Python/2.4.3
19:31:09 M: 30298112    INFO: Sending headers...
19:31:09 M: 30298112   DEBUG: Curl:: Debug Date: Thu, 05 Jun 2008 17:31:09 GMT
19:31:09 M: 30298112    INFO: 
19:31:09 M: 30298112   DEBUG: Curl:: Debug Content-Disposition: inline; filename="Bones 3x13 The Verdict In The Story.avi"
19:31:09 M: 30822400   DEBUG: Curl:: Debug Content-type: application/x-msvideo
19:31:09 M: 30822400   DEBUG: Curl:: Debug Last-Modified: Wed, 21 Feb 2000 08:43:39 GMT
19:31:09 M: 30822400   DEBUG: Curl:: Debug ETag: efd7e9ed0e5e694ba6df444d84dfa37d
19:31:09 M: 30822400   DEBUG: Curl:: Debug Accept-Ranges: bytes
19:31:09 M: 30822400   DEBUG: Curl:: Debug Cache-Control: public, must-revalidate
19:31:09 M: 30822400   DEBUG: Curl:: Debug Cache-Control: no-cache
19:31:09 M: 30822400   DEBUG: Curl:: Debug Pragma: no-cache
19:31:09 M: 30822400   DEBUG: Curl:: Debug features: seekable,stridable
19:31:09 M: 30822400   DEBUG: Curl:: Debug client-id: 12345
19:31:09 M: 30822400   DEBUG: Curl:: Debug Content-Length: 363024692
19:31:09 M: 30822400   DEBUG: Curl:: Debug
19:31:09 M: 30822400   DEBUG: Curl:: Debug Closing connection #0
19:31:09 M: 30822400   DEBUG: FileCurl::Close(D00B4D24) http://127.0.0.1:64652/2873?.avi
19:31:09 M: 30822400   DEBUG: CPlayerCoreFactor::GetPlayers(http://127.0.0.1:64652/2873?.avi)
19:31:09 M: 30822400   DEBUG: file:http://127.0.0.1:64652/2873?.avi IsDVDImage:0 IsDVDIfoFile:0
19:31:09 M: 30822400    INFO: Loading skin file: DialogProgress.xml
19:31:09 M: 30822400   DEBUG: Load DialogProgress.xml: 35.47ms (32.79 ms xml load)
19:31:09 M: 30789632   DEBUG: Alloc resources: 15.43ms (0.00 ms skin load, 0.25 ms preload)
19:31:10 M: 29876224    INFO: mplayer play:http://127.0.0.1:64652/2873?.avi cachesize:8192
19:31:10 M: 23990272   DEBUG:   msg:  set audio functions called
19:31:10 M: 23990272   DEBUG:   msg:  done
19:31:10 M: 23990272   DEBUG: FileCurl::Open(00EB2990) http://127.0.0.1:64652/2873?.avi
19:31:10 M: 23887872   DEBUG: Curl:: Debug About to connect() to 127.0.0.1 port 64652 (#0)
19:31:10 M: 23887872   DEBUG: Curl:: Debug   Trying 127.0.0.1...
19:31:10 M: 23363584   DEBUG: Curl:: Debug connected
19:31:10 M: 23351296   DEBUG: Curl:: Debug Connected to 127.0.0.1 (127.0.0.1) port 64652 (#0)
19:31:10 M: 23363584    INFO: Thu Jun 05 19:31:10 2008
19:31:10 M: 23363584   DEBUG: Curl:: Debug GET /2873?.avi HTTP/1.1

                             User-Agent: XBMC/pre-2.1 (compatible; MSIE 6.0; Windows NT 5.1; WinampMPEG/5.09)

                             Host: 127.0.0.1:64652

                             Accept: */*
19:31:10 M: 23363584    INFO: 
19:31:10 M: 23363584    INFO: Serving GET request...
19:31:10 M: 23363584    INFO: 
19:31:10 M: 23363584 WARNING: Untested function dllgethostbyaddr called!
19:31:10 M: 23363584    INFO: 127.0.0.1 - - [05/Jun/2008 19:31:10] "GET /2873?.avi HTTP/1.1" 200 -
19:31:10 M: 23363584   DEBUG: Curl:: Debug HTTP 1.0, assume close after body
19:31:10 M: 23363584    INFO: Thu Jun 05 19:31:10 2008
19:31:10 M: 23363584   DEBUG: Curl:: Debug HTTP/1.0 200 OK
19:31:10 M: 23363584    INFO: 
19:31:10 M: 23363584   DEBUG: Curl:: Debug Server: BaseHTTP/0.3 Python/2.4.3
19:31:10 M: 23363584    INFO: Sending headers...
19:31:10 M: 23363584   DEBUG: Curl:: Debug Date: Thu, 05 Jun 2008 17:31:10 GMT
19:31:10 M: 23363584    INFO: 
19:31:10 M: 23363584   DEBUG: Curl:: Debug Content-Disposition: inline; filename="Bones 3x13 The Verdict In The Story.avi"
19:31:10 M: 23363584    INFO: Thu Jun 05 19:31:10 2008
19:31:10 M: 23363584   DEBUG: Curl:: Debug Content-type: application/x-msvideo
19:31:10 M: 23363584    INFO: 
19:31:10 M: 23363584   DEBUG: Curl:: Debug Last-Modified: Wed, 21 Feb 2000 08:43:39 GMT
19:31:10 M: 23363584    INFO: Starting download at byte
19:31:10 M: 23363584   DEBUG: Curl:: Debug ETag: efd7e9ed0e5e694ba6df444d84dfa37d
19:31:10 M: 23363584    INFO: 
19:31:10 M: 23363584   DEBUG: Curl:: Debug Accept-Ranges: bytes
19:31:10 M: 23363584    INFO: 0
19:31:10 M: 23363584   DEBUG: Curl:: Debug Cache-Control: public, must-revalidate
19:31:10 M: 23363584    INFO: 
19:31:10 M: 23363584   DEBUG: Curl:: Debug Cache-Control: no-cache
19:31:10 M: 23363584   DEBUG: Curl:: Debug Pragma: no-cache
19:31:10 M: 23363584   DEBUG: Curl:: Debug features: seekable,stridable
19:31:10 M: 23363584   DEBUG: Curl:: Debug client-id: 12345
19:31:10 M: 23363584   DEBUG: Curl:: Debug Content-Length: 363024692
19:31:10 M: 23363584   DEBUG: Curl:: Debug
19:31:11 M: 22941696   DEBUG: FileCurl::Close(00EB2990) http://127.0.0.1:64652/2873?.avi
19:31:11 M: 22962176   DEBUG: Curl:: Debug Expire cleared
19:31:11 M: 22962176   DEBUG: Curl:: Debug Closing connection #0
19:31:11 M: 23166976    INFO: Using -subcp enca:__:CP1252 to detect the subtitle charset
19:31:11 M: 23166976    INFO: Client Closed the connection.
19:31:11 M: 23166976    INFO: Flipping bi-directional subtitles disabled
19:31:11 M: 23166976    INFO: 
19:31:11 M: 23961600   DEBUG:   msg: mplayer_init()
19:31:11 M: 23961600   DEBUG:   msg: MPlayer XBMC-SVN-r10070-4.1.2 (C) 2000-2004 MPlayer Team
19:31:11 M: 23961600   DEBUG:   msg: CPU: Intel Celeron 2/Pentium III Coppermine,Geyserville
19:31:11 M: 23953408   DEBUG:   msg: (Family: 6, Stepping: 10)
19:31:11 M: 23953408   DEBUG:   msg: Detected cache-line size is 32 bytes
19:31:11 M: 23953408   DEBUG:   msg: CPUflags:  MMX: 1 MMX2: 1 3DNow: 0 3DNow2: 0 SSE: 0 SSE2: 0
19:31:11 M: 23953408   DEBUG:   msg: Compiled for x86 CPU with extensions:
19:31:11 M: 23953408   DEBUG:   msg:  MMX
19:31:11 M: 23953408   DEBUG:   msg:  MMX2
19:31:11 M: 23953408   DEBUG:   msg:  SSE
19:31:11 M: 23953408   DEBUG:   msg:
19:31:11 M: 23941120   DEBUG:   msg:
19:31:11 M: 23949312   DEBUG:   msg: Creating config file: Q:\system\players\mplayer\config
19:31:11 M: 23941120   DEBUG:   msg:
19:31:11 M: 23953408   DEBUG:   msg: get_path('codecs.conf') -> 'Q:\system\players\mplayer\codecs.conf'
19:31:11 M: 23932928   DEBUG:   msg: Reading Q:\system\players\mplayer\codecs.conf:
19:31:12 M: 23609344   DEBUG:   msg: 108 audio & 225 video codecs
19:31:12 M: 23609344   DEBUG:   msg: CommandLine:
19:31:12 M: 23609344   DEBUG:   msg:  '-dr'
19:31:12 M: 23609344   DEBUG:   msg:  '-v'
19:31:12 M: 23609344   DEBUG:   msg:  '-subcp'
19:31:12 M: 23609344   DEBUG:   msg:  'enca:__:CP1252'
19:31:12 M: 23609344   DEBUG:   msg:  '-channels'
19:31:12 M: 23609344   DEBUG:   msg:  '6'
19:31:12 M: 23609344   DEBUG:   msg:  '-ac'
19:31:12 M: 23609344   DEBUG:   msg:  'hwdts,hwac3,'
19:31:12 M: 23609344   DEBUG:   msg:  '-noflip-hebrew'
19:31:12 M: 23609344   DEBUG:   msg:  '-noflip-hebrew-commas'
19:31:12 M: 23609344   DEBUG:   msg:  '-vf'
19:31:12 M: 23609344   DEBUG:   msg:  'noise=1ta:1ta'
19:31:12 M: 23609344   DEBUG:   msg:  '-fs'
19:31:12 M: 23609344   DEBUG:   msg:  '1.avi'
19:31:12 M: 23609344   DEBUG:   msg:
19:31:12 M: 23609344   DEBUG:   msg: get_path('font/font.desc') -> 'Q:\system\players\mplayer\font/font.desc'
19:31:12 M: 23080960   DEBUG:   msg: font: can't open file: Q:\system\players\mplayer\font/font.desc
19:31:12 M: 23080960   DEBUG:   msg: font: can't open file: Q:\system\players\mplayer/font/font.desc
19:31:12 M: 23609344   DEBUG:   msg: Using MMX (with tiny bit MMX2) Optimized OnScreenDisplay
19:31:12 M: 23576576   DEBUG:   msg: Using XBOX High Resolution Timers for timing
19:31:12 M: 23576576   DEBUG:   msg: get_path('input.conf') -> 'Q:\system\players\mplayer\input.conf'
19:31:12 M: 23576576   DEBUG:   msg: Parsing input config file Q:\system\players\mplayer\input.conf
19:31:12 M: 23564288   DEBUG:   msg: Input config file Q:\system\players\mplayer\input.conf parsed: 51 binds
19:31:12 M: 23564288   DEBUG:   msg: mplayer_open_file(http://127.0.0.1:64652/2873?.avi)
19:31:12 M: 23564288   DEBUG:   msg: get_path('2873?.avi.conf') -> 'Q:\system\players\mplayer\2873?.avi.conf'
19:31:12 M: 23564288   DEBUG:   msg: 
                             Playing http://127.0.0.1:64652/2873?.avi.
19:31:12 M: 23564288   DEBUG:   msg: find subtitles
19:31:12 M: 23564288   DEBUG:   msg: auto open Z:\subtitle
19:31:12 M: 23564288   DEBUG:   msg: Filename for url is now http://127.0.0.1:64652/2873?.avi
19:31:12 M: 23564288   DEBUG:   msg: protocol:http
19:31:12 M: 23564288   DEBUG:   msg: open stream protocol:http
19:31:12 M: 23543808   DEBUG:   msg: WINSOCK2 init: 0
19:31:12 M: 23543808   DEBUG:   msg: Filename for url is now http://127.0.0.1:64652/2873?.avi
19:31:12 M: 23543808   DEBUG:   msg: Connecting to server 127.0.0.1[127.0.0.1]:64652 ...
19:31:12 M: 23019520    INFO: Thu Jun 05 19:31:12 2008
19:31:12 M: 23019520    INFO: 
19:31:12 M: 23019520    INFO: Serving GET request...
19:31:12 M: 23019520    INFO: 
19:31:12 M: 23019520 WARNING: Untested function dllgethostbyaddr called!
19:31:12 M: 23019520    INFO: 127.0.0.1 - - [05/Jun/2008 19:31:12] "GET /2873?.avi HTTP/1.0" 200 -
19:31:12 M: 23019520    INFO: Thu Jun 05 19:31:12 2008
19:31:12 M: 23019520    INFO: 
19:31:12 M: 23019520    INFO: Sending headers...
19:31:12 M: 23019520    INFO: 
19:31:12 M: 23019520    INFO: Thu Jun 05 19:31:12 2008
19:31:12 M: 23019520    INFO: 
19:31:12 M: 23019520    INFO: Starting download at byte
19:31:12 M: 23019520    INFO: 
19:31:12 M: 23019520    INFO: 0
19:31:12 M: 23019520    INFO: 
19:31:12 M: 23019520   DEBUG:   msg: --- HTTP DEBUG HEADER --- START ---
19:31:12 M: 23019520   DEBUG:   msg: protocol:           [HTTP/1.0]
19:31:12 M: 23019520   DEBUG:   msg: http minor version: [0]
19:31:12 M: 23019520   DEBUG:   msg: uri:                [(null)]
19:31:12 M: 23019520   DEBUG:   msg: method:             [(null)]
19:31:12 M: 23019520   DEBUG:   msg: status code:        [200]
19:31:12 M: 23019520   DEBUG:   msg: reason phrase:      [OK]
19:31:12 M: 23019520   DEBUG:   msg: body size:          [0]
19:31:12 M: 23019520   DEBUG:   msg: Fields:
19:31:12 M: 23019520   DEBUG:   msg:  0 - Server: BaseHTTP/0.3 Python/2.4.3
19:31:12 M: 23019520   DEBUG:   msg:  1 - Date: Thu, 05 Jun 2008 17:31:12 GMT
19:31:12 M: 23019520   DEBUG:   msg:  2 - Content-Disposition: inline; filename="Bones 3x13 The Verdict In The Story.avi"
19:31:12 M: 23019520   DEBUG:   msg:  3 - Content-type: application/x-msvideo
19:31:12 M: 23019520   DEBUG:   msg:  4 - Last-Modified: Wed, 21 Feb 2000 08:43:39 GMT
19:31:12 M: 23019520   DEBUG:   msg:  5 - ETag: efd7e9ed0e5e694ba6df444d84dfa37d
19:31:12 M: 23019520   DEBUG:   msg:  6 - Accept-Ranges: bytes
19:31:12 M: 23019520   DEBUG:   msg:  7 - Cache-Control: public, must-revalidate
19:31:12 M: 23019520   DEBUG:   msg:  8 - Cache-Control: no-cache
19:31:12 M: 23019520   DEBUG:   msg:  9 - Pragma: no-cache
19:31:12 M: 23019520   DEBUG:   msg:  10 - features: seekable,stridable
19:31:12 M: 23019520   DEBUG:   msg:  11 - client-id: 12345
19:31:12 M: 23019520   DEBUG:   msg:  12 - Content-Length: 363024692
19:31:12 M: 23019520   DEBUG:   msg: --- HTTP DEBUG HEADER --- END ---
19:31:12 M: 23019520   DEBUG:   msg: Content-Type: [application/x-msvideo]
19:31:12 M: 23019520   DEBUG:   msg: Content-Length: [363024692]
19:31:12 M: 23019520   DEBUG:   msg: Cache size set to 8192 KBytes
19:31:12 M: 23019520   DEBUG:   msg: Connected to server: 127.0.0.1
19:31:12 M: 14069760   DEBUG:   msg: CACHE_PRE_INIT: 0 [0] 0  pre:1677721  eof:0
19:31:12 M: 14069760   DEBUG:   msg: 
Cache fill:  0.00% (0 bytes)
19:31:12 M: 14069760   DEBUG:   msg: 
Cache fill:  0.00% (0 bytes)
<Stuff removed, pastebin size limit>
19:31:16 M: 13971456   DEBUG:   msg: 
Cache fill:  3.13% (262144 bytes)
19:31:17 M: 13918208   DEBUG:   msg: 
Cache fill:  3.13% (262144 bytes)
19:31:17 M: 13905920    INFO: XCURL::DllLibCurlGlobal::CheckIdle - Closing session to http ://127.0.0.1 (easy=00EEA010, multi=00E6AFD0)
19:31:17 M: 13881344   DEBUG:   msg: 
Cache fill:  3.13% (262144 bytes)
19:31:17 M: 13819904   DEBUG:   msg: 
Cache fill:  3.13% (262144 bytes)
<Stuff removed, pastebin size limit>
19:31:24 M: 13787136   DEBUG:   msg: 
Cache fill: 18.75% (1572864 bytes)
19:31:24 M: 13737984   DEBUG:   msg: 
Cache fill: 18.75% (1572864 bytes)
19:31:25 M: 14086144   DEBUG:   msg:
19:31:25 M: 14086144   DEBUG:   msg: demux_open(00FDE010,0,-1,-1,-1)
19:31:25 M: 14086144   DEBUG:   msg: AVI file format detected.
19:31:25 M: 14086144   DEBUG:   msg: list_end=0x146
19:31:25 M: 14086144   DEBUG:   msg: ======= AVI Header =======
19:31:25 M: 14086144   DEBUG:   msg: us/frame: 41708  (fps=23.976)
19:31:25 M: 14086144   DEBUG:   msg: max bytes/sec: 0
19:31:25 M: 14086144   DEBUG:   msg: padding: 0
19:31:25 M: 14086144   DEBUG:   msg: MainAVIHeader.dwFlags: (905279863) HAS_INDEX MUST_USE_INDEX IS_INTERLEAVED TRUST_CKTYPE WAS_CAPTUREFILE
19:31:25 M: 14086144   DEBUG:   msg: frames  total: 61085   initial: 0
19:31:25 M: 14086144   DEBUG:   msg: streams: 2
19:31:25 M: 14086144   DEBUG:   msg: Suggested BufferSize: 0
19:31:25 M: 14086144   DEBUG:   msg: Size:  624 x 352
19:31:25 M: 14086144   DEBUG:   msg: ==========================
19:31:25 M: 14086144   DEBUG:   msg: list_end=0xD4
19:31:25 M: 14086144   DEBUG:   msg: ==> Found video stream: 0
19:31:25 M: 14086144   DEBUG:   msg: ====== STREAM Header =====
19:31:25 M: 14086144   DEBUG:   msg: Type: vids   FCC: XVID (44495658)
19:31:25 M: 14086144   DEBUG:   msg: Flags: 0
19:31:25 M: 14086144   DEBUG:   msg: Priority: 0   Language: 0
19:31:25 M: 14086144   DEBUG:   msg: InitialFrames: 0
19:31:25 M: 14086144   DEBUG:   msg: Rate: 23976024/1000000 = 23.976
19:31:25 M: 14086144   DEBUG:   msg: Start: 0   Len: 61085
19:31:25 M: 14086144   DEBUG:   msg: Suggested BufferSize: 140037
19:31:25 M: 14086144   DEBUG:   msg: Quality 0
19:31:25 M: 14086144   DEBUG:   msg: Sample size: 0
19:31:25 M: 14086144   DEBUG:   msg: ==========================
19:31:25 M: 14086144   DEBUG:   msg: Found 'bih', 40 bytes of 40
19:31:25 M: 14086144   DEBUG:   msg: ======= VIDEO Format ======
19:31:25 M: 14086144   DEBUG:   msg:   biSize 40
19:31:25 M: 14086144   DEBUG:   msg:   biWidth 624
19:31:25 M: 14086144   DEBUG:   msg:   biHeight 352
19:31:25 M: 14073856   DEBUG:   msg:   biPlanes 1
19:31:25 M: 14073856   DEBUG:   msg:   biBitCount 24
19:31:25 M: 14073856   DEBUG:   msg:   biCompression 1145656920='XVID'
19:31:25 M: 14073856   DEBUG:   msg:   biSizeImage 658944
19:31:25 M: 14073856   DEBUG:   msg: ===========================
19:31:25 M: 14073856   DEBUG:   msg: Regenerating keyframe table for MPEG-4 video.
19:31:25 M: 14073856   DEBUG:   msg: list_end=0x146
19:31:25 M: 14073856   DEBUG:   msg: ==> Found audio stream: 1
19:31:25 M: 14073856   DEBUG:   msg: ====== STREAM Header =====
19:31:25 M: 14073856   DEBUG:   msg: Type: auds   FCC:  (0)
19:31:25 M: 14073856   DEBUG:   msg: Flags: 0
19:31:25 M: 14073856   DEBUG:   msg: Priority: 0   Language: 0
19:31:25 M: 14073856   DEBUG:   msg: InitialFrames: 0
19:31:25 M: 14073856   DEBUG:   msg: Rate: 48000/1152 = 41.667
19:31:25 M: 14073856   DEBUG:   msg: Start: 0   Len: 106157
19:31:25 M: 14073856   DEBUG:   msg: Suggested BufferSize: 0
19:31:25 M: 14073856   DEBUG:   msg: Quality 0
19:31:25 M: 14073856   DEBUG:   msg: Sample size: 0
19:31:25 M: 14073856   DEBUG:   msg: ==========================
19:31:25 M: 14073856   DEBUG:   msg: Found 'wf', 30 bytes of 18
19:31:25 M: 14073856   DEBUG:   msg: ======= WAVE Format =======
19:31:25 M: 14073856   DEBUG:   msg: Format Tag: 85 (0x55)
19:31:25 M: 14073856   DEBUG:   msg: Channels: 2
19:31:25 M: 14073856   DEBUG:   msg: Samplerate: 48000
19:31:25 M: 14073856   DEBUG:   msg: avg byte/sec: 16000
19:31:25 M: 14073856   DEBUG:   msg: Block align: 1152
19:31:25 M: 14073856   DEBUG:   msg: bits/sample: 16
19:31:25 M: 14073856   DEBUG:   msg: cbSize: 12
19:31:25 M: 14073856   DEBUG:   msg: mp3.wID=1
19:31:25 M: 14073856   DEBUG:   msg: mp3.fdwFlags=0x2
19:31:25 M: 14073856   DEBUG:   msg: mp3.nBlockSize=1152
19:31:25 M: 14073856   DEBUG:   msg: mp3.nFramesPerBlock=1
19:31:25 M: 14073856   DEBUG:   msg: mp3.nCodecDelay=0
19:31:25 M: 14073856   DEBUG:   msg: ===========================
19:31:25 M: 14073856   DEBUG:   msg: list_end=0x16A
19:31:25 M: 14073856   DEBUG:   msg: hdr=Software  size=16
19:31:25 M: 14073856   DEBUG:   msg: Software  : transcode-1.0.2
19:31:25 M: 14073856   DEBUG:   msg: list_end=0x157A7C8C
19:31:25 M: 14073856   DEBUG:   msg: Found movie at 0x800 - 0x157A7C8C
19:31:26 M: 13832192   DEBUG:   msg: Connecting to server 127.0.0.1[127.0.0.1]:64652 ...
19:31:26 M: 13205504    INFO: Thu Jun 05 19:31:26 2008
19:31:26 M: 13205504    INFO: 
19:31:26 M: 13205504    INFO: Serving GET request...
19:31:26 M: 13205504    INFO: 
19:31:26 M: 13205504 WARNING: Untested function dllgethostbyaddr called!
19:31:26 M: 13205504    INFO: 127.0.0.1 - - [05/Jun/2008 19:31:26] "GET /2873?.avi HTTP/1.0" 206 -
19:31:26 M: 13205504    INFO: Thu Jun 05 19:31:26 2008
19:31:26 M: 13205504    INFO: 
19:31:26 M: 13205504    INFO: Sending headers...
19:31:26 M: 13205504    INFO: 
19:31:26 M: 13205504    INFO: Thu Jun 05 19:31:26 2008
19:31:26 M: 13205504   DEBUG:   msg: Content-Type: [application/x-msvideo]
19:31:26 M: 13205504    INFO: 
19:31:26 M: 13205504   DEBUG:   msg: Content-Length: [2691380]
19:31:26 M: 13303808    INFO: Starting download at byte
19:31:26 M: 13303808    INFO: Client Closed the connection.
19:31:26 M: 13303808    INFO: 
19:31:26 M: 13570048    INFO: 
19:31:26 M: 13570048    INFO: 360333312
19:31:26 M: 13570048    INFO: 
19:31:27 M: 14094336   DEBUG:   msg: Reading INDEX block, 167242 chunks for 61085 frames (fpos=360348820).
19:31:46 M: 11919360   DEBUG:   msg: Connecting to server 127.0.0.1[127.0.0.1]:64652 ...
19:31:46 M: 11390976    INFO: Thu Jun 05 19:31:46 2008
19:31:46 M: 11390976    INFO: 
19:31:46 M: 11390976    INFO: Serving GET request...
19:31:46 M: 11390976    INFO: 
19:31:46 M: 11390976 WARNING: Untested function dllgethostbyaddr called!
19:31:46 M: 11390976    INFO: 127.0.0.1 - - [05/Jun/2008 19:31:46] "GET /2873?.avi HTTP/1.0" 200 -
19:31:46 M: 11390976    INFO: Thu Jun 05 19:31:46 2008
19:31:46 M: 11390976    INFO: 
19:31:46 M: 11390976    INFO: Sending headers...
19:31:46 M: 11390976    INFO: 
19:31:46 M: 11390976   DEBUG:   msg: Content-Type: [application/x-msvideo]
19:31:46 M: 11390976    INFO: Thu Jun 05 19:31:46 2008
19:31:46 M: 11390976    INFO: 
19:31:46 M: 11390976   DEBUG:   msg: Content-Length: [363024692]
19:31:46 M: 11390976    INFO: Starting download at byte
19:31:46 M: 11390976    INFO: 
19:31:46 M: 11390976    INFO: 0
19:31:46 M: 11390976    INFO: 
19:31:47 M: 11218944   DEBUG: SECTION:UnloadDelayed(DLL: Q:\system\libcurl.dll)
19:31:47 M: 11870208   DEBUG:   msg: AVI index offset: 0x0 (movi=0x800 idx0=0x800 idx1=0x119E)
19:31:47 M: 11870208   DEBUG:   msg: Auto-selected AVI video ID = 0
19:31:47 M: 11870208   DEBUG:   msg: Auto-selected AVI audio ID = 1
19:31:47 M: 11870208   DEBUG:   msg: AVI: Searching for audio stream (id:1)
19:31:47 M: 11870208   DEBUG:   msg: XXX initial  v_pts=0.000  a_pos=0 (0.000)
19:31:47 M: 11870208   DEBUG:   msg: AVI video size=316759146 (61085) audio size=42209016 (106157)
19:31:47 M: 11870208   DEBUG:   msg: [V] filefmt:3  fourcc:0x44495658  size:624x352  fps:23.98  ftime:=0.0417
19:31:47 M: 11870208   DEBUG:   msg: Clip info:
19:31:47 M: 11870208   DEBUG:   msg:  Software: transcode-1.0.2
19:31:47 M: 11870208   DEBUG:   msg: get_path('sub/') -> 'Q:\system\players\mplayer\sub/'
19:31:47 M: 11870208   DEBUG:   msg: Starting lookup for subs
19:31:47 M: 11870208   DEBUG:   msg: Global Subsize: 0 - VOBSUB: -1, DEMUX: -1, SUBS: -1
19:31:47 M: 11870208   DEBUG:   msg: Global Subpos : -1
19:31:47 M: 11870208   DEBUG:   msg: ==========================================================================
19:31:47 M: 11870208   DEBUG:   msg: Forced audio codec: hwdts
19:31:47 M: 11870208   DEBUG:   msg: Forced audio codec: hwac3
19:31:48 M: 11870208   DEBUG:   msg: Opening audio decoder: [mp3lib] MPEG layer-2, layer-3
19:31:48 M: 11870208   DEBUG:   msg: dec_audio: Allocating 4608 + 65536 = 70144 bytes for output buffer.
19:31:48 M: 11796480   DEBUG:   msg: mp3lib: made decode tables with MMX optimization
19:31:48 M: 11796480   DEBUG:   msg: mp3lib: using MMX optimized decore!
19:31:48 M: 11796480   DEBUG:   msg: MP3lib: init layer2&3 finished, tables done
19:31:48 M: 11788288   DEBUG:   msg: 
MPEG 1.0, Layer III, 48000 Hz 128 kbit Joint-Stereo, BPF: 384
19:31:48 M: 11788288   DEBUG:   msg: Channels: 2, copyright: No, original: Yes, CRC: No, emphasis: 0
19:31:48 M: 11788288   DEBUG:   msg: AUDIO: 48000 Hz, 2 ch, 16 bit (0x10), ratio: 16000->192000 (128.0 kbit)
19:31:48 M: 11788288   DEBUG:   msg: Selected audio codec: [mp3] afm:mp3lib (mp3lib MPEG layer-2, layer-3)
19:31:48 M: 11788288   DEBUG:   msg: ==========================================================================
19:31:48 M: 11788288   DEBUG: CXBoxRenderManager::PreInit - Selected RGB-Renderer V2
19:31:48 M: 11788288   DEBUG: Deleted 444P video textures
19:31:48 M: 11776000   DEBUG:   msg: Opening video filter: [noise=1ta:1ta]
19:31:48 M: 11685888   DEBUG:   msg: [vo] query(Planar YV12) -> 3
19:31:48 M: 11685888   DEBUG:   msg: ==========================================================================
19:31:48 M: 11685888   DEBUG:   msg: Opening video decoder: [ffmpeg] FFmpeg's libavcodec codec family
19:31:48 M: 11198464   DEBUG:   msg: INFO: libavcodec init OK!
19:31:48 M: 11198464   DEBUG:   msg: Selected video codec: [ffodivx] vfm:ffmpeg (FFmpeg MPEG-4)
19:31:48 M: 11198464   DEBUG:   msg: ==========================================================================
19:31:48 M: 11198464   DEBUG:   msg: Checking audio filter chain for 48000Hz/2ch/16bit -> 48000Hz/6ch/16bit...
19:31:48 M: 11198464   DEBUG:   msg: [libaf] Adding filter dummy
19:31:48 M: 11198464   DEBUG:   msg: [dummy] Was reinitialized, rate=48000Hz, nch = 2, format = 0x00000001 and bps = 2
19:31:48 M: 11198464   DEBUG:   msg: AF_pre: af format: 2 bps, 2 ch, 48000 hz, little endian signed int
19:31:48 M: 11198464   DEBUG:   msg: AF_pre: 48000Hz 2ch Signed 16-bit (Little-Endian)
19:31:48 M: 11137024   DEBUG: Pause stream
19:31:48 M: 11132928   DEBUG:   msg: AO: [win32] 48000Hz 2ch Signed 16-bit (Little-Endian) (2 bps)
19:31:48 M: 11132928   DEBUG:   msg: AO: Description: Windows waveOut audio output
                             AO: Author: SaschaSommer <saschasommer@freenet.de>
19:31:48 M: 11132928   DEBUG:   msg: Building audio filter chain for 48000Hz/2ch/16bit -> 48000Hz/2ch/16bit...
19:31:48 M: 11132928   DEBUG:   msg: [dummy] Was reinitialized, rate=48000Hz, nch = 2, format = 0x00000001 and bps = 2
19:31:48 M: 11132928   DEBUG:   msg: [dummy] Was reinitialized, rate=48000Hz, nch = 2, format = 0x00000001 and bps = 2
19:31:48 M: 11063296   DEBUG:   msg: Starting playback...
19:31:48 M: 11063296   DEBUG:   msg: mplayer_open_file(http://127.0.0.1:64652/2873?.avi) done 1.0000
19:31:48 M: 10960896    INFO: Loading skin file: VideoOverlay.xml
19:31:48 M: 10960896   DEBUG: Playback has started
19:31:48 M: 11485184   DEBUG: Load VideoOverlay.xml: 16.90ms (13.21 ms xml load)
19:31:48 M: 11427840   DEBUG: Alloc resources: 24.82ms (24.82 ms skin load, 0.00 ms preload)
19:31:48 M: 11042816   DEBUG: CGUIInfoManager::SetCurrentMovie(http://127.0.0.1:64652/2873?.avi)
19:31:48 M: 11042816   DEBUG:   msg: [ffmpeg] aspect_ratio: 1.772727
19:31:48 M: 11030528   DEBUG:   msg: VDec: vo config request - 624 x 352 (preferred colorspace: Planar YV12)
19:31:48 M: 11030528   DEBUG:   msg: Trying filter chain:
19:31:48 M: 11018240   DEBUG:   msg:  noise
19:31:48 M: 11010048   DEBUG:   msg:  vo
19:31:48 M: 11010048   DEBUG:   msg:
19:31:48 M: 11010048   DEBUG:   msg: VDec: using Planar YV12 as output csp (no 0)
19:31:48 M: 10997760   DEBUG:   msg: Movie-Aspect is 1.77:1 - prescaling to correct movie aspect.
19:31:48 M: 10997760   DEBUG:   msg: VO Config (624x352->624x352,flags=1,'MPlayer',0x32315659)
19:31:48 M: 10985472   DEBUG:   msg: REQ: flags=0x437  req=0x0
19:31:48 M: 10973184   DEBUG:   msg: VO: [directx] 624x352 => 624x352 Planar YV12  [fs]
19:31:48 M: 10973184   DEBUG:   msg: VO: Description: XBOX Direct3D8 YUY2 renderer
19:31:48 M: 10973184   DEBUG:   msg: VO: Author: Frodo/JCMarshall
19:31:48 M: 10973184   DEBUG: mplayer::video_config(624, 352, 624, 352, 1, 842094169)
19:31:48 M: 10973184  NOTICE: Display resolution USER : 720p 16:9 (1)
19:31:48 M: 10420224   DEBUG: Activating window ID: 12005
19:31:48 M: 10420224   DEBUG: Checking if window ID 12005 is locked.
19:31:48 M: 11902976   DEBUG: Setting resolution 1
19:31:48 M: 13737984   DEBUG: We set resolution 1
19:31:48 M: 13381632   DEBUG: Created YV12 texture 0
19:31:48 M: 12500992    INFO: Created 444P full texture
19:31:48 M: 12500992    INFO: Loading skin file: VideoFullScreen.xml
19:31:48 M: 12484608   DEBUG: Load VideoFullScreen.xml: 30.59ms (12.82 ms xml load)
19:31:48 M: 12451840   DEBUG: Alloc resources: 43.31ms (32.25 ms skin load, 0.27 ms preload)
19:31:48 M: 12451840    INFO: Loading skin file: VideoOSD.xml
19:31:48 M: 12447744   DEBUG: Load VideoOSD.xml: 21.98ms (12.78 ms xml load)
19:31:48 M: 11649024   DEBUG: Alloc resources: 33.26ms (0.00 ms skin load, 1.02 ms preload)
19:31:48 M: 11649024    INFO: Loading skin file: VideoOSDSettings.xml
19:31:48 M: 11636736   DEBUG: Load VideoOSDSettings.xml: 22.80ms (18.29 ms xml load)
19:31:48 M: 10944512   DEBUG: Alloc resources: 35.93ms (0.00 ms skin load, 0.52 ms preload)
19:31:48 M: 10944512    INFO: Loading skin file: VideoOSDSettings.xml
19:31:48 M: 10932224   DEBUG: Load VideoOSDSettings.xml: 12.45ms (7.77 ms xml load)
19:31:48 M: 10940416   DEBUG: Alloc resources: 0.43ms (0.00 ms skin load, 0.15 ms preload)
19:31:48 M: 10895360   DEBUG: CGUIFontTTF::Load Scaled size of font Q:\media\Fonts\arial.ttf (32.763889): width = 127, height = 45, lineheight = 38
19:31:48 M: 10518528   DEBUG:   msg: *** [noise] Allocating mp_image_t, 624x352x12bpp YUV planar, 329472 bytes
19:31:48 M: 10461184   DEBUG: Alloc resources: 0.69ms (0.00 ms skin load, 0.39 ms preload)
19:32:19 M: 10276864   DEBUG:   msg: *** [noise] Allocating mp_image_t, 624x352x12bpp YUV planar, 329472 bytes
19:32:19 M: 10244096   DEBUG:   msg: FFRW:normal play
19:32:19 M:  9920512   DEBUG:   msg: *** [vo] Allocating mp_image_t, 624x352x12bpp YUV planar, 329472 bytes
19:32:19 M:  9920512   DEBUG:   msg: *** [noise] Direct Rendering mp_image_t, 624x352x12bpp YUV planar, 329472 bytes
19:32:19 M:  9310208    INFO: Starting async renderer thread
19:36:11 M:  9203712   DEBUG: Alloc resources: 0.45ms (0.00 ms skin load, 0.17 ms preload)
19:36:45 M:  9027584   DEBUG:   msg: FFRW:normal play
19:49:10 M:  9318400    INFO: Thu Jun 05 19:49:10 2008
19:49:10 M:  9318400    INFO: 
19:49:10 M:  9318400    INFO: Could not read from URL...reopening...
19:49:10 M:  9318400    INFO: 
20:14:46 M:  9437184   DEBUG: Alloc resources: 21.66ms (0.00 ms skin load, 0.17 ms preload)
20:15:23 M:  9314304   DEBUG:   msg: FFRW:normal play
20:16:01 M: 10039296   DEBUG:   msg: ds_fill_buffer: EOF reached (stream: audio)
20:16:01 M: 10051584   DEBUG:   msg: ds_fill_buffer: EOF reached (stream: video)
20:16:01 M: 10055680   DEBUG:   msg:  mplayer_close_file()
20:16:01 M: 10055680   DEBUG:   msg:
20:16:01 M: 10055680   DEBUG:   msg:  uninit_player
20:16:01 M: 18997248   DEBUG:   msg: WINSOCK2 uninit
20:16:01 M: 19013632   DEBUG:   msg: Uninit audio filters...
20:16:01 M: 19013632   DEBUG:   msg: [libaf] Removing filter dummy
20:16:01 M: 19013632   DEBUG:   msg: Uninit audio: mp3lib
20:16:01 M: 19144704   DEBUG:   msg: Uninit video: ffmpeg
20:16:01 M: 24870912   DEBUG: Deleted 444P video textures
20:16:01 M: 25755648   DEBUG: Deleted YV12 texture 0
20:16:01 M: 26091520   DEBUG:   msg:  uninit_player - done
20:16:01 M: 26091520   DEBUG: Freeing FS segment @ 0x807080
20:16:01 M: 26091520   DEBUG: Playback has finished
20:16:01 M: 26607616   DEBUG: CVideoDatabase::GetMovieInfo (http://127.0.0.1:64652/2873?.avi), query = select idMovie from movie where idFile=3247
20:16:01 M: 26607616   DEBUG: CVideoDatabase::GetEpisodeInfo (http://127.0.0.1:64652/2873?.avi), query = select idEpisode from episode where idFile=3247
20:16:01 M: 26607616   DEBUG: CVideoDatabase::GetMusicVideoInfo (http://127.0.0.1:64652/2873?.avi), query = select idMVideo from musicvideo where idFile=3247
20:16:02 M: 26615808   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
20:16:02 M: 28221440   DEBUG: Setting resolution 1
20:16:02 M: 26337280   DEBUG: We set resolution 1
20:16:02 M: 26435584   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
20:16:02 M: 26427392    INFO: Loading skin file: MyVideo.xml
20:16:02 M: 26181632   DEBUG: Load MyVideo.xml: 152.57ms (40.75 ms xml load)
20:16:02 M: 26046464   DEBUG: Alloc resources: 236.21ms (185.82 ms skin load, 0.82 ms preload)
20:16:02 M: 26066944   DEBUG: CGUIMediaWindow::GetDirectory (plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Fvideo%2F2873&mode=4&name=Bones+3x13+The+Verdict+In+The+Story)
20:16:02 M: 26066944   DEBUG:   ParentPath = [plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Fvideo%2F2873&mode=4&name=Bones+3x13+The+Verdict+In+The+Story]
20:16:02 M: 26066944   DEBUG: DIRECTORY::CPluginDirectory::GetDirectory - URL for plugin settings: Ninja Video/
20:16:02 M: 26066944   ERROR: Unable to load: Q:\plugins\video\Ninja Video\resources\settings.xml, Line 0
                             Failed to open file
20:16:02 M: 26066944   ERROR: unable to load Q:\plugins\video\Ninja Video\resources\language\German\strings.xml: Failed to open file at line 0
20:16:02 M: 26066944   ERROR: unable to load Q:\plugins\video\Ninja Video\resources\language\english\strings.xml: Failed to open file at line 0
20:16:02 M: 26066944   DEBUG: DIRECTORY::CPluginDirectory::GetDirectory - calling plugin Q:\plugins\video\Ninja Video\default.py('plugin://video/Ninja Video/','1','?url=http%3A%2F%2Fwww.ninjavideo.net%2Fvideo%2F2873&mode=4&name=Bones+3x13+The+Verdict+In+The+Story')
20:16:02 M: 25542656   DEBUG: DIRECTORY::CPluginDirectory::WaitOnScriptResult - waiting on the Ninja Video plugin...
20:16:02 M: 25542656    INFO: Mode: 4
20:16:02 M: 25542656    INFO: 
20:16:02 M: 25542656    INFO: URL: http://www.ninjavideo.net/video/2873
20:16:02 M: 25542656    INFO: 
20:16:02 M: 25542656    INFO: Name: Bones 3x13 The Verdict In The Story
20:16:02 M: 25542656    INFO: 
20:16:02 M: 25542656    INFO: index of videolinks : http://www.ninjavideo.net/video/2873
20:16:02 M: 25542656    INFO: 
20:16:03 M: 25542656   DEBUG: DIRECTORY::CPluginDirectory::WaitOnScriptResult- plugin returned successfully
20:16:03 M: 25542656    INFO: Scriptresult: Succes
20:16:03 M: 25518080    INFO: Python script stopped
20:16:03 M: 25542656   DEBUG: Q:\system\players\mplayer\mplayer.dll (base 010A0000): Detected memory leaks: 289 leaks
20:16:03 M: 25976832   DEBUG: leak caller address  10a10cc, size      128, counter    1
20:16:03 M: 26501120   DEBUG: leak caller address  10ae000, size        4, counter    1
20:16:03 M: 26501120   DEBUG: leak caller address  10ae1d5, size       40, counter    1
20:16:03 M: 26501120   DEBUG: leak caller address  10ae2a9, size       72, counter    2
20:16:03 M: 26501120   DEBUG: leak caller address  10ae49b, size        8, counter    1
20:16:03 M: 26501120   DEBUG: leak caller address  10ae4b3, size        0, counter    1
20:16:03 M: 26501120   DEBUG: leak caller address  10bd2d7, size       28, counter    2
20:16:03 M: 26501120   DEBUG: leak caller address  10bd300, size        0, counter    5
20:16:03 M: 26501120   DEBUG: leak caller address  10bd3a7, size       16, counter    1
20:16:03 M: 26501120   DEBUG: leak caller address  10bd3cc, size        0, counter    1
20:16:03 M: 26501120   DEBUG: leak caller address  10bd469, size        0, counter    5
20:16:03 M: 26501120   DEBUG: leak caller address  10c3106, size        0, counter    1
20:16:03 M: 26501120   DEBUG: leak caller address  10c312b, size        0, counter    1
20:16:03 M: 26501120   DEBUG: leak caller address  10c3151, size        0, counter    1
20:16:03 M: 26501120   DEBUG: leak caller address  113afb0, size       44, counter    1
20:16:03 M: 26501120   DEBUG: leak caller address  1159026, size       28, counter    1
20:16:03 M: 26501120   DEBUG: leak caller address  1159096, size        0, counter    1
20:16:03 M: 26501120   DEBUG: leak caller address  11590f1, size        5, counter    1
20:16:03 M: 26501120   DEBUG: leak caller address  11592cf, size       10, counter    1
20:16:03 M: 26501120   DEBUG: leak caller address  1159325, size        0, counter    1
20:16:03 M: 26501120   DEBUG: leak caller address  115cf29, size     1633, counter   50
20:16:03 M: 26501120   DEBUG: leak caller address  11c8dd4, size      869, counter    1
20:16:03 M: 26501120   DEBUG: leak caller address  11c8ef0, size    31232, counter   10
20:16:03 M: 26501120   DEBUG: leak caller address  13c24ad, size   496650, counter  198
20:16:03 M: 26501120   DEBUG: mplayer.dll: Total bytes leaked: 530767
20:16:23 M: 31072256   DEBUG: CApplication::OnKey: 216 pressed, action is 9
20:16:23 M: 31199232   DEBUG: CGUIMediaWindow::GetDirectory (plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Fcat%2F294&mode=2&name=Bones)
20:16:23 M: 31199232   DEBUG:   ParentPath = [plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Ftvshows&mode=1&name=3.+TV+SHOWS]
20:16:23 M: 31199232   DEBUG: DIRECTORY::CPluginDirectory::GetDirectory - URL for plugin settings: Ninja Video/
20:16:23 M: 31199232   ERROR: Unable to load: Q:\plugins\video\Ninja Video\resources\settings.xml, Line 0
                             Failed to open file
20:16:23 M: 31199232   ERROR: unable to load Q:\plugins\video\Ninja Video\resources\language\German\strings.xml: Failed to open file at line 0
20:16:23 M: 31199232   ERROR: unable to load Q:\plugins\video\Ninja Video\resources\language\english\strings.xml: Failed to open file at line 0
20:16:23 M: 31199232   DEBUG: DIRECTORY::CPluginDirectory::GetDirectory - calling plugin Q:\plugins\video\Ninja Video\default.py('plugin://video/Ninja Video/','1','?url=http%3A%2F%2Fwww.ninjavideo.net%2Fcat%2F294&mode=2&name=Bones')
20:16:23 M: 30674944   DEBUG: DIRECTORY::CPluginDirectory::WaitOnScriptResult - waiting on the Ninja Video plugin...
20:16:23 M: 30683136    INFO: Mode: 2
20:16:23 M: 30683136    INFO: 
20:16:23 M: 30683136    INFO: URL: http://www.ninjavideo.net/cat/294
20:16:23 M: 30683136    INFO: 
20:16:23 M: 30683136    INFO: Name: Bones
20:16:23 M: 30683136    INFO: 
20:16:23 M: 30683136    INFO: index of seasons : http://www.ninjavideo.net/cat/294
20:16:23 M: 30683136    INFO: 
20:16:23 M: 30683136    INFO: Scriptresult: Succes
20:16:23 M: 30683136   DEBUG: DIRECTORY::CPluginDirectory::WaitOnScriptResult- plugin returned successfully
20:16:23 M: 30683136    INFO: Python script stopped
20:16:24 M: 31080448   DEBUG: CApplication::OnKey: 167 pressed, action is 4
20:16:27 M: 31080448   DEBUG: CApplication::OnKey: 11 pressed, action is 7
20:16:27 M: 31080448   DEBUG: Clearing cached fileitems [plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Fvideo%2F3823&mode=4&name=Bones+3x14+The+Wannabe+in+the+Weeds+]
20:16:27 M: 31207424   DEBUG: CGUIMediaWindow::GetDirectory (plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Fvideo%2F3823&mode=4&name=Bones+3x14+The+Wannabe+in+the+Weeds+)
20:16:27 M: 31207424   DEBUG:   ParentPath = [plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Fcat%2F294&mode=2&name=Bones]
20:16:27 M: 31207424   DEBUG: DIRECTORY::CPluginDirectory::GetDirectory - URL for plugin settings: Ninja Video/
20:16:27 M: 31207424   ERROR: Unable to load: Q:\plugins\video\Ninja Video\resources\settings.xml, Line 0
                             Failed to open file
20:16:27 M: 31207424   ERROR: unable to load Q:\plugins\video\Ninja Video\resources\language\German\strings.xml: Failed to open file at line 0
20:16:27 M: 31207424   ERROR: unable to load Q:\plugins\video\Ninja Video\resources\language\english\strings.xml: Failed to open file at line 0
20:16:27 M: 31207424   DEBUG: DIRECTORY::CPluginDirectory::GetDirectory - calling plugin Q:\plugins\video\Ninja Video\default.py('plugin://video/Ninja Video/','1','?url=http%3A%2F%2Fwww.ninjavideo.net%2Fvideo%2F3823&mode=4&name=Bones+3x14+The+Wannabe+in+the+Weeds+')
20:16:27 M: 30683136   DEBUG: DIRECTORY::CPluginDirectory::WaitOnScriptResult - waiting on the Ninja Video plugin...
20:16:27 M: 30683136    INFO: Mode: 4
20:16:27 M: 30683136    INFO: 
20:16:27 M: 30683136    INFO: URL: http://www.ninjavideo.net/video/3823
20:16:27 M: 30683136    INFO: 
20:16:27 M: 30683136    INFO: Name: Bones 3x14 The Wannabe in the Weeds
20:16:27 M: 30683136    INFO: 
20:16:27 M: 30683136    INFO: index of videolinks : http://www.ninjavideo.net/video/3823
20:16:27 M: 30683136    INFO: 
20:16:27 M: 30683136   DEBUG: DIRECTORY::CPluginDirectory::WaitOnScriptResult- plugin returned successfully
20:16:27 M: 30683136    INFO: Scriptresult: Succes
20:16:27 M: 30662656    INFO: Python script stopped
20:16:29 M: 31080448   DEBUG: CApplication::OnKey: 167 pressed, action is 4
20:16:30 M: 31080448   DEBUG: CApplication::OnKey: 11 pressed, action is 7
20:16:30 M: 31080448   DEBUG: Initializing FS_SEG..
20:16:30 M: 31080448   DEBUG: FS segment @ 0xe5e000
20:16:30 M: 30597120   DEBUG: Unable to resolve kernel32.dll PeekNamedPipe
20:16:30 M: 30597120   DEBUG: Dll MSVCP71.dll was not found in path
20:16:30 M: 30597120   DEBUG: Unable to load referenced dll MSVCP71.dll - Dll: Q:\system\libcurl.dll
20:16:30 M: 30597120   DEBUG: Unable to resolve MSVCP71.dll ?_Nomemory@std@@YAXXZ
20:16:30 M: 30597120   DEBUG: kernel32.dll fake function DisableThreadLibraryCalls called
20:16:30 M: 30560256    INFO: XCURL::DllLibCurlGlobal::easy_aquire - Created session to http://127.0.0.1
20:16:30 M: 30560256   DEBUG: Curl:: Debug About to connect() to 127.0.0.1 port 64652 (#0)
20:16:30 M: 30560256   DEBUG: Curl:: Debug   Trying 127.0.0.1...
20:16:30 M: 30035968   DEBUG: Curl:: Debug connected
20:16:30 M: 30023680   DEBUG: Curl:: Debug Connected to 127.0.0.1 (127.0.0.1) port 64652 (#0)
20:16:30 M: 30023680   DEBUG: Curl:: Debug HEAD /3823?.avi HTTP/1.1

                             User-Agent: XBMC/pre-2.1 (compatible; MSIE 6.0; Windows NT 5.1; WinampMPEG/5.09)

                             Host: 127.0.0.1:64652

                             Accept: */*
20:16:30 M: 30035968    INFO: Thu Jun 05 20:16:30 2008
20:16:30 M: 30035968    INFO: 
20:16:30 M: 30035968    INFO: Serving HEAD request...
20:16:30 M: 30035968    INFO: 
20:16:30 M: 30035968    INFO: Thu Jun 05 20:16:30 2008
20:16:30 M: 30035968    INFO: 
20:16:30 M: 30035968    INFO: Getting infos from NinjaVideo.net for Video ID
20:16:30 M: 30035968    INFO: 
20:16:30 M: 30035968    INFO: 3823
20:16:30 M: 30035968    INFO: 
20:16:30 M: 30035968    INFO: ...
20:16:30 M: 30035968    INFO: 
20:16:31 M: 30044160 WARNING: Untested function dllgethostbyaddr called!
20:16:31 M: 30044160    INFO: 127.0.0.1 - - [05/Jun/2008 20:16:31] "HEAD /3823?.avi HTTP/1.1" 200 -
20:16:31 M: 30044160   DEBUG: Curl:: Debug HTTP 1.0, assume close after body
20:16:31 M: 30044160    INFO: Thu Jun 05 20:16:31 2008
20:16:31 M: 30044160   DEBUG: Curl:: Debug HTTP/1.0 200 OK
20:16:31 M: 30044160    INFO: 
20:16:31 M: 30044160   DEBUG: Curl:: Debug Server: BaseHTTP/0.3 Python/2.4.3
20:16:31 M: 30044160    INFO: Sending headers...
20:16:31 M: 30044160   DEBUG: Curl:: Debug Date: Thu, 05 Jun 2008 18:16:31 GMT
20:16:31 M: 30044160    INFO: 
20:16:31 M: 30044160   DEBUG: Curl:: Debug Content-Disposition: inline; filename="Bones 3x14 The Wannabe in the Weeds .avi"
20:16:31 M: 30568448   DEBUG: Curl:: Debug Content-type: application/x-msvideo
20:16:31 M: 30568448   DEBUG: Curl:: Debug Last-Modified: Wed, 21 Feb 2000 08:43:39 GMT
20:16:31 M: 30568448   DEBUG: Curl:: Debug ETag: bca382c81484983f2d437f97d1e141f3
20:16:31 M: 30568448   DEBUG: Curl:: Debug Accept-Ranges: bytes
20:16:31 M: 30568448   DEBUG: Curl:: Debug Cache-Control: public, must-revalidate
20:16:31 M: 30568448   DEBUG: Curl:: Debug Cache-Control: no-cache
20:16:31 M: 30568448   DEBUG: Curl:: Debug Pragma: no-cache
20:16:31 M: 30568448   DEBUG: Curl:: Debug features: seekable,stridable
20:16:31 M: 30568448   DEBUG: Curl:: Debug client-id: 12345
20:16:31 M: 30568448   DEBUG: Curl:: Debug Content-Length: 367852568
20:16:31 M: 30568448   DEBUG: Curl:: Debug
20:16:31 M: 30568448   DEBUG: Curl:: Debug Closing connection #0
20:16:31 M: 30568448   DEBUG: FileCurl::Close(D00B4D24) http://127.0.0.1:64652/3823?.avi
20:16:31 M: 30568448   DEBUG: CPlayerCoreFactor::GetPlayers(http://127.0.0.1:64652/3823?.avi)
20:16:31 M: 30568448   ERROR: XFILE::CFile::Delete - Error deleting file Z:\xbmc.edl
20:16:31 M: 30568448   DEBUG: file:http://127.0.0.1:64652/3823?.avi IsDVDImage:0 IsDVDIfoFile:0
20:16:31 M: 30568448    INFO: Loading skin file: DialogProgress.xml
20:16:31 M: 30568448   DEBUG: Load DialogProgress.xml: 38.35ms (35.68 ms xml load)
20:16:31 M: 30515200   DEBUG: Alloc resources: 15.46ms (0.00 ms skin load, 0.34 ms preload)
20:16:31 M: 29601792    INFO: mplayer play:http://127.0.0.1:64652/3823?.avi cachesize:8192
20:16:32 M: 23711744   DEBUG:   msg:  set audio functions called
20:16:32 M: 23711744   DEBUG:   msg:  done
20:16:32 M: 23711744   DEBUG: FileCurl::Open(00E90CC0) http://127.0.0.1:64652/3823?.avi
20:16:32 M: 23609344   DEBUG: Curl:: Debug About to connect() to 127.0.0.1 port 64652 (#0)
20:16:32 M: 23609344   DEBUG: Curl:: Debug   Trying 127.0.0.1...
20:16:32 M: 23085056   DEBUG: Curl:: Debug connected
20:16:32 M: 23076864   DEBUG: Curl:: Debug Connected to 127.0.0.1 (127.0.0.1) port 64652 (#0)
20:16:32 M: 23080960    INFO: Thu Jun 05 20:16:32 2008
20:16:32 M: 23080960   DEBUG: Curl:: Debug GET /3823?.avi HTTP/1.1

                             User-Agent: XBMC/pre-2.1 (compatible; MSIE 6.0; Windows NT 5.1; WinampMPEG/5.09)

                             Host: 127.0.0.1:64652

                             Accept: */*
20:16:32 M: 23080960    INFO: 
20:16:32 M: 23080960    INFO: Serving GET request...
20:16:32 M: 23080960    INFO: 
20:16:32 M: 23080960 WARNING: Untested function dllgethostbyaddr called!
20:16:32 M: 23080960    INFO: 127.0.0.1 - - [05/Jun/2008 20:16:32] "GET /3823?.avi HTTP/1.1" 200 -
20:16:32 M: 23080960   DEBUG: Curl:: Debug HTTP 1.0, assume close after body
20:16:32 M: 23080960    INFO: Thu Jun 05 20:16:32 2008
20:16:32 M: 23080960   DEBUG: Curl:: Debug HTTP/1.0 200 OK
20:16:32 M: 23080960    INFO: 
20:16:32 M: 23080960   DEBUG: Curl:: Debug Server: BaseHTTP/0.3 Python/2.4.3
20:16:32 M: 23080960    INFO: Sending headers...
20:16:32 M: 23080960   DEBUG: Curl:: Debug Date: Thu, 05 Jun 2008 18:16:32 GMT
20:16:32 M: 23080960    INFO: 
20:16:32 M: 23080960   DEBUG: Curl:: Debug Content-Disposition: inline; filename="Bones 3x14 The Wannabe in the Weeds .avi"
20:16:32 M: 23080960    INFO: Thu Jun 05 20:16:32 2008
20:16:32 M: 23080960   DEBUG: Curl:: Debug Content-type: application/x-msvideo
20:16:32 M: 23080960    INFO: 
20:16:32 M: 23080960   DEBUG: Curl:: Debug Last-Modified: Wed, 21 Feb 2000 08:43:39 GMT
20:16:32 M: 23080960    INFO: Starting download at byte
20:16:32 M: 23080960   DEBUG: Curl:: Debug ETag: bca382c81484983f2d437f97d1e141f3
20:16:32 M: 23080960    INFO: 
20:16:32 M: 23080960   DEBUG: Curl:: Debug Accept-Ranges: bytes
20:16:32 M: 23080960    INFO: 0
20:16:32 M: 23080960   DEBUG: Curl:: Debug Cache-Control: public, must-revalidate
20:16:32 M: 23080960    INFO: 
20:16:32 M: 23080960   DEBUG: Curl:: Debug Cache-Control: no-cache
20:16:32 M: 23080960   DEBUG: Curl:: Debug Pragma: no-cache
20:16:32 M: 23080960   DEBUG: Curl:: Debug features: seekable,stridable
20:16:32 M: 23080960   DEBUG: Curl:: Debug client-id: 12345
20:16:32 M: 23080960   DEBUG: Curl:: Debug Content-Length: 367852568
20:16:32 M: 23080960   DEBUG: Curl:: Debug
20:16:33 M: 22708224   DEBUG: FileCurl::Close(00E90CC0) http://127.0.0.1:64652/3823?.avi
20:16:33 M: 22708224   DEBUG: Curl:: Debug Expire cleared
20:16:33 M: 22708224   DEBUG: Curl:: Debug Closing connection #0
20:16:33 M: 22917120    INFO: Using -subcp enca:__:CP1252 to detect the subtitle charset
20:16:33 M: 22917120    INFO: Client Closed the connection.
20:16:33 M: 22917120    INFO: Flipping bi-directional subtitles disabled
20:16:33 M: 22917120    INFO: 
20:16:33 M: 23711744   DEBUG:   msg: mplayer_init()
20:16:33 M: 23711744   DEBUG:   msg: MPlayer XBMC-SVN-r10070-4.1.2 (C) 2000-2004 MPlayer Team
20:16:33 M: 23711744   DEBUG:   msg: CPU: Intel Celeron 2/Pentium III Coppermine,Geyserville
20:16:33 M: 23711744   DEBUG:   msg: (Family: 6, Stepping: 10)
20:16:33 M: 23711744   DEBUG:   msg: Detected cache-line size is 32 bytes
20:16:33 M: 23711744   DEBUG:   msg: CPUflags:  MMX: 1 MMX2: 1 3DNow: 0 3DNow2: 0 SSE: 0 SSE2: 0
20:16:33 M: 23711744   DEBUG:   msg: Compiled for x86 CPU with extensions:
20:16:33 M: 23711744   DEBUG:   msg:  MMX
20:16:33 M: 23711744   DEBUG:   msg:  MMX2
20:16:33 M: 23711744   DEBUG:   msg:  SSE
20:16:33 M: 23711744   DEBUG:   msg:
20:16:33 M: 23699456   DEBUG:   msg:
20:16:33 M: 23711744   DEBUG:   msg: Creating config file: Q:\system\players\mplayer\config
20:16:33 M: 23699456   DEBUG:   msg:
20:16:33 M: 23711744   DEBUG:   msg: get_path('codecs.conf') -> 'Q:\system\players\mplayer\codecs.conf'
20:16:33 M: 23711744   DEBUG:   msg: Reading Q:\system\players\mplayer\codecs.conf:
20:16:33 M: 23375872   DEBUG:   msg: 108 audio & 225 video codecs
20:16:33 M: 23375872   DEBUG:   msg: CommandLine:
20:16:33 M: 23375872   DEBUG:   msg:  '-dr'
20:16:33 M: 23375872   DEBUG:   msg:  '-v'
20:16:33 M: 23375872   DEBUG:   msg:  '-subcp'
20:16:33 M: 23375872   DEBUG:   msg:  'enca:__:CP1252'
20:16:33 M: 23375872   DEBUG:   msg:  '-channels'
20:16:33 M: 23375872   DEBUG:   msg:  '6'
20:16:33 M: 23375872   DEBUG:   msg:  '-ac'
20:16:33 M: 23375872   DEBUG:   msg:  'hwdts,hwac3,'
20:16:33 M: 23375872   DEBUG:   msg:  '-noflip-hebrew'
20:16:33 M: 23375872   DEBUG:   msg:  '-noflip-hebrew-commas'
20:16:33 M: 23375872   DEBUG:   msg:  '-vf'
20:16:33 M: 23375872   DEBUG:   msg:  'noise=1ta:1ta'
20:16:33 M: 23375872   DEBUG:   msg:  '-fs'
20:16:33 M: 23375872   DEBUG:   msg:  '1.avi'
20:16:33 M: 23375872   DEBUG:   msg:
20:16:33 M: 23375872   DEBUG:   msg: get_path('font/font.desc') -> 'Q:\system\players\mplayer\font/font.desc'
20:16:33 M: 22847488   DEBUG:   msg: font: can't open file: Q:\system\players\mplayer\font/font.desc
20:16:33 M: 22847488   DEBUG:   msg: font: can't open file: Q:\system\players\mplayer/font/font.desc
20:16:33 M: 23375872   DEBUG:   msg: Using MMX (with tiny bit MMX2) Optimized OnScreenDisplay
20:16:33 M: 23367680   DEBUG:   msg: Using XBOX High Resolution Timers for timing
20:16:33 M: 23367680   DEBUG:   msg: get_path('input.conf') -> 'Q:\system\players\mplayer\input.conf'
20:16:33 M: 23367680   DEBUG:   msg: Parsing input config file Q:\system\players\mplayer\input.conf
20:16:33 M: 23363584   DEBUG:   msg: Input config file Q:\system\players\mplayer\input.conf parsed: 51 binds
20:16:33 M: 23363584   DEBUG:   msg: mplayer_open_file(http://127.0.0.1:64652/3823?.avi)
20:16:33 M: 23363584   DEBUG:   msg: get_path('3823?.avi.conf') -> 'Q:\system\players\mplayer\3823?.avi.conf'
20:16:33 M: 23363584   DEBUG:   msg: 
                             Playing http://127.0.0.1:64652/3823?.avi.
20:16:33 M: 23363584   DEBUG:   msg: find subtitles
20:16:33 M: 23363584   DEBUG:   msg: auto open Z:\subtitle
20:16:33 M: 23363584   DEBUG:   msg: Filename for url is now http://127.0.0.1:64652/3823?.avi
20:16:33 M: 23363584   DEBUG:   msg: protocol:http
20:16:33 M: 23363584   DEBUG:   msg: open stream protocol:http
20:16:33 M: 23343104   DEBUG:   msg: WINSOCK2 init: 0
20:16:33 M: 23343104   DEBUG:   msg: Filename for url is now http://127.0.0.1:64652/3823?.avi
20:16:33 M: 23343104   DEBUG:   msg: Connecting to server 127.0.0.1[127.0.0.1]:64652 ...
20:16:33 M: 22822912    INFO: Thu Jun 05 20:16:33 2008
20:16:33 M: 22822912    INFO: 
20:16:33 M: 22822912    INFO: Serving GET request...
20:16:33 M: 22822912    INFO: 
20:16:33 M: 22822912 WARNING: Untested function dllgethostbyaddr called!
20:16:33 M: 22822912    INFO: 127.0.0.1 - - [05/Jun/2008 20:16:33] "GET /3823?.avi HTTP/1.0" 200 -
20:16:33 M: 22822912    INFO: Thu Jun 05 20:16:33 2008
20:16:33 M: 22822912    INFO: 
20:16:33 M: 22822912    INFO: Sending headers...
20:16:34 M: 22822912    INFO: 
20:16:34 M: 22822912    INFO: Thu Jun 05 20:16:34 2008
20:16:34 M: 22822912    INFO: 
20:16:34 M: 22822912   DEBUG:   msg: --- HTTP DEBUG HEADER --- START ---
20:16:34 M: 22822912    INFO: Starting download at byte
20:16:34 M: 22822912   DEBUG:   msg: protocol:           [HTTP/1.0]
20:16:34 M: 22822912    INFO: 
20:16:34 M: 22822912   DEBUG:   msg: http minor version: [0]
20:16:34 M: 22822912    INFO: 0
20:16:34 M: 22822912   DEBUG:   msg: uri:                [(null)]
20:16:34 M: 22822912    INFO: 
20:16:34 M: 22822912   DEBUG:   msg: method:             [(null)]
20:16:34 M: 22822912   DEBUG:   msg: status code:        [200]
20:16:34 M: 22822912   DEBUG:   msg: reason phrase:      [OK]
20:16:34 M: 22822912   DEBUG:   msg: body size:          [0]
20:16:34 M: 22822912   DEBUG:   msg: Fields:
20:16:34 M: 22822912   DEBUG:   msg:  0 - Server: BaseHTTP/0.3 Python/2.4.3
20:16:34 M: 22822912   DEBUG:   msg:  1 - Date: Thu, 05 Jun 2008 18:16:33 GMT
20:16:34 M: 22822912   DEBUG:   msg:  2 - Content-Disposition: inline; filename="Bones 3x14 The Wannabe in the Weeds .avi"
20:16:34 M: 22822912   DEBUG:   msg:  3 - Content-type: application/x-msvideo
20:16:34 M: 22822912   DEBUG:   msg:  4 - Last-Modified: Wed, 21 Feb 2000 08:43:39 GMT
20:16:34 M: 22822912   DEBUG:   msg:  5 - ETag: bca382c81484983f2d437f97d1e141f3
20:16:34 M: 22822912   DEBUG:   msg:  6 - Accept-Ranges: bytes
20:16:34 M: 22822912   DEBUG:   msg:  7 - Cache-Control: public, must-revalidate
20:16:34 M: 22822912   DEBUG:   msg:  8 - Cache-Control: no-cache
20:16:34 M: 22822912   DEBUG:   msg:  9 - Pragma: no-cache
20:16:34 M: 22822912   DEBUG:   msg:  10 - features: seekable,stridable
20:16:34 M: 22822912   DEBUG:   msg:  11 - client-id: 12345
20:16:34 M: 22822912   DEBUG:   msg:  12 - Content-Length: 367852568
20:16:34 M: 22822912   DEBUG:   msg: --- HTTP DEBUG HEADER --- END ---
20:16:34 M: 22822912   DEBUG:   msg: Content-Type: [application/x-msvideo]
20:16:34 M: 22822912   DEBUG:   msg: Content-Length: [367852568]
20:16:34 M: 22822912   DEBUG:   msg: Cache size set to 8192 KBytes
20:16:34 M: 22822912   DEBUG:   msg: Connected to server: 127.0.0.1
20:16:34 M: 13877248   DEBUG:   msg: CACHE_PRE_INIT: 0 [0] 0  pre:1677721  eof:0
20:16:34 M: 13877248   DEBUG:   msg: 
Cache fill:  0.00% (0 bytes)
20:16:34 M: 13852672   DEBUG:   msg: 
Cache fill:  0.00% (0 bytes)
<Stuff removed, pastebin size limit>
20:16:38 M: 13668352   DEBUG:   msg: 
Cache fill:  6.25% (524288 bytes)
20:16:38 M: 13611008   DEBUG:   msg: 
Cache fill:  6.25% (524288 bytes)
20:16:38 M: 13545472    INFO: XCURL::DllLibCurlGlobal::CheckIdle - Closing session to http ://127.0.0.1 (easy=00FF2010, multi=00A23C10)
20:16:38 M: 13565952   DEBUG:   msg: 
Cache fill:  6.25% (524288 bytes)
20:16:39 M: 13504512   DEBUG:   msg: 
Cache fill:  6.25% (524288 bytes)
<Stuff removed, pastebin size limit>
20:16:46 M: 13619200   DEBUG:   msg: 
Cache fill: 18.75% (1572864 bytes)
20:16:46 M: 13545472   DEBUG:   msg: 
Cache fill: 18.75% (1572864 bytes)
20:16:47 M: 13897728   DEBUG:   msg:
20:16:47 M: 13897728   DEBUG:   msg: demux_open(00FFA590,0,-1,-1,-1)
20:16:47 M: 13897728   DEBUG:   msg: AVI file format detected.
20:16:47 M: 13897728   DEBUG:   msg: list_end=0x146
20:16:47 M: 13897728   DEBUG:   msg: ======= AVI Header =======
20:16:47 M: 13897728   DEBUG:   msg: us/frame: 41708  (fps=23.976)
20:16:47 M: 13897728   DEBUG:   msg: max bytes/sec: 0
20:16:47 M: 13897728   DEBUG:   msg: padding: 0
20:16:47 M: 13897728   DEBUG:   msg: MainAVIHeader.dwFlags: (272) HAS_INDEX IS_INTERLEAVED
20:16:47 M: 13897728   DEBUG:   msg: frames  total: 60983   initial: 0
20:16:47 M: 13897728   DEBUG:   msg: streams: 2
20:16:47 M: 13897728   DEBUG:   msg: Suggested BufferSize: 0
20:16:47 M: 13897728   DEBUG:   msg: Size:  624 x 352
20:16:47 M: 13897728   DEBUG:   msg: ==========================
20:16:47 M: 13897728   DEBUG:   msg: list_end=0xD4
20:16:47 M: 13897728   DEBUG:   msg: ==> Found video stream: 0
20:16:47 M: 13897728   DEBUG:   msg: ====== STREAM Header =====
20:16:47 M: 13897728   DEBUG:   msg: Type: vids   FCC: XVID (44495658)
20:16:47 M: 13897728   DEBUG:   msg: Flags: 0
20:16:47 M: 13897728   DEBUG:   msg: Priority: 0   Language: 0
20:16:47 M: 13897728   DEBUG:   msg: InitialFrames: 0
20:16:47 M: 13897728   DEBUG:   msg: Rate: 23976024/1000000 = 23.976
20:16:47 M: 13897728   DEBUG:   msg: Start: 0   Len: 60983
20:16:47 M: 13897728   DEBUG:   msg: Suggested BufferSize: 65885
20:16:47 M: 13897728   DEBUG:   msg: Quality 0
20:16:47 M: 13897728   DEBUG:   msg: Sample size: 0
20:16:47 M: 13897728   DEBUG:   msg: ==========================
20:16:47 M: 13897728   DEBUG:   msg: Found 'bih', 40 bytes of 40
20:16:47 M: 13897728   DEBUG:   msg: ======= VIDEO Format ======
20:16:47 M: 13897728   DEBUG:   msg:   biSize 40
20:16:47 M: 13897728   DEBUG:   msg:   biWidth 624
20:16:47 M: 13897728   DEBUG:   msg:   biHeight 352
20:16:47 M: 13897728   DEBUG:   msg:   biPlanes 1
20:16:47 M: 13897728   DEBUG:   msg:   biBitCount 24
20:16:47 M: 13897728   DEBUG:   msg:   biCompression 1145656920='XVID'
20:16:47 M: 13897728   DEBUG:   msg:   biSizeImage 658944
20:16:47 M: 13897728   DEBUG:   msg: ===========================
20:16:47 M: 13897728   DEBUG:   msg: Regenerating keyframe table for MPEG-4 video.
20:16:47 M: 13897728   DEBUG:   msg: list_end=0x146
20:16:47 M: 13897728   DEBUG:   msg: ==> Found audio stream: 1
20:16:47 M: 13897728   DEBUG:   msg: ====== STREAM Header =====
20:16:47 M: 13897728   DEBUG:   msg: Type: auds   FCC:  (0)
20:16:47 M: 13897728   DEBUG:   msg: Flags: 0
20:16:47 M: 13897728   DEBUG:   msg: Priority: 0   Language: 0
20:16:47 M: 13897728   DEBUG:   msg: InitialFrames: 0
20:16:47 M: 13897728   DEBUG:   msg: Rate: 48000/1152 = 41.667
20:16:47 M: 13897728   DEBUG:   msg: Start: 0   Len: 105980
20:16:47 M: 13897728   DEBUG:   msg: Suggested BufferSize: 0
20:16:47 M: 13897728   DEBUG:   msg: Quality 0
20:16:47 M: 13897728   DEBUG:   msg: Sample size: 0
20:16:47 M: 13897728   DEBUG:   msg: ==========================
20:16:47 M: 13897728   DEBUG:   msg: Found 'wf', 30 bytes of 18
20:16:47 M: 13897728   DEBUG:   msg: ======= WAVE Format =======
20:16:47 M: 13897728   DEBUG:   msg: Format Tag: 85 (0x55)
20:16:47 M: 13897728   DEBUG:   msg: Channels: 2
20:16:47 M: 13897728   DEBUG:   msg: Samplerate: 48000
20:16:47 M: 13897728   DEBUG:   msg: avg byte/sec: 16000
20:16:47 M: 13897728   DEBUG:   msg: Block align: 1152
20:16:47 M: 13897728   DEBUG:   msg: bits/sample: 16
20:16:47 M: 13897728   DEBUG:   msg: cbSize: 12
20:16:47 M: 13897728   DEBUG:   msg: mp3.wID=1
20:16:47 M: 13897728   DEBUG:   msg: mp3.fdwFlags=0x2
20:16:47 M: 13897728   DEBUG:   msg: mp3.nBlockSize=1152
20:16:47 M: 13897728   DEBUG:   msg: mp3.nFramesPerBlock=1
20:16:47 M: 13897728   DEBUG:   msg: mp3.nCodecDelay=0
20:16:47 M: 13897728   DEBUG:   msg: ===========================
20:16:47 M: 13897728   DEBUG:   msg: list_end=0x16A
20:16:47 M: 13897728   DEBUG:   msg: hdr=Software  size=16
20:16:47 M: 13897728   DEBUG:   msg: Software  : transcode-1.0.2
20:16:47 M: 13897728   DEBUG:   msg: list_end=0x15C438E0
20:16:47 M: 13897728   DEBUG:   msg: Found movie at 0x800 - 0x15C438E0
20:16:49 M: 13619200   DEBUG:   msg: Connecting to server 127.0.0.1[127.0.0.1]:64652 ...
20:16:49 M: 13004800    INFO: Thu Jun 05 20:16:49 2008
20:16:49 M: 13004800    INFO: 
20:16:49 M: 13004800    INFO: Serving GET request...
20:16:49 M: 13004800    INFO: 
20:16:49 M: 13004800 WARNING: Untested function dllgethostbyaddr called!
20:16:49 M: 13004800    INFO: 127.0.0.1 - - [05/Jun/2008 20:16:49] "GET /3823?.avi HTTP/1.0" 206 -
20:16:49 M: 13004800    INFO: Thu Jun 05 20:16:49 2008
20:16:49 M: 13004800    INFO: 
20:16:49 M: 13004800    INFO: Sending headers...
20:16:49 M: 13004800    INFO: 
20:16:49 M: 13004800   DEBUG:   msg: Content-Type: [application/x-msvideo]
20:16:49 M: 13004800    INFO: Thu Jun 05 20:16:49 2008
20:16:49 M: 13004800   DEBUG:   msg: Content-Length: [2685976]
20:16:49 M: 13107200    INFO: 
20:16:49 M: 13107200    INFO: Starting download at byte
20:16:49 M: 13107200    INFO: 
20:16:49 M: 13107200    INFO: 365166592
20:16:49 M: 13107200    INFO: 
20:16:49 M: 13107200    INFO: Client Closed the connection.
20:16:49 M: 13107200    INFO: 
20:16:51 M: 13897728   DEBUG:   msg: Reading INDEX block, 166963 chunks for 60983 frames (fpos=365181160).
20:17:06 M: 11747328   DEBUG:   msg: Connecting to server 127.0.0.1[127.0.0.1]:64652 ...
20:17:06 M: 11223040    INFO: Thu Jun 05 20:17:06 2008
20:17:06 M: 11223040    INFO: 
20:17:06 M: 11223040    INFO: Serving GET request...
20:17:06 M: 11223040    INFO: 
20:17:06 M: 11223040 WARNING: Untested function dllgethostbyaddr called!
20:17:06 M: 11223040    INFO: 127.0.0.1 - - [05/Jun/2008 20:17:06] "GET /3823?.avi HTTP/1.0" 200 -
20:17:06 M: 11223040    INFO: Thu Jun 05 20:17:06 2008
20:17:06 M: 11223040    INFO: 
20:17:06 M: 11223040    INFO: Sending headers...
20:17:06 M: 11223040    INFO: 
20:17:06 M: 11223040   DEBUG:   msg: Content-Type: [application/x-msvideo]
20:17:06 M: 11223040    INFO: Thu Jun 05 20:17:06 2008
20:17:06 M: 11223040   DEBUG:   msg: Content-Length: [367852568]
20:17:06 M: 11223040    INFO: 
20:17:06 M: 11223040    INFO: Starting download at byte
20:17:06 M: 11223040    INFO: 
20:17:06 M: 11223040    INFO: 0
20:17:06 M: 11223040    INFO: 
20:17:07 M: 11223040   DEBUG:   msg: AVI index offset: 0x0 (movi=0x800 idx0=0x800 idx1=0x1174)
20:17:07 M: 11223040   DEBUG:   msg: Auto-selected AVI video ID = 0
20:17:07 M: 11223040   DEBUG:   msg: Auto-selected AVI audio ID = 1
20:17:07 M: 11223040   DEBUG:   msg: AVI: Searching for audio stream (id:1)
20:17:07 M: 11223040   DEBUG:   msg: XXX initial  v_pts=0.000  a_pos=0 (0.000)
20:17:07 M: 11223040   DEBUG:   msg: AVI video size=321156747 (60983) audio size=42656040 (105980)
20:17:07 M: 11223040   DEBUG:   msg: [V] filefmt:3  fourcc:0x44495658  size:624x352  fps:23.98  ftime:=0.0417
20:17:07 M: 11223040   DEBUG:   msg: Clip info:
20:17:07 M: 11223040   DEBUG:   msg:  Software: transcode-1.0.2
20:17:07 M: 11223040   DEBUG:   msg: get_path('sub/') -> 'Q:\system\players\mplayer\sub/'
20:17:07 M: 11223040   DEBUG:   msg: Starting lookup for subs
20:17:07 M: 11223040   DEBUG:   msg: Global Subsize: 0 - VOBSUB: -1, DEMUX: -1, SUBS: -1
20:17:07 M: 11223040   DEBUG:   msg: Global Subpos : -1
20:17:07 M: 11223040   DEBUG:   msg: ==========================================================================
20:17:07 M: 11223040   DEBUG:   msg: Forced audio codec: hwdts
20:17:07 M: 11223040   DEBUG:   msg: Forced audio codec: hwac3
20:17:07 M: 11223040   DEBUG:   msg: Opening audio decoder: [mp3lib] MPEG layer-2, layer-3
20:17:07 M: 11223040   DEBUG:   msg: dec_audio: Allocating 4608 + 65536 = 70144 bytes for output buffer.
20:17:07 M: 11149312   DEBUG:   msg: mp3lib: made decode tables with MMX optimization
20:17:07 M: 11149312   DEBUG:   msg: mp3lib: using MMX optimized decore!
20:17:07 M: 11149312   DEBUG:   msg: MP3lib: init layer2&3 finished, tables done
20:17:07 M: 11149312   DEBUG:   msg: 
MPEG 1.0, Layer III, 48000 Hz 32 kbit Joint-Stereo, BPF: 96
20:17:07 M: 11149312   DEBUG:   msg: Channels: 2, copyright: No, original: Yes, CRC: No, emphasis: 0
20:17:07 M: 11149312   DEBUG:   msg: AUDIO: 48000 Hz, 2 ch, 16 bit (0x10), ratio: 4000->192000 (32.0 kbit)
20:17:07 M: 11149312   DEBUG:   msg: Selected audio codec: [mp3] afm:mp3lib (mp3lib MPEG layer-2, layer-3)
20:17:07 M: 11149312   DEBUG:   msg: ==========================================================================
20:17:07 M: 11149312   DEBUG: CXBoxRenderManager::PreInit - Selected RGB-Renderer V2
20:17:07 M: 11149312   DEBUG: Deleted 444P video textures
20:17:07 M: 11149312   DEBUG:   msg: Opening video filter: [noise=1ta:1ta]
20:17:07 M: 11071488   DEBUG:   msg: [vo] query(Planar YV12) -> 3
20:17:07 M: 11071488   DEBUG:   msg: ==========================================================================
20:17:07 M: 11071488   DEBUG:   msg: Opening video decoder: [ffmpeg] FFmpeg's libavcodec codec family
20:17:07 M: 10735616   DEBUG:   msg: INFO: libavcodec init OK!
20:17:07 M: 10735616   DEBUG:   msg: Selected video codec: [ffodivx] vfm:ffmpeg (FFmpeg MPEG-4)
20:17:07 M: 10735616   DEBUG:   msg: ==========================================================================
20:17:07 M: 10735616   DEBUG:   msg: Checking audio filter chain for 48000Hz/2ch/16bit -> 48000Hz/6ch/16bit...
20:17:07 M: 10735616   DEBUG:   msg: [libaf] Adding filter dummy
20:17:07 M: 10723328   DEBUG:   msg: [dummy] Was reinitialized, rate=48000Hz, nch = 2, format = 0x00000001 and bps = 2
20:17:07 M: 10723328   DEBUG:   msg: AF_pre: af format: 2 bps, 2 ch, 48000 hz, little endian signed int
20:17:07 M: 10723328   DEBUG:   msg: AF_pre: 48000Hz 2ch Signed 16-bit (Little-Endian)
20:17:07 M: 10674176   DEBUG: Pause stream
20:17:07 M: 10674176   DEBUG:   msg: AO: [win32] 48000Hz 2ch Signed 16-bit (Little-Endian) (2 bps)
20:17:07 M: 10674176   DEBUG:   msg: AO: Description: Windows waveOut audio output
                             AO: Author: SaschaSommer <saschasommer@freenet.de>
20:17:07 M: 10674176   DEBUG:   msg: Building audio filter chain for 48000Hz/2ch/16bit -> 48000Hz/2ch/16bit...
20:17:07 M: 10674176   DEBUG:   msg: [dummy] Was reinitialized, rate=48000Hz, nch = 2, format = 0x00000001 and bps = 2
20:17:07 M: 10674176   DEBUG:   msg: [dummy] Was reinitialized, rate=48000Hz, nch = 2, format = 0x00000001 and bps = 2
20:17:07 M: 10604544   DEBUG:   msg: Starting playback...
20:17:07 M: 10604544   DEBUG:   msg: mplayer_open_file(http://127.0.0.1:64652/3823?.avi) done 1.0000
20:17:07 M: 10522624    INFO: Loading skin file: DialogProgress.xml
20:17:07 M: 10522624   DEBUG: Playback has started
20:17:07 M: 10162176   DEBUG: Load DialogProgress.xml: 45.83ms (43.12 ms xml load)
20:17:07 M: 10108928   DEBUG: Alloc resources: 15.28ms (0.00 ms skin load, 0.33 ms preload)
20:17:07 M: 10108928   DEBUG: CGUIInfoManager::SetCurrentMovie(http://127.0.0.1:64652/3823?.avi)
20:17:07 M: 10108928    INFO: Loading skin file: VideoOverlay.xml
20:17:08 M: 10113024   DEBUG: Load VideoOverlay.xml: 35.21ms (32.29 ms xml load)
20:17:08 M: 10113024   DEBUG: Alloc resources: 36.30ms (36.30 ms skin load, 0.00 ms preload)
20:17:08 M: 10158080   DEBUG:   msg: [ffmpeg] aspect_ratio: 1.772727
20:17:08 M: 10145792   DEBUG:   msg: VDec: vo config request - 624 x 352 (preferred colorspace: Planar YV12)
20:17:08 M: 10145792   DEBUG:   msg: Trying filter chain:
20:17:08 M: 10145792   DEBUG:   msg:  noise
20:17:08 M: 10133504   DEBUG:   msg:  vo
20:17:08 M: 10133504   DEBUG:   msg:
20:17:08 M: 10121216   DEBUG:   msg: VDec: using Planar YV12 as output csp (no 0)
20:17:08 M: 10121216   DEBUG:   msg: Movie-Aspect is 1.77:1 - prescaling to correct movie aspect.
20:17:08 M: 10121216   DEBUG:   msg: VO Config (624x352->624x352,flags=1,'MPlayer',0x32315659)
20:17:08 M: 10104832   DEBUG:   msg: REQ: flags=0x437  req=0x0
20:17:08 M: 10104832   DEBUG:   msg: VO: [directx] 624x352 => 624x352 Planar YV12  [fs]
20:17:08 M: 10092544   DEBUG:   msg: VO: Description: XBOX Direct3D8 YUY2 renderer
20:17:08 M: 10080256   DEBUG:   msg: VO: Author: Frodo/JCMarshall
20:17:08 M: 10088448   DEBUG: mplayer::video_config(624, 352, 624, 352, 1, 842094169)
20:17:08 M: 10076160  NOTICE: Display resolution USER : 720p 16:9 (1)
20:17:08 M:  9928704   DEBUG: Activating window ID: 12005
20:17:08 M:  9928704   DEBUG: Checking if window ID 12005 is locked.
20:17:08 M: 11489280   DEBUG: Setting resolution 1
20:17:08 M: 13361152   DEBUG: We set resolution 1
20:17:08 M: 13000704   DEBUG: Created YV12 texture 0
20:17:08 M: 12120064    INFO: Created 444P full texture
20:17:08 M: 12120064    INFO: Loading skin file: VideoFullScreen.xml
20:17:08 M: 12107776   DEBUG: Load VideoFullScreen.xml: 21.55ms (4.34 ms xml load)
20:17:08 M: 12029952   DEBUG: Alloc resources: 35.12ms (23.35 ms skin load, 0.36 ms preload)
20:17:08 M: 12029952    INFO: Loading skin file: VideoOSD.xml
20:17:08 M: 12013568   DEBUG: Load VideoOSD.xml: 15.62ms (7.43 ms xml load)
20:17:08 M: 11198464   DEBUG: Alloc resources: 46.70ms (0.01 ms skin load, 1.11 ms preload)
20:17:08 M: 11198464    INFO: Loading skin file: VideoOSDSettings.xml
20:17:08 M: 11198464   DEBUG: Load VideoOSDSettings.xml: 10.40ms (6.06 ms xml load)
20:17:08 M: 10498048   DEBUG: Alloc resources: 33.58ms (0.00 ms skin load, 0.59 ms preload)
20:17:08 M: 10502144    INFO: Loading skin file: VideoOSDSettings.xml
20:17:08 M: 10489856   DEBUG: Load VideoOSDSettings.xml: 7.03ms (2.45 ms xml load)
20:17:08 M: 10489856   DEBUG: Alloc resources: 0.42ms (0.00 ms skin load, 0.15 ms preload)
20:17:08 M: 10457088   DEBUG: CGUIFontTTF::Load Scaled size of font Q:\media\Fonts\arial.ttf (32.763889): width = 127, height = 45, lineheight = 38
20:17:08 M: 10072064   DEBUG:   msg: *** [noise] Allocating mp_image_t, 624x352x12bpp YUV planar, 329472 bytes
20:17:08 M:  9682944   DEBUG:   msg: *** [vo] Allocating mp_image_t, 624x352x12bpp YUV planar, 329472 bytes
20:17:08 M:  9158656    INFO: Starting async renderer thread
20:17:08 M:  9142272   DEBUG: Alloc resources: 0.54ms (0.00 ms skin load, 0.18 ms preload)
20:17:09 M:  9035776   DEBUG: SECTION:UnloadDelayed(DLL: Q:\system\libcurl.dll)
20:17:42 M:  9871360   DEBUG:   msg: FFRW:normal play
20:17:42 M:  9576448   DEBUG:   msg: *** [noise] Allocating mp_image_t, 624x352x12bpp YUV planar, 329472 bytes
20:23:11 M:  9510912   DEBUG: Alloc resources: 17.51ms (0.00 ms skin load, 0.17 ms preload)
20:23:54 M:  9371648   DEBUG:   msg: FFRW:normal play
20:34:55 M:  9752576   DEBUG: Alloc resources: 23.44ms (0.00 ms skin load, 0.18 ms preload)
20:35:36 M:  9617408   DEBUG:   msg: FFRW:normal play
20:36:10 M:  9797632   DEBUG: Alloc resources: 13.93ms (0.00 ms skin load, 0.18 ms preload)
20:36:56 M:  9654272   DEBUG:   msg: FFRW:normal play
20:44:05 M:  9793536   DEBUG: Alloc resources: 19.24ms (0.00 ms skin load, 0.18 ms preload)
20:44:46 M:  9666560   DEBUG:   msg: FFRW:normal play
20:50:23 M:  9805824   DEBUG: Alloc resources: 22.59ms (0.00 ms skin load, 0.22 ms preload)
20:51:16 M:  9695232   DEBUG:   msg: FFRW:normal play
20:56:45 M:  9781248   DEBUG: Alloc resources: 11.41ms (0.00 ms skin load, 0.18 ms preload)
20:57:38 M:  9629696   DEBUG:   msg: FFRW:normal play
20:57:56 M:  9748480   DEBUG: Alloc resources: 7.25ms (0.00 ms skin load, 0.18 ms preload)
20:58:51 M:  9572352   DEBUG:   msg: FFRW:normal play
21:01:31 M:  9789440   DEBUG: Alloc resources: 9.28ms (0.00 ms skin load, 0.18 ms preload)
21:02:25 M:  9682944   DEBUG:   msg: FFRW:normal play
21:04:42 M:  9678848   DEBUG: CApplication::OnKey: 216 pressed, action is 76
21:04:42 M:  9637888   DEBUG:   msg: SEEK: idx=158553  (a:158553 v:158555)  v.skip=0  a.skip=0/0.000
21:04:42 M:  9613312   DEBUG: Stop stream
21:04:43 M:  9445376   DEBUG: Alloc resources: 5.02ms (0.00 ms skin load, 0.19 ms preload)
21:05:55 M:  9818112   DEBUG: Alloc resources: 10.22ms (0.00 ms skin load, 0.17 ms preload)
21:06:46 M:  9695232   DEBUG:   msg: FFRW:normal play
21:07:41 M: 10362880   DEBUG:   msg: ds_fill_buffer: EOF reached (stream: audio)
21:07:41 M: 10362880   DEBUG:   msg: ds_fill_buffer: EOF reached (stream: video)
21:07:41 M: 10362880   DEBUG:   msg:  mplayer_close_file()
21:07:41 M: 10362880   DEBUG:   msg:
21:07:41 M: 10362880   DEBUG:   msg:  uninit_player
21:07:41 M: 19300352   DEBUG:   msg: WINSOCK2 uninit
21:07:41 M: 19312640   DEBUG:   msg: Uninit audio filters...
21:07:41 M: 19312640   DEBUG:   msg: [libaf] Removing filter dummy
21:07:41 M: 19312640   DEBUG:   msg: Uninit audio: mp3lib
21:07:41 M: 19451904   DEBUG:   msg: Uninit video: ffmpeg
21:07:41 M: 25088000   DEBUG: Deleted 444P video textures
21:07:41 M: 25972736   DEBUG: Deleted YV12 texture 0
21:07:41 M: 26210304   DEBUG:   msg:  uninit_player - done
21:07:41 M: 26210304   DEBUG: Freeing FS segment @ 0xe5e000
21:07:41 M: 26218496   DEBUG: Playback has finished
21:07:41 M: 26730496   DEBUG: CVideoDatabase::GetMovieInfo (http://127.0.0.1:64652/3823?.avi), query = select idMovie from movie where idFile=3248
21:07:41 M: 26730496   DEBUG: CVideoDatabase::GetEpisodeInfo (http://127.0.0.1:64652/3823?.avi), query = select idEpisode from episode where idFile=3248
21:07:41 M: 26730496   DEBUG: CVideoDatabase::GetMusicVideoInfo (http://127.0.0.1:64652/3823?.avi), query = select idMVideo from musicvideo where idFile=3248
21:07:42 M: 26742784   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
21:07:42 M: 28364800   DEBUG: Setting resolution 1
21:07:42 M: 26480640   DEBUG: We set resolution 1
21:07:42 M: 26599424   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
21:07:42 M: 26587136    INFO: Loading skin file: MyVideo.xml
21:07:42 M: 26390528   DEBUG: Load MyVideo.xml: 186.01ms (40.78 ms xml load)
21:07:42 M: 26300416   DEBUG: Alloc resources: 269.55ms (210.70 ms skin load, 0.83 ms preload)
21:07:42 M: 26300416   DEBUG: CGUIMediaWindow::GetDirectory (plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Fvideo%2F3823&mode=4&name=Bones+3x14+The+Wannabe+in+the+Weeds+)
21:07:42 M: 26300416   DEBUG:   ParentPath = [plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Fvideo%2F3823&mode=4&name=Bones+3x14+The+Wannabe+in+the+Weeds+]
21:07:42 M: 26292224   DEBUG: DIRECTORY::CPluginDirectory::GetDirectory - URL for plugin settings: Ninja Video/
21:07:42 M: 26292224   ERROR: Unable to load: Q:\plugins\video\Ninja Video\resources\settings.xml, Line 0
                             Failed to open file
21:07:42 M: 26292224   ERROR: unable to load Q:\plugins\video\Ninja Video\resources\language\German\strings.xml: Failed to open file at line 0
21:07:42 M: 26292224   ERROR: unable to load Q:\plugins\video\Ninja Video\resources\language\english\strings.xml: Failed to open file at line 0
21:07:42 M: 26292224   DEBUG: DIRECTORY::CPluginDirectory::GetDirectory - calling plugin Q:\plugins\video\Ninja Video\default.py('plugin://video/Ninja Video/','1','?url=http%3A%2F%2Fwww.ninjavideo.net%2Fvideo%2F3823&mode=4&name=Bones+3x14+The+Wannabe+in+the+Weeds+')
21:07:42 M: 25767936   DEBUG: DIRECTORY::CPluginDirectory::WaitOnScriptResult - waiting on the Ninja Video plugin...
21:07:42 M: 25767936    INFO: Mode: 4
21:07:42 M: 25767936    INFO: 
21:07:42 M: 25767936    INFO: URL: http://www.ninjavideo.net/video/3823
21:07:42 M: 25767936    INFO: 
21:07:42 M: 25767936    INFO: Name: Bones 3x14 The Wannabe in the Weeds
21:07:42 M: 25767936    INFO: 
21:07:42 M: 25767936    INFO: index of videolinks : http://www.ninjavideo.net/video/3823
21:07:42 M: 25767936    INFO: 
21:07:43 M: 25772032   DEBUG: DIRECTORY::CPluginDirectory::WaitOnScriptResult- plugin returned successfully
21:07:43 M: 25776128    INFO: Scriptresult: Succes
21:07:43 M: 25755648    INFO: Python script stopped
21:07:43 M: 25776128   DEBUG: Q:\system\players\mplayer\mplayer.dll (base 01EC0000): Detected memory leaks: 289 leaks
21:07:43 M: 26185728   DEBUG: leak caller address  1ec10cc, size      128, counter    1
21:07:43 M: 26710016   DEBUG: leak caller address  1ece000, size        4, counter    1
21:07:43 M: 26710016   DEBUG: leak caller address  1ece1d5, size       40, counter    1
21:07:43 M: 26710016   DEBUG: leak caller address  1ece2a9, size       72, counter    2
21:07:43 M: 26710016   DEBUG: leak caller address  1ece49b, size        8, counter    1
21:07:43 M: 26710016   DEBUG: leak caller address  1ece4b3, size        0, counter    1
21:07:43 M: 26710016   DEBUG: leak caller address  1edd2d7, size       28, counter    2
21:07:43 M: 26710016   DEBUG: leak caller address  1edd300, size        0, counter    5
21:07:43 M: 26710016   DEBUG: leak caller address  1edd3a7, size       16, counter    1
21:07:43 M: 26710016   DEBUG: leak caller address  1edd3cc, size        0, counter    1
21:07:43 M: 26710016   DEBUG: leak caller address  1edd469, size        0, counter    5
21:07:43 M: 26710016   DEBUG: leak caller address  1ee3106, size        0, counter    1
21:07:43 M: 26710016   DEBUG: leak caller address  1ee312b, size        0, counter    1
21:07:43 M: 26710016   DEBUG: leak caller address  1ee3151, size        0, counter    1
21:07:43 M: 26710016   DEBUG: leak caller address  1f5afb0, size       44, counter    1
21:07:43 M: 26710016   DEBUG: leak caller address  1f79026, size       28, counter    1
21:07:43 M: 26710016   DEBUG: leak caller address  1f79096, size        0, counter    1
21:07:43 M: 26710016   DEBUG: leak caller address  1f790f1, size        5, counter    1
21:07:43 M: 26710016   DEBUG: leak caller address  1f792cf, size       10, counter    1
21:07:43 M: 26710016   DEBUG: leak caller address  1f79325, size        0, counter    1
21:07:43 M: 26710016   DEBUG: leak caller address  1f7cf29, size     1636, counter   50
21:07:43 M: 26710016   DEBUG: leak caller address  1fe8dd4, size      869, counter    1
21:07:43 M: 26710016   DEBUG: leak caller address  1fe8ef0, size    31232, counter   10
21:07:43 M: 26710016   DEBUG: leak caller address  21e24ad, size   496650, counter  198
21:07:43 M: 26710016   DEBUG: mplayer.dll: Total bytes leaked: 530770
21:07:45 M: 31309824   DEBUG: CApplication::OnKey: 216 pressed, action is 9
21:07:45 M: 31436800   DEBUG: CGUIMediaWindow::GetDirectory (plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Fcat%2F294&mode=2&name=Bones)
21:07:45 M: 31436800   DEBUG:   ParentPath = [plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Ftvshows&mode=1&name=3.+TV+SHOWS]
21:07:45 M: 31436800   DEBUG: DIRECTORY::CPluginDirectory::GetDirectory - URL for plugin settings: Ninja Video/
21:07:45 M: 31436800   ERROR: Unable to load: Q:\plugins\video\Ninja Video\resources\settings.xml, Line 0
                             Failed to open file
21:07:45 M: 31436800   ERROR: unable to load Q:\plugins\video\Ninja Video\resources\language\German\strings.xml: Failed to open file at line 0
21:07:45 M: 31436800   ERROR: unable to load Q:\plugins\video\Ninja Video\resources\language\english\strings.xml: Failed to open file at line 0
21:07:45 M: 31436800   DEBUG: DIRECTORY::CPluginDirectory::GetDirectory - calling plugin Q:\plugins\video\Ninja Video\default.py('plugin://video/Ninja Video/','1','?url=http%3A%2F%2Fwww.ninjavideo.net%2Fcat%2F294&mode=2&name=Bones')
21:07:45 M: 30912512   DEBUG: DIRECTORY::CPluginDirectory::WaitOnScriptResult - waiting on the Ninja Video plugin...
21:07:45 M: 30912512    INFO: Mode: 2
21:07:45 M: 30912512    INFO: 
21:07:45 M: 30912512    INFO: URL: http://www.ninjavideo.net/cat/294
21:07:45 M: 30912512    INFO: 
21:07:45 M: 30912512    INFO: Name: Bones
21:07:45 M: 30912512    INFO: 
21:07:45 M: 30912512    INFO: index of seasons : http://www.ninjavideo.net/cat/294
21:07:45 M: 30912512    INFO: 
21:07:46 M: 30908416    INFO: Scriptresult: Succes
21:07:46 M: 30908416   DEBUG: DIRECTORY::CPluginDirectory::WaitOnScriptResult- plugin returned successfully
21:07:46 M: 30908416    INFO: Python script stopped
21:07:47 M: 31305728   DEBUG: CApplication::OnKey: 167 pressed, action is 4
21:07:48 M: 31305728   DEBUG: CApplication::OnKey: 11 pressed, action is 7
21:07:48 M: 31305728   DEBUG: Clearing cached fileitems [plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Fvideo%2F4665&mode=4&name=Bones+3x15+The+Pain+in+the+Heart]
21:07:48 M: 31432704   DEBUG: CGUIMediaWindow::GetDirectory (plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Fvideo%2F4665&mode=4&name=Bones+3x15+The+Pain+in+the+Heart)
21:07:48 M: 31432704   DEBUG:   ParentPath = [plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Fcat%2F294&mode=2&name=Bones]
21:07:48 M: 31432704   DEBUG: DIRECTORY::CPluginDirectory::GetDirectory - URL for plugin settings: Ninja Video/
21:07:48 M: 31432704   ERROR: Unable to load: Q:\plugins\video\Ninja Video\resources\settings.xml, Line 0
                             Failed to open file
21:07:48 M: 31432704   ERROR: unable to load Q:\plugins\video\Ninja Video\resources\language\German\strings.xml: Failed to open file at line 0
21:07:48 M: 31432704   ERROR: unable to load Q:\plugins\video\Ninja Video\resources\language\english\strings.xml: Failed to open file at line 0
21:07:48 M: 31432704   DEBUG: DIRECTORY::CPluginDirectory::GetDirectory - calling plugin Q:\plugins\video\Ninja Video\default.py('plugin://video/Ninja Video/','1','?url=http%3A%2F%2Fwww.ninjavideo.net%2Fvideo%2F4665&mode=4&name=Bones+3x15+The+Pain+in+the+Heart')
21:07:48 M: 30908416   DEBUG: DIRECTORY::CPluginDirectory::WaitOnScriptResult - waiting on the Ninja Video plugin...
21:07:48 M: 30908416    INFO: Mode: 4
21:07:48 M: 30908416    INFO: 
21:07:49 M: 30908416    INFO: URL: http://www.ninjavideo.net/video/4665
21:07:49 M: 30908416    INFO: 
21:07:49 M: 30908416    INFO: Name: Bones 3x15 The Pain in the Heart
21:07:49 M: 30908416    INFO: 
21:07:49 M: 30908416    INFO: index of videolinks : http://www.ninjavideo.net/video/4665
21:07:49 M: 30908416    INFO: 
21:07:49 M: 30908416   DEBUG: DIRECTORY::CPluginDirectory::WaitOnScriptResult- plugin returned successfully
21:07:49 M: 30908416    INFO: Scriptresult: Succes
21:07:49 M: 30887936    INFO: Python script stopped
21:07:50 M: 31305728   DEBUG: CApplication::OnKey: 167 pressed, action is 4
21:07:51 M: 31305728   DEBUG: CApplication::OnKey: 11 pressed, action is 7
21:07:51 M: 31305728   DEBUG: Initializing FS_SEG..
21:07:51 M: 31305728   DEBUG: FS segment @ 0xb29530
21:07:51 M: 30818304   DEBUG: Unable to resolve kernel32.dll PeekNamedPipe
21:07:51 M: 30818304   DEBUG: Dll MSVCP71.dll was not found in path
21:07:51 M: 30818304   DEBUG: Unable to load referenced dll MSVCP71.dll - Dll: Q:\system\libcurl.dll
21:07:51 M: 30818304   DEBUG: Unable to resolve MSVCP71.dll ?_Nomemory@std@@YAXXZ
21:07:51 M: 30818304   DEBUG: kernel32.dll fake function DisableThreadLibraryCalls called
21:07:51 M: 30781440    INFO: XCURL::DllLibCurlGlobal::easy_aquire - Created session to http://127.0.0.1
21:07:51 M: 30781440   DEBUG: Curl:: Debug About to connect() to 127.0.0.1 port 64652 (#0)
21:07:51 M: 30781440   DEBUG: Curl:: Debug   Trying 127.0.0.1...
21:07:51 M: 30257152   DEBUG: Curl:: Debug connected
21:07:51 M: 30257152   DEBUG: Curl:: Debug Connected to 127.0.0.1 (127.0.0.1) port 64652 (#0)
21:07:51 M: 30257152   DEBUG: Curl:: Debug HEAD /4665?.avi HTTP/1.1

                             User-Agent: XBMC/pre-2.1 (compatible; MSIE 6.0; Windows NT 5.1; WinampMPEG/5.09)

                             Host: 127.0.0.1:64652

                             Accept: */*
21:07:51 M: 30261248    INFO: Thu Jun 05 21:07:51 2008
21:07:51 M: 30261248    INFO: 
21:07:51 M: 30261248    INFO: Serving HEAD request...
21:07:51 M: 30261248    INFO: 
21:07:51 M: 30261248    INFO: Thu Jun 05 21:07:51 2008
21:07:51 M: 30261248    INFO: 
21:07:51 M: 30261248    INFO: Getting infos from NinjaVideo.net for Video ID
21:07:51 M: 30261248    INFO: 
21:07:51 M: 30261248    INFO: 4665
21:07:51 M: 30261248    INFO: 
21:07:51 M: 30261248    INFO: ...
21:07:51 M: 30261248    INFO: 
21:07:52 M: 30257152 WARNING: Untested function dllgethostbyaddr called!
21:07:52 M: 30257152    INFO: 127.0.0.1 - - [05/Jun/2008 21:07:52] "HEAD /4665?.avi HTTP/1.1" 200 -
21:07:52 M: 30257152    INFO: Thu Jun 05 21:07:52 2008
21:07:52 M: 30257152   DEBUG: Curl:: Debug HTTP 1.0, assume close after body
21:07:52 M: 30257152    INFO: 
21:07:52 M: 30257152   DEBUG: Curl:: Debug HTTP/1.0 200 OK
21:07:52 M: 30257152    INFO: Sending headers...
21:07:52 M: 30257152   DEBUG: Curl:: Debug Server: BaseHTTP/0.3 Python/2.4.3
21:07:52 M: 30257152    INFO: 
21:07:52 M: 30781440   DEBUG: Curl:: Debug Date: Thu, 05 Jun 2008 19:07:52 GMT
21:07:52 M: 30781440   DEBUG: Curl:: Debug Content-Disposition: inline; filename="Bones 3x15 The Pain in the Heart.avi"
21:07:52 M: 30781440   DEBUG: Curl:: Debug Content-type: application/x-msvideo
21:07:52 M: 30781440   DEBUG: Curl:: Debug Last-Modified: Wed, 21 Feb 2000 08:43:39 GMT
21:07:52 M: 30781440   DEBUG: Curl:: Debug ETag: 7c0f63c15f8749d716ba1ac9121cc1a8
21:07:52 M: 30781440   DEBUG: Curl:: Debug Accept-Ranges: bytes
21:07:52 M: 30781440   DEBUG: Curl:: Debug Cache-Control: public, must-revalidate
21:07:52 M: 30781440   DEBUG: Curl:: Debug Cache-Control: no-cache
21:07:52 M: 30781440   DEBUG: Curl:: Debug Pragma: no-cache
21:07:52 M: 30781440   DEBUG: Curl:: Debug features: seekable,stridable
21:07:52 M: 30781440   DEBUG: Curl:: Debug client-id: 12345
21:07:52 M: 30781440   DEBUG: Curl:: Debug Content-Length: 366774272
21:07:52 M: 30781440   DEBUG: Curl:: Debug
21:07:52 M: 30781440   DEBUG: Curl:: Debug Closing connection #0
21:07:52 M: 30781440   DEBUG: FileCurl::Close(D00B4D24) http://127.0.0.1:64652/4665?.avi
21:07:52 M: 30781440   DEBUG: CPlayerCoreFactor::GetPlayers(http://127.0.0.1:64652/4665?.avi)
21:07:52 M: 30781440   ERROR: XFILE::CFile::Delete - Error deleting file Z:\xbmc.edl
21:07:52 M: 30781440   DEBUG: file:http://127.0.0.1:64652/4665?.avi IsDVDImage:0 IsDVDIfoFile:0
21:07:52 M: 30781440    INFO: Loading skin file: DialogProgress.xml
21:07:52 M: 30781440   DEBUG: Load DialogProgress.xml: 38.45ms (35.77 ms xml load)
21:07:52 M: 30728192   DEBUG: Alloc resources: 15.44ms (0.00 ms skin load, 0.34 ms preload)
21:07:53 M: 29814784    INFO: mplayer play:http://127.0.0.1:64652/4665?.avi cachesize:8192
21:07:53 M: 23924736   DEBUG:   msg:  set audio functions called
21:07:53 M: 23924736   DEBUG:   msg:  done
21:07:53 M: 23924736   DEBUG: FileCurl::Open(00EC19A0) http://127.0.0.1:64652/4665?.avi
21:07:53 M: 23822336   DEBUG: Curl:: Debug About to connect() to 127.0.0.1 port 64652 (#0)
21:07:53 M: 23822336   DEBUG: Curl:: Debug   Trying 127.0.0.1...
21:07:53 M: 23298048   DEBUG: Curl:: Debug connected
21:07:53 M: 23285760   DEBUG: Curl:: Debug Connected to 127.0.0.1 (127.0.0.1) port 64652 (#0)
21:07:53 M: 23298048   DEBUG: Curl:: Debug GET /4665?.avi HTTP/1.1

                             User-Agent: XBMC/pre-2.1 (compatible; MSIE 6.0; Windows NT 5.1; WinampMPEG/5.09)

                             Host: 127.0.0.1:64652

                             Accept: */*
21:07:53 M: 23298048    INFO: Thu Jun 05 21:07:53 2008
21:07:53 M: 23298048    INFO: 
21:07:53 M: 23298048    INFO: Serving GET request...
21:07:53 M: 23298048    INFO: 
21:07:53 M: 23298048 WARNING: Untested function dllgethostbyaddr called!
21:07:53 M: 23298048    INFO: 127.0.0.1 - - [05/Jun/2008 21:07:53] "GET /4665?.avi HTTP/1.1" 200 -
21:07:53 M: 23298048    INFO: Thu Jun 05 21:07:53 2008
21:07:53 M: 23298048   DEBUG: Curl:: Debug HTTP 1.0, assume close after body
21:07:53 M: 23298048    INFO: 
21:07:53 M: 23298048   DEBUG: Curl:: Debug HTTP/1.0 200 OK
21:07:53 M: 23298048    INFO: Sending headers...
21:07:53 M: 23298048   DEBUG: Curl:: Debug Server: BaseHTTP/0.3 Python/2.4.3
21:07:53 M: 23298048    INFO: 
21:07:53 M: 23298048   DEBUG: Curl:: Debug Date: Thu, 05 Jun 2008 19:07:53 GMT
21:07:53 M: 23298048    INFO: Thu Jun 05 21:07:53 2008
21:07:53 M: 23298048   DEBUG: Curl:: Debug Content-Disposition: inline; filename="Bones 3x15 The Pain in the Heart.avi"
21:07:53 M: 23298048    INFO: 
21:07:53 M: 23298048   DEBUG: Curl:: Debug Content-type: application/x-msvideo
21:07:53 M: 23298048    INFO: Starting download at byte
21:07:53 M: 23298048   DEBUG: Curl:: Debug Last-Modified: Wed, 21 Feb 2000 08:43:39 GMT
21:07:53 M: 23298048    INFO: 
21:07:53 M: 23298048   DEBUG: Curl:: Debug ETag: 7c0f63c15f8749d716ba1ac9121cc1a8
21:07:53 M: 23298048    INFO: 0
21:07:53 M: 23298048   DEBUG: Curl:: Debug Accept-Ranges: bytes
21:07:53 M: 23298048    INFO: 
21:07:53 M: 23298048   DEBUG: Curl:: Debug Cache-Control: public, must-revalidate
21:07:53 M: 23298048   DEBUG: Curl:: Debug Cache-Control: no-cache
21:07:53 M: 23298048   DEBUG: Curl:: Debug Pragma: no-cache
21:07:53 M: 23298048   DEBUG: Curl:: Debug features: seekable,stridable
21:07:53 M: 23298048   DEBUG: Curl:: Debug client-id: 12345
21:07:53 M: 23298048   DEBUG: Curl:: Debug Content-Length: 366774272
21:07:53 M: 23298048   DEBUG: Curl:: Debug
21:07:55 M: 23031808   DEBUG: FileCurl::Close(00EC19A0) http://127.0.0.1:64652/4665?.avi
21:07:55 M: 22929408   DEBUG: Curl:: Debug Expire cleared
21:07:55 M: 22929408   DEBUG: Curl:: Debug Closing connection #0
21:07:55 M: 23031808    INFO: Client Closed the connection.
21:07:55 M: 23134208    INFO: Using -subcp enca:__:CP1252 to detect the subtitle charset
21:07:55 M: 23134208    INFO: 
21:07:55 M: 23924736    INFO: Flipping bi-directional subtitles disabled
21:07:55 M: 23924736   DEBUG:   msg: mplayer_init()
21:07:55 M: 23924736   DEBUG:   msg: MPlayer XBMC-SVN-r10070-4.1.2 (C) 2000-2004 MPlayer Team
21:07:55 M: 23924736   DEBUG:   msg: CPU: Intel Celeron 2/Pentium III Coppermine,Geyserville
21:07:55 M: 23924736   DEBUG:   msg: (Family: 6, Stepping: 10)
21:07:55 M: 23924736   DEBUG:   msg: Detected cache-line size is 32 bytes
21:07:55 M: 23924736   DEBUG:   msg: CPUflags:  MMX: 1 MMX2: 1 3DNow: 0 3DNow2: 0 SSE: 0 SSE2: 0
21:07:55 M: 23924736   DEBUG:   msg: Compiled for x86 CPU with extensions:
21:07:55 M: 23924736   DEBUG:   msg:  MMX
21:07:55 M: 23924736   DEBUG:   msg:  MMX2
21:07:55 M: 23924736   DEBUG:   msg:  SSE
21:07:55 M: 23924736   DEBUG:   msg:
21:07:55 M: 23912448   DEBUG:   msg:
21:07:55 M: 23924736   DEBUG:   msg: Creating config file: Q:\system\players\mplayer\config
21:07:55 M: 23912448   DEBUG:   msg:
21:07:55 M: 23924736   DEBUG:   msg: get_path('codecs.conf') -> 'Q:\system\players\mplayer\codecs.conf'
21:07:55 M: 23924736   DEBUG:   msg: Reading Q:\system\players\mplayer\codecs.conf:
21:07:55 M: 23613440   DEBUG:   msg: 108 audio & 225 video codecs
21:07:55 M: 23613440   DEBUG:   msg: CommandLine:
21:07:55 M: 23613440   DEBUG:   msg:  '-dr'
21:07:55 M: 23613440   DEBUG:   msg:  '-v'
21:07:55 M: 23613440   DEBUG:   msg:  '-subcp'
21:07:55 M: 23613440   DEBUG:   msg:  'enca:__:CP1252'
21:07:55 M: 23613440   DEBUG:   msg:  '-channels'
21:07:55 M: 23613440   DEBUG:   msg:  '6'
21:07:55 M: 23613440   DEBUG:   msg:  '-ac'
21:07:55 M: 23613440   DEBUG:   msg:  'hwdts,hwac3,'
21:07:55 M: 23613440   DEBUG:   msg:  '-noflip-hebrew'
21:07:55 M: 23613440   DEBUG:   msg:  '-noflip-hebrew-commas'
21:07:55 M: 23613440   DEBUG:   msg:  '-vf'
21:07:55 M: 23613440   DEBUG:   msg:  'noise=1ta:1ta'
21:07:55 M: 23613440   DEBUG:   msg:  '-fs'
21:07:55 M: 23613440   DEBUG:   msg:  '1.avi'
21:07:55 M: 23613440   DEBUG:   msg:
21:07:55 M: 23613440   DEBUG:   msg: get_path('font/font.desc') -> 'Q:\system\players\mplayer\font/font.desc'
21:07:55 M: 23085056   DEBUG:   msg: font: can't open file: Q:\system\players\mplayer\font/font.desc
21:07:55 M: 23085056   DEBUG:   msg: font: can't open file: Q:\system\players\mplayer/font/font.desc
21:07:55 M: 23613440   DEBUG:   msg: Using MMX (with tiny bit MMX2) Optimized OnScreenDisplay
21:07:55 M: 23580672   DEBUG:   msg: Using XBOX High Resolution Timers for timing
21:07:55 M: 23580672   DEBUG:   msg: get_path('input.conf') -> 'Q:\system\players\mplayer\input.conf'
21:07:55 M: 23580672   DEBUG:   msg: Parsing input config file Q:\system\players\mplayer\input.conf
21:07:55 M: 23576576   DEBUG:   msg: Input config file Q:\system\players\mplayer\input.conf parsed: 51 binds
21:07:55 M: 23576576   DEBUG:   msg: mplayer_open_file(http://127.0.0.1:64652/4665?.avi)
21:07:55 M: 23576576   DEBUG:   msg: get_path('4665?.avi.conf') -> 'Q:\system\players\mplayer\4665?.avi.conf'
21:07:55 M: 23576576   DEBUG:   msg: 
                             Playing http://127.0.0.1:64652/4665?.avi.
21:07:55 M: 23576576   DEBUG:   msg: find subtitles
21:07:55 M: 23576576   DEBUG:   msg: auto open Z:\subtitle
21:07:55 M: 23576576   DEBUG:   msg: Filename for url is now http://127.0.0.1:64652/4665?.avi
21:07:55 M: 23576576   DEBUG:   msg: protocol:http
21:07:55 M: 23576576   DEBUG:   msg: open stream protocol:http
21:07:55 M: 23556096   DEBUG:   msg: WINSOCK2 init: 0
21:07:55 M: 23556096   DEBUG:   msg: Filename for url is now http://127.0.0.1:64652/4665?.avi
21:07:55 M: 23556096   DEBUG:   msg: Connecting to server 127.0.0.1[127.0.0.1]:64652 ...
21:07:55 M: 23031808    INFO: Thu Jun 05 21:07:55 2008
21:07:55 M: 23031808    INFO: 
21:07:55 M: 23031808    INFO: Serving GET request...
21:07:55 M: 23031808    INFO: 
21:07:55 M: 23031808 WARNING: Untested function dllgethostbyaddr called!
21:07:55 M: 23031808    INFO: 127.0.0.1 - - [05/Jun/2008 21:07:55] "GET /4665?.avi HTTP/1.0" 200 -
21:07:55 M: 23031808    INFO: Thu Jun 05 21:07:55 2008
21:07:55 M: 23031808    INFO: 
21:07:55 M: 23031808    INFO: Sending headers...
21:07:55 M: 23031808    INFO: 
21:07:55 M: 23031808    INFO: Thu Jun 05 21:07:55 2008
21:07:55 M: 23031808   DEBUG:   msg: --- HTTP DEBUG HEADER --- START ---
21:07:55 M: 23031808    INFO: 
21:07:55 M: 23031808   DEBUG:   msg: protocol:           [HTTP/1.0]
21:07:55 M: 23031808    INFO: Starting download at byte
21:07:55 M: 23031808    INFO: 
21:07:55 M: 23031808    INFO: 0
21:07:55 M: 23031808    INFO: 
21:07:55 M: 23031808   DEBUG:   msg: http minor version: [0]
21:07:55 M: 23031808   DEBUG:   msg: uri:                [(null)]
21:07:55 M: 23031808   DEBUG:   msg: method:             [(null)]
21:07:55 M: 23031808   DEBUG:   msg: status code:        [200]
21:07:55 M: 23031808   DEBUG:   msg: reason phrase:      [OK]
21:07:55 M: 23031808   DEBUG:   msg: body size:          [0]
21:07:55 M: 23031808   DEBUG:   msg: Fields:
21:07:55 M: 23031808   DEBUG:   msg:  0 - Server: BaseHTTP/0.3 Python/2.4.3
21:07:55 M: 23031808   DEBUG:   msg:  1 - Date: Thu, 05 Jun 2008 19:07:55 GMT
21:07:55 M: 23031808   DEBUG:   msg:  2 - Content-Disposition: inline; filename="Bones 3x15 The Pain in the Heart.avi"
21:07:55 M: 23031808   DEBUG:   msg:  3 - Content-type: application/x-msvideo
21:07:55 M: 23031808   DEBUG:   msg:  4 - Last-Modified: Wed, 21 Feb 2000 08:43:39 GMT
21:07:55 M: 23031808   DEBUG:   msg:  5 - ETag: 7c0f63c15f8749d716ba1ac9121cc1a8
21:07:55 M: 23031808   DEBUG:   msg:  6 - Accept-Ranges: bytes
21:07:55 M: 23031808   DEBUG:   msg:  7 - Cache-Control: public, must-revalidate
21:07:55 M: 23031808   DEBUG:   msg:  8 - Cache-Control: no-cache
21:07:55 M: 23031808   DEBUG:   msg:  9 - Pragma: no-cache
21:07:55 M: 23031808   DEBUG:   msg:  10 - features: seekable,stridable
21:07:55 M: 23031808   DEBUG:   msg:  11 - client-id: 12345
21:07:55 M: 23031808   DEBUG:   msg:  12 - Content-Length: 366774272
21:07:55 M: 23031808   DEBUG:   msg: --- HTTP DEBUG HEADER --- END ---
21:07:56 M: 23031808   DEBUG:   msg: Content-Type: [application/x-msvideo]
21:07:56 M: 23031808   DEBUG:   msg: Content-Length: [366774272]
21:07:56 M: 23031808   DEBUG:   msg: Cache size set to 8192 KBytes
21:07:56 M: 23031808   DEBUG:   msg: Connected to server: 127.0.0.1
21:07:56 M: 14086144   DEBUG:   msg: CACHE_PRE_INIT: 0 [0] 0  pre:1677721  eof:0
21:07:56 M: 14086144   DEBUG:   msg: 
Cache fill:  0.00% (0 bytes)
21:07:56 M: 14073856   DEBUG:   msg: 
Cache fill:  0.00% (0 bytes)
<Stuff removed, pastebin size limit>
21:08:00 M: 13979648   DEBUG:   msg: 
Cache fill:  6.25% (524288 bytes)
21:08:00 M: 13905920   DEBUG:   msg: 
Cache fill:  6.25% (524288 bytes)
21:08:00 M: 13893632    INFO: XCURL::DllLibCurlGlobal::CheckIdle - Closing session to http ://127.0.0.1 (easy=00FED010, multi=00CF3D00)
21:08:01 M: 13926400   DEBUG:   msg: 
Cache fill:  6.25% (524288 bytes)
21:08:01 M: 13828096   DEBUG:   msg: 
Cache fill:  6.25% (524288 bytes)
<Stuff removed, pastebin size limit>
21:08:13 M: 13709312   DEBUG:   msg: 
Cache fill: 18.75% (1572864 bytes)
21:08:13 M: 13672448   DEBUG:   msg: 
Cache fill: 18.75% (1572864 bytes)
21:08:13 M: 14082048   DEBUG:   msg:
21:08:13 M: 14082048   DEBUG:   msg: demux_open(01008010,0,-1,-1,-1)
21:08:13 M: 14082048   DEBUG:   msg: AVI file format detected.
21:08:13 M: 14082048   DEBUG:   msg: list_end=0x229A
21:08:13 M: 14082048   DEBUG:   msg: ======= AVI Header =======
21:08:13 M: 14082048   DEBUG:   msg: us/frame: 41708  (fps=23.976)
21:08:13 M: 14082048   DEBUG:   msg: max bytes/sec: 0
21:08:13 M: 14082048   DEBUG:   msg: padding: 0
21:08:13 M: 14082048   DEBUG:   msg: MainAVIHeader.dwFlags: (272) HAS_INDEX IS_INTERLEAVED
21:08:13 M: 14082048   DEBUG:   msg: frames  total: 62030   initial: 0
21:08:13 M: 14082048   DEBUG:   msg: streams: 2
21:08:13 M: 14082048   DEBUG:   msg: Suggested BufferSize: 0
21:08:13 M: 14082048   DEBUG:   msg: Size:  624 x 352
21:08:13 M: 14082048   DEBUG:   msg: ==========================
21:08:13 M: 14082048   DEBUG:   msg: list_end=0x10F8
21:08:13 M: 14082048   DEBUG:   msg: ==> Found video stream: 0
21:08:13 M: 14082048   DEBUG:   msg: ====== STREAM Header =====
21:08:13 M: 14082048   DEBUG:   msg: Type: vids   FCC: xvid (64697678)
21:08:13 M: 14082048   DEBUG:   msg: Flags: 0
21:08:13 M: 14082048   DEBUG:   msg: Priority: 0   Language: 0
21:08:13 M: 14082048   DEBUG:   msg: InitialFrames: 0
21:08:13 M: 14082048   DEBUG:   msg: Rate: 24000/1001 = 23.976
21:08:13 M: 14082048   DEBUG:   msg: Start: 0   Len: 62030
21:08:13 M: 14082048   DEBUG:   msg: Suggested BufferSize: 128347
21:08:13 M: 14082048   DEBUG:   msg: Quality 10000
21:08:13 M: 14082048   DEBUG:   msg: Sample size: 0
21:08:13 M: 14082048   DEBUG:   msg: ==========================
21:08:13 M: 14082048   DEBUG:   msg: Found 'bih', 40 bytes of 40
21:08:13 M: 14082048   DEBUG:   msg: ======= VIDEO Format ======
21:08:13 M: 14082048   DEBUG:   msg:   biSize 40
21:08:13 M: 14082048   DEBUG:   msg:   biWidth 624
21:08:13 M: 14082048   DEBUG:   msg:   biHeight 352
21:08:13 M: 14082048   DEBUG:   msg:   biPlanes 1
21:08:13 M: 14082048   DEBUG:   msg:   biBitCount 24
21:08:13 M: 14082048   DEBUG:   msg:   biCompression 1145656920='XVID'
21:08:13 M: 14082048   DEBUG:   msg:   biSizeImage 1317888
21:08:13 M: 14082048   DEBUG:   msg: ===========================
21:08:13 M: 14082048   DEBUG:   msg: Regenerating keyframe table for MPEG-4 video.
21:08:13 M: 14082048   DEBUG:   msg: list_end=0x218E
21:08:13 M: 14082048   DEBUG:   msg: ==> Found audio stream: 1
21:08:13 M: 14082048   DEBUG:   msg: ====== STREAM Header =====
21:08:13 M: 14082048   DEBUG:   msg: Type: auds   FCC:  (0)
21:08:13 M: 14082048   DEBUG:   msg: Flags: 0
21:08:13 M: 14082048   DEBUG:   msg: Priority: 0   Language: 0
21:08:13 M: 14082048   DEBUG:   msg: InitialFrames: 1
21:08:13 M: 14082048   DEBUG:   msg: Rate: 48000/1152 = 41.667
21:08:13 M: 14082048   DEBUG:   msg: Start: 0   Len: 107797
21:08:13 M: 14082048   DEBUG:   msg: Suggested BufferSize: 960
21:08:13 M: 14082048   DEBUG:   msg: Quality -1
21:08:13 M: 14082048   DEBUG:   msg: Sample size: 0
21:08:13 M: 14082048   DEBUG:   msg: ==========================
21:08:13 M: 14082048   DEBUG:   msg: Found 'wf', 30 bytes of 18
21:08:13 M: 14082048   DEBUG:   msg: ======= WAVE Format =======
21:08:13 M: 14082048   DEBUG:   msg: Format Tag: 85 (0x55)
21:08:13 M: 14082048   DEBUG:   msg: Channels: 2
21:08:13 M: 14082048   DEBUG:   msg: Samplerate: 48000
21:08:13 M: 14082048   DEBUG:   msg: avg byte/sec: 16612
21:08:13 M: 14082048   DEBUG:   msg: Block align: 1152
21:08:13 M: 14082048   DEBUG:   msg: bits/sample: 0
21:08:13 M: 14082048   DEBUG:   msg: cbSize: 12
21:08:13 M: 14082048   DEBUG:   msg: mp3.wID=1
21:08:13 M: 14082048   DEBUG:   msg: mp3.fdwFlags=0x2
21:08:13 M: 14082048   DEBUG:   msg: mp3.nBlockSize=398
21:08:13 M: 14082048   DEBUG:   msg: mp3.nFramesPerBlock=1
21:08:13 M: 14082048   DEBUG:   msg: mp3.nCodecDelay=0
21:08:13 M: 14082048   DEBUG:   msg: ===========================
21:08:13 M: 14082048   DEBUG:   msg: list_end=0x229A
21:08:13 M: 14082048   DEBUG:   msg: AVI: dmlh found (size=248) (total_frames=62030)
21:08:13 M: 14082048   DEBUG:   msg: list_end=0x22BA
21:08:13 M: 14082048   DEBUG:   msg: hdr=Software  size=11
21:08:13 M: 14082048   DEBUG:   msg: Software  : VirtualDub
21:08:13 M: 14082048   DEBUG:   msg: list_end=0x15B31120
21:08:13 M: 14082048   DEBUG:   msg: Found movie at 0x280C - 0x15B31120
21:08:15 M: 13811712   DEBUG:   msg: Connecting to server 127.0.0.1[127.0.0.1]:64652 ...
21:08:15 M: 13185024    INFO: Thu Jun 05 21:08:15 2008
21:08:15 M: 13185024    INFO: 
21:08:15 M: 13185024    INFO: Serving GET request...
21:08:15 M: 13185024    INFO: 
21:08:15 M: 13185024 WARNING: Untested function dllgethostbyaddr called!
21:08:15 M: 13185024    INFO: 127.0.0.1 - - [05/Jun/2008 21:08:15] "GET /4665?.avi HTTP/1.0" 206 -
21:08:15 M: 13185024    INFO: Thu Jun 05 21:08:15 2008
21:08:15 M: 13185024    INFO: 
21:08:15 M: 13185024    INFO: Sending headers...
21:08:15 M: 13185024    INFO: 
21:08:15 M: 13185024   DEBUG:   msg: Content-Type: [application/x-msvideo]
21:08:15 M: 13185024    INFO: Thu Jun 05 21:08:15 2008
21:08:15 M: 13185024   DEBUG:   msg: Content-Length: [2721792]
21:08:15 M: 13287424    INFO: 
21:08:15 M: 13287424    INFO: Starting download at byte
21:08:15 M: 13287424    INFO: 
21:08:15 M: 13287424    INFO: 364052480
21:08:15 M: 13287424    INFO: 
21:08:15 M: 13287424    INFO: Client Closed the connection.
21:08:15 M: 13287424    INFO: 
21:08:17 M: 14082048   DEBUG:   msg: Reading INDEX block, 169827 chunks for 62030 frames (fpos=364056872).
21:08:31 M: 11362304   DEBUG: SECTION:UnloadDelayed(DLL: Q:\system\libcurl.dll)
21:08:42 M: 12361728   DEBUG:   msg: Connecting to server 127.0.0.1[127.0.0.1]:64652 ...
21:08:42 M: 11837440    INFO: Thu Jun 05 21:08:42 2008
21:08:42 M: 11837440    INFO: 
21:08:42 M: 11837440    INFO: Serving GET request...
21:08:42 M: 11837440    INFO: 
21:08:42 M: 11837440 WARNING: Untested function dllgethostbyaddr called!
21:08:42 M: 11837440    INFO: 127.0.0.1 - - [05/Jun/2008 21:08:42] "GET /4665?.avi HTTP/1.0" 200 -
21:08:42 M: 11837440    INFO: Thu Jun 05 21:08:42 2008
21:08:42 M: 11837440    INFO: 
21:08:42 M: 11837440    INFO: Sending headers...
21:08:42 M: 11837440    INFO: 
21:08:42 M: 11833344    INFO: Thu Jun 05 21:08:42 2008
21:08:42 M: 11833344   DEBUG:   msg: Content-Type: [application/x-msvideo]
21:08:42 M: 11833344    INFO: 
21:08:42 M: 11833344   DEBUG:   msg: Content-Length: [366774272]
21:08:42 M: 11837440    INFO: Starting download at byte
21:08:42 M: 11837440    INFO: 
21:08:42 M: 11837440    INFO: 0
21:08:42 M: 11837440    INFO: 
21:08:44 M: 11837440   DEBUG:   msg: AVI index offset: 0x2808 (movi=0x280C idx0=0x4 idx1=0x18C)
21:08:44 M: 11837440   DEBUG:   msg: Auto-selected AVI audio ID = 1
21:08:44 M: 11837440   DEBUG:   msg: Auto-selected AVI video ID = 0
21:08:44 M: 11837440   DEBUG:   msg: AVI: Searching for audio stream (id:1)
21:08:44 M: 11837440   DEBUG:   msg: AVI video size=319680620 (62030) audio size=42976272 (107797)
21:08:44 M: 11837440   DEBUG:   msg: [V] filefmt:3  fourcc:0x44495658  size:624x352  fps:23.98  ftime:=0.0417
21:08:44 M: 11837440   DEBUG:   msg: Clip info:
21:08:44 M: 11837440   DEBUG:   msg:  Software: VirtualDub
21:08:44 M: 11837440   DEBUG:   msg: get_path('sub/') -> 'Q:\system\players\mplayer\sub/'
21:08:44 M: 11837440   DEBUG:   msg: Starting lookup for subs
21:08:44 M: 11837440   DEBUG:   msg: Global Subsize: 0 - VOBSUB: -1, DEMUX: -1, SUBS: -1
21:08:44 M: 11837440   DEBUG:   msg: Global Subpos : -1
21:08:44 M: 11837440   DEBUG:   msg: ==========================================================================
21:08:44 M: 11837440   DEBUG:   msg: Forced audio codec: hwdts
21:08:44 M: 11837440   DEBUG:   msg: Forced audio codec: hwac3
21:08:44 M: 11837440   DEBUG:   msg: Opening audio decoder: [mp3lib] MPEG layer-2, layer-3
21:08:44 M: 11837440   DEBUG:   msg: dec_audio: Allocating 4608 + 65536 = 70144 bytes for output buffer.
21:08:44 M: 11755520   DEBUG:   msg: mp3lib: made decode tables with MMX optimization
21:08:44 M: 11755520   DEBUG:   msg: mp3lib: using MMX optimized decore!
21:08:44 M: 11755520   DEBUG:   msg: MP3lib: init layer2&3 finished, tables done
21:08:44 M: 11755520   DEBUG:   msg: 
MPEG 1.0, Layer III, 48000 Hz 32 kbit Joint-Stereo, BPF: 96
21:08:44 M: 11755520   DEBUG:   msg: Channels: 2, copyright: No, original: Yes, CRC: Yes, emphasis: 0
21:08:44 M: 11755520   DEBUG:   msg: AUDIO: 48000 Hz, 2 ch, 16 bit (0x10), ratio: 4000->192000 (32.0 kbit)
21:08:44 M: 11755520   DEBUG:   msg: Selected audio codec: [mp3] afm:mp3lib (mp3lib MPEG layer-2, layer-3)
21:08:44 M: 11755520   DEBUG:   msg: ==========================================================================
21:08:44 M: 11755520   DEBUG: CXBoxRenderManager::PreInit - Selected RGB-Renderer V2
21:08:44 M: 11755520   DEBUG: Deleted 444P video textures
21:08:44 M: 11755520   DEBUG:   msg: Opening video filter: [noise=1ta:1ta]
21:08:44 M: 11677696   DEBUG:   msg: [vo] query(Planar YV12) -> 3
21:08:44 M: 11677696   DEBUG:   msg: ==========================================================================
21:08:44 M: 11677696   DEBUG:   msg: Opening video decoder: [ffmpeg] FFmpeg's libavcodec codec family
21:08:44 M: 11370496   DEBUG:   msg: INFO: libavcodec init OK!
21:08:44 M: 11370496   DEBUG:   msg: Selected video codec: [ffodivx] vfm:ffmpeg (FFmpeg MPEG-4)
21:08:44 M: 11370496   DEBUG:   msg: ==========================================================================
21:08:44 M: 11370496   DEBUG:   msg: Checking audio filter chain for 48000Hz/2ch/16bit -> 48000Hz/6ch/16bit...
21:08:44 M: 11370496   DEBUG:   msg: [libaf] Adding filter dummy
21:08:44 M: 11370496   DEBUG:   msg: [dummy] Was reinitialized, rate=48000Hz, nch = 2, format = 0x00000001 and bps = 2
21:08:44 M: 11370496   DEBUG:   msg: AF_pre: af format: 2 bps, 2 ch, 48000 hz, little endian signed int
21:08:44 M: 11370496   DEBUG:   msg: AF_pre: 48000Hz 2ch Signed 16-bit (Little-Endian)
21:08:44 M: 11321344   DEBUG: Pause stream
21:08:44 M: 11321344   DEBUG:   msg: AO: [win32] 48000Hz 2ch Signed 16-bit (Little-Endian) (2 bps)
21:08:44 M: 11321344   DEBUG:   msg: AO: Description: Windows waveOut audio output
                             AO: Author: SaschaSommer <saschasommer@freenet.de>
21:08:44 M: 11321344   DEBUG:   msg: Building audio filter chain for 48000Hz/2ch/16bit -> 48000Hz/2ch/16bit...
21:08:44 M: 11321344   DEBUG:   msg: [dummy] Was reinitialized, rate=48000Hz, nch = 2, format = 0x00000001 and bps = 2
21:08:44 M: 11321344   DEBUG:   msg: [dummy] Was reinitialized, rate=48000Hz, nch = 2, format = 0x00000001 and bps = 2
21:08:44 M: 11251712   DEBUG:   msg: Starting playback...
21:08:44 M: 11251712   DEBUG:   msg: mplayer_open_file(http://127.0.0.1:64652/4665?.avi) done 1.0000
21:08:44 M: 11169792    INFO: Loading skin file: VideoOverlay.xml
21:08:44 M: 11169792   DEBUG: Playback has started
21:08:44 M: 11329536   DEBUG: Load VideoOverlay.xml: 48.19ms (45.28 ms xml load)
21:08:44 M: 11329536   DEBUG: Alloc resources: 49.33ms (49.33 ms skin load, 0.00 ms preload)
21:08:44 M: 11321344   DEBUG:   msg: [ffmpeg] aspect_ratio: 1.772727
21:08:44 M: 11321344   DEBUG: CGUIInfoManager::SetCurrentMovie(http://127.0.0.1:64652/4665?.avi)
21:08:44 M: 11321344   DEBUG:   msg: VDec: vo config request - 624 x 352 (preferred colorspace: Planar YV12)
21:08:44 M: 11321344   DEBUG:   msg: Trying filter chain:
21:08:44 M: 11321344   DEBUG:   msg:  noise
21:08:44 M: 11321344   DEBUG:   msg:  vo
21:08:44 M: 11309056   DEBUG:   msg:
21:08:44 M: 11309056   DEBUG:   msg: VDec: using Planar YV12 as output csp (no 0)
21:08:44 M: 11309056   DEBUG:   msg: Movie-Aspect is 1.77:1 - prescaling to correct movie aspect.
21:08:44 M: 11309056   DEBUG:   msg: VO Config (624x352->624x352,flags=1,'MPlayer',0x32315659)
21:08:44 M: 11309056   DEBUG:   msg: REQ: flags=0x437  req=0x0
21:08:44 M: 11309056   DEBUG:   msg: VO: [directx] 624x352 => 624x352 Planar YV12  [fs]
21:08:44 M: 11300864   DEBUG:   msg: VO: Description: XBOX Direct3D8 YUY2 renderer
21:08:44 M: 11300864   DEBUG:   msg: VO: Author: Frodo/JCMarshall
21:08:44 M: 11300864   DEBUG: mplayer::video_config(624, 352, 624, 352, 1, 842094169)
21:08:44 M: 11300864  NOTICE: Display resolution USER : 720p 16:9 (1)
21:08:44 M: 10760192   DEBUG: Activating window ID: 12005
21:08:44 M: 10760192   DEBUG: Checking if window ID 12005 is locked.
21:08:44 M: 12337152   DEBUG: Setting resolution 1
21:08:44 M: 14196736   DEBUG: We set resolution 1
21:08:45 M: 13836288   DEBUG: Created YV12 texture 0
21:08:45 M: 12955648    INFO: Created 444P full texture
21:08:45 M: 12955648    INFO: Loading skin file: VideoFullScreen.xml
21:08:45 M: 12943360   DEBUG: Load VideoFullScreen.xml: 47.56ms (30.90 ms xml load)
21:08:45 M: 12853248   DEBUG: Alloc resources: 73.22ms (49.23 ms skin load, 0.37 ms preload)
21:08:45 M: 12853248    INFO: Loading skin file: VideoOSD.xml
21:08:45 M: 12861440   DEBUG: Load VideoOSD.xml: 15.27ms (7.20 ms xml load)
21:08:45 M: 12054528   DEBUG: Alloc resources: 31.67ms (0.00 ms skin load, 1.04 ms preload)
21:08:45 M: 12058624    INFO: Loading skin file: VideoOSDSettings.xml
21:08:45 M: 12046336   DEBUG: Load VideoOSDSettings.xml: 34.20ms (29.69 ms xml load)
21:08:45 M: 11350016   DEBUG: Alloc resources: 49.51ms (0.00 ms skin load, 0.54 ms preload)
21:08:45 M: 11350016    INFO: Loading skin file: VideoOSDSettings.xml
21:08:45 M: 11337728   DEBUG: Load VideoOSDSettings.xml: 14.50ms (9.95 ms xml load)
21:08:45 M: 11345920   DEBUG: Alloc resources: 0.43ms (0.00 ms skin load, 0.15 ms preload)
21:08:45 M: 11317248   DEBUG: CGUIFontTTF::Load Scaled size of font Q:\media\Fonts\arial.ttf (32.763889): width = 127, height = 45, lineheight = 38
21:08:45 M: 10940416   DEBUG:   msg: *** [noise] Allocating mp_image_t, 624x352x12bpp YUV planar, 329472 bytes
21:08:45 M: 10903552   DEBUG: Alloc resources: 0.53ms (0.00 ms skin load, 0.19 ms preload)
21:09:31 M: 10936320   DEBUG:   msg: XXX initial  v_pts=0.000  a_pos=2208 (0.133)
21:09:31 M: 10604544   DEBUG:   msg: *** [noise] Allocating mp_image_t, 624x352x12bpp YUV planar, 329472 bytes
21:09:31 M: 10240000   DEBUG:   msg: *** [vo] Allocating mp_image_t, 624x352x12bpp YUV planar, 329472 bytes
21:09:31 M:  9715712   DEBUG:   msg: FFRW:normal play
21:09:31 M:  9715712    INFO: Starting async renderer thread
21:09:31 M:  9715712   DEBUG:   msg: *** [noise] Direct Rendering mp_image_t, 624x352x12bpp YUV planar, 329472 bytes
21:11:54 M:  9715712    INFO: Thu Jun 05 21:11:54 2008
21:11:54 M:  9715712    INFO: 
21:11:54 M:  9715712    INFO: Could not read from URL...reopening...
21:11:54 M:  9715712    INFO: 
21:12:22 M:  9711616   DEBUG: Alloc resources: 0.52ms (0.00 ms skin load, 0.18 ms preload)
21:13:15 M:  9621504   DEBUG:   msg: FFRW:normal play
21:13:41 M:  9691136   DEBUG: Alloc resources: 0.53ms (0.00 ms skin load, 0.18 ms preload)
21:14:29 M:  9613312   DEBUG:   msg: FFRW:normal play
21:15:07 M:  9703424   DEBUG: Alloc resources: 0.52ms (0.00 ms skin load, 0.18 ms preload)
21:16:00 M:  9621504   DEBUG:   msg: FFRW:normal play
21:16:24 M:  9662464   DEBUG: Alloc resources: 0.52ms (0.00 ms skin load, 0.18 ms preload)
21:17:15 M:  9605120   DEBUG:   msg: FFRW:normal play
21:18:23 M:  9691136   DEBUG: Alloc resources: 0.53ms (0.00 ms skin load, 0.18 ms preload)
21:19:33 M:  9621504   DEBUG:   msg: FFRW:normal play
21:19:54 M:  9666560   DEBUG: Alloc resources: 0.52ms (0.00 ms skin load, 0.18 ms preload)
21:21:23 M:  9596928   DEBUG:   msg: FFRW:normal play
21:21:52 M:  9687040   DEBUG: Alloc resources: 0.53ms (0.00 ms skin load, 0.18 ms preload)
21:23:23 M:  9601024   DEBUG:   msg: FFRW:normal play
21:24:17 M:  9699328   DEBUG: Alloc resources: 0.52ms (0.00 ms skin load, 0.18 ms preload)
21:25:02 M:  9277440   DEBUG:   msg: nop_streaming_read error : Unknown error
21:25:02 M:  9273344   DEBUG:   msg: FFRW:normal play
21:25:16 M:  9322496   DEBUG:   msg: [mpeg4 @ 0227FF20]
21:25:16 M:  9326592   DEBUG:   msg: ac-tex damaged at 15 19
21:25:16 M:  9326592   DEBUG:   msg: [mpeg4 @ 0227FF20]
21:25:16 M:  9326592   DEBUG:   msg: Error at MB: 775
21:25:16 M:  9326592   DEBUG:   msg: [mpeg4 @ 0227FF20]
21:25:16 M:  9326592   DEBUG:   msg: concealing 151 DC, 151 AC, 151 MV errors
21:25:16 M:  9347072   DEBUG:   msg: ds_fill_buffer: EOF reached (stream: video)
21:25:16 M:  9342976   DEBUG:   msg:  mplayer_close_file()
21:25:16 M:  9342976   DEBUG:   msg:
21:25:16 M:  9342976   DEBUG:   msg:  uninit_player
21:25:16 M: 18382848    INFO: Client Closed the connection.
21:25:16 M: 18382848    INFO: 
21:25:16 M: 19169280   DEBUG:   msg: WINSOCK2 uninit
21:25:16 M: 19185664   DEBUG:   msg: Uninit audio filters...
21:25:16 M: 19185664   DEBUG:   msg: [libaf] Removing filter dummy
21:25:16 M: 19185664   DEBUG:   msg: Uninit audio: mp3lib
21:25:16 M: 19324928   DEBUG:   msg: Uninit video: ffmpeg
21:25:16 M: 25014272   DEBUG: Deleted 444P video textures
21:25:16 M: 25899008   DEBUG: Deleted YV12 texture 0
21:25:16 M: 26169344   DEBUG:   msg:  uninit_player - done
21:25:16 M: 26169344   DEBUG: Freeing FS segment @ 0xb29530
21:25:16 M: 26169344   DEBUG: Playback has finished
21:25:16 M: 26681344   DEBUG: CVideoDatabase::GetMovieInfo (http://127.0.0.1:64652/4665?.avi), query = select idMovie from movie where idFile=3249
21:25:16 M: 26681344   DEBUG: CVideoDatabase::GetEpisodeInfo (http://127.0.0.1:64652/4665?.avi), query = select idEpisode from episode where idFile=3249
21:25:16 M: 26681344   DEBUG: CVideoDatabase::GetMusicVideoInfo (http://127.0.0.1:64652/4665?.avi), query = select idMVideo from musicvideo where idFile=3249
21:25:16 M: 26693632   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
21:25:17 M: 28270592   DEBUG: Setting resolution 1
21:25:17 M: 26386432   DEBUG: We set resolution 1
21:25:17 M: 26501120   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
21:25:17 M: 26488832    INFO: Loading skin file: MyVideo.xml
21:25:17 M: 26361856   DEBUG: Load MyVideo.xml: 160.85ms (40.74 ms xml load)
21:25:17 M: 26238976   DEBUG: Alloc resources: 244.37ms (185.64 ms skin load, 0.83 ms preload)
21:25:17 M: 26259456   DEBUG: CGUIMediaWindow::GetDirectory (plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Fvideo%2F4665&mode=4&name=Bones+3x15+The+Pain+in+the+Heart)
21:25:17 M: 26279936   DEBUG:   ParentPath = [plugin://video/Ninja Video/?url=http%3A%2F%2Fwww.ninjavideo.net%2Fvideo%2F4665&mode=4&name=Bones+3x15+The+Pain+in+the+Heart]
21:25:17 M: 26271744   DEBUG: DIRECTORY::CPluginDirectory::GetDirectory - URL for plugin settings: Ninja Video/
21:25:17 M: 26271744   ERROR: Unable to load: Q:\plugins\video\Ninja Video\resources\settings.xml, Line 0
                             Failed to open file
21:25:17 M: 26271744   ERROR: unable to load Q:\plugins\video\Ninja Video\resources\language\German\strings.xml: Failed to open file at line 0
21:25:17 M: 26271744   ERROR: unable to load Q:\plugins\video\Ninja Video\resources\language\english\strings.xml: Failed to open file at line 0
21:25:17 M: 26271744   DEBUG: DIRECTORY::CPluginDirectory::GetDirectory - calling plugin Q:\plugins\video\Ninja Video\default.py('plugin://video/Ninja Video/','1','?url=http%3A%2F%2Fwww.ninjavideo.net%2Fvideo%2F4665&mode=4&name=Bones+3x15+The+Pain+in+the+Heart')
21:25:17 M: 25747456   DEBUG: DIRECTORY::CPluginDirectory::WaitOnScriptResult - waiting on the Ninja Video plugin...
21:25:17 M: 25751552    INFO: Mode: 4
21:25:17 M: 25751552    INFO: 
21:25:17 M: 25751552    INFO: URL: http://www.ninjavideo.net/video/4665
21:25:17 M: 25751552    INFO: 
21:25:17 M: 25751552    INFO: Name: Bones 3x15 The Pain in the Heart
21:25:17 M: 25751552    INFO: 
21:25:17 M: 25751552    INFO: index of videolinks : http://www.ninjavideo.net/video/4665
21:25:17 M: 25751552    INFO: 
21:25:17 M: 25755648   DEBUG: DIRECTORY::CPluginDirectory::WaitOnScriptResult- plugin returned successfully
21:25:17 M: 25759744    INFO: Scriptresult: Succes
21:25:17 M: 25739264    INFO: Python script stopped
21:25:18 M: 25759744   DEBUG: Q:\system\players\mplayer\mplayer.dll (base 01EC0000): Detected memory leaks: 289 leaks
21:25:18 M: 26124288   DEBUG: leak caller address  1ec10cc, size      128, counter    1
21:25:18 M: 26648576   DEBUG: leak caller address  1ece000, size        4, counter    1
21:25:18 M: 26648576   DEBUG: leak caller address  1ece1d5, size       40, counter    1
21:25:18 M: 26648576   DEBUG: leak caller address  1ece2a9, size       72, counter    2
21:25:18 M: 26648576   DEBUG: leak caller address  1ece49b, size        8, counter    1
21:25:18 M: 26648576   DEBUG: leak caller address  1ece4b3, size        0, counter    1
21:25:18 M: 26648576   DEBUG: leak caller address  1edd2d7, size       28, counter    2
21:25:18 M: 26648576   DEBUG: leak caller address  1edd300, size        0, counter    5
21:25:18 M: 26648576   DEBUG: leak caller address  1edd3a7, size       16, counter    1
21:25:18 M: 26648576   DEBUG: leak caller address  1edd3cc, size        0, counter    1
21:25:18 M: 26648576   DEBUG: leak caller address  1edd469, size        0, counter    5
21:25:18 M: 26648576   DEBUG: leak caller address  1ee3106, size        0, counter    1
21:25:18 M: 26648576   DEBUG: leak caller address  1ee312b, size        0, counter    1
21:25:18 M: 26648576   DEBUG: leak caller address  1ee3151, size        0, counter    1
21:25:18 M: 26648576   DEBUG: leak caller address  1f5afb0, size       44, counter    1
21:25:18 M: 26648576   DEBUG: leak caller address  1f79026, size       28, counter    1
21:25:18 M: 26648576   DEBUG: leak caller address  1f79096, size        0, counter    1
21:25:18 M: 26648576   DEBUG: leak caller address  1f790f1, size        5, counter    1
21:25:18 M: 26648576   DEBUG: leak caller address  1f792cf, size       10, counter    1
21:25:18 M: 26648576   DEBUG: leak caller address  1f79325, size        0, counter    1
21:25:18 M: 26648576   DEBUG: leak caller address  1f7cf29, size     1624, counter   50
21:25:18 M: 26648576   DEBUG: leak caller address  1fe8dd4, size      869, counter    1
21:25:18 M: 26648576   DEBUG: leak caller address  1fe8ef0, size    31232, counter   10
21:25:18 M: 26648576   DEBUG: leak caller address  21e24ad, size   496650, counter  198
21:25:18 M: 26648576   DEBUG: mplayer.dll: Total bytes leaked: 530758