1080i Smooth playback, 720p jerky WAAhTT?

If you need any help with XBMC4XBOX, such as installing or using the software, please post it in here.
Post Reply
bluskyonmars
Posts: 7
Joined: Sun Aug 05, 2012 8:46 am

1080i Smooth playback, 720p jerky WAAhTT?

Post by bluskyonmars »

I have an anomaly..

If video playback resolution is changed to '720p 16:9' opposed to 'Best available' (settings:video) I get huge judder/jerky/page tearing? of video playback and it's not smooth at all.

Selecting 1080i 16:9 in video playback res will often instant crash system due to low memory!
Strange when 'Best available' is chosen video playback is smooth and pressing Y on controller shows 1920x1080 1080i 16:9 - how strange!

My default resolution is 720p, all video sources are Xvid,264 *dvd resolutions*

Jon.
User avatar
Dan Dar3
Posts: 1176
Joined: Sun Jul 08, 2012 4:09 pm
Has thanked: 273 times
Been thanked: 257 times
Contact:

Re: 1080i Smooth playback, 720p jerky WAAhTT?

Post by Dan Dar3 »

DVD resolution, but what bitrate? Playing over the network, wireless or wired?

Either way, provide a debug log so we can see what's going on with the playback in 720 mode. 1080 is supposed to crash, the box has only 64 MB of RAM.
http://www.xbmc4xbox.org.uk/forum/viewt ... p?f=6&t=65
bluskyonmars
Posts: 7
Joined: Sun Aug 05, 2012 8:46 am

Re: 1080i Smooth playback, 720p jerky WAAhTT?

Post by bluskyonmars »

Hi Dar Dar,

All videos are transcoded from my Pal DVD's and into single File XVID .AVI files per movie. Audio is original AC3 stream ~448kb/s CBR and videos are VBR I believe. Each movie(s) is capped at ~2GB maximum file size. Average resolution is cropped 720x304. 25fps.

Playing over wired network from a central file server.

1080i runs okayish as long as library mode is not used as that eats up ram, unstable and will crash but using files mode is ok.

Perhaps I could do a video of playback comparison.... Can I upload video attachments into forum posts? ...
..Basically I am stuck and *have* to watch everying in 1080i because 720p is jerky.

I will post a debug log for you.

Jon.
bluskyonmars
Posts: 7
Joined: Sun Aug 05, 2012 8:46 am

Re: 1080i Smooth playback, 720p jerky WAAhTT?

Post by bluskyonmars »

