Project

General

Profile

Actions
Copy link

Bug #149

closed

Cannot play a video while a shoutcast stream is active

Added by abevi over 13 years ago. Updated over 13 years ago.

Status:
Closed
Priority:
Normal
Assignee:
arnova
Category:
Other (un-categorized)
Target version:
3.0
Start date:
Due date:
% Done:

0%

Resolution:
fixed
Affected Version:

Description

If you're listening to a shoutcast stream, then try to start a video (avi over SMB) the video never loads and the busy indicator just stays on screen.

This ticket may be similar to #147 ..,

Log is as follows:

13:15:30 M: 57331712  NOTICE: -----------------------------------------------------------------------
13:15:30 M: 57311232  NOTICE: Starting XBMC4Xbox.  Built on Dec 11 2010 (SVN:30754, compiler 1310)
13:15:30 M: 57311232  NOTICE: special://xbmc/ is mapped to: Q:\
13:15:30 M: 57311232  NOTICE: special://masterprofile/ is mapped to: q:\UserData
13:15:30 M: 57311232  NOTICE: special://home/ is mapped to: Q:\
13:15:30 M: 57311232  NOTICE: special://temp/ is mapped to: Z:\
13:15:30 M: 57311232  NOTICE: The executable running is: E:\apps\XBMC\default.xbe
13:15:30 M: 57311232  NOTICE: Log File is located: Q:\xbmc.log
13:15:30 M: 57311232  NOTICE: -----------------------------------------------------------------------
13:15:30 M: 57311232  NOTICE: Unmapped drive T
13:15:30 M: 57311232  NOTICE: Mapping drive T to Harddisk0\Partition1\apps\XBMC\UserData
13:15:30 M: 57311232  NOTICE: Setup DirectX
13:15:30 M: 57286656  NOTICE: Mapping drive C to Harddisk0\Partition2
13:15:30 M: 57286656  NOTICE: Mapping drive E to Harddisk0\Partition1
13:15:30 M: 57286656  NOTICE: Unmapped drive D
13:15:30 M: 57286656  NOTICE: Mapping drive D to Cdrom0
13:15:30 M: 57286656  NOTICE:   map extended drive R:
13:15:30 M: 57286656  NOTICE: Mapping drive R to Harddisk0\Partition8
13:15:30 M: 57286656  NOTICE: Mapping drive X to Harddisk0\Partition3
13:15:30 M: 57282560  NOTICE: Mapping drive Y to Harddisk0\Partition4
13:15:30 M: 57282560  NOTICE: Unmapped drive Z
13:15:30 M: 57282560  NOTICE: Mapping drive Z to Harddisk0\Partition5
13:15:30 M: 57282560  NOTICE: load settings...
13:15:30 M: 57282560  NOTICE: Mapping drive P to Harddisk0\Partition1\apps\XBMC\UserData
13:15:30 M: 57282560  NOTICE: special://profile/ is mapped to: q:\UserData
13:15:30 M: 57282560  NOTICE: loading special://masterprofile/guisettings.xml
13:15:30 M: 56979456  NOTICE: Getting hardware information now...
13:15:30 M: 56979456  NOTICE: Checking resolution 10
13:15:30 M: 56979456  NOTICE: Setting autoresolution mode 4
13:15:30 M: 56979456  NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
13:15:30 M: 56958976  NOTICE: Loaded playercorefactory configuration
13:15:30 M: 56938496  NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
13:15:30 M: 56938496  NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
13:15:30 M: 56938496  NOTICE: No advancedsettings.xml to load (special://masterprofile/advancedsettings.xml)
13:15:30 M: 56938496  NOTICE: Default Video Player: mplayer
13:15:30 M: 56938496  NOTICE: Default Audio Player: paplayer
13:15:30 M: 56938496  NOTICE: Per AV pack settings are off
13:15:30 M: 57196544  NOTICE: q:\UserData\sources.xml
13:15:31 M: 50659328  NOTICE: Network: Using dashboard IP settings
13:15:31 M: 50331648  NOTICE: Checking the Date!
13:15:31 M: 50331648  NOTICE: start dvd mediatype detection
13:15:31 M: 50266112  NOTICE: initializing playlistplayer
13:15:31 M: 50266112  NOTICE: DONE initializing playlistplayer
13:15:31 M: 50257920  NOTICE: load default skin:[Project Mayhem III]
13:15:32 M: 46878720  NOTICE: initialize done
13:15:32 M: 46878720  NOTICE: Running the application...
13:15:33 M: 46878720   ERROR: Python script "Q:\plugins\weather\weather.com plus\default.py" does not exist
13:15:33 M: 43458560  NOTICE: Webserver: Starting...
13:15:34 M: 43253760  NOTICE: Webserver: Started
13:15:34 M: 43253760  NOTICE: XBFileZilla: Starting...
13:15:34 M: 43184128  NOTICE: starting upnp server
13:15:34 M: 42921984  NOTICE: ES: Starting event server
13:15:34 M: 41807872  NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777
13:15:34 M: 41242624  NOTICE: UDP: Listening on port 9777
13:15:34 M: 40550400  NOTICE: XBFileZilla: Started
13:15:35 M: 41086976   ERROR: Python script "Q:\plugins\weather\weather.com plus\default.py" does not exist
13:15:51 M: 44048384  NOTICE: Enabled debug logging due to GUI setting
13:15:56 M: 44060672   DEBUG: CApplication::OnKey: 247 pressed, action is 10
13:15:56 M: 44052480   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
13:15:56 M: 44052480   DEBUG: ------ Window Deinit (SettingsCategory.xml) ------
13:15:56 M: 45367296   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
13:15:56 M: 45367296   DEBUG: ------ Window Init (Settings.xml) ------
13:15:56 M: 45367296    INFO: Loading skin file: Settings.xml
13:15:57 M: 43520000   DEBUG: CApplication::OnKey: 247 pressed, action is 10
13:15:57 M: 43511808   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
13:15:57 M: 43511808   DEBUG: ------ Window Deinit (Settings.xml) ------
13:15:57 M: 45367296   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
13:15:57 M: 45367296   DEBUG: ------ Window Init (Home.xml) ------
13:15:57 M: 45367296    INFO: Loading skin file: Home.xml
13:15:59 M: 40751104   DEBUG: CApplication::OnKey: 169 pressed, action is 1
Previous line repeats 1 times.
13:16:00 M: 40681472   DEBUG: CApplication::OnKey: 11 pressed, action is 7
13:16:00 M: 40669184   DEBUG: CApplication::OnMessage : Translating [[ActivateWindow]](Favourites)
13:16:00 M: 40669184   DEBUG: CApplication::OnMessage : To [[ActivateWindow]](Favourites)
13:16:00 M: 40669184   DEBUG: Activating window ID: 10134
13:16:00 M: 40669184   DEBUG: Checking if window ID 10134 is locked.
13:16:00 M: 40669184   DEBUG: ------ Window Init (DialogFavourites.xml) ------
13:16:00 M: 40669184    INFO: Loading skin file: [[DialogFavourites]].xml
13:16:00 M: 40669184   DEBUG: CFavourites::Load - no system favourites found, skipping
13:16:01 M: 39788544   DEBUG: CApplication::OnKey: 167 pressed, action is 4
Previous line repeats 1 times.
13:16:03 M: 39788544   DEBUG: CApplication::OnKey: 11 pressed, action is 7
13:16:03 M: 39776256   DEBUG: CApplication::OnMessage : Translating [[PlayMedia]](shout://scfire-ntc-aa06.stream.aol.com:80/stream/1004)
13:16:03 M: 39776256   DEBUG: CApplication::OnMessage : To [[PlayMedia]](shout://scfire-ntc-aa06.stream.aol.com:80/stream/1004)
13:16:03 M: 39739392    INFO: XCURL::DllLibCurlGlobal::easy_aquire - Created session to http://scfire-ntc-aa06.stream.aol.com
13:16:03 M: 39739392   DEBUG: CFileCurl::GetMimeType - shout://scfire-ntc-aa06.stream.aol.com:80/stream/1004 -> audio/mpeg
13:16:03 M: 39739392   DEBUG: CPlayerCoreFactory::GetPlayers(shout://scfire-ntc-aa06.stream.aol.com:80/stream/1004)
13:16:03 M: 39739392   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: un-named
13:16:03 M: 39739392   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: un-named
13:16:03 M: 39739392   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv/mms
13:16:03 M: 39739392   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: myth/cmyth
13:16:03 M: 39739392   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/rtmp/htsp/udp
13:16:03 M: 39739392   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
13:16:03 M: 39739392   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: lastfm/shout
13:16:03 M: 39739392   DEBUG: CPlayerSelectionRule::GetPlayers: adding player: PAPlayer (3) for rule: lastfm/shout
13:16:03 M: 39739392   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
13:16:03 M: 39739392   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
13:16:03 M: 39739392   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams
13:16:03 M: 39739392   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: flv/aacp/sdp
13:16:03 M: 39739392   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2
13:16:03 M: 39739392   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: wmv
13:16:03 M: 39739392   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
13:16:03 M: 39739392   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
13:16:03 M: 39739392   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
13:16:03 M: 39739392   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: asf/mp4
13:16:03 M: 39739392   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: ogv/flv/mov
13:16:03 M: 39739392   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
13:16:04 M: 39739392   DEBUG: CPlayerCoreFactory::GetPlayers: matched 1 rules with players
13:16:04 M: 39739392   DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (3)
13:16:04 M: 39739392   DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1
13:16:04 M: 39739392   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: PAPlayer (3)
13:16:04 M: 39739392   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: MODPlayer (4)
13:16:04 M: 39739392   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
13:16:04 M: 39739392   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
13:16:04 M: 39739392   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: MPlayer (2)
13:16:04 M: 39739392   DEBUG: CPlayerCoreFactory::GetPlayers: added 4 players
13:16:04 M: 39022592   DEBUG: kernel32.dll fake function [[DisableThreadLibraryCalls]] called
13:16:04 M: 38998016    INFO: MP3Codec: Loaded decoder at 00CEC020
13:16:04 M: 38203392   DEBUG: CFileCache::Open - opening <stream/1004> using cache
13:16:05 M: 37232640   DEBUG: ------ Window Init (DialogBusy.xml) ------
13:16:05 M: 37232640    INFO: Loading skin file: [[DialogBusy]].xml
13:16:05 M: 36356096   DEBUG: kernel32.dll fake function [[DisableThreadLibraryCalls]] called
13:16:05 M: 35536896   DEBUG: CFileCache::Open - opening <stream/1004> using cache
13:16:05 M: 36257792   DEBUG: kernel32.dll fake function [[DisableThreadLibraryCalls]] called
13:16:05 M: 35459072   DEBUG: CFileCache::Open - opening <stream/1004> using cache
Previous line repeats 1 times.
13:16:06 M: 36188160   DEBUG: PAPlayer: Playing shout://scfire-ntc-aa06.stream.aol.com:80/stream/1004
13:16:06 M: 35561472   DEBUG: CApplication::OnPlayBackStarted - Playback has started
13:16:06 M: 35561472   DEBUG: PAPlayer: Thread started
13:16:06 M: 35561472   DEBUG: CApplication::OnPlayBackStarted - Playback has started
13:16:06 M: 34398208    INFO: [[AudioDecoder]]: File is queued
13:16:06 M: 35504128   DEBUG: CGUIInfoManager::SetCurrentSong(shout://scfire-ntc-aa06.stream.aol.com:80/stream/1004)
13:16:06 M: 35508224   DEBUG: CFileItem::LoadMusicTag: loading tag information for file: shout://scfire-ntc-aa06.stream.aol.com:80/stream/1004
13:16:06 M: 35508224   DEBUG: CGUIInfoManager::SetCurrentSong(shout://scfire-ntc-aa06.stream.aol.com:80/stream/1004)
13:16:06 M: 35508224   DEBUG: CFileItem::LoadMusicTag: loading tag information for file: shout://scfire-ntc-aa06.stream.aol.com:80/stream/1004
13:16:06 M: 35508224   DEBUG: ------ Window Deinit (DialogFavourites.xml) ------
13:16:06 M: 36397056   DEBUG: ------ Window Deinit (DialogBusy.xml) ------
13:16:09 M: 36536320   DEBUG: CApplication::OnKey: 169 pressed, action is 1
13:16:10 M: 36605952   DEBUG: CApplication::OnKey: 166 pressed, action is 3
Previous line repeats 3 times.
13:16:11 M: 36499456   DEBUG: CApplication::OnKey: 11 pressed, action is 7
13:16:11 M: 36499456   DEBUG: CApplication::OnMessage : Translating [[ActivateWindow]](MyVideos)
13:16:11 M: 36499456   DEBUG: CApplication::OnMessage : To [[ActivateWindow]](MyVideos)
13:16:11 M: 36499456   DEBUG: Activating window ID: 10025
13:16:11 M: 36499456   DEBUG: Checking if window ID 10024 is locked.
13:16:11 M: 36499456   DEBUG: ------ Window Deinit (Home.xml) ------
13:16:12 M: 41189376    INFO: Attempting to default to: [[MovieTitles]]
13:16:13 M: 41193472   DEBUG: ------ Window Init (MyVideoNav.xml) ------
13:16:13 M: 41193472    INFO: Loading skin file: [[MyVideoNav]].xml
13:16:14 M: 40263680   DEBUG: CGUIMediaWindow::GetDirectory (videodb://1/2/)
13:16:14 M: 40271872   DEBUG:   [[ParentPath]] = [videodb://1/2/]
13:16:14 M: 40271872   DEBUG: CVideoDatabase::GetSetsNav query: select sets.idSet,sets.strSet,count(1),count(files.playCount) from sets join setlinkmovie on sets.idSet=setlinkmovie.idSet join movie on setlinkmovie.idMovie=movie.idMovie join files on files.idFile=movie.idFile  group by sets.idSet
13:16:14 M: 40271872   DEBUG: CVideoDatabase::GetMoviesByWhere query: select * from movieview where movieview.idmovie NOT in (select idmovie from setlinkmovie)
13:16:14 M: 40247296   DEBUG: ------ Window Init (DialogBusy.xml) ------
13:16:14 M: 40247296    INFO: Loading skin file: [[DialogBusy]].xml
13:16:15 M: 39047168   DEBUG: Time for actual SQL query = 854
13:16:17 M: 39178240   DEBUG: Time to retrieve movies from dataset = 2331
13:16:17 M: 39206912   DEBUG: CFileItemList::Sort, sorting took 6 millis
13:16:17 M: 39206912   DEBUG: Saving fileitems [videodb://1/2/]
13:16:18 M: 39206912   DEBUG:   -- items: 265, sort method: 24, ascending: true
13:16:19 M: 38334464   DEBUG: DIRECTORY::CSMBDirectory::OpenDir - Using authentication url smb://BASEMENT/MOVIES/Disney%20Movies
13:16:19 M: 38215680   DEBUG: ------ Window Init (MusicOverlay.xml) ------
13:16:19 M: 38215680    INFO: Loading skin file: [[MusicOverlay]].xml
13:16:21 M: 33107968   DEBUG: DIRECTORY::CSMBDirectory::OpenDir - Using authentication url smb://BASEMENT/Movies
13:16:21 M: 33021952   DEBUG: SECTION:UnloadDelayed(DLL: Q:\system\players\PAPlayer\MACDll.dll)
13:16:21 M: 33161216    INFO: XCURL::DllLibCurlGlobal::CheckIdle - Closing session to http://scfire-ntc-aa06.stream.aol.com (easy=00CA9450, multi=00000000)
13:16:21 M: 33083392   DEBUG: ------ Window Deinit (DialogBusy.xml) ------
13:16:21 M: 33742848   DEBUG: SECTION:UnloadDelayed(DLL: Q:\system\libid3tag.dll)
13:16:22 M: 33841152   DEBUG: CApplication::OnKey: 167 pressed, action is 4
13:16:25 M: 33841152   DEBUG: CApplication::OnKey: 11 pressed, action is 7
13:16:26 M: 33837056   DEBUG: CPlayerCoreFactory::GetPlayers(smb://BASEMENT/MOVIES/The.6th.Day.2000.SE.iNTERNAL.DVDRiP.XViD-aGGr0-CD1.avi)
13:16:26 M: 33837056   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: un-named
13:16:26 M: 33837056   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: un-named
13:16:26 M: 33837056   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv/mms
13:16:26 M: 33837056   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: myth/cmyth
13:16:26 M: 33837056   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/rtmp/htsp/udp
13:16:26 M: 33837056   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
13:16:26 M: 33837056   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
13:16:26 M: 33837056   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
13:16:26 M: 33837056   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
13:16:26 M: 33837056   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
13:16:26 M: 33837056   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
13:16:26 M: 33837056   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: asf/mp4
13:16:26 M: 33837056   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: ogv/flv/mov
13:16:26 M: 33837056   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
13:16:26 M: 33837056   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
13:16:26 M: 33837056   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (2)
13:16:26 M: 33837056   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
13:16:26 M: 33837056   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
13:16:26 M: 33837056   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
13:16:26 M: 33837056   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: MPlayer (2)
13:16:26 M: 33837056   DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players
13:16:26 M: 33837056    INFO: PAPlayer: End of playback reached
13:16:27 M: 35287040   DEBUG: ------ Window Init (DialogBusy.xml) ------
13:16:27 M: 35287040    INFO: Loading skin file: [[DialogBusy]].xml

Files

xbmc.log (12.3 KB) xbmc.log Log abevi, 28/12/2010 12:53 PM
Actions
Copy link
 #1

Updated by arnova over 13 years ago

Any idea when this problem first started to occur?

Actions
Copy link
 #2

Updated by arnova over 13 years ago

Please attach full debug logs as attachement!

Actions
Copy link
 #3

Updated by arnova over 13 years ago

  • Status changed from New to In Progress
Actions
Copy link
 #4

Updated by abevi over 13 years ago

This issue isn't limited to just trying to play a video while a shoutcast stream is active. You can reproduce this issue by simply starting a shoutcast stream, then trying to stop it.

The stream stops, but the busy indicator is never removed.

I've attached a full log from SVN30762. This issue seems to have popped up in the last month or so.

Actions
Copy link
 #5

Updated by arnova over 13 years ago

  • Status changed from In Progress to Closed
  • Resolution set to fixed

Nasty regression. Fixed in r30764

Actions
Copy link
 #6

Updated by abevi over 13 years ago

Thanks dude, works perfectly :)

Actions
Copy link

Also available in: Atom PDF