19:19:10 M: 54185984 NOTICE: ----------------------------------------------------------------------- 19:19:10 M: 54165504 NOTICE: Starting XBoxMediaCenter. Built on Dec 25 2006 19:19:10 M: 54165504 NOTICE: Q is mapped to: Harddisk0\Partition1\APPS\XBMC 19:19:10 M: 54165504 NOTICE: Log File is located: Q:\xbmc.log 19:19:10 M: 54165504 NOTICE: ----------------------------------------------------------------------- 19:19:10 M: 54165504 NOTICE: Setup DirectX 19:19:10 M: 54120448 NOTICE: load settings... 19:19:10 M: 54120448 NOTICE: loading T:\guisettings.xml 19:19:10 M: 53915648 NOTICE: Getting hardware information now... 19:19:10 M: 53915648 NOTICE: Checking resolution 1 19:19:10 M: 53915648 NOTICE: Per AV pack settings are on 19:19:10 M: 53895168 NOTICE: HDTV found : loading T:\avpacksettings.xml 19:19:10 M: 53821440 NOTICE: Getting hardware information now... 19:19:10 M: 53821440 NOTICE: Checking resolution 1 19:19:10 M: 54067200 NOTICE: q:\userdata\sources.xml 19:19:11 M: 43507712 ERROR: Window Translator: Can't find window myvideotitles 19:19:11 M: 42196992 NOTICE: Checking the Date! 19:19:12 M: 40108032 NOTICE: start dvd mediatype detection 19:19:12 M: 39583744 NOTICE: initializing playlistplayer 19:19:12 M: 39583744 NOTICE: DONE initializing playlistplayer 19:19:12 M: 39583744 NOTICE: load default skin:[PDM] 19:19:14 M: 40972288 NOTICE: use static ip 19:19:14 M: 40947712 NOTICE: initialize done 19:19:14 M: 40980480 INFO: Key combination detected for full debug logging (X+Y) 19:19:14 M: 40943616 INFO: CNetwork::LogState - Link: full duplex 19:19:14 M: 40943616 NOTICE: Running the application... 19:19:14 M: 41009152 INFO: CNetwork::LogState - Link: 100 mbps 19:19:14 M: 40968192 INFO: CNetwork::LogState - State: dns 19:19:14 M: 40972288 DEBUG: LoadLibraryA('python24.dll') 19:19:14 M: 40935424 INFO: CNetwork::LogState - State: static 19:19:15 M: 40996864 INFO: CNetwork::LogState - ip: 192.168.0.50 19:19:15 M: 40923136 INFO: CNetwork::LogState - subnet: 255.255.255.0 19:19:15 M: 39841792 INFO: CNetwork::LogState - gateway: 192.168.0.1 19:19:15 M: 39841792 INFO: CNetwork::LogState - dns: 195.92.195.94, 0.0.0.0 19:19:15 M: 39804928 INFO: WEATHER: Downloading weather 19:19:15 M: 39841792 INFO: Get URL: http://xoap.weather.com/weather/local/UKXX0139?cc=*&unit=m&dayf=4&prod=xoap&par=1004124588&key=079f24145f208494 19:19:15 M: 39878656 DEBUG: LoadLibrary('python24.dll') returning: 0xc14de0 19:19:16 M: 39841792 DEBUG: dllGetProcAddress(0x9a0e08(kernel32.dll), 'InterlockedCompareExchange') => 0x1c2964 19:19:16 M: 39473152 DEBUG: GetModuleFileNameA(0x0, 0xd92490, 256) => 'xbmc.xbe' 19:19:16 M: 39464960 DEBUG: advapi32.dll fake function RegQueryInfoKeyA called 19:19:16 M: 39505920 DEBUG: advapi32.dll fake function RegQueryInfoKeyA called 19:19:16 M: 39477248 INFO: WEATHER: Weather download successful 19:19:16 M: 39489536 INFO: Stating file Q:\system\python\python24.zlib 19:19:17 M: 34799616 INFO: Stating file Q:\system\python\python24.zlib\zlib 19:19:17 M: 35037184 INFO: Stating file Q:\system\python\DLLs 19:19:17 M: 34754560 INFO: Stating file Q:\system\python\DLLs\zlib 19:19:17 M: 34832384 DEBUG: LoadLibraryExA called with flags: - LOAD_WITH_ALTERED_SEARCH_PATH 19:19:17 M: 34889728 DEBUG: LoadLibraryA('zlib.pyd') 19:19:17 M: 34885632 INFO: Using protocol iso9660:// 19:19:17 M: 34885632 INFO: Disc label: STAR_WARS_EPISODE_IV 19:19:17 M: 35151872 DEBUG: MEDIA_DETECT::CDetectDVDMedia::SetNewDVDShareUrl: looking for disc thumb:[iso9660://folder.jpg] 19:19:17 M: 34885632 DEBUG: kernel32.dll fake function DisableThreadLibraryCalls called 19:19:17 M: 34885632 DEBUG: LoadLibrary('zlib.pyd') returning: 0xc35010 19:19:17 M: 34885632 DEBUG: dllGetProcAddress(0x13c0000(zlib.pyd), 'initzlib') => 0x13c2150 19:19:18 M: 34205696 INFO: Stating file Q:\system\python 19:19:18 M: 34209792 INFO: Stating file Q:\system\python\lib\site-packages 19:19:18 M: 33837056 INFO: Stating file Q:\system\python\python24.zlib\encodings 19:19:18 M: 33837056 INFO: Stating file Q:\system\python\python24.zlib 19:19:18 M: 34082816 INFO: Stating file Q:\system\python\python24.zlib\encodings\codecs 19:19:18 M: 33837056 INFO: Stating file Q:\system\python\python24.zlib\encodings\exceptions 19:19:18 M: 33837056 INFO: Stating file Q:\system\python\python24.zlib\encodings\types 19:19:18 M: 33824768 INFO: Stating file Q:\system\python\python24.zlib\encodings\cp0 19:19:18 M: 33824768 INFO: Stating file Q:\system\python\python24.zlib\cp0 19:19:18 M: 34091008 INFO: Stating file Q:\system\python\DLLs\cp0 19:19:18 M: 33824768 INFO: Stating file Q:\system\python\Lib 19:19:18 M: 33824768 INFO: Stating file Q:\system\python\Lib\cp0 19:19:18 M: 33824768 INFO: Stating file Q:\system\python\spyce 19:19:18 M: 34091008 INFO: Stating file Q:\system\python\spyce\cp0 19:19:18 M: 33824768 INFO: Stating file Q:\system\python\python24.zip 19:19:18 M: 33824768 INFO: Stating file Q:\system\python 19:19:18 M: 33824768 INFO: Stating file Q:\system\python\python24.zip\cp0 19:19:18 M: 33824768 INFO: Stating file Q:\system\python\lib\plat-win 19:19:18 M: 33824768 INFO: Stating file Q:\system\python\lib 19:19:18 M: 33824768 INFO: Stating file Q:\system\python\lib\plat-win\cp0 19:19:18 M: 34091008 INFO: Stating file Q:\system\python\lib\lib-tk 19:19:18 M: 33824768 INFO: Stating file Q:\system\python\lib 19:19:18 M: 33824768 INFO: Stating file Q:\system\python\lib\lib-tk\cp0 19:19:18 M: 33824768 INFO: Stating file Q:\python 19:19:18 M: 34091008 INFO: Stating file Q:\python\cp0 19:19:18 M: 33824768 INFO: Stating file Q:\system\python 19:19:18 M: 33824768 INFO: Stating file Q:\system\python\cp0 19:19:18 M: 33824768 INFO: Stating file Q:\system\python\python24.zlib\sitecustomize 19:19:18 M: 34091008 INFO: Stating file Q:\system\python\DLLs\sitecustomize 19:19:18 M: 33824768 INFO: Stating file Q:\system\python\Lib\sitecustomize 19:19:18 M: 33824768 INFO: Stating file Q:\system\python\spyce\sitecustomize 19:19:18 M: 33824768 INFO: Stating file Q:\system\python\python24.zip\sitecustomize 19:19:18 M: 33824768 INFO: Stating file Q:\system\python\lib\plat-win\sitecustomize 19:19:18 M: 34091008 INFO: Stating file Q:\system\python\lib\lib-tk\sitecustomize 19:19:18 M: 33824768 INFO: Stating file Q:\python\sitecustomize 19:19:18 M: 33824768 INFO: Stating file Q:\system\python\sitecustomize 19:19:18 M: 33804288 INFO: -->Python Initialized<-- 19:19:18 M: 33804288 INFO: 19:19:18 M: 33280000 DEBUG: CNetwork::NetworkMessage - Starting network services 19:19:18 M: 33259520 NOTICE: start timeserver client 19:19:18 M: 32731136 DEBUG: kernel32.dll fake function SetConsoleCtrlHandler called 19:19:18 M: 32731136 NOTICE: Webserver: Starting... 19:19:18 M: 32919552 INFO: Scriptresult: Succes 19:19:18 M: 32653312 INFO: Python script stopped 19:19:18 M: 33177600 DEBUG: xbmcHttpShim starts 19:19:18 M: 32649216 NOTICE: XBFileZilla: Starting... 19:19:18 M: 32575488 INFO: WebServer: Server starting using Q:\web on 192.168.0.50:80 19:19:18 M: 31531008 DEBUG: AudioScrobbler: load cache 19:19:18 M: 31797248 DEBUG: DB: Registering database table 19:19:18 M: 31191040 DEBUG: AudioScrobbler: ... 19:19:18 M: 30851072 DEBUG: DB: Registering database table 19:19:18 M: 30457856 INFO: Get URL: http://post.audioscrobbler.com/?hs=true&p=1.1&c=xbm&v=0.1&u=gergtreble 19:19:18 M: 30457856 DEBUG: DB: Registering database table 19:19:18 M: 30400512 DEBUG: UM: Loading User Configuration from file 19:19:18 M: 30666752 DEBUG: DB: About to read data file 19:19:18 M: 30937088 DEBUG: DB: Failed to stat persistent data file. 19:19:18 M: 30785536 DEBUG: webs: Listening for HTTP requests at address 192.168.0.50 19:19:18 M: 30785536 NOTICE: Webserver: Started 19:19:18 M: 28749824 DEBUG: AudioScrobbler: Handshaking: Client up to date. 19:19:18 M: 28741632 DEBUG: AudioScrobbler: Submit interval set to 1 seconds. 19:19:18 M: 28741632 NOTICE: AudioScrobbler: Handshake successful. 19:19:18 M: 28753920 INFO: Scriptresult: Succes 19:19:18 M: 28999680 INFO: Python script stopped 19:19:18 M: 29544448 INFO: Python, unloading python24.dll cause no scripts are running anymore 19:19:19 M: 30203904 NOTICE: XBFileZilla: Started 19:19:21 M: 35323904 DEBUG: CApplication::OnKey: 270 pressed, action is 3 19:19:21 M: 35205120 DEBUG: CApplication::OnKey: 271 pressed, action is 4 19:19:22 M: 35225600 DEBUG: CApplication::OnKey: 256 pressed, action is 7 19:19:22 M: 34701312 INFO: WEATHER: Downloading weather 19:19:22 M: 34701312 INFO: Get URL: http://xoap.weather.com/weather/local/UKXX0139?cc=*&unit=m&dayf=4&prod=xoap&par=1004124588&key=079f24145f208494 19:19:22 M: 34701312 DEBUG: Activating window ID: 10000 19:19:22 M: 34701312 DEBUG: Checking if window ID 10000 is locked. 19:19:23 M: 36995072 INFO: WEATHER: Weather download successful 19:19:23 M: 37412864 DEBUG: Activating window ID: 10024 19:19:23 M: 37412864 DEBUG: Checking if window ID 10024 is locked. 19:19:23 M: 37588992 INFO: Attempting to default to: My Videos 19:19:23 M: 37588992 DEBUG: CURL::CURL - Url has no protocol My Videos, empty CURL created 19:19:23 M: 37588992 INFO: Success! Opened destination path: My Videos 19:19:23 M: 37588992 DEBUG: CVirtualPathDirectory::GetTypeAndBookmark(virtualpath://video/Videos) = [video],[Videos] 19:19:23 M: 37588992 DEBUG: CURL::CURL - Url has no protocol Videos, empty CURL created 19:19:23 M: 37576704 INFO: Loading skin file: MyVideo.xml 19:19:23 M: 37543936 ERROR: Texture manager unable to load file: Q:\skin\PDM\media\select-left.gif 19:19:23 M: 37539840 ERROR: Texture manager unable to load file: Q:\skin\PDM\media\select-right.gif 19:19:23 M: 37486592 DEBUG: CGUIMediaWindow::GetDirectory (smb://192.168.0.77/DISK 1/My Videos) 19:19:23 M: 37486592 DEBUG: ParentPath = [smb://192.168.0.77/DISK 1/My Videos] 19:19:23 M: 37146624 DEBUG: DIRECTORY::CSMBDirectory::OpenDir - Using authentication url smb://192.168.0.77/DISK%201/My%20Videos 19:19:29 M: 33464320 DEBUG: CApplication::OnKey: 257 pressed, action is 9 19:19:29 M: 33652736 DEBUG: CGUIMediaWindow::GetDirectory () 19:19:29 M: 33652736 DEBUG: ParentPath = [] 19:19:29 M: 33652736 DEBUG: CVirtualPathDirectory::GetTypeAndBookmark(virtualpath://video/Videos) = [video],[Videos] 19:19:29 M: 33652736 DEBUG: CURL::CURL - Url has no protocol Videos, empty CURL created 19:19:30 M: 33546240 DEBUG: CApplication::OnKey: 270 pressed, action is 3 19:19:30 M: 33427456 DEBUG: CApplication::OnKey: 270 pressed, action is 3 19:19:31 M: 33447936 DEBUG: CApplication::OnKey: 256 pressed, action is 7 19:19:31 M: 33632256 DEBUG: CGUIMediaWindow::GetDirectory (iso9660://) 19:19:31 M: 33632256 DEBUG: ParentPath = [] 19:19:35 M: 33705984 DEBUG: CApplication::OnKey: 271 pressed, action is 4 19:19:35 M: 33587200 DEBUG: CApplication::OnKey: 256 pressed, action is 7 19:19:35 M: 33632256 DEBUG: CGUIMediaWindow::GetDirectory (iso9660://VIDEO_TS) 19:19:35 M: 33632256 DEBUG: ParentPath = [iso9660://] 19:19:37 M: 33705984 DEBUG: CApplication::OnKey: 257 pressed, action is 9 19:19:37 M: 33632256 DEBUG: CGUIMediaWindow::GetDirectory (iso9660://) 19:19:37 M: 33632256 DEBUG: ParentPath = [] 19:19:37 M: 33566720 DEBUG: CApplication::OnKey: 257 pressed, action is 9 19:19:37 M: 33611776 DEBUG: CGUIMediaWindow::GetDirectory () 19:19:37 M: 33611776 DEBUG: ParentPath = [] 19:19:37 M: 33611776 DEBUG: CVirtualPathDirectory::GetTypeAndBookmark(virtualpath://video/Videos) = [video],[Videos] 19:19:37 M: 33611776 DEBUG: CURL::CURL - Url has no protocol Videos, empty CURL created 19:19:40 M: 33566720 DEBUG: CApplication::OnKey: 261 pressed, action is 117 19:19:40 M: 33566720 INFO: Loading skin file: DialogContextMenu.xml 19:19:40 M: 33566720 DEBUG: CURL::CURL - Url has no protocol My Videos, empty CURL created 19:19:41 M: 33099776 DEBUG: CApplication::OnKey: 256 pressed, action is 7 19:19:41 M: 33427456 DEBUG: CPlayerCoreFactor::GetPlayers(dvd://1) 19:19:41 M: 33427456 NOTICE: DVDPlayer: Opening: dvd://1 19:19:41 M: 33427456 NOTICE: CDVDPlayer::CloseFile() 19:19:41 M: 33427456 NOTICE: DVDPlayer: waiting for threads to exit 19:19:41 M: 33427456 NOTICE: DVDPlayer: finished waiting 19:19:41 M: 32903168 NOTICE: Creating InputStream 19:19:41 M: 32649216 INFO: msg: libdvdnav: Using dvdnav version 0.2.0cvs from http://dvd.sf.net 19:19:41 M: 32649216 INFO: msg: libdvdread: Using libdvdcss version 1.2.9 for DVD access 19:19:41 M: 32649216 INFO: Stating file \Device\Cdrom0 19:19:41 M: 32649216 INFO: msg: libdvdcss debug: 19:19:41 M: 32649216 INFO: msg: opening target `\Device\Cdrom0' 19:19:41 M: 32649216 INFO: msg: 19:19:41 M: 32649216 INFO: msg: libdvdcss debug: 19:19:41 M: 32649216 INFO: msg: using Win2K API for access 19:19:41 M: 32649216 INFO: msg: 19:19:41 M: 32649216 INFO: msg: libdvdcss debug: 19:19:41 M: 32649216 INFO: msg: disc is scrambled 19:19:41 M: 32649216 INFO: msg: 19:19:41 M: 32649216 INFO: msg: libdvdcss debug: 19:19:41 M: 32649216 INFO: msg: requesting AGID 19:19:41 M: 32649216 INFO: msg: 19:19:41 M: 32649216 INFO: msg: libdvdcss debug: 19:19:41 M: 32649216 INFO: msg: drive authenticated, using variant 0 19:19:41 M: 32649216 INFO: msg: 19:19:41 M: 32649216 INFO: msg: libdvdcss debug: 19:19:41 M: 32649216 INFO: msg: authentication established 19:19:41 M: 32649216 INFO: msg: 19:19:42 M: 32649216 INFO: msg: libdvdcss debug: 19:19:42 M: 32649216 INFO: msg: GetASF authenticated, ASF=1 19:19:42 M: 32649216 INFO: msg: 19:19:42 M: 32649216 INFO: msg: libdvdcss debug: 19:19:42 M: 32649216 INFO: msg: decrypting disc key bf:72:f2:0a:61 19:19:42 M: 32649216 INFO: msg: 19:19:42 M: 32649216 INFO: msg: libdvdcss debug: 19:19:42 M: 32649216 INFO: msg: trying player key 01:af:e3:12:80 19:19:42 M: 32649216 INFO: msg: 19:19:42 M: 32649216 INFO: msg: libdvdcss debug: 19:19:42 M: 32649216 INFO: msg: decrypted disc key is 00:fa:b4:a2:ba 19:19:42 M: 32649216 INFO: msg: 19:19:42 M: 32649216 INFO: msg: libdvdcss debug: 19:19:42 M: 32649216 INFO: msg: using CSS key cache dir: T:\cache/STAR_WARS_EPISODE_IV/2004061418065500-00fab4a2ba/ 19:19:42 M: 32649216 INFO: msg: 19:19:42 M: 32649216 INFO: msg: libdvdnav: vm: DVD Title: STAR_WARS_EPISODE_IV 19:19:42 M: 32649216 INFO: msg: libdvdnav: Unable to find map file 'Q:\dvdplayer/.dvdnav/STAR_WARS_EPISODE_IV.map' 19:19:42 M: 32649216 INFO: msg: libdvdnav: DVD disk reports itself with Region mask 0x00f50000. Regions: 19:19:42 M: 32649216 INFO: msg: 2 19:19:42 M: 32649216 INFO: msg: 4 19:19:42 M: 32649216 DEBUG: msg: 19:19:42 M: 32649216 NOTICE: DVDPlayer: playing a dvd with menu's 19:19:42 M: 32649216 NOTICE: Creating Demuxer 19:19:43 M: 28995584 DEBUG: Playback has started 19:19:43 M: 28995584 DEBUG: CDVDInputStreamNavigator::SetActiveSubtitleStream - id: 0, stream: -1 19:19:43 M: 29179904 DEBUG: CGUIMediaWindow::GetDirectory () 19:19:43 M: 29179904 DEBUG: ParentPath = [] 19:19:43 M: 29179904 DEBUG: CVirtualPathDirectory::GetTypeAndBookmark(virtualpath://video/Videos) = [video],[Videos] 19:19:43 M: 29179904 DEBUG: CURL::CURL - Url has no protocol Videos, empty CURL created 19:19:43 M: 29179904 DEBUG: DVDNAV_HIGHLIGHT: Highlight button 1 19:19:43 M: 29179904 DEBUG: - DVDNAV_VTS_CHANGE (HOLDING) 19:19:43 M: 29179904 DEBUG: DVDNAV_VTS_CHANGE 19:19:43 M: 29179904 INFO: CDVDInputStreamNavigator::GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 1 19:19:43 M: 29163520 WARNING: CDVDMessageQueue::Put MSGQ_NOT_INITIALIZED 19:19:43 M: 29143040 INFO: CDVDInputStreamNavigator::GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 1 19:19:43 M: 29122560 ERROR: Error reading data from demuxer 19:19:43 M: 29126656 DEBUG: DVDNAV_CELL_CHANGE 19:19:43 M: 29106176 DEBUG: DVDNAV_SPU_CLUT_CHANGE 19:19:43 M: 29106176 DEBUG: DVDNAV_SPU_STREAM_CHANGE 19:19:43 M: 29147136 DEBUG: DVDNAV_AUDIO_STREAM_CHANGE 19:19:43 M: 28655616 INFO: msg: libdvdcss debug: 19:19:43 M: 29179904 INFO: msg: title key found in cache 00:00:00:00:00 19:19:43 M: 29179904 INFO: msg: 19:19:43 M: 28151808 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:0 TO:480000 DIFF:480000 19:19:43 M: 28053504 NOTICE: Opening video stream: 0 19:19:43 M: 27983872 NOTICE: Creating video codec with codec id: 2 19:19:43 M: 28000256 DEBUG: FactoryCodec - Video: libmpeg2 - Opening 19:19:43 M: 27836416 DEBUG: CGUIInfoManager::SetCurrentMovie(dvd://1) 19:19:43 M: 26574848 DEBUG: FactoryCodec - Video: libmpeg2 - Opened 19:19:43 M: 26554368 NOTICE: Creating video thread 19:19:43 M: 26046464 NOTICE: running thread: video_thread 19:19:43 M: 26046464 DEBUG: CDVDPlayerVideo::Process - Resync recieved. 19:19:43 M: 25018368 DEBUG: CDVDPlayerVideo:: Resync - clock:0, delay:0 19:19:43 M: 23130112 NOTICE: Initializing video device 19:19:43 M: 23130112 DEBUG: CXBoxRenderManager::PreInit - Selected RGB-Renderer 19:19:43 M: 23089152 NOTICE: fps: 25.000000, pwidth: 720, pheight: 576, dwidth: 1024, dheight: 576 19:19:43 M: 23089152 NOTICE: Display resolution USER : 720p 16:9 (1) 19:19:43 M: 22609920 DEBUG: - DVDNAV_WAIT (HOLDING) 19:19:43 M: 22618112 DEBUG: CVideoDatabase::GetMovieInfo(dvd://1), query = select * from movie join path on movie.idPath = path.idPath join movieinfo on movie.idMovie = movieinfo.idMovie join files on movie.idMovie = files.idMovie where (path.strPath like 'dvd:/' and files.strFileName like '/1') or path.strPath like 'stack://dvd:/' 19:19:43 M: 22573056 DEBUG: DVDNAV_STILL_FRAME - waiting 3 sec, with delay of 0 sec 19:19:43 M: 22634496 DEBUG: Activating window ID: 12005 19:19:43 M: 22634496 DEBUG: Checking if window ID 12005 is locked. 19:19:43 M: 26152960 DEBUG: Setting resolution 1 19:19:43 M: 28037120 DEBUG: We set resolution 1 19:19:43 M: 27373568 DEBUG: Created YV12 texture 0 19:19:43 M: 27373568 INFO: Loading skin file: VideoFullscreen.xml 19:19:43 M: 27365376 INFO: Loading skin file: VideoOSD.xml 19:19:43 M: 26763264 INFO: Loading skin file: VideoOSDSettings.xml 19:19:43 M: 26583040 INFO: Loading skin file: VideoOSDSettings.xml 19:19:43 M: 26554368 DEBUG: CGUIFontTTF::Load Scaled size of font Q:\Media\Fonts\Arial.ttf (28): width = 76, height = 39 19:19:44 M: 26640384 INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25 fps 19:19:46 M: 26042368 DEBUG: DVDNAV_VTS_CHANGE 19:19:46 M: 26042368 INFO: CDVDInputStreamNavigator::GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 1 19:19:46 M: 26042368 DEBUG: CDVDPlayerVideo - CDVDMsg::VIDEO_SET_ASPECT 19:19:46 M: 26042368 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback 19:19:46 M: 26042368 DEBUG: DVDNAV_CELL_CHANGE 19:19:46 M: 26042368 DEBUG: DVDNAV_SPU_CLUT_CHANGE 19:19:46 M: 26042368 DEBUG: DVDNAV_SPU_STREAM_CHANGE 19:19:46 M: 26042368 DEBUG: DVDNAV_AUDIO_STREAM_CHANGE 19:19:46 M: 26042368 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:3480000 TO:480000 DIFF:-3000000 19:19:47 M: 25571328 DEBUG: CDVDPlayer::CheckContinuity - Potential long duration frame 19:19:47 M: 25571328 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 19:19:47 M: 25796608 NOTICE: Opening Subtitle stream: 1 19:19:47 M: 25706496 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 19:19:47 M: 25706496 DEBUG: DVDNAV_CELL_CHANGE (HOLDING) 19:19:47 M: 25931776 DEBUG: DVDNAV_CELL_CHANGE 19:19:47 M: 25952256 DEBUG: DVDNAV_SPU_CLUT_CHANGE 19:19:47 M: 25952256 DEBUG: DVDNAV_SPU_STREAM_CHANGE 19:19:47 M: 25952256 DEBUG: DVDNAV_AUDIO_STREAM_CHANGE 19:19:47 M: 25530368 DEBUG: CDVDPlayer::CheckContinuity - Potential long duration frame 19:19:47 M: 25530368 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 19:19:47 M: 25026560 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 19:19:47 M: 25026560 DEBUG: - DVDNAV_WAIT (HOLDING) 19:19:47 M: 25243648 DEBUG: DVDNAV_STILL_FRAME - waiting 10 sec, with delay of 0 sec 19:19:47 M: 25243648 INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25 fps 19:19:57 M: 25309184 DEBUG: DVDNAV_VTS_CHANGE 19:19:57 M: 25382912 INFO: CDVDInputStreamNavigator::GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 2 19:19:57 M: 25382912 DEBUG: DVDNAV_CELL_CHANGE 19:19:57 M: 25403392 DEBUG: DVDNAV_SPU_CLUT_CHANGE 19:19:57 M: 25403392 DEBUG: DVDNAV_SPU_STREAM_CHANGE 19:19:57 M: 25403392 DEBUG: DVDNAV_AUDIO_STREAM_CHANGE 19:19:57 M: 25403392 DEBUG: CDVDPlayerVideo - CDVDMsg::VIDEO_SET_ASPECT 19:19:57 M: 25403392 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback 19:19:57 M: 25403392 INFO: msg: libdvdcss debug: 19:19:57 M: 25403392 INFO: msg: title key found in cache c0:83:08:de:97 19:19:57 M: 25403392 INFO: msg: 19:19:57 M: 25403392 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:20480000 TO:195666 DIFF:-20284333 19:19:57 M: 25391104 DEBUG: CDVDPlayer::CheckContinuity - Potential long duration frame 19:19:57 M: 25391104 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 19:19:57 M: 25382912 NOTICE: Opening audio stream: 1 19:19:57 M: 25362432 NOTICE: Finding audio codec for: 86020 19:19:57 M: 25362432 DEBUG: FactoryCodec - Audio: passthrough - Opening 19:19:57 M: 25055232 DEBUG: FactoryCodec - Audio: passthrough - Opened 19:19:57 M: 25055232 NOTICE: Creating audio thread 19:19:57 M: 24530944 NOTICE: running thread: CDVDPlayerAudio::Process() 19:19:57 M: 24530944 INFO: CDVDInputStreamNavigator::GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 2 19:19:57 M: 24530944 DEBUG: CDVDAudioCodecPassthrough: Synced to AC3 frame 19:19:57 M: 24510464 NOTICE: Creating audio device with codec id: 86020, channels: 2, sample rate: 48000, pass-through 19:19:57 M: 24875008 DEBUG: CDVDPlayerAudio:: Resync - clock:23031999, delay:59666 19:19:57 M: 23433216 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 19:19:57 M: 23437312 DEBUG: CDVDPlayerVideo - CDVDMsg::VIDEO_SET_ASPECT 19:19:57 M: 23437312 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC 19:20:12 M: 23199744 DEBUG: DVDNAV_CELL_CHANGE (HOLDING) 19:20:12 M: 23339008 DEBUG: DVDNAV_CELL_CHANGE 19:20:12 M: 23339008 DEBUG: DVDNAV_SPU_CLUT_CHANGE 19:20:12 M: 23339008 DEBUG: DVDNAV_SPU_STREAM_CHANGE 19:20:12 M: 23339008 DEBUG: DVDNAV_AUDIO_STREAM_CHANGE 19:20:12 M: 23412736 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:17595666 TO:195666 DIFF:-17400000 19:20:14 M: 23277568 DEBUG: CDVDPlayerAudio:: Detected Audio Discontinuity, syncing clock. diff was: -8110, -8101, av: -8105 19:20:22 M: 23203840 DEBUG: DVDNAV_CELL_CHANGE (HOLDING) 19:20:22 M: 23412736 DEBUG: DVDNAV_CELL_CHANGE 19:20:22 M: 23412736 DEBUG: DVDNAV_SPU_CLUT_CHANGE 19:20:22 M: 23412736 DEBUG: DVDNAV_SPU_STREAM_CHANGE 19:20:22 M: 23412736 DEBUG: DVDNAV_AUDIO_STREAM_CHANGE 19:20:22 M: 23457792 NOTICE: Opening Subtitle stream: 2 19:20:22 M: 23367680 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 19:20:24 M: 23138304 DEBUG: CDVDPlayerAudio:: Detected Audio Discontinuity, syncing clock. diff was: -56, 31970, av: 15957 19:20:39 M: 23019520 DEBUG: CApplication::OnKey: 271 pressed, action is 4 19:20:39 M: 23019520 DEBUG: - move down 19:20:39 M: 23060480 DEBUG: DVDNAV_HIGHLIGHT: Highlight button 2 19:20:39 M: 23097344 DEBUG: CApplication::OnKey: 271 pressed, action is 4 19:20:39 M: 23080960 DEBUG: - move down 19:20:39 M: 23105536 DEBUG: DVDNAV_HIGHLIGHT: Highlight button 3 19:20:41 M: 22896640 DEBUG: - DVDNAV_WAIT (HOLDING) 19:20:41 M: 23257088 DEBUG: DVDNAV_CELL_CHANGE 19:20:41 M: 23277568 DEBUG: DVDNAV_SPU_CLUT_CHANGE 19:20:41 M: 23277568 DEBUG: DVDNAV_SPU_STREAM_CHANGE 19:20:41 M: 23277568 DEBUG: DVDNAV_AUDIO_STREAM_CHANGE 19:20:41 M: 23302144 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:29155666 TO:9795666 DIFF:-19360000 19:20:41 M: 23212032 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 19:20:43 M: 23019520 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC 19:20:43 M: 23056384 DEBUG: CDVDPlayerAudio:: Resync - clock:69135999, delay:57833 19:20:45 M: 23076864 DEBUG: CApplication::OnKey: 256 pressed, action is 7 19:20:45 M: 23076864 DEBUG: - button select 19:20:45 M: 23089152 DEBUG: DVDNAV_HOP_CHANNEL 19:20:45 M: 24727552 DEBUG: CDVDPlayerAudio:: Detected Audio Discontinuity, syncing clock. diff was: -28, 72126, av: 36049 19:20:45 M: 24862720 ERROR: Error reading data from demuxer 19:20:45 M: 24862720 DEBUG: DVDNAV_HIGHLIGHT: Highlight button 1 19:20:45 M: 24862720 DEBUG: DVDNAV_CELL_CHANGE (HOLDING) 19:20:45 M: 24862720 DEBUG: DVDNAV_CELL_CHANGE 19:20:45 M: 24862720 DEBUG: DVDNAV_SPU_CLUT_CHANGE 19:20:45 M: 24862720 DEBUG: DVDNAV_SPU_STREAM_CHANGE 19:20:45 M: 24862720 DEBUG: DVDNAV_AUDIO_STREAM_CHANGE 19:20:45 M: 24875008 DEBUG: CDVDVideoCodecLibMpeg2::Reset() 19:20:45 M: 24895488 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:13555666 TO:195666 DIFF:-13360000 19:20:45 M: 24592384 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC 19:20:45 M: 24739840 DEBUG: CDVDAudioCodecPassthrough: Synced to AC3 frame 19:20:45 M: 24739840 DEBUG: CDVDPlayerAudio:: Resync - clock:73151999, delay:59666 19:20:47 M: 23224320 DEBUG: DVDNAV_CELL_CHANGE (HOLDING) 19:20:47 M: 23400448 DEBUG: DVDNAV_CELL_CHANGE 19:20:47 M: 23400448 DEBUG: DVDNAV_SPU_CLUT_CHANGE 19:20:47 M: 23400448 DEBUG: DVDNAV_SPU_STREAM_CHANGE 19:20:47 M: 23400448 DEBUG: DVDNAV_AUDIO_STREAM_CHANGE 19:20:47 M: 23359488 NOTICE: Opening Subtitle stream: 2