DEBUG LOG:
1080i ~ Good, Smooth playback :
19:42:07 M: 55324672 NOTICE: -----------------------------------------------------------------------
19:42:07 M: 55304192 NOTICE: Starting XBMC4Xbox. Built on Aug 5 2012 (SVN:31316, compiler 1310)
19:42:07 M: 55304192 NOTICE: special://xbmc/ is mapped to: Q:\
19:42:07 M: 55304192 NOTICE: special://masterprofile/ is mapped to: q:\UserData
19:42:07 M: 55304192 NOTICE: special://home/ is mapped to: Q:\
19:42:07 M: 55304192 NOTICE: special://temp/ is mapped to: Z:\
19:42:07 M: 55304192 NOTICE: The executable running is: F:\Apps\XBMC\default.xbe
19:42:07 M: 55304192 NOTICE: Log File is located: Q:\xbmc.log
19:42:07 M: 55304192 NOTICE: -----------------------------------------------------------------------
19:42:07 M: 55304192 NOTICE: Unmapped drive T
19:42:07 M: 55304192 NOTICE: Mapping drive T to Harddisk0\Partition6\Apps\XBMC\UserData
19:42:07 M: 55304192 NOTICE: Setup DirectX
19:42:07 M: 55279616 NOTICE: Mapping drive C to Harddisk0\Partition2
19:42:07 M: 55279616 NOTICE: Mapping drive E to Harddisk0\Partition1
19:42:07 M: 55279616 NOTICE: Unmapped drive D
19:42:07 M: 55279616 NOTICE: Mapping drive D to Cdrom0
19:42:07 M: 55279616 NOTICE: map extended drive F:
19:42:07 M: 55279616 NOTICE: Mapping drive F to Harddisk0\Partition6
19:42:08 M: 55275520 NOTICE: map extended drive G:
19:42:08 M: 55275520 NOTICE: Mapping drive G to Harddisk0\Partition7
19:42:08 M: 55275520 NOTICE: Mapping drive X to Harddisk0\Partition3
19:42:08 M: 55275520 NOTICE: Mapping drive Y to Harddisk0\Partition4
19:42:08 M: 55275520 NOTICE: Unmapped drive Z
19:42:08 M: 55275520 NOTICE: Mapping drive Z to Harddisk0\Partition5
19:42:08 M: 55275520 NOTICE: load settings...
19:42:08 M: 55275520 NOTICE: Mapping drive P to Harddisk0\Partition6\Apps\XBMC\UserData
19:42:08 M: 55275520 NOTICE: special://profile/ is mapped to: q:\UserData
19:42:08 M: 55275520 NOTICE: loading special://masterprofile/guisettings.xml
19:42:08 M: 55062528 NOTICE: Getting hardware information now...
19:42:08 M: 55062528 NOTICE: Checking resolution 1
19:42:08 M: 55062528 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
19:42:08 M: 55042048 NOTICE: Loaded playercorefactory configuration
19:42:08 M: 55042048 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
19:42:08 M: 55042048 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
19:42:08 M: 55042048 NOTICE: Loaded advancedsettings.xml from special://profile/advancedsettings.xml
19:42:08 M: 55042048 NOTICE: Contents of special://profile/advancedsettings.xml are...
<advancedsettings>
<network>
<cachemembuffersize>0</cachemembuffersize>
</network>
<video>
<adjustrefreshrate>
<override>
<fps>25</fps>
<refresh>60.0</refresh>
</override>
<override>
<fps>50</fps>
<refresh>60.0</refresh>
</override>
</adjustrefreshrate>
</video>
</advancedsettings>
19:42:08 M: 55042048 NOTICE: Getting hardware information now...
19:42:08 M: 55042048 NOTICE: Checking resolution 1
19:42:08 M: 55042048 NOTICE: Default Video Player: dvdplayer
19:42:08 M: 55042048 NOTICE: Default Audio Player: paplayer
19:42:08 M: 55042048 NOTICE: Enabled debug logging due to GUI setting
19:42:08 M: 55042048 NOTICE: Per AV pack settings are off
19:42:08 M: 55230464 NOTICE: q:\UserData\sources.xml
19:42:08 M: 55230464 INFO: Checking skinpath existence, and existence of keymap.xml:Q:\skin...
19:42:08 M: 55230464 DEBUG: We set resolution 1
19:42:08 M: 46723072 INFO: load language info file:special://xbmc/language/English/langinfo.xml
19:42:08 M: 46723072 INFO: load keyboard layout configuration info file: Q:\language\english\keyboardmap.xml
19:42:08 M: 46723072 INFO: unable to load Q:\language\english\keyboardmap.xml: Failed to open file at line 0
19:42:08 M: 46198784 INFO: load language file:special://xbmc/language/English/strings.xml
19:42:08 M: 42635264 INFO: load keymapping
19:42:08 M: 42676224 INFO: Loading special://xbmc/system/Keymap.xml
19:42:08 M: 45813760 DEBUG: CButtonTranslator::Load - no userdata Keymap.xml found, skipping
19:42:08 M: 45826048 INFO: Checking skin version of: SLik
19:42:08 M: 45826048 INFO: Skin version is: 2.11 (2.110000)
19:42:08 M: 45826048 INFO: GUI format 1280x720 720p 16:9
19:42:08 M: 45826048 INFO: install unhandled exception filter
19:42:08 M: 45826048 INFO: creating subdirectories
19:42:08 M: 45826048 INFO: userdata folder: q:\UserData
19:42:08 M: 45826048 INFO: recording folder:
19:42:08 M: 45826048 INFO: screenshots folder:
19:42:08 M: 45826048 INFO: thumbnails folder: q:\UserData\Thumbnails
19:42:08 M: 45826048 DEBUG: CNetwork::SetupNetwork - Setting up network...
19:42:08 M: 45826048 NOTICE: Network: Using static IP settings
19:42:08 M: 45498368 NOTICE: Checking the Date!
19:42:08 M: 45498368 INFO: Current Date is: 30-9-2012
19:42:08 M: 45498368 NOTICE: start dvd mediatype detection
19:42:08 M: 45428736 NOTICE: initializing playlistplayer
19:42:08 M: 45428736 DEBUG: Compiled with libcdio Version 0.72
19:42:08 M: 45428736 NOTICE: DONE initializing playlistplayer
19:42:08 M: 45428736 INFO: lcd not used
19:42:08 M: 45428736 NOTICE: start fancontroller
19:42:08 M: 44896256 NOTICE: load default skin:[SLik]
19:42:08 M: 44896256 INFO: load skin from:Q:\skin\SLik
19:42:08 M: 44896256 INFO: delete old skin...
19:42:08 M: 44896256 DEBUG: ------ Window Deinit (Pointer.xml) ------
19:42:08 M: 44896256 DEBUG: ------ Window Deinit (DialogMuteBug.xml) ------
19:42:08 M: 44896256 INFO: Default 4:3 resolution directory is Q:\skin\SLik\720p
19:42:08 M: 44896256 INFO: Default 16:9 resolution directory is Q:\skin\SLik\720p
19:42:08 M: 44896256 INFO: Skin version is: 2.11
19:42:08 M: 44896256 INFO: Loading skin includes from Q:\skin\SLik\720p\includes.xml
19:42:09 M: 44539904 INFO: load fonts for skin...
19:42:09 M: 44535808 INFO: Loading fonts from Q:\skin\SLik\720p\Font.xml
19:42:09 M: 42946560 INFO: load new skin...
19:42:09 M: 42946560 INFO: Skin version is: 2.11 (2.110000)
19:42:09 M: 42946560 INFO: Loading skin file: Home.xml
19:42:09 M: 41791488 INFO: Loading user windows, path Q:\skin\SLik\720p
19:42:09 M: 41791488 INFO: Loading skin file: Q:\skin\SLik\720p\custom_SkinResetDialog_1112.xml
19:42:09 M: 41791488 INFO: Loading skin file: Q:\skin\SLik\720p\custom_SkinSetting_1111.xml
19:42:09 M: 41783296 INFO: Loading skin file: Q:\skin\SLik\720p\custom_UpdateDialog_1113.xml
19:42:09 M: 41787392 INFO: Loading user windows, path Q:\skin\SLik\720p
19:42:09 M: 41787392 INFO: Loading skin file: Q:\skin\SLik\720p\custom_SkinResetDialog_1112.xml
19:42:09 M: 41787392 INFO: Loading skin file: Q:\skin\SLik\720p\custom_SkinSetting_1111.xml
19:42:09 M: 41783296 INFO: Loading skin file: Q:\skin\SLik\720p\custom_UpdateDialog_1113.xml
19:42:09 M: 41787392 INFO: Loading user windows, path Q:\skin\SLik\720p
19:42:09 M: 41787392 INFO: Loading skin file: Q:\skin\SLik\720p\custom_SkinResetDialog_1112.xml
19:42:09 M: 41787392 INFO: Loading skin file: Q:\skin\SLik\720p\custom_SkinSetting_1111.xml
19:42:09 M: 41783296 INFO: Loading skin file: Q:\skin\SLik\720p\custom_UpdateDialog_1113.xml
19:42:09 M: 41787392 DEBUG: Load Skin XML: 678.80ms
19:42:09 M: 41787392 INFO: initialize new skin...
19:42:09 M: 41787392 INFO: Loading skin file: Pointer.xml
19:42:09 M: 41742336 INFO: Loading skin file: DialogVolumeBar.xml
19:42:09 M: 41734144 INFO: Loading skin file: DialogSeekBar.xml
19:42:09 M: 41717760 INFO: Loading skin file: DialogKaiToast.xml
19:42:09 M: 41717760 INFO: Loading skin file: DialogMuteBug.xml
19:42:09 M: 41320448 INFO: Loading special://xbmc/sounds/Bursting Bubbles/sounds.xml
19:42:10 M: 41320448 INFO: skin loaded...
19:42:10 M: 40435712 DEBUG: kernel32.dll fake function DisableThreadLibraryCalls called
19:42:10 M: 40435712 INFO: Loading skin file: DialogBusy.xml
19:42:10 M: 40960000 DEBUG: Activating window ID: 12999
19:42:10 M: 40960000 DEBUG: Checking if window ID 12999 is locked.
19:42:10 M: 40960000 DEBUG: ------ Window Init (Startup.xml) ------
19:42:10 M: 40960000 INFO: Loading skin file: Startup.xml
19:42:10 M: 40935424 INFO: removing tempfiles
19:42:10 M: 40935424 NOTICE: initialize done
19:42:10 M: 40935424 NOTICE: Running the application...
19:42:10 M: 40935424 DEBUG: CApplication::OnMessage : Translating Skin.SetBool(Startup)
19:42:10 M: 40935424 DEBUG: CApplication::OnMessage : To Skin.SetBool(Startup)
19:42:11 M: 40935424 DEBUG: CApplication::OnMessage : Translating ReplaceWindow(Home)
19:42:11 M: 40935424 DEBUG: CApplication::OnMessage : To ReplaceWindow(Home)
19:42:11 M: 40935424 DEBUG: Activating window ID: 10000
19:42:11 M: 40935424 DEBUG: Checking if window ID 10000 is locked.
19:42:11 M: 40935424 DEBUG: ------ Window Deinit (Startup.xml) ------
19:42:11 M: 40960000 DEBUG: ------ Window Init (Home.xml) ------
19:42:11 M: 40341504 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
19:42:11 M: 40341504 DEBUG: XBPython::Process - no system autoexec.py (Q:\scripts\autoexec.py) found, skipping
19:42:11 M: 40341504 DEBUG: CNetwork::NetworkUp - Network service is up
19:42:11 M: 40329216 INFO: CNetwork::LogState - Link: full duplex
19:42:11 M: 40329216 INFO: CNetwork::LogState - Link: 100 mbps
19:42:11 M: 40329216 INFO: CNetwork::LogState - State: dns
19:42:11 M: 40329216 INFO: CNetwork::LogState - State: static
19:42:11 M: 40329216 INFO: CNetwork::LogState - ip: 192.168.10.42
19:42:11 M: 40329216 INFO: CNetwork::LogState - subnet: 255.255.255.0
19:42:11 M: 40329216 INFO: CNetwork::LogState - gateway: 192.168.10.1
19:42:11 M: 40329216 INFO: CNetwork::LogState - dns: 192.168.10.1, 0.0.0.0
19:42:11 M: 39153664 WARNING: old getenv from python.lib called, library check needed
19:42:11 M: 39157760 DEBUG: JpegIO::Read - Using scale_num of 9, 1152 x 576
19:42:11 M: 36503552 WARNING: old getenv from python.lib called, library check needed
Previous line repeats 3 times.
19:42:11 M: 35344384 WARNING: CApplication::OnMessage : Translating SetFocus(8999)
19:42:11 M: 35344384 DEBUG: CApplication::OnMessage : To SetFocus(8999)
19:42:11 M: 35344384 DEBUG: CNetwork::NetworkMessage - Starting network services
19:42:11 M: 35344384 NOTICE: Webserver: Starting...
19:42:11 M: 35536896 DEBUG: SECTION:Section LIBHTTP loaded count:1 size:80300
19:42:11 M: 35536896 DEBUG: xbmcHttpShim starts
19:42:11 M: 35405824 INFO: WebServer: Server starting using Q:\web on 192.168.10.42:8080
19:42:11 M: 35405824 DEBUG: DB: Registering database table <users>
19:42:11 M: 35405824 DEBUG: DB: Registering database table <groups>
19:42:11 M: 35405824 DEBUG: DB: Registering database table <access>
19:42:11 M: 35405824 DEBUG: UM: Loading User Configuration from file <umconfig.txt>
19:42:11 M: 35405824 DEBUG: DB: About to read data file <Q:\web\umconfig.txt>
19:42:11 M: 35405824 DEBUG: GetRecentlyAddedAlbumSongs() query: SELECT songview.* FROM (SELECT idAlbum FROM albumview ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum=recentalbums.idAlbum
19:42:11 M: 35405824 DEBUG: DB: Setting rows to <1> in table <users>
19:42:11 M: 35405824 DEBUG: DB: Adding a row to table <users>
19:42:11 M: 35405824 DEBUG: DB: Setting rows to <1> in table <groups>
19:42:11 M: 35405824 DEBUG: DB: Adding a row to table <groups>
19:42:11 M: 35405824 DEBUG: DB: Setting rows to <1> in table <access>
19:42:11 M: 35405824 DEBUG: DB: Adding a row to table <access>
19:42:11 M: 35405824 DEBUG: webs: Listening for HTTP requests at address 192.168.10.42:8080
19:42:11 M: 35405824 NOTICE: Webserver: Started
19:42:11 M: 35405824 DEBUG: UM: Adding group <sys_xbox>
19:42:11 M: 35405824 DEBUG: DB: Adding a row to table <groups>
19:42:11 M: 35405824 DEBUG: UM: Adding User <0>
19:42:11 M: 35405824 DEBUG: DB: Adding a row to table <users>
19:42:11 M: 35405824 DEBUG: UM: Adding Access Limit for </>
19:42:11 M: 35405824 DEBUG: DB: Adding a row to table <access>
19:42:11 M: 35401728 DEBUG: UM: Writing User Configuration to file <umconfig.txt>
19:42:11 M: 35401728 DEBUG: DB: About to save database to file
19:42:11 M: 35405824 DEBUG: CMusicDatabase::GetRecentlyAddedAlbums query: select * from albumview order by idAlbum desc limit 10
19:42:11 M: 35401728 NOTICE: XBFileZilla: Starting...
19:42:11 M: 35336192 NOTICE: ES: Starting event server
19:42:11 M: 34799616 DEBUG: CLastfmScrobbler: Clearing session.
19:42:11 M: 34799616 NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777
19:42:11 M: 34787328 DEBUG: CLibrefmScrobbler: Clearing session.
19:42:11 M: 34787328 NOTICE: UDP: Listening on port 9777
19:42:11 M: 34193408 INFO: WEATHER: Downloading weather
19:42:11 M: 34193408 DEBUG: FileCurl::Open(D04848DC) http://feeds.feedburner.com/xbmc4xbox
19:42:11 M: 34156544 DEBUG: FileCurl::Open(D04739E0) http://xml.weather.com/weather/local/US ... 145f208494
19:42:11 M: 34156544 INFO: XCURL::DllLibCurlGlobal::easy_aquire - Created session to http://feeds.feedburner.com
19:42:11 M: 33931264 DEBUG: CApplication::OnMessage : Translating SetFocus(9000)
19:42:11 M: 33927168 INFO: XCURL::DllLibCurlGlobal::easy_aquire - Created session to http://xml.weather.com
19:42:11 M: 33726464 DEBUG: CApplication::OnMessage : To SetFocus(9000)
19:42:11 M: 33742848 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
19:42:11 M: 33062912 NOTICE: XBFileZilla: Started
19:42:11 M: 32702464 DEBUG: CVideoDatabase::GetMoviesByWhere query: select * from movieview order by idMovie desc limit 10
19:42:12 M: 32702464 DEBUG: Time for actual SQL query = 133
19:42:12 M: 32702464 INFO: WEATHER: Weather download successful
19:42:12 M: 28061696 DEBUG: Time to retrieve movies from dataset = 210
19:42:12 M: 28061696 DEBUG: CVideoDatabase::GetEpisodesByWhere query: select * from episodeview order by idEpisode desc limit 10
19:42:12 M: 27852800 DEBUG: Got rss feed: http://feeds.feedburner.com/xbmc4xbox
19:42:12 M: 27865088 DEBUG: RSS feed encoding: UTF-8
19:42:12 M: 27844608 DEBUG: Parsed rss feed: http://feeds.feedburner.com/xbmc4xbox
19:42:13 M: 32043008 DEBUG: CApplication::OnKey: 273 pressed, action is 2
19:42:13 M: 32894976 WARNING: old getenv from python.lib called, library check needed
19:42:13 M: 32870400 DEBUG: JpegIO::Read - Using scale_num of 9, 1152 x 576
19:42:14 M: 30433280 DEBUG: Time for actual SQL query = 1739
19:42:14 M: 30457856 DEBUG: CApplication::OnKey: 272 pressed, action is 1
19:42:14 M: 30437376 DEBUG: Time to retrieve episodes from dataset = 254
19:42:14 M: 30461952 DEBUG: CApplication::OnKey: 273 pressed, action is 2
Previous line repeats 1 times.
19:42:15 M: 29986816 DEBUG: old getenv from python.lib called, library check needed
19:42:15 M: 29974528 DEBUG: JpegIO::Read - Using scale_num of 9, 1152 x 576
19:42:15 M: 27815936 DEBUG: CVideoDatabase::GetMusicVideosByWhere query = select * from musicvideoview order by idMVideo desc limit 10
19:42:15 M: 27815936 DEBUG: CVideoDatabase::GetMusicVideosByWhere time for actual SQL query = 17
19:42:22 M: 33669120 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
19:42:22 M: 33648640 INFO: XCURL::DllLibCurlGlobal::CheckIdle - Closing session to http://feeds.feedburner.com (easy=00C0F010, multi=00BF6670)
19:42:23 M: 33779712 INFO: XCURL::DllLibCurlGlobal::CheckIdle - Closing session to http://xml.weather.com (easy=00C6B010, multi=00C738F0)
19:42:25 M: 33914880 DEBUG: CApplication::OnKey: 272 pressed, action is 1
19:42:25 M: 33193984 WARNING: old getenv from python.lib called, library check needed
19:42:25 M: 33169408 DEBUG: JpegIO::Read - Using scale_num of 9, 1152 x 576
19:42:25 M: 30736384 DEBUG: CApplication::OnKey: 272 pressed, action is 1
19:42:25 M: 30482432 WARNING: old getenv from python.lib called, library check needed
19:42:25 M: 30466048 DEBUG: JpegIO::Read - Using scale_num of 9, 1152 x 576
19:42:26 M: 28082176 DEBUG: CApplication::OnKey: 271 pressed, action is 4
Previous line repeats 1 times.
19:42:26 M: 28082176 DEBUG: CApplication::OnKey: 272 pressed, action is 1
19:42:27 M: 28082176 DEBUG: CApplication::OnKey: 271 pressed, action is 4
Previous line repeats 2 times.
19:42:28 M: 28082176 DEBUG: CApplication::OnKey: 256 pressed, action is 7
19:42:28 M: 28049408 DEBUG: CApplication::OnMessage : Translating ActivateWindow(VideoFiles,Root)
19:42:28 M: 28049408 DEBUG: CApplication::OnMessage : To ActivateWindow(VideoFiles,Root)
19:42:28 M: 28049408 DEBUG: Activating window ID: 10024
19:42:28 M: 28049408 DEBUG: Checking if window ID 10024 is locked.
19:42:28 M: 28049408 DEBUG: ------ Window Deinit (Home.xml) ------
19:42:28 M: 30109696 INFO: Attempting to quickpath to: Root
19:42:28 M: 30109696 WARNING: CUtil::GetMatchingSource... no matching source found for [Root]
19:42:28 M: 30109696 ERROR: Failed! Destination parameter (Root) does not match a valid source!
19:42:28 M: 30097408 DEBUG: ------ Window Init (MyVideo.xml) ------
19:42:28 M: 30101504 INFO: Loading skin file: MyVideo.xml
19:42:29 M: 28717056 DEBUG: CGUIMediaWindow::GetDirectory ()
19:42:29 M: 28737536 DEBUG: ParentPath = []
19:42:29 M: 28737536 DEBUG: CFileItemList::Sort, sorting took 0 millis
19:42:30 M: 33099776 DEBUG: CApplication::OnKey: 271 pressed, action is 4
19:42:30 M: 33099776 DEBUG: CApplication::OnKey: 256 pressed, action is 7
19:42:30 M: 33988608 DEBUG: CGUIMediaWindow::GetDirectory (smb://LINKSTATION/readonly/Movies/)
19:42:30 M: 33988608 DEBUG: ParentPath = []
19:42:30 M: 33648640 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies
19:42:31 M: 33890304 DEBUG: Saving fileitems [smb://LINKSTATION/readonly/Movies/]
19:42:31 M: 33890304 DEBUG: -- items: 41, sort method: 0, ascending: false
19:42:32 M: 33894400 DEBUG: CFileItemList::Sort, sorting took 1 millis
19:42:32 M: 33026048 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/007%2dThe%20man%20with%20the%20Golden%20Gun
19:42:32 M: 35364864 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/1408
19:42:32 M: 35364864 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Aeon%20Flux
19:42:32 M: 35364864 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Avatar
19:42:32 M: 35368960 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Back%20to%20the%20future%20%2d%20Part%20I
19:42:32 M: 35373056 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Back%20to%20the%20future%20%2d%20Part%20II
19:42:32 M: 35373056 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Back%20to%20the%20future%20%2d%20Part%20III
19:42:32 M: 35373056 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/The%20Book%20Of%20Eli
19:42:32 M: 35373056 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Demolition%20Man
19:42:32 M: 35373056 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Die%20Hard
19:42:32 M: 35373056 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Die%20Hard%202
19:42:32 M: 35373056 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Eraser
19:42:33 M: 35373056 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Event%20Horizon
19:42:33 M: 35373056 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/The%20Fifth%20Element
19:42:33 M: 35373056 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Fight%20Club
19:42:33 M: 35373056 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Futurama%20%2d%20Benders%20Big%20Score
19:42:33 M: 35373056 DEBUG: CApplication::OnKey: 271 pressed, action is 4
19:42:33 M: 35373056 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Futurama%20%2d%20The%20Beast%20with%20a%20Billion%20Backs
19:42:33 M: 35340288 DEBUG: CApplication::OnKey: 279 pressed, action is 112
19:42:33 M: 35340288 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Goldeneye
19:42:33 M: 35340288 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 2 times.
19:42:33 M: 35340288 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Identity
19:42:33 M: 35340288 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 3 times.
19:42:33 M: 35373056 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/K%2dPAX
19:42:33 M: 35373056 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 4 times.
19:42:33 M: 35373056 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Lost%20in%20Space
19:42:33 M: 35639296 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 4 times.
19:42:33 M: 35639296 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/The%20Matrix
19:42:33 M: 35639296 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 3 times.
19:42:33 M: 35639296 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/The%20Matrix%20Reloaded
19:42:33 M: 35639296 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 2 times.
19:42:33 M: 35639296 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Minority%20Report
19:42:33 M: 35639296 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 3 times.
19:42:33 M: 35639296 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Passenger%2057
19:42:33 M: 35639296 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 3 times.
19:42:33 M: 35639296 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Planet%20Of%20The%20Apes%2020012
19:42:33 M: 35639296 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 2 times.
19:42:33 M: 35639296 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/The%20Ring
19:42:34 M: 35639296 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Shallow%20Hal
19:42:34 M: 35639296 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Shaun%20Of%20The%20Dead
19:42:34 M: 35639296 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Shawshank%20Redemption
19:42:34 M: 35639296 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Star%20Trek%20%2d%20First%20Contact
19:42:34 M: 35639296 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Star%20Trek%20%2d%20Generations
19:42:34 M: 35639296 DEBUG: CApplication::OnKey: 270 pressed, action is 3
Previous line repeats 1 times.
19:42:35 M: 35639296 DEBUG: CApplication::OnKey: 271 pressed, action is 4
19:42:35 M: 35606528 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Star%20Trek%20%2d%20Nemesis
19:42:35 M: 35606528 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Star%20Wars%20%2d%20The%20Phantom%20Menace
19:42:35 M: 35606528 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Sunshine
19:42:35 M: 35639296 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Swordfish
19:42:35 M: 35639296 DEBUG: CApplication::OnKey: 271 pressed, action is 4
19:42:35 M: 35606528 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Terminator%20II%20Judgement%20Day
19:42:35 M: 35606528 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/The%20Time%20Machine%20%281960%29
19:42:35 M: 35606528 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Tropic%20Thunder
19:42:35 M: 35639296 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Vanilla%20Sky
19:42:35 M: 35639296 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/War%20Of%20The%20Worlds%20%282005%29
19:42:35 M: 36163584 DEBUG: CApplication::OnKey: 270 pressed, action is 3
19:42:36 M: 36163584 DEBUG: CApplication::OnKey: 273 pressed, action is 2
Previous line repeats 1 times.
19:42:36 M: 36163584 DEBUG: CApplication::OnKey: 256 pressed, action is 7
19:42:36 M: 36474880 DEBUG: CGUIMediaWindow::GetDirectory (smb://LINKSTATION/readonly/Movies/Star Trek - First Contact/)
19:42:36 M: 36474880 DEBUG: ParentPath = [smb://LINKSTATION/readonly/Movies/]
19:42:36 M: 36474880 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Star%20Trek%20%2d%20First%20Contact
19:42:36 M: 36474880 DEBUG: CFileItemList::Sort, sorting took 0 millis
19:42:37 M: 35082240 WARNING: old getenv from python.lib called, library check needed
19:42:37 M: 35065856 DEBUG: JpegIO::Read - Using scale_num of 5, 157 x 235
19:42:37 M: 35495936 DEBUG: CApplication::OnKey: 273 pressed, action is 2
19:42:39 M: 35495936 DEBUG: CApplication::OnKey: 256 pressed, action is 7
19:42:39 M: 35450880 INFO: Loading skin file: DialogContextMenu.xml
19:42:39 M: 35450880 DEBUG: ------ Window Init (DialogContextMenu.xml) ------
19:42:42 M: 34930688 DEBUG: CApplication::OnKey: 271 pressed, action is 4
19:42:43 M: 34930688 DEBUG: CApplication::OnKey: 256 pressed, action is 7
19:42:43 M: 34930688 DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------
19:42:43 M: 36003840 DEBUG: CPlayerCoreFactory::GetPlayers(smb://LINKSTATION/readonly/Movies/Star Trek - First Contact/Star Trek - First Contact.avi)
19:42:43 M: 36003840 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: un-named
19:42:43 M: 36003840 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: un-named
19:42:43 M: 36003840 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
19:42:43 M: 36003840 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp
19:42:43 M: 36003840 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
19:42:43 M: 36003840 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
19:42:43 M: 36003840 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
19:42:43 M: 36003840 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
19:42:43 M: 36003840 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
19:42:43 M: 36003840 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
19:42:43 M: 36003840 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: ogv/flv/mov
19:42:43 M: 36003840 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
19:42:43 M: 36003840 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
19:42:43 M: 36003840 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
19:42:43 M: 36003840 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
19:42:43 M: 36003840 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
19:42:43 M: 36003840 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
19:42:43 M: 36003840 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: MPlayer (2)
19:42:43 M: 36003840 DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players
19:42:43 M: 36003840 NOTICE: DVDPlayer: Opening: smb://LINKSTATION/readonly/Movies/Star Trek - First Contact/Star Trek - First Contact.avi
19:42:43 M: 36003840 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
19:42:43 M: 35479552 NOTICE: Creating InputStream
19:42:43 M: 35479552 DEBUG: CSmbFile::Open - opened readonly/Movies/Star Trek - First Contact/Star Trek - First Contact.avi, fd=10000
19:42:43 M: 35479552 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles
19:42:43 M: 35479552 DEBUG: CUtil::CacheSubtitles: START
19:42:43 M: 35479552 DEBUG: CUtil::CacheSubtitles: Checking for common subdirs...
19:42:43 M: 35479552 DEBUG: CUtil::CacheSubtitles: Done (time: 52 ms)
19:42:43 M: 35479552 DEBUG: CUtil::CacheSubtitles: Searching for subtitles...
19:42:43 M: 35479552 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Star%20Trek%20%2d%20First%20Contact
19:42:43 M: 35479552 DEBUG: CUtil::CacheSubtitles: Done (time: 19 ms)
19:42:43 M: 35479552 DEBUG: CUtil::CacheSubtitles: END (total time: 82 ms)
19:42:43 M: 35479552 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles done
19:42:43 M: 35479552 NOTICE: Creating Demuxer
19:42:43 M: 35172352 DEBUG: GetModuleHandleA('libgcc_s_dw2-1.dll') failed
Previous line repeats 2 times.
19:42:43 M: 29601792 DEBUG: CDVDDemuxFFmpeg::Open - probing detected format [avi]
19:42:45 M: 24055808 DEBUG: ------ Window Init (DialogBusy.xml) ------
19:42:45 M: 24055808 INFO: Loading skin file: DialogBusy.xml
19:42:47 M: 20766720 DEBUG: CDVDDemuxFFmpeg::Open - avformat_find_stream_info starting
19:42:47 M: 20742144 DEBUG: Unable to resolve: kernel32.dll InitializeConditionVariable
19:42:47 M: 20742144 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'InitializeConditionVariable') => 00000000
19:42:47 M: 20742144 DEBUG: Unable to resolve: kernel32.dll WakeAllConditionVariable
19:42:47 M: 20742144 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'WakeAllConditionVariable') => 00000000
19:42:47 M: 20742144 DEBUG: Unable to resolve: kernel32.dll WakeConditionVariable
19:42:47 M: 20742144 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'WakeConditionVariable') => 00000000
19:42:47 M: 20742144 DEBUG: Unable to resolve: kernel32.dll SleepConditionVariableCS
19:42:47 M: 20742144 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'SleepConditionVariableCS') => 00000000
19:42:47 M: 20619264 DEBUG: Unable to resolve: kernel32.dll InitializeConditionVariable
19:42:47 M: 20619264 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'InitializeConditionVariable') => 00000000
19:42:47 M: 20619264 DEBUG: Unable to resolve: kernel32.dll WakeAllConditionVariable
19:42:47 M: 20619264 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'WakeAllConditionVariable') => 00000000
19:42:47 M: 20619264 DEBUG: Unable to resolve: kernel32.dll WakeConditionVariable
19:42:47 M: 20619264 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'WakeConditionVariable') => 00000000
19:42:47 M: 20619264 DEBUG: Unable to resolve: kernel32.dll SleepConditionVariableCS
19:42:47 M: 20619264 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'SleepConditionVariableCS') => 00000000
19:42:47 M: 20799488 DEBUG: CDVDDemuxFFmpeg::Open - av_find_stream_info finished
19:42:47 M: 20799488 INFO: ffmpeg[188]: Input #0, avi, from 'smb://LINKSTATION/readonly/Movies/Star Trek - First Contact/Star Trek - First Contact.avi':
19:42:47 M: 20799488 INFO: ffmpeg[188]: Metadata:
19:42:47 M: 20799488 INFO: ffmpeg[188]: encoder : VirtualDubMod 1.5.4.1 (build 2178/release)
19:42:47 M: 20799488 INFO: ffmpeg[188]: IAS1 : English
19:42:47 M: 20799488 INFO: ffmpeg[188]: Duration: 01:46:09.00, start: 0.000000, bitrate: 2820 kb/s
19:42:47 M: 20799488 INFO: ffmpeg[188]: Stream #0:0: Video: mpeg4 (XVID / 0x44495658), yuv420p, 720x304 [SAR 1:1 DAR 45:19], 25 tbr, 25 tbn, 25 tbc
19:42:47 M: 20799488 INFO: ffmpeg[188]: Stream #0:1: Audio: ac3 ([0] [0][0] / 0x2000), 48000 Hz, 5.1(side), s16, 448 kb/s
19:42:47 M: 20799488 NOTICE: Opening video stream: 0 source: 256
19:42:47 M: 20799488 NOTICE: Creating video codec with codec id: 13
19:42:47 M: 20799488 DEBUG: FactoryCodec - Video: - Opening
19:42:47 M: 20787200 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: mpeg4
19:42:47 M: 20787200 DEBUG: Unable to resolve: kernel32.dll InitializeConditionVariable
19:42:47 M: 20787200 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'InitializeConditionVariable') => 00000000
19:42:47 M: 20787200 DEBUG: Unable to resolve: kernel32.dll WakeAllConditionVariable
19:42:47 M: 20787200 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'WakeAllConditionVariable') => 00000000
19:42:47 M: 20787200 DEBUG: Unable to resolve: kernel32.dll WakeConditionVariable
19:42:47 M: 20787200 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'WakeConditionVariable') => 00000000
19:42:47 M: 20787200 DEBUG: Unable to resolve: kernel32.dll SleepConditionVariableCS
19:42:47 M: 20787200 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'SleepConditionVariableCS') => 00000000
19:42:47 M: 20787200 DEBUG: FactoryCodec - Video: ff-mpeg4 - Opened
19:42:47 M: 20787200 NOTICE: Creating video thread
19:42:47 M: 20262912 NOTICE: Opening audio stream: 1 source: 256
19:42:47 M: 20262912 DEBUG: CXBoxRenderManager::PreInit - Selected RGB-Renderer
19:42:47 M: 20262912 NOTICE: Finding audio codec for: 86019
19:42:47 M: 20070400 DEBUG: FactoryCodec - Audio: FFmpeg - Opening
19:42:47 M: 19947520 DEBUG: Unable to resolve: kernel32.dll InitializeConditionVariable
19:42:47 M: 19947520 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'InitializeConditionVariable') => 00000000
19:42:47 M: 19947520 DEBUG: Unable to resolve: kernel32.dll WakeAllConditionVariable
19:42:47 M: 19947520 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'WakeAllConditionVariable') => 00000000
19:42:47 M: 19947520 DEBUG: Unable to resolve: kernel32.dll WakeConditionVariable
19:42:47 M: 19947520 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'WakeConditionVariable') => 00000000
19:42:47 M: 19947520 DEBUG: Unable to resolve: kernel32.dll SleepConditionVariableCS
19:42:47 M: 19947520 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'SleepConditionVariableCS') => 00000000
19:42:47 M: 19947520 DEBUG: FactoryCodec - Audio: FFmpeg - Opened
19:42:47 M: 19947520 NOTICE: Creating audio thread
19:42:47 M: 19423232 NOTICE: running thread: CDVDPlayerAudio::Process()
19:42:47 M: 19423232 DEBUG: CEdl::ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb://LINKSTATION/readonly/Movies/Star Trek - First Contact/Star Trek - First Contact.avi
19:42:47 M: 19410944 NOTICE: running thread: video_thread
19:42:47 M: 19410944 DEBUG: CDVDPlayer::SetCaching - caching state 2
19:42:47 M: 19410944 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
19:42:47 M: 19415040 NOTICE: Creating audio device with codec id: 86019, channels: 2, sample rate: 48000, no pass-through
19:42:47 M: 19361792 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:42:47 M: 19361792 DEBUG: Pause stream
19:42:47 M: 18505728 DEBUG: CDVDPlayer::HandleMessages - player started 1
19:42:47 M: 18382848 DEBUG: CApplication::OnPlayBackStarted - Playback has started
19:42:47 M: 18386944 DEBUG: GetModuleHandleA('libgcc_s_dw2-1.dll') failed
19:42:47 M: 18051072 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
19:42:47 M: 17125376 NOTICE: fps: 25.000000, pwidth: 720, pheight: 304, dwidth: 720, dheight: 304
19:42:47 M: 17125376 DEBUG: CDVDPlayerVideo::OutputPicture - change configuration. 720x304. framerate: 25.00
19:42:47 M: 17125376 NOTICE: Display resolution AUTO : 1080i 16:9 (0)
19:42:47 M: 19369984 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
19:42:48 M: 19369984 DEBUG: CGUIInfoManager::SetCurrentMovie(smb://LINKSTATION/readonly/Movies/Star Trek - First Contact/Star Trek - First Contact.avi)
19:42:48 M: 19542016 DEBUG: Created YV12 texture 0
19:42:48 M: 19189760 DEBUG: Created YV12 texture 1
19:42:48 M: 19189760 DEBUG: CDVDPlayer::HandleMessages - player started 2
19:42:48 M: 19189760 DEBUG: CVideoDatabase::GetMovieId (smb://LINKSTATION/readonly/Movies/Star Trek - First Contact/Star Trek - First Contact.avi), query = select idMovie from movie where idFile=220
19:42:48 M: 19189760 DEBUG: CDVDPlayer::SetCaching - caching state 3
19:42:48 M: 19189760 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(32000.000000, 1)
19:42:48 M: 19189760 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(40000.000000, 0)
19:42:48 M: 19189760 DEBUG: CDVDPlayerAudio:: Discontinuity - was:750.720000, should be:0.000000, error:-750.720000
19:42:48 M: 18956288 DEBUG: CDVDPlayer::SetCaching - caching state 0
19:42:48 M: 18800640 DEBUG: CVideoDatabase::GetMovieId (smb://LINKSTATION/readonly/Movies/Star Trek - First Contact/Star Trek - First Contact.avi), query = select idMovie from movie where idFile=220
19:42:48 M: 18665472 DEBUG: CGUIInfoManager::SetCurrentMovie, got movie info!
19:42:48 M: 18665472 DEBUG: Title = Star Trek: First Contact
19:42:48 M: 18673664 DEBUG: Activating window ID: 12005
19:42:48 M: 18640896 DEBUG: Checking if window ID 12005 is locked.
19:42:48 M: 18649088 DEBUG: ------ Window Deinit (MyVideo.xml) ------
19:42:48 M: 19394560 DEBUG: Setting resolution 0
19:42:48 M: 10883072 DEBUG: We set resolution 0
19:42:48 M: 8773632 DEBUG: Deleted YV12 texture 1
19:42:48 M: 8773632 INFO: Starting async renderer thread
19:42:48 M: 8773632 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
19:42:48 M: 8773632 INFO: Loading skin file: VideoFullScreen.xml
19:42:48 M: 8679424 INFO: Loading skin file: VideoOSD.xml
19:42:48 M: 7954432 INFO: Loading skin file: VideoOSDSettings.xml
Previous line repeats 1 times.
19:42:48 M: 7430144 INFO: Loading skin file: DialogFullScreenInfo.xml
19:42:57 M: 7221248 DEBUG: CApplication::OnKey: 257 pressed, action is 13
19:42:57 M: 7213056 NOTICE: CDVDPlayer::CloseFile()
19:42:57 M: 7213056 NOTICE: DVDPlayer: waiting for threads to exit
19:42:57 M: 7213056 NOTICE: CDVDPlayer::OnExit()
19:42:58 M: 7229440 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
19:42:58 M: 7245824 WARNING: CXBoxRenderer::GetImage - Timeout waiting for texture 0
19:42:58 M: 7245824 NOTICE: DVDPlayer: closing audio stream
19:42:58 M: 7258112 NOTICE: Closing audio stream
19:42:58 M: 7258112 NOTICE: Waiting for audio thread to exit
19:42:58 M: 7258112 NOTICE: thread end: CDVDPlayerAudio::OnExit()
19:42:58 M: 7802880 NOTICE: Closing audio device
19:42:58 M: 7802880 DEBUG: Stop stream
19:42:58 M: 7864320 NOTICE: Deleting audio codec
19:42:58 M: 8175616 NOTICE: DVDPlayer: closing video stream
19:42:58 M: 8175616 NOTICE: Closing video stream
19:42:58 M: 8175616 NOTICE: waiting for video thread to exit
19:42:58 M: 9408512 NOTICE: thread end: video_thread
19:42:58 M: 10780672 NOTICE: deleting video codec
19:42:58 M: 12017664 NOTICE: CDVDPlayer::OnExit() deleting demuxer
19:42:58 M: 17563648 NOTICE: CDVDPlayer::OnExit() deleting input stream
19:42:58 M: 17563648 DEBUG: CSmbFile::Close closing fd 10000
19:42:58 M: 17563648 DEBUG: CApplication::OnPlayBackStopped - Playback was stopped
19:42:58 M: 18087936 NOTICE: DVDPlayer: finished waiting
19:42:58 M: 18087936 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
19:42:58 M: 18087936 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
19:42:59 M: 19398656 DEBUG: Setting resolution 1
19:42:59 M: 27836416 INFO: Loading skin file: DialogBusy.xml
19:42:59 M: 27836416 DEBUG: We set resolution 1
19:42:59 M: 27414528 DEBUG: Created YV12 texture 1
19:42:59 M: 27484160 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
19:42:59 M: 27467776 DEBUG: ------ Window Init (MyVideo.xml) ------
19:42:59 M: 27467776 INFO: Loading skin file: MyVideo.xml
19:42:59 M: 26693632 DEBUG: CGUIMediaWindow::GetDirectory (smb://LINKSTATION/readonly/Movies/Star Trek - First Contact/)
19:42:59 M: 26693632 DEBUG: ParentPath = [smb://LINKSTATION/readonly/Movies/Star Trek - First Contact/]
19:42:59 M: 26693632 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Star%20Trek%20%2d%20First%20Contact
19:43:00 M: 26693632 DEBUG: CFileItemList::Sort, sorting took 0 millis
19:43:00 M: 25706496 NOTICE: CDVDPlayer::CloseFile()
19:43:00 M: 25706496 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
19:43:00 M: 25706496 NOTICE: DVDPlayer: waiting for threads to exit
19:43:00 M: 25706496 NOTICE: DVDPlayer: finished waiting
19:43:00 M: 25706496 NOTICE: CDVDPlayerVideo::~CDVDPlayerVideo - uninitting video device
19:43:00 M: 26755072 DEBUG: CXBoxRenderer::UnInit - Cleaning up resources
19:43:00 M: 27107328 DEBUG: Deleted YV12 texture 0
19:43:00 M: 27459584 DEBUG: Deleted YV12 texture 1
19:43:00 M: 27459584 DEBUG: CApplication::SaveFileState - Saving file state for video item smb://LINKSTATION/readonly/Movies/Star Trek - First Contact/Star Trek - First Contact.avi
19:43:08 M: 27992064 DEBUG: SECTION:UnloadDelayed(DLL: Q:\system\players\dvdplayer\avutil-51.dll)
19:43:09 M: 27992064 DEBUG: SECTION:UnloadDelayed(DLL: Q:\system\players\dvdplayer\avcodec-53.dll)
19:43:09 M: 27713536 DEBUG: SECTION:UnloadDelayed(DLL: Q:\system\players\dvdplayer\avformat-53.dll)
19:43:09 M: 27713536 DEBUG: GetModuleHandleA('libgcc_s_dw2-1.dll') failed
Previous line repeats 1 times.
19:43:10 M: 33214464 DEBUG: SECTION:UnloadDelayed(DLL: Q:\system\players\dvdplayer\postproc-52.dll)
19:43:10 M: 33214464 DEBUG: GetModuleHandleA('libgcc_s_dw2-1.dll') failed
bluskyonmars
Posts: 7
Joined: Sun Aug 05, 2012 8:46 am

Re: 1080i Smooth playback, 720p jerky WAAhTT?

Post by bluskyonmars »

Debug: 720P Playback (settings > Video > Playback > Resolution > 720p 16:9)
Jerky! Like playing a video on a 486..:
19:44:45 M: 55324672 NOTICE: -----------------------------------------------------------------------
19:44:45 M: 55304192 NOTICE: Starting XBMC4Xbox. Built on Aug 5 2012 (SVN:31316, compiler 1310)
19:44:45 M: 55304192 NOTICE: special://xbmc/ is mapped to: Q:\
19:44:45 M: 55304192 NOTICE: special://masterprofile/ is mapped to: q:\UserData
19:44:45 M: 55304192 NOTICE: special://home/ is mapped to: Q:\
19:44:45 M: 55304192 NOTICE: special://temp/ is mapped to: Z:\
19:44:45 M: 55304192 NOTICE: The executable running is: F:\Apps\XBMC\default.xbe
19:44:45 M: 55304192 NOTICE: Log File is located: Q:\xbmc.log
19:44:45 M: 55304192 NOTICE: -----------------------------------------------------------------------
19:44:45 M: 55304192 NOTICE: Unmapped drive T
19:44:45 M: 55304192 NOTICE: Mapping drive T to Harddisk0\Partition6\Apps\XBMC\UserData
19:44:45 M: 55304192 NOTICE: Setup DirectX
19:44:45 M: 55279616 NOTICE: Mapping drive C to Harddisk0\Partition2
19:44:45 M: 55279616 NOTICE: Mapping drive E to Harddisk0\Partition1
19:44:45 M: 55279616 NOTICE: Unmapped drive D
19:44:45 M: 55279616 NOTICE: Mapping drive D to Cdrom0
19:44:45 M: 55279616 NOTICE: map extended drive F:
19:44:45 M: 55279616 NOTICE: Mapping drive F to Harddisk0\Partition6
19:44:46 M: 55275520 NOTICE: map extended drive G:
19:44:46 M: 55275520 NOTICE: Mapping drive G to Harddisk0\Partition7
19:44:46 M: 55275520 NOTICE: Mapping drive X to Harddisk0\Partition3
19:44:46 M: 55275520 NOTICE: Mapping drive Y to Harddisk0\Partition4
19:44:46 M: 55275520 NOTICE: Unmapped drive Z
19:44:46 M: 55275520 NOTICE: Mapping drive Z to Harddisk0\Partition5
19:44:46 M: 55275520 NOTICE: load settings...
19:44:46 M: 55275520 NOTICE: Mapping drive P to Harddisk0\Partition6\Apps\XBMC\UserData
19:44:46 M: 55275520 NOTICE: special://profile/ is mapped to: q:\UserData
19:44:46 M: 55275520 NOTICE: loading special://masterprofile/guisettings.xml
19:44:46 M: 55062528 NOTICE: Getting hardware information now...
19:44:46 M: 55062528 NOTICE: Checking resolution 1
19:44:46 M: 55062528 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
19:44:46 M: 55042048 NOTICE: Loaded playercorefactory configuration
19:44:46 M: 55042048 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
19:44:46 M: 55042048 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
19:44:46 M: 55042048 NOTICE: Loaded advancedsettings.xml from special://profile/advancedsettings.xml
19:44:46 M: 55042048 NOTICE: Contents of special://profile/advancedsettings.xml are...
<advancedsettings>
<network>
<cachemembuffersize>0</cachemembuffersize>
</network>
<video>
<adjustrefreshrate>
<override>
<fps>25</fps>
<refresh>60.0</refresh>
</override>
<override>
<fps>50</fps>
<refresh>60.0</refresh>
</override>
</adjustrefreshrate>
</video>
</advancedsettings>
19:44:46 M: 55042048 NOTICE: Getting hardware information now...
19:44:46 M: 55042048 NOTICE: Checking resolution 1
19:44:46 M: 55042048 NOTICE: Default Video Player: dvdplayer
19:44:46 M: 55042048 NOTICE: Default Audio Player: paplayer
19:44:46 M: 55042048 NOTICE: Enabled debug logging due to GUI setting
19:44:46 M: 55042048 NOTICE: Per AV pack settings are off
19:44:46 M: 55230464 NOTICE: q:\UserData\sources.xml
19:44:46 M: 55230464 INFO: Checking skinpath existence, and existence of keymap.xml:Q:\skin...
19:44:46 M: 55230464 DEBUG: We set resolution 1
19:44:46 M: 46723072 INFO: load language info file:special://xbmc/language/English/langinfo.xml
19:44:46 M: 46723072 INFO: load keyboard layout configuration info file: Q:\language\english\keyboardmap.xml
19:44:46 M: 46723072 INFO: unable to load Q:\language\english\keyboardmap.xml: Failed to open file at line 0
19:44:46 M: 46198784 INFO: load language file:special://xbmc/language/English/strings.xml
19:44:46 M: 42635264 INFO: load keymapping
19:44:46 M: 42672128 INFO: Loading special://xbmc/system/Keymap.xml
19:44:46 M: 45817856 DEBUG: CButtonTranslator::Load - no userdata Keymap.xml found, skipping
19:44:46 M: 45817856 INFO: Checking skin version of: SLik
19:44:46 M: 45817856 INFO: Skin version is: 2.11 (2.110000)
19:44:46 M: 45817856 INFO: GUI format 1280x720 720p 16:9
19:44:46 M: 45817856 INFO: install unhandled exception filter
19:44:46 M: 45817856 INFO: creating subdirectories
19:44:46 M: 45817856 INFO: userdata folder: q:\UserData
19:44:46 M: 45817856 INFO: recording folder:
19:44:46 M: 45817856 INFO: screenshots folder:
19:44:46 M: 45817856 INFO: thumbnails folder: q:\UserData\Thumbnails
19:44:46 M: 45821952 DEBUG: CNetwork::SetupNetwork - Setting up network...
19:44:46 M: 45821952 NOTICE: Network: Using static IP settings
19:44:46 M: 45494272 NOTICE: Checking the Date!
19:44:46 M: 45494272 INFO: Current Date is: 30-9-2012
19:44:46 M: 45494272 NOTICE: start dvd mediatype detection
19:44:46 M: 45424640 NOTICE: initializing playlistplayer
19:44:46 M: 45424640 DEBUG: Compiled with libcdio Version 0.72
19:44:46 M: 45424640 NOTICE: DONE initializing playlistplayer
19:44:46 M: 45424640 INFO: lcd not used
19:44:46 M: 45424640 NOTICE: start fancontroller
19:44:46 M: 44892160 NOTICE: load default skin:[SLik]
19:44:46 M: 44892160 INFO: load skin from:Q:\skin\SLik
19:44:46 M: 44892160 INFO: delete old skin...
19:44:46 M: 44892160 DEBUG: ------ Window Deinit (Pointer.xml) ------
19:44:46 M: 44892160 DEBUG: ------ Window Deinit (DialogMuteBug.xml) ------
19:44:46 M: 44892160 INFO: Default 4:3 resolution directory is Q:\skin\SLik\720p
19:44:46 M: 44892160 INFO: Default 16:9 resolution directory is Q:\skin\SLik\720p
19:44:46 M: 44892160 INFO: Skin version is: 2.11
19:44:46 M: 44892160 INFO: Loading skin includes from Q:\skin\SLik\720p\includes.xml
19:44:47 M: 44548096 INFO: load fonts for skin...
19:44:47 M: 44544000 INFO: Loading fonts from Q:\skin\SLik\720p\Font.xml
19:44:47 M: 42946560 INFO: load new skin...
19:44:47 M: 42946560 INFO: Skin version is: 2.11 (2.110000)
19:44:47 M: 42946560 INFO: Loading skin file: Home.xml
19:44:47 M: 41775104 INFO: Loading user windows, path Q:\skin\SLik\720p
19:44:47 M: 41775104 INFO: Loading skin file: Q:\skin\SLik\720p\custom_SkinResetDialog_1112.xml
19:44:47 M: 41775104 INFO: Loading skin file: Q:\skin\SLik\720p\custom_SkinSetting_1111.xml
19:44:47 M: 41766912 INFO: Loading skin file: Q:\skin\SLik\720p\custom_UpdateDialog_1113.xml
19:44:47 M: 41775104 INFO: Loading user windows, path Q:\skin\SLik\720p
19:44:47 M: 41775104 INFO: Loading skin file: Q:\skin\SLik\720p\custom_SkinResetDialog_1112.xml
19:44:47 M: 41775104 INFO: Loading skin file: Q:\skin\SLik\720p\custom_SkinSetting_1111.xml
19:44:47 M: 41766912 INFO: Loading skin file: Q:\skin\SLik\720p\custom_UpdateDialog_1113.xml
19:44:47 M: 41775104 INFO: Loading user windows, path Q:\skin\SLik\720p
19:44:47 M: 41775104 INFO: Loading skin file: Q:\skin\SLik\720p\custom_SkinResetDialog_1112.xml
19:44:47 M: 41775104 INFO: Loading skin file: Q:\skin\SLik\720p\custom_SkinSetting_1111.xml
19:44:47 M: 41766912 INFO: Loading skin file: Q:\skin\SLik\720p\custom_UpdateDialog_1113.xml
19:44:47 M: 41775104 DEBUG: Load Skin XML: 658.43ms
19:44:47 M: 41775104 INFO: initialize new skin...
19:44:47 M: 41775104 INFO: Loading skin file: Pointer.xml
19:44:47 M: 41730048 INFO: Loading skin file: DialogVolumeBar.xml
19:44:47 M: 41721856 INFO: Loading skin file: DialogSeekBar.xml
19:44:47 M: 41705472 INFO: Loading skin file: DialogKaiToast.xml
19:44:47 M: 41705472 INFO: Loading skin file: DialogMuteBug.xml
19:44:47 M: 41308160 INFO: Loading special://xbmc/sounds/Bursting Bubbles/sounds.xml
19:44:47 M: 41308160 INFO: skin loaded...
19:44:48 M: 40423424 DEBUG: kernel32.dll fake function DisableThreadLibraryCalls called
19:44:48 M: 40423424 INFO: Loading skin file: DialogBusy.xml
19:44:48 M: 40947712 DEBUG: Activating window ID: 12999
19:44:48 M: 40947712 DEBUG: Checking if window ID 12999 is locked.
19:44:48 M: 40947712 DEBUG: ------ Window Init (Startup.xml) ------
19:44:48 M: 40947712 INFO: Loading skin file: Startup.xml
19:44:48 M: 40931328 INFO: removing tempfiles
19:44:48 M: 40931328 NOTICE: initialize done
19:44:48 M: 40931328 NOTICE: Running the application...
19:44:48 M: 40931328 DEBUG: CApplication::OnMessage : Translating Skin.SetBool(Startup)
19:44:48 M: 40931328 DEBUG: CApplication::OnMessage : To Skin.SetBool(Startup)
19:44:49 M: 40931328 DEBUG: CApplication::OnMessage : Translating ReplaceWindow(Home)
19:44:49 M: 40931328 DEBUG: CApplication::OnMessage : To ReplaceWindow(Home)
19:44:49 M: 40931328 DEBUG: Activating window ID: 10000
19:44:49 M: 40931328 DEBUG: Checking if window ID 10000 is locked.
19:44:49 M: 40931328 DEBUG: ------ Window Deinit (Startup.xml) ------
19:44:49 M: 40955904 DEBUG: ------ Window Init (Home.xml) ------
19:44:49 M: 40337408 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
19:44:49 M: 40337408 DEBUG: XBPython::Process - no system autoexec.py (Q:\scripts\autoexec.py) found, skipping
19:44:49 M: 40337408 DEBUG: CNetwork::NetworkUp - Network service is up
19:44:49 M: 40325120 INFO: CNetwork::LogState - Link: full duplex
19:44:49 M: 40325120 INFO: CNetwork::LogState - Link: 100 mbps
19:44:49 M: 40325120 INFO: CNetwork::LogState - State: dns
19:44:49 M: 40325120 INFO: CNetwork::LogState - State: static
19:44:49 M: 40325120 INFO: CNetwork::LogState - ip: 192.168.10.42
19:44:49 M: 40325120 INFO: CNetwork::LogState - subnet: 255.255.255.0
19:44:49 M: 40325120 INFO: CNetwork::LogState - gateway: 192.168.10.1
19:44:49 M: 40325120 INFO: CNetwork::LogState - dns: 192.168.10.1, 0.0.0.0
19:44:49 M: 39149568 WARNING: old getenv from python.lib called, library check needed
19:44:49 M: 39153664 DEBUG: JpegIO::Read - Using scale_num of 9, 1152 x 576
19:44:49 M: 36233216 WARNING: old getenv from python.lib called, library check needed
Previous line repeats 3 times.
19:44:49 M: 35344384 WARNING: CApplication::OnMessage : Translating SetFocus(8999)
19:44:49 M: 35344384 DEBUG: CApplication::OnMessage : To SetFocus(8999)
19:44:49 M: 35344384 DEBUG: CNetwork::NetworkMessage - Starting network services
19:44:49 M: 35344384 NOTICE: Webserver: Starting...
19:44:49 M: 35524608 DEBUG: SECTION:Section LIBHTTP loaded count:1 size:80300
19:44:49 M: 35524608 DEBUG: xbmcHttpShim starts
19:44:49 M: 35393536 INFO: WebServer: Server starting using Q:\web on 192.168.10.42:8080
19:44:49 M: 35393536 DEBUG: DB: Registering database table <users>
19:44:49 M: 35393536 DEBUG: DB: Registering database table <groups>
19:44:49 M: 35393536 DEBUG: DB: Registering database table <access>
19:44:49 M: 35393536 DEBUG: UM: Loading User Configuration from file <umconfig.txt>
19:44:49 M: 35393536 DEBUG: DB: About to read data file <Q:\web\umconfig.txt>
19:44:49 M: 35393536 DEBUG: GetRecentlyAddedAlbumSongs() query: SELECT songview.* FROM (SELECT idAlbum FROM albumview ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum=recentalbums.idAlbum
19:44:49 M: 35393536 DEBUG: DB: Setting rows to <1> in table <users>
19:44:49 M: 35393536 DEBUG: DB: Adding a row to table <users>
19:44:49 M: 35393536 DEBUG: DB: Setting rows to <1> in table <groups>
19:44:49 M: 35393536 DEBUG: DB: Adding a row to table <groups>
19:44:49 M: 35393536 DEBUG: DB: Setting rows to <1> in table <access>
19:44:49 M: 35393536 DEBUG: DB: Adding a row to table <access>
19:44:49 M: 35393536 DEBUG: webs: Listening for HTTP requests at address 192.168.10.42:8080
19:44:49 M: 35393536 NOTICE: Webserver: Started
19:44:49 M: 35393536 DEBUG: UM: Adding group <sys_xbox>
19:44:49 M: 35393536 DEBUG: DB: Adding a row to table <groups>
19:44:49 M: 35393536 DEBUG: UM: Adding User <0>
19:44:49 M: 35393536 DEBUG: DB: Adding a row to table <users>
19:44:49 M: 35389440 DEBUG: UM: Adding Access Limit for </>
19:44:49 M: 35389440 DEBUG: DB: Adding a row to table <access>
19:44:49 M: 35389440 DEBUG: UM: Writing User Configuration to file <umconfig.txt>
19:44:49 M: 35389440 DEBUG: DB: About to save database to file
19:44:49 M: 35393536 DEBUG: CMusicDatabase::GetRecentlyAddedAlbums query: select * from albumview order by idAlbum desc limit 10
19:44:49 M: 35393536 NOTICE: XBFileZilla: Starting...
19:44:49 M: 35323904 NOTICE: ES: Starting event server
19:44:49 M: 34787328 DEBUG: CLastfmScrobbler: Clearing session.
19:44:49 M: 34787328 NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777
19:44:49 M: 34770944 DEBUG: CLibrefmScrobbler: Clearing session.
19:44:49 M: 34775040 NOTICE: UDP: Listening on port 9777
19:44:49 M: 34181120 DEBUG: CApplication::OnMessage : Translating SetFocus(9000)
19:44:49 M: 34181120 INFO: WEATHER: Downloading weather
19:44:49 M: 34201600 DEBUG: FileCurl::Open(D04848DC) http://feeds.feedburner.com/xbmc4xbox
19:44:49 M: 34189312 DEBUG: CApplication::OnMessage : To SetFocus(9000)
19:44:49 M: 34152448 DEBUG: FileCurl::Open(D04739E0) http://xml.weather.com/weather/local/US ... 145f208494
19:44:49 M: 34140160 INFO: XCURL::DllLibCurlGlobal::easy_aquire - Created session to http://feeds.feedburner.com
19:44:49 M: 33894400 INFO: XCURL::DllLibCurlGlobal::easy_aquire - Created session to http://xml.weather.com
19:44:49 M: 33701888 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
19:44:49 M: 33071104 NOTICE: XBFileZilla: Started
19:44:49 M: 32661504 DEBUG: Got rss feed: http://feeds.feedburner.com/xbmc4xbox
19:44:49 M: 32661504 DEBUG: RSS feed encoding: UTF-8
19:44:49 M: 32657408 DEBUG: Parsed rss feed: http://feeds.feedburner.com/xbmc4xbox
19:44:49 M: 32866304 DEBUG: CVideoDatabase::GetMoviesByWhere query: select * from movieview order by idMovie desc limit 10
19:44:50 M: 32849920 DEBUG: Time for actual SQL query = 187
19:44:50 M: 32841728 INFO: WEATHER: Weather download successful
19:44:50 M: 28192768 DEBUG: Time to retrieve movies from dataset = 234
19:44:50 M: 28184576 DEBUG: CVideoDatabase::GetEpisodesByWhere query: select * from episodeview order by idEpisode desc limit 10
19:44:51 M: 28114944 DEBUG: CApplication::OnKey: 271 pressed, action is 4
Previous line repeats 1 times.
19:44:51 M: 33062912 DEBUG: Time for actual SQL query = 1503
19:44:51 M: 33124352 DEBUG: CApplication::OnKey: 271 pressed, action is 4
19:44:52 M: 33103872 DEBUG: Time to retrieve episodes from dataset = 235
19:44:52 M: 33124352 DEBUG: CApplication::OnKey: 256 pressed, action is 7
19:44:52 M: 33091584 DEBUG: CApplication::OnMessage : Translating ActivateWindow(VideoFiles,Root)
19:44:52 M: 33091584 DEBUG: CApplication::OnMessage : To ActivateWindow(VideoFiles,Root)
19:44:52 M: 33091584 DEBUG: Activating window ID: 10024
19:44:52 M: 33091584 DEBUG: Checking if window ID 10024 is locked.
19:44:52 M: 33091584 DEBUG: ------ Window Deinit (Home.xml) ------
19:44:52 M: 35086336 INFO: Attempting to quickpath to: Root
19:44:52 M: 35086336 WARNING: CUtil::GetMatchingSource... no matching source found for [Root]
19:44:52 M: 35086336 ERROR: Failed! Destination parameter (Root) does not match a valid source!
19:44:53 M: 35074048 DEBUG: ------ Window Init (MyVideo.xml) ------
19:44:53 M: 35074048 INFO: Loading skin file: MyVideo.xml
19:44:54 M: 33513472 DEBUG: CGUIMediaWindow::GetDirectory ()
19:44:54 M: 33533952 DEBUG: ParentPath = []
19:44:54 M: 33546240 DEBUG: CFileItemList::Sort, sorting took 0 millis
19:44:55 M: 35799040 DEBUG: CVideoDatabase::GetMusicVideosByWhere query = select * from musicvideoview order by idMVideo desc limit 10
19:44:55 M: 35799040 DEBUG: CVideoDatabase::GetMusicVideosByWhere time for actual SQL query = 43
19:44:55 M: 35811328 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
19:44:55 M: 35799040 DEBUG: CApplication::OnKey: 271 pressed, action is 4
19:44:56 M: 35799040 DEBUG: CApplication::OnKey: 256 pressed, action is 7
19:44:56 M: 36691968 DEBUG: CGUIMediaWindow::GetDirectory (smb://LINKSTATION/readonly/Movies/)
19:44:56 M: 36691968 DEBUG: ParentPath = []
19:44:56 M: 36347904 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies
19:44:58 M: 32903168 DEBUG: ------ Window Init (DialogBusy.xml) ------
19:44:58 M: 32903168 INFO: Loading skin file: DialogBusy.xml
19:45:00 M: 32772096 DEBUG: Saving fileitems [smb://LINKSTATION/readonly/Movies/]
19:45:00 M: 32772096 DEBUG: -- items: 41, sort method: 0, ascending: false
19:45:00 M: 32772096 DEBUG: CFileItemList::Sort, sorting took 1 millis
19:45:00 M: 32247808 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
19:45:00 M: 35479552 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/007%2dThe%20man%20with%20the%20Golden%20Gun
19:45:00 M: 35205120 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/1408
19:45:00 M: 34889728 INFO: XCURL::DllLibCurlGlobal::CheckIdle - Closing session to http://feeds.feedburner.com (easy=00C6A180, multi=00C1C520)
19:45:00 M: 35004416 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Aeon%20Flux
19:45:00 M: 35008512 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Avatar
19:45:00 M: 35016704 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Back%20to%20the%20future%20%2d%20Part%20I
19:45:00 M: 35049472 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Back%20to%20the%20future%20%2d%20Part%20II
19:45:00 M: 35049472 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Back%20to%20the%20future%20%2d%20Part%20III
19:45:00 M: 35049472 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/The%20Book%20Of%20Eli
19:45:00 M: 35049472 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Demolition%20Man
19:45:00 M: 35049472 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Die%20Hard
19:45:00 M: 35049472 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Die%20Hard%202
19:45:00 M: 35049472 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Eraser
19:45:00 M: 35049472 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Event%20Horizon
19:45:00 M: 35049472 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/The%20Fifth%20Element
19:45:00 M: 35049472 DEBUG: CApplication::OnKey: 279 pressed, action is 112
19:45:00 M: 35049472 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Fight%20Club
19:45:00 M: 35049472 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 1 times.
19:45:01 M: 35049472 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Futurama%20%2d%20Benders%20Big%20Score
19:45:01 M: 35049472 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 2 times.
19:45:01 M: 35049472 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Futurama%20%2d%20The%20Beast%20with%20a%20Billion%20Backs
19:45:01 M: 35049472 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 1 times.
19:45:01 M: 35049472 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Goldeneye
19:45:01 M: 35049472 DEBUG: CApplication::OnKey: 279 pressed, action is 112
19:45:01 M: 35049472 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Identity
19:45:01 M: 35049472 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 3 times.
19:45:01 M: 35049472 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/K%2dPAX
19:45:01 M: 35049472 DEBUG: CApplication::OnKey: 279 pressed, action is 112
19:45:01 M: 35049472 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Lost%20in%20Space
19:45:01 M: 35315712 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 2 times.
19:45:01 M: 35315712 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/The%20Matrix
19:45:01 M: 35315712 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 1 times.
19:45:01 M: 35315712 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/The%20Matrix%20Reloaded
19:45:01 M: 35315712 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 1 times.
19:45:01 M: 35315712 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Minority%20Report
19:45:01 M: 35315712 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 1 times.
19:45:01 M: 35315712 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Passenger%2057
19:45:01 M: 35315712 DEBUG: CApplication::OnKey: 279 pressed, action is 112
19:45:01 M: 35315712 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Planet%20Of%20The%20Apes%2020012
19:45:01 M: 35315712 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 2 times.
19:45:01 M: 35315712 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/The%20Ring
19:45:01 M: 35315712 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 1 times.
19:45:01 M: 35315712 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Shallow%20Hal
19:45:01 M: 35315712 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 1 times.
19:45:01 M: 35315712 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Shaun%20Of%20The%20Dead
19:45:01 M: 35315712 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 1 times.
19:45:01 M: 35315712 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Shawshank%20Redemption
19:45:01 M: 35315712 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 2 times.
19:45:01 M: 35315712 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Star%20Trek%20%2d%20First%20Contact
19:45:01 M: 35315712 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 1 times.
19:45:01 M: 35315712 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Star%20Trek%20%2d%20Generations
19:45:01 M: 35315712 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 1 times.
19:45:01 M: 35315712 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Star%20Trek%20%2d%20Nemesis
19:45:01 M: 35315712 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 1 times.
19:45:01 M: 35315712 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Star%20Wars%20%2d%20The%20Phantom%20Menace
19:45:01 M: 35315712 DEBUG: CApplication::OnKey: 279 pressed, action is 112
Previous line repeats 1 times.
19:45:01 M: 35315712 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Sunshine
19:45:01 M: 35315712 DEBUG: CApplication::OnKey: 279 pressed, action is 112
19:45:01 M: 35315712 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Swordfish
19:45:01 M: 35315712 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Terminator%20II%20Judgement%20Day
19:45:01 M: 35315712 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/The%20Time%20Machine%20%281960%29
19:45:01 M: 35315712 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Tropic%20Thunder
19:45:01 M: 35315712 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Vanilla%20Sky
19:45:01 M: 35315712 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/War%20Of%20The%20Worlds%20%282005%29
19:45:02 M: 35840000 INFO: XCURL::DllLibCurlGlobal::CheckIdle - Closing session to http://xml.weather.com (easy=00C91010, multi=00C998F0)
19:45:02 M: 35966976 DEBUG: CApplication::OnKey: 271 pressed, action is 4
Previous line repeats 2 times.
19:45:03 M: 35704832 DEBUG: CApplication::OnKey: 270 pressed, action is 3
Previous line repeats 2 times.
19:45:04 M: 35975168 DEBUG: CApplication::OnKey: 273 pressed, action is 2
19:45:05 M: 35975168 DEBUG: CApplication::OnKey: 272 pressed, action is 1
19:45:05 M: 35975168 DEBUG: CApplication::OnKey: 256 pressed, action is 7
19:45:05 M: 36306944 DEBUG: CGUIMediaWindow::GetDirectory (smb://LINKSTATION/readonly/Movies/Star Trek - First Contact/)
19:45:05 M: 36306944 DEBUG: ParentPath = [smb://LINKSTATION/readonly/Movies/]
19:45:05 M: 36306944 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Star%20Trek%20%2d%20First%20Contact
19:45:06 M: 36306944 DEBUG: CFileItemList::Sort, sorting took 0 millis
19:45:06 M: 34910208 WARNING: old getenv from python.lib called, library check needed
19:45:06 M: 34893824 DEBUG: JpegIO::Read - Using scale_num of 5, 157 x 235
19:45:06 M: 35319808 DEBUG: CApplication::OnKey: 271 pressed, action is 4
19:45:06 M: 35319808 DEBUG: CApplication::OnKey: 273 pressed, action is 2
19:45:07 M: 35319808 DEBUG: CApplication::OnKey: 256 pressed, action is 7
19:45:07 M: 35274752 INFO: Loading skin file: DialogContextMenu.xml
19:45:07 M: 35274752 DEBUG: ------ Window Init (DialogContextMenu.xml) ------
19:45:08 M: 34230272 DEBUG: CApplication::OnKey: 271 pressed, action is 4
19:45:09 M: 34230272 DEBUG: CApplication::OnKey: 256 pressed, action is 7
19:45:09 M: 34230272 DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------
19:45:09 M: 35303424 DEBUG: CPlayerCoreFactory::GetPlayers(smb://LINKSTATION/readonly/Movies/Star Trek - First Contact/Star Trek - First Contact.avi)
19:45:09 M: 35303424 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: un-named
19:45:09 M: 35303424 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: un-named
19:45:09 M: 35303424 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
19:45:09 M: 35303424 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp
19:45:09 M: 35303424 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
19:45:09 M: 35303424 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
19:45:09 M: 35303424 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
19:45:09 M: 35303424 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
19:45:09 M: 35303424 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
19:45:09 M: 35303424 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
19:45:09 M: 35303424 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: ogv/flv/mov
19:45:09 M: 35303424 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
19:45:09 M: 35303424 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
19:45:09 M: 35303424 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
19:45:09 M: 35303424 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
19:45:09 M: 35303424 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
19:45:09 M: 35303424 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
19:45:09 M: 35303424 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: MPlayer (2)
19:45:09 M: 35303424 DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players
19:45:09 M: 35303424 NOTICE: DVDPlayer: Opening: smb://LINKSTATION/readonly/Movies/Star Trek - First Contact/Star Trek - First Contact.avi
19:45:09 M: 35303424 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
19:45:09 M: 34779136 NOTICE: Creating InputStream
19:45:09 M: 34779136 DEBUG: CSmbFile::Open - opened readonly/Movies/Star Trek - First Contact/Star Trek - First Contact.avi, fd=10000
19:45:09 M: 34779136 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles
19:45:09 M: 34779136 DEBUG: CUtil::CacheSubtitles: START
19:45:09 M: 34779136 DEBUG: CUtil::CacheSubtitles: Checking for common subdirs...
19:45:09 M: 34779136 DEBUG: CUtil::CacheSubtitles: Done (time: 55 ms)
19:45:09 M: 34779136 DEBUG: CUtil::CacheSubtitles: Searching for subtitles...
19:45:09 M: 34779136 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Star%20Trek%20%2d%20First%20Contact
19:45:09 M: 34779136 DEBUG: CUtil::CacheSubtitles: Done (time: 21 ms)
19:45:09 M: 34779136 DEBUG: CUtil::CacheSubtitles: END (total time: 78 ms)
19:45:09 M: 34779136 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles done
19:45:09 M: 34779136 NOTICE: Creating Demuxer
19:45:09 M: 34471936 DEBUG: GetModuleHandleA('libgcc_s_dw2-1.dll') failed
Previous line repeats 2 times.
19:45:09 M: 28913664 DEBUG: CDVDDemuxFFmpeg::Open - probing detected format [avi]
19:45:11 M: 24137728 DEBUG: ------ Window Init (DialogBusy.xml) ------
19:45:11 M: 24137728 INFO: Loading skin file: DialogBusy.xml
19:45:13 M: 20590592 DEBUG: CDVDDemuxFFmpeg::Open - avformat_find_stream_info starting
19:45:13 M: 20566016 DEBUG: Unable to resolve: kernel32.dll InitializeConditionVariable
19:45:13 M: 20566016 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'InitializeConditionVariable') => 00000000
19:45:13 M: 20566016 DEBUG: Unable to resolve: kernel32.dll WakeAllConditionVariable
19:45:13 M: 20566016 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'WakeAllConditionVariable') => 00000000
19:45:13 M: 20566016 DEBUG: Unable to resolve: kernel32.dll WakeConditionVariable
19:45:13 M: 20566016 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'WakeConditionVariable') => 00000000
19:45:13 M: 20566016 DEBUG: Unable to resolve: kernel32.dll SleepConditionVariableCS
19:45:13 M: 20566016 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'SleepConditionVariableCS') => 00000000
19:45:13 M: 20443136 DEBUG: Unable to resolve: kernel32.dll InitializeConditionVariable
19:45:13 M: 20443136 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'InitializeConditionVariable') => 00000000
19:45:13 M: 20443136 DEBUG: Unable to resolve: kernel32.dll WakeAllConditionVariable
19:45:13 M: 20443136 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'WakeAllConditionVariable') => 00000000
19:45:13 M: 20443136 DEBUG: Unable to resolve: kernel32.dll WakeConditionVariable
19:45:13 M: 20443136 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'WakeConditionVariable') => 00000000
19:45:13 M: 20443136 DEBUG: Unable to resolve: kernel32.dll SleepConditionVariableCS
19:45:13 M: 20443136 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'SleepConditionVariableCS') => 00000000
19:45:13 M: 20619264 DEBUG: CDVDDemuxFFmpeg::Open - av_find_stream_info finished
19:45:13 M: 20619264 INFO: ffmpeg[18C]: Input #0, avi, from 'smb://LINKSTATION/readonly/Movies/Star Trek - First Contact/Star Trek - First Contact.avi':
19:45:13 M: 20619264 INFO: ffmpeg[18C]: Metadata:
19:45:13 M: 20619264 INFO: ffmpeg[18C]: encoder : VirtualDubMod 1.5.4.1 (build 2178/release)
19:45:13 M: 20619264 INFO: ffmpeg[18C]: IAS1 : English
19:45:13 M: 20619264 INFO: ffmpeg[18C]: Duration: 01:46:09.00, start: 0.000000, bitrate: 2820 kb/s
19:45:13 M: 20619264 INFO: ffmpeg[18C]: Stream #0:0: Video: mpeg4 (XVID / 0x44495658), yuv420p, 720x304 [SAR 1:1 DAR 45:19], 25 tbr, 25 tbn, 25 tbc
19:45:13 M: 20619264 INFO: ffmpeg[18C]: Stream #0:1: Audio: ac3 ([0] [0][0] / 0x2000), 48000 Hz, 5.1(side), s16, 448 kb/s
19:45:13 M: 20619264 NOTICE: Opening video stream: 0 source: 256
19:45:13 M: 20619264 NOTICE: Creating video codec with codec id: 13
19:45:13 M: 20619264 DEBUG: FactoryCodec - Video: - Opening
19:45:13 M: 20606976 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: mpeg4
19:45:13 M: 20606976 DEBUG: Unable to resolve: kernel32.dll InitializeConditionVariable
19:45:13 M: 20606976 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'InitializeConditionVariable') => 00000000
19:45:13 M: 20606976 DEBUG: Unable to resolve: kernel32.dll WakeAllConditionVariable
19:45:13 M: 20606976 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'WakeAllConditionVariable') => 00000000
19:45:13 M: 20606976 DEBUG: Unable to resolve: kernel32.dll WakeConditionVariable
19:45:13 M: 20606976 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'WakeConditionVariable') => 00000000
19:45:13 M: 20606976 DEBUG: Unable to resolve: kernel32.dll SleepConditionVariableCS
19:45:13 M: 20606976 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'SleepConditionVariableCS') => 00000000
19:45:13 M: 20606976 DEBUG: FactoryCodec - Video: ff-mpeg4 - Opened
19:45:13 M: 20606976 NOTICE: Creating video thread
19:45:13 M: 20082688 NOTICE: Opening audio stream: 1 source: 256
19:45:13 M: 20082688 DEBUG: CXBoxRenderManager::PreInit - Selected RGB-Renderer
19:45:13 M: 20082688 NOTICE: Finding audio codec for: 86019
19:45:13 M: 19890176 DEBUG: FactoryCodec - Audio: FFmpeg - Opening
19:45:13 M: 19767296 DEBUG: Unable to resolve: kernel32.dll InitializeConditionVariable
19:45:13 M: 19767296 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'InitializeConditionVariable') => 00000000
19:45:13 M: 19767296 DEBUG: Unable to resolve: kernel32.dll WakeAllConditionVariable
19:45:13 M: 19767296 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'WakeAllConditionVariable') => 00000000
19:45:13 M: 19767296 DEBUG: Unable to resolve: kernel32.dll WakeConditionVariable
19:45:13 M: 19767296 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'WakeConditionVariable') => 00000000
19:45:13 M: 19767296 NOTICE: running thread: video_thread
19:45:13 M: 19767296 DEBUG: Unable to resolve: kernel32.dll SleepConditionVariableCS
19:45:13 M: 19767296 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
19:45:13 M: 19767296 DEBUG: dllGetProcAddress(00853E20(kernel32.dll), 'SleepConditionVariableCS') => 00000000
19:45:13 M: 19767296 DEBUG: FactoryCodec - Audio: FFmpeg - Opened
19:45:13 M: 19767296 NOTICE: Creating audio thread
19:45:13 M: 19243008 DEBUG: CEdl::ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb://LINKSTATION/readonly/Movies/Star Trek - First Contact/Star Trek - First Contact.avi
19:45:13 M: 19243008 NOTICE: running thread: CDVDPlayerAudio::Process()
19:45:13 M: 19243008 DEBUG: CDVDPlayer::SetCaching - caching state 2
19:45:13 M: 19230720 NOTICE: Creating audio device with codec id: 86019, channels: 2, sample rate: 48000, no pass-through
19:45:13 M: 19181568 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:45:13 M: 19181568 DEBUG: Pause stream
19:45:13 M: 18321408 DEBUG: CDVDPlayer::HandleMessages - player started 1
19:45:13 M: 18202624 DEBUG: GetModuleHandleA('libgcc_s_dw2-1.dll') failed
19:45:13 M: 17629184 DEBUG: CApplication::OnPlayBackStarted - Playback has started
19:45:13 M: 16941056 NOTICE: fps: 25.000000, pwidth: 720, pheight: 304, dwidth: 720, dheight: 304
19:45:13 M: 16941056 DEBUG: CDVDPlayerVideo::OutputPicture - change configuration. 720x304. framerate: 25.00
19:45:13 M: 16941056 NOTICE: Display resolution USER : 720p 16:9 (1)
19:45:13 M: 15880192 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
19:45:13 M: 19181568 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
19:45:13 M: 19181568 DEBUG: CGUIInfoManager::SetCurrentMovie(smb://LINKSTATION/readonly/Movies/Star Trek - First Contact/Star Trek - First Contact.avi)
19:45:13 M: 19353600 DEBUG: Created YV12 texture 0
19:45:13 M: 18997248 DEBUG: Created YV12 texture 1
19:45:13 M: 18997248 DEBUG: CDVDPlayer::HandleMessages - player started 2
19:45:13 M: 18997248 DEBUG: CDVDPlayer::SetCaching - caching state 0
19:45:13 M: 18997248 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(32000.000000, 1)
19:45:13 M: 18980864 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(40000.000000, 0)
19:45:13 M: 18980864 DEBUG: CDVDPlayerAudio:: Discontinuity - was:764.030455, should be:0.000000, error:-764.030455
19:45:13 M: 18415616 DEBUG: CVideoDatabase::GetMovieId (smb://LINKSTATION/readonly/Movies/Star Trek - First Contact/Star Trek - First Contact.avi), query = select idMovie from movie where idFile=220
Previous line repeats 1 times.
19:45:14 M: 18227200 DEBUG: CGUIInfoManager::SetCurrentMovie, got movie info!
19:45:14 M: 18227200 DEBUG: Title = Star Trek: First Contact
19:45:14 M: 18198528 DEBUG: Activating window ID: 12005
19:45:14 M: 18198528 DEBUG: Checking if window ID 12005 is locked.
19:45:14 M: 18214912 DEBUG: ------ Window Deinit (MyVideo.xml) ------
19:45:14 M: 18960384 DEBUG: Setting resolution 1
19:45:14 M: 20807680 DEBUG: We set resolution 1
19:45:14 M: 20639744 DEBUG: Deleted YV12 texture 1
19:45:14 M: 20639744 INFO: Starting async renderer thread
19:45:14 M: 20639744 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
19:45:14 M: 20570112 INFO: Loading skin file: VideoFullScreen.xml
19:45:14 M: 20561920 INFO: Loading skin file: VideoOSD.xml
19:45:14 M: 19808256 INFO: Loading skin file: VideoOSDSettings.xml
Previous line repeats 1 times.
19:45:14 M: 19214336 INFO: Loading skin file: DialogFullScreenInfo.xml
19:45:14 M: 18890752 DEBUG: CDVDPlayerAudio:: Discontinuity - was:1032405.851378, should be:1021399.990943, error:-11005.860435
19:45:47 M: 19607552 DEBUG: CApplication::OnKey: 257 pressed, action is 13
19:45:47 M: 19607552 NOTICE: CDVDPlayer::CloseFile()
19:45:47 M: 19607552 NOTICE: DVDPlayer: waiting for threads to exit
19:45:47 M: 19607552 NOTICE: CDVDPlayer::OnExit()
19:45:47 M: 19607552 NOTICE: DVDPlayer: closing audio stream
19:45:47 M: 19607552 NOTICE: Closing audio stream
19:45:47 M: 19607552 NOTICE: Waiting for audio thread to exit
19:45:47 M: 19607552 DEBUG: CDVDPlayerAudio:: Discontinuity - was:-529400.884762, should be:33775216.570495, error:34304617.455257
19:45:47 M: 19607552 NOTICE: thread end: CDVDPlayerAudio::OnExit()
19:45:47 M: 20131840 NOTICE: Closing audio device
19:45:47 M: 20131840 DEBUG: Stop stream
19:45:47 M: 20250624 NOTICE: Deleting audio codec
19:45:47 M: 20557824 NOTICE: DVDPlayer: closing video stream
19:45:47 M: 20557824 NOTICE: Closing video stream
19:45:47 M: 20557824 NOTICE: waiting for video thread to exit
19:45:47 M: 20557824 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
19:45:47 M: 21815296 NOTICE: thread end: video_thread
19:45:47 M: 22945792 NOTICE: deleting video codec
19:45:47 M: 24190976 NOTICE: CDVDPlayer::OnExit() deleting demuxer
19:45:47 M: 29745152 NOTICE: CDVDPlayer::OnExit() deleting input stream
19:45:47 M: 29745152 DEBUG: CSmbFile::Close closing fd 10000
19:45:47 M: 29745152 DEBUG: CApplication::OnPlayBackStopped - Playback was stopped
19:45:48 M: 30269440 NOTICE: DVDPlayer: finished waiting
19:45:48 M: 30269440 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
19:45:48 M: 30269440 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
19:45:49 M: 31580160 DEBUG: Setting resolution 1
19:45:49 M: 29696000 DEBUG: We set resolution 1
19:45:49 M: 29278208 DEBUG: Created YV12 texture 1
19:45:49 M: 29343744 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
19:45:49 M: 29327360 DEBUG: ------ Window Init (MyVideo.xml) ------
19:45:49 M: 29327360 INFO: Loading skin file: MyVideo.xml
19:45:49 M: 25591808 DEBUG: ------ Window Init (DialogBusy.xml) ------
19:45:49 M: 25612288 DEBUG: CGUIMediaWindow::GetDirectory (smb://LINKSTATION/readonly/Movies/Star Trek - First Contact/)
19:45:49 M: 25612288 INFO: Loading skin file: DialogBusy.xml
19:45:49 M: 25612288 DEBUG: ParentPath = [smb://LINKSTATION/readonly/Movies/Star Trek - First Contact/]
19:45:49 M: 25612288 DEBUG: XFILE::CSMBDirectory::OpenDir - Using authentication url smb://LINKSTATION/readonly/Movies/Star%20Trek%20%2d%20First%20Contact
19:45:50 M: 25612288 DEBUG: CFileItemList::Sort, sorting took 0 millis
19:45:50 M: 25088000 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
19:45:50 M: 27910144 NOTICE: CDVDPlayer::CloseFile()
19:45:50 M: 27910144 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
19:45:50 M: 27910144 NOTICE: DVDPlayer: waiting for threads to exit
19:45:50 M: 27910144 NOTICE: DVDPlayer: finished waiting
19:45:50 M: 27910144 NOTICE: CDVDPlayerVideo::~CDVDPlayerVideo - uninitting video device
19:45:50 M: 28958720 DEBUG: CXBoxRenderer::UnInit - Cleaning up resources
19:45:50 M: 29310976 DEBUG: Deleted YV12 texture 0
19:45:50 M: 29663232 DEBUG: Deleted YV12 texture 1
19:45:50 M: 29663232 DEBUG: CApplication::SaveFileState - Saving file state for video item smb://LINKSTATION/readonly/Movies/Star Trek - First Contact/Star Trek - First Contact.avi
19:45:57 M: 29655040 DEBUG: SECTION:UnloadDelayed(DLL: Q:\system\players\dvdplayer\avutil-51.dll)
19:45:58 M: 29618176 DEBUG: SECTION:UnloadDelayed(DLL: Q:\system\players\dvdplayer\avcodec-53.dll)
User avatar
Dom DXecutioner
Posts: 587
Joined: Thu Jul 05, 2012 11:59 pm
Location: California
Has thanked: 249 times
Been thanked: 226 times
Contact:

Re: 1080i Smooth playback, 720p jerky WAAhTT?

Post by Dom DXecutioner »

READ about the Debug Logs section in the following topic! http://www.xbmc4xbox.org.uk/forum/viewt ... p?f=6&t=65
Image
Post Reply