Kodi Community Forum

Hi folks,

Noticed in two separate instances that I can no longer navigate within XBMC but the background process (activity) is still running.

1st Instance
~~~~~~~~
1. Idle at XBMC home screen
2. Screensaver set to: Dim, 10%, 1 minute
3. Auto Temp control on at 55 C
4. Establish FTP connection (FlashFXP, CuteFTP, BeyondCompare 2)
5. Transfer 130GB of files over to G Partition

After approximately 2.5 hours, using the D-Pad or other controller buttons does not nothing. However, the FTP transfer action continued to it's end - approximately 1.5 hours later. Had to restart the XBOX to regain control.

2nd Instance
~~~~~~~~
1. Screensaver set to: Dim, 10%, 1 minute
2. Auto Temp control on at 55 C
3. Navigate to Music
4. In Files View, navigate to G\Music\MP3Audio
5. Select Scan All To Database

After approximately 2.5 hours, using the D-Pad or other controller buttons does not nothing. However, the Scan All To Database action continued to it's end - approximately 1 hour later. Had to restart the XBOX to regain control.

I do not experience this problem when performing the same activity on the last June CVS.

I can turn on logging if you like, but considering XBMC hasn't really "crashed" I'm not sure of useful it would be. In any case, let me know.

Cheers,

KZ

something has crashed, thus a debug log will likely help.

Hi elupus,

Here are the pertinent parts of the debug log; the log is 18 MB in size; I suspect it's an out-of-memory issue as a result of encountering multiple errors over time.

In any case, here's the Top:

<><><><><>< TOP ><><><><><>

20:57:44 M: 52838400 NOTICE: -----------------------------------------------------------------------
20:57:44 M: 52817920 NOTICE: Starting XBoxMediaCenter. Built on Oct 4 2006
20:57:44 M: 52817920 NOTICE: Q is mapped to: Harddisk0\Partition6\apps\XBMC
20:57:44 M: 52817920 NOTICE: Log File is located: Q:\xbmc.log
20:57:44 M: 52817920 NOTICE: -----------------------------------------------------------------------
20:57:44 M: 52817920 NOTICE: Setup DirectX
20:57:45 M: 52793344 NOTICE: load settings...
20:57:45 M: 52789248 NOTICE: loading T:\guisettings.xml
20:57:45 M: 52637696 NOTICE: Getting hardware information now...
20:57:45 M: 52637696 NOTICE: Checking resolution 10
20:57:45 M: 52637696 NOTICE: Setting autoresolution mode 5
20:57:45 M: 52727808 INFO: Key combination detected for full debug logging (X+Y)
20:57:45 M: 52727808 INFO: map drives...
20:57:45 M: 52727808 INFO: map drive C:
20:57:45 M: 52727808 INFO: map drive E:
20:57:45 M: 52727808 INFO: map drive D:
20:57:45 M: 52727808 INFO: map drive F:
20:57:47 M: 52727808 INFO: map drive G:
20:57:47 M: 52727808 INFO: Drives are mapped
20:57:47 M: 52727808 INFO: Checking skinpath existance, and existence of keymap.xml:Q:\skin...
20:57:47 M: 52727808 DEBUG: Setting resolution 5
20:57:47 M: 52727808 DEBUG: We set resolution 5
20:57:47 M: 48558080 DEBUG: Setting resolution 5
20:57:47 M: 49790976 DEBUG: We set resolution 5
20:57:47 M: 49790976 INFO: load language info file:Q:\language\english\langinfo.xml
20:57:47 M: 49266688 INFO: load language file:Q:\language\english\strings.xml
20:57:47 M: 49094656 NOTICE: loaded Q:\system\players\mplayer\ISO639-1.xml
20:57:47 M: 49045504 NOTICE: loaded Q:\system\players\mplayer\ISO639-2.xml
20:57:47 M: 49045504 INFO: load keymapping
20:57:47 M: 49045504 INFO: Loading P:\keymap.xml
20:57:47 M: 48951296 ERROR: Window Translator: Can't find window mymusictop100
20:57:47 M: 48988160 INFO: Checking skin version of: Project Mayhem III
20:57:47 M: 49000448 INFO: Skin version is: 2.0
20:57:47 M: 49000448 INFO: GUI format 720x480 NTSC 16:9
20:57:47 M: 49000448 INFO: install unhandled exception filter
20:57:47 M: 49000448 INFO: creating subdirectories
20:57:47 M: 49000448 INFO: userdata folder: q:\userdata
20:57:47 M: 49000448 INFO: recording folder:
20:57:47 M: 49000448 INFO: screenshots folder:
20:57:47 M: 49000448 INFO: thumbnails folder:q:\userdata\Thumbnails
20:57:47 M: 49000448 NOTICE: Checking the Date!
20:57:47 M: 49000448 INFO: - Current Date is: 8-10-2006
20:57:47 M: 49000448 NOTICE: start dvd mediatype detection
20:57:47 M: 48476160 NOTICE: initializing playlistplayer
20:57:47 M: 47951872 NOTICE: load default skin:[Project Mayhem III]
20:57:47 M: 47951872 INFO: load skin from:Q:\skin\Project Mayhem III
20:57:47 M: 47951872 INFO: delete old skin...
20:57:47 M: 47951872 INFO: Default 4:3 resolution directory is Q:\skin\Project Mayhem III\pal
20:57:47 M: 47951872 INFO: Default 16:9 resolution directory is Q:\skin\Project Mayhem III\pal16x9
20:57:47 M: 47951872 INFO: Skin version is: 2.0
20:57:47 M: 47951872 INFO: Loading skin includes from Q:\skin\Project Mayhem III\pal\includes.xml
20:57:47 M: 47943680 INFO: load fonts for skin...
20:57:47 M: 47943680 INFO: Loading fonts from Q:\skin\Project Mayhem III\ntsc16x9\font.xml
20:57:47 M: 47935488 INFO: Load font:common-font10.xpr path:Q:\skin\Project Mayhem III\fonts\common-font10.xpr
20:57:47 M: 47849472 INFO: Load font:common-font12.xpr path:Q:\skin\Project Mayhem III\fonts\common-font12.xpr
20:57:47 M: 47759360 INFO: Load font:common-font13.xpr path:Q:\skin\Project Mayhem III\fonts\common-font13.xpr
20:57:47 M: 47628288 INFO: Load font:matrix8.xpr path:Q:\skin\Project Mayhem III\fonts\matrix8.xpr
20:57:47 M: 47501312 INFO: Load font Confused

pecial-normal.xpr path:Q:\skin\Project Mayhem III\fonts\special-normal.xpr
20:57:47 M: 47349760 INFO: Load font Confused

pecial-bold.xpr path:Q:\skin\Project Mayhem III\fonts\special-bold.xpr
20:57:47 M: 47173632 INFO: load new skin...
20:57:47 M: 47185920 INFO: Skin version is: 2.0
20:57:47 M: 47185920 INFO: Loading skin file: home.xml
20:57:47 M: 46936064 INFO: Loading references file: Q:\skin\Project Mayhem III\ntsc16x9\references.xml
20:57:48 M: 46768128 INFO: Loading user windows, path Q:\skin\Project Mayhem III\pal16x9
20:57:48 M: 46768128 INFO: Loading skin file: Q:\skin\Project Mayhem III\pal16x9\custom1_SettingsSubmenu.xml
20:57:48 M: 46764032 INFO: Loading skin file: Q:\skin\Project Mayhem III\pal16x9\custom2_SkinSettings.xml
20:57:48 M: 46768128 INFO: Loading skin file: Q:\skin\Project Mayhem III\pal16x9\custom5_SlideshowOverlay.xml
20:57:48 M: 46768128 INFO: Loading user windows, path Q:\skin\Project Mayhem III\pal
20:57:48 M: 46768128 INFO: Loading skin file: Q:\skin\Project Mayhem III\pal\custom1_SettingsSubmenu.xml
20:57:48 M: 46768128 INFO: Loading skin file: Q:\skin\Project Mayhem III\pal\custom2_SkinSettings.xml
20:57:48 M: 46768128 INFO: Loading skin file: Q:\skin\Project Mayhem III\pal\custom5_SlideshowOverlay.xml
20:57:48 M: 46768128 INFO: initialize new skin...
20:57:48 M: 46768128 INFO: Loading skin file: Pointer.xml
20:57:48 M: 46411776 INFO: Loading skin file: MusicOverlay.xml
20:57:48 M: 46411776 INFO: Loading skin file: VideoOverlay.xml
20:57:48 M: 46411776 INFO: Loading skin file: DialogVolumeBar.xml
20:57:48 M: 46403584 INFO: Loading skin file: DialogSeekbar.xml
20:57:48 M: 46366720 INFO: Loading skin file: DialogKaiToast.xml
20:57:48 M: 46301184 INFO: Loading skin file: DialogMuteBug.xml
20:57:48 M: 45907968 INFO: Loading Q:\skin\Project Mayhem III\sounds\sounds.xml
20:57:48 M: 45907968 INFO: skin loaded...

... text box limit ...

20:57:48 M: 46432256 DEBUG: Activating window ID: 12999
20:57:48 M: 46432256 DEBUG: Checking if window ID 12999 is locked.
20:57:48 M: 46002176 INFO: Loading skin file: Startup.xml
20:57:48 M: 46002176 WARNING: use dashboard
20:57:48 M: 46002176 INFO: removing tempfiles
20:57:48 M: 46002176 NOTICE: initialize done
20:57:48 M: 46002176 INFO: Key combination detected for full debug logging (X+Y)
20:57:48 M: 46002176 NOTICE: Running the application...
20:57:48 M: 46002176 DEBUG: CApplication::OnMessage : Translating ReplaceWindow(Home)
20:57:48 M: 46002176 DEBUG: CApplication::OnMessage : To ReplaceWindow(Home)
20:57:48 M: 46002176 DEBUG: Activating window ID: 10000
20:57:48 M: 46002176 DEBUG: Checking if window ID 10000 is locked.
20:57:48 M: 42319872 INFO: CNetwork::LogState - Link: full duplex
20:57:48 M: 42319872 INFO: CNetwork::LogState - Link: 100 mbps
20:57:48 M: 41943040 INFO: CNetwork::LogState - State: dns
20:57:48 M: 41943040 INFO: CNetwork::LogState - State: dhcp
20:57:48 M: 41631744 INFO: CNetwork::LogState - ip: 192.168.200.107
20:57:48 M: 39448576 INFO: Get URL: http://feeds.feedburner.com/XboxScene
20:57:48 M: 39448576 INFO: CNetwork::LogState - subnet: 255.255.255.0
20:57:48 M: 39448576 INFO: CNetwork::LogState - gateway: 192.168.1.1
20:57:48 M: 39448576 INFO: CNetwork::LogState - dns: 192.168.1.10, 192.168.1.11
20:57:48 M: 39448576 INFO: WEATHER: Downloading weather
20:57:48 M: 39858176 INFO: Get URL: http://xoap.weather.com/weather/local/GM...145f208494
20:57:48 M: 39858176 DEBUG: CNetwork::NetworkMessage - Starting network services
20:57:48 M: 39858176 NOTICE: XBFileZilla: Starting...
20:57:48 M: 37232640 NOTICE: XBFileZilla: Started
20:57:49 M: 37195776 INFO: WEATHER: Weather download successful
20:57:49 M: 32710656 DEBUG: Got rss feed: http://feeds.feedburner.com/XboxScene
20:57:49 M: 32710656 DEBUG: RSS feed encoding: UTF-8
20:57:49 M: 32710656 DEBUG: Parsed rss feed: http://feeds.feedburner.com/XboxScene
20:57:54 M: 37945344 DEBUG: CApplication::OnKey: 277 pressed, action is 122
20:57:54 M: 37945344 DEBUG: Activating window ID: 10111
20:57:54 M: 37945344 DEBUG: Checking if window ID 10111 is locked.
20:57:54 M: 37945344 INFO: Loading skin file: DialogButtonMenu.xml
20:57:55 M: 37736448 DEBUG: CApplication::OnKey: 272 pressed, action is 1
20:57:56 M: 37711872 DEBUG: CApplication::OnKey: 256 pressed, action is 7
20:57:56 M: 37732352 DEBUG: CApplication::OnMessage : Translating XBMC.ActivateWindow(7)
20:57:56 M: 37732352 DEBUG: CApplication::OnMessage : To XBMC.ActivateWindow(7)
20:57:56 M: 37732352 DEBUG: Activating window ID: 10007
20:57:56 M: 37732352 DEBUG: Checking if window ID 10007 is locked.
20:57:56 M: 42848256 INFO: Loading skin file: SettingsSystemInfo.xml
20:57:56 M: 42840064 INFO: Loading skin file: DialogProgress.xml
20:57:59 M: 41725952 DEBUG: CApplication::OnKey: 275 pressed, action is 10
20:57:59 M: 43737088 INFO: Loading skin file: Home.xml
20:58:00 M: 38400000 DEBUG: CApplication::OnKey: 271 pressed, action is 4
20:58:01 M: 38375424 DEBUG: CApplication::OnKey: 271 pressed, action is 4
20:58:01 M: 38400000 DEBUG: CApplication::OnKey: 271 pressed, action is 4
20:58:01 M: 38400000 DEBUG: CApplication::OnKey: 256 pressed, action is 7
20:58:01 M: 38387712 DEBUG: CApplication::OnMessage : Translating ActivateWindow(MyMusic)
20:58:01 M: 38387712 DEBUG: CApplication::OnMessage : To ActivateWindow(MyMusic)
20:58:01 M: 38387712 DEBUG: Activating window ID: 10501
20:58:01 M: 38387712 DEBUG: Checking if window ID 10501 is locked.
20:58:02 M: 43708416 INFO: Attempting to default to:
20:58:02 M: 43696128 INFO: Set page size
20:58:02 M: 43696128 INFO: Set default cache size
20:58:02 M: 43696128 INFO: creating version table
20:58:02 M: 43696128 INFO: create artist table
20:58:02 M: 43696128 INFO: create album table
20:58:02 M: 43696128 INFO: create genre table
20:58:02 M: 43696128 INFO: create path table
20:58:02 M: 43696128 INFO: create song table
20:58:02 M: 43696128 INFO: create albuminfo table
20:58:02 M: 43696128 INFO: create albuminfosong table
20:58:02 M: 43696128 INFO: create thumb table
20:58:02 M: 43696128 INFO: create partymode table
20:58:02 M: 43696128 INFO: create exartistsong table
20:58:02 M: 43696128 INFO: create extragenresong table
20:58:02 M: 43696128 INFO: create exartistalbum table
20:58:02 M: 43696128 INFO: create exgenrealbum table
20:58:02 M: 43696128 INFO: create exartistsong index
20:58:03 M: 43696128 INFO: create exgenresong index
20:58:03 M: 43696128 INFO: create exartistalbum index
20:58:03 M: 43696128 INFO: create exgenrealbum index
20:58:03 M: 43696128 INFO: create album index
20:58:03 M: 43696128 INFO: create genre index
20:58:03 M: 43696128 INFO: create artist index
20:58:03 M: 43696128 INFO: create path index
20:58:03 M: 43696128 INFO: create song index
20:58:03 M: 43696128 INFO: create song index1
20:58:03 M: 43696128 INFO: create song index2
20:58:03 M: 43696128 INFO: create thumb index
20:58:03 M: 43696128 INFO: create albuminfo trigger
20:58:03 M: 43696128 INFO: create song view
20:58:03 M: 43696128 INFO: create album view
20:58:03 M: 43696128 INFO: Loading skin file: MyMusicSongs.xml
20:58:04 M: 43589632 DEBUG: CGUIMediaWindow::GetDirectory ()
20:58:04 M: 43589632 DEBUG: ParentPath = []
20:58:04 M: 43589632 DEBUG: RetrieveMusicInfo() took 0msec
20:58:05 M: 39923712 DEBUG: CApplication::OnKey: 271 pressed, action is 4
20:58:05 M: 39899136 DEBUG: CApplication::OnKey: 271 pressed, action is 4
20:58:05 M: 39899136 DEBUG: CApplication::OnKey: 271 pressed, action is 4
20:58:06 M: 39923712 DEBUG: CApplication::OnKey: 256 pressed, action is 7
20:58:06 M: 39923712 DEBUG: CGUIMediaWindow::GetDirectory (G:\Music)
20:58:06 M: 39923712 DEBUG: ParentPath = []
20:58:06 M: 39923712 DEBUG: RetrieveMusicInfo() took 0msec
20:58:07 M: 39927808 DEBUG: CApplication::OnKey: 271 pressed, action is 4
20:58:07 M: 39903232 DEBUG: CApplication::OnKey: 271 pressed, action is 4
20:58:07 M: 39903232 DEBUG: CApplication::OnKey: 271 pressed, action is 4
20:58:07 M: 39927808 DEBUG: CApplication::OnKey: 256 pressed, action is 7
20:58:07 M: 39923712 DEBUG: CGUIMediaWindow::GetDirectory (G:\Music\MP3Audio)
20:58:07 M: 39923712 DEBUG: ParentPath = [G:\Music]
20:58:07 M: 39923712 DEBUG: RetrieveMusicInfo() took 0msec
20:58:08 M: 39927808 DEBUG: CApplication::OnKey: 261 pressed, action is 117
20:58:08 M: 39927808 INFO: Loading skin file: DialogContextMenu.xml
20:58:08 M: 39927808 DEBUG: CPlayerCoreFactor::GetPlayers(G:\Music)
20:58:11 M: 39813120 DEBUG: CApplication::OnKey: 256 pressed, action is 7
20:58:11 M: 39915520 INFO: Loading skin file: DialogMusicScan.xml
20:58:11 M: 39907328 ERROR: Control 402 in window 10112 has been asked to focus, but it can't
20:58:11 M: 39907328 INFO: Music scan has started ... enabling Tag Reading, and Remote Thumbs

<><><><><>< MIDDLE ><><><><><>

05:18:44 M: 30830592 DEBUG: MUSIC_INFO::CMusicInfoScanner::RetrieveMusicInfo - Reading tag for: G:\Music\MP3Audio\Z\XXXX\Greatest Hits\XXXX.mp3
05:18:45 M: 30748672 INFO: Creating album thumb from memory: q:\userdata\Thumbnails\Music\4ead694c.tbn
05:18:45 M: 30695424 DEBUG: msg: PICTURE::SaveThumb: Unable to save image: q:\userdata\Thumbnails\Music\4ead694c.tbn Error:
05:18:45 M: 30748672 ERROR: PICTURE::CreateAlbumThumbnailFromMemory: exception: memfile FileType: jpeg
05:18:45 M: 30748672 ERROR: Tag loader mp3: Unable to create album art for G:\Music\MP3Audio\Z\XXXX\Greatest Hits\XXXX.mp3 (extension=jpeg, size=79008)
05:18:46 M: 30830592 DEBUG: MUSIC_INFO::CMusicInfoScanner::RetrieveMusicInfo - Tag loaded for: G:\Music\MP3Audio\Z\XXXX\Greatest Hits\XXXX.mp3

<><><><><>< BOTTOM ><><><><><>

05:18:47 M: 30830592 DEBUG: MUSIC_INFO::CMusicInfoScanner: Big Grin

oScan - Finished dir: G:\Music\MP3Audio\Z\ZZ Top\Greatest Hits
05:18:47 M: 35115008 DEBUG: MUSIC_INFO::CMusicInfoScanner: Tongue

rocess - Finished scan
05:18:47 M: 35115008 NOTICE: My Music: Scanning for music info using worker thread, operation took 08:20:36
05:18:47 M: 35115008 INFO: Music scan was stopped or finished ... restoring FindRemoteThumbs
05:18:47 M: 35119104 DEBUG: CGUIMediaWindow::GetDirectory (G:\Music\MP3Audio)
05:18:47 M: 35119104 DEBUG: ParentPath = [G:\Music\MP3Audio]
05:18:47 M: 35119104 DEBUG: RetrieveMusicInfo() took 0msec
07:06:27 M: 36560896 INFO: CNetwork::LogState - Link: full duplex
07:06:27 M: 36560896 INFO: CNetwork::LogState - Link: 100 mbps
07:06:27 M: 36560896 INFO: CNetwork::LogState - State: dns
07:06:27 M: 36560896 INFO: CNetwork::LogState - State: dhcp
07:06:27 M: 36560896 INFO: CNetwork::LogState - ip: 192.168.200.107
07:06:27 M: 36560896 INFO: CNetwork::LogState - subnet: 255.255.255.0
07:06:27 M: 36560896 INFO: CNetwork::LogState - gateway: 192.168.200.1
07:06:27 M: 36560896 INFO: CNetwork::LogState - dns: 192.168.200.10, 192.168.200.11
07:06:27 M: 36560896 DEBUG: CNetwork::NetworkMessage - Stopping network services
07:06:27 M: 36560896 INFO: XBFileZilla: Stopping...
07:06:27 M: 38166528 INFO: XBFileZilla: Stopped
07:06:27 M: 38166528 DEBUG: CNetwork::NetworkMessage - Starting network services
07:06:27 M: 38166528 NOTICE: XBFileZilla: Starting...
07:06:27 M: 36593664 NOTICE: XBFileZilla: Started

ok, yea it could be an out of memory issue.. but that would show up in log. since you probably snipped out those parts it's hard to tell. make a search for any WARNING or ERROR in the log and see if you can spot anything of interest. better is to rar the logfile up and upload it somewhere.

if you can reproduce it using the dim only and ftp transfer (won't log as much i think) it would probably be better, sadly it seem to take alot of time so i'd understand if you wouldn't want to.

Hi elupus,

Where can I upload the RAR'ed debug log for you?

Cheers,

KZ

megaupload.com
or
rapidshare.de

Hi elupus,

Sent you a pm

kzr1y2

elupus

kzr1y2

kzr1y2

kzr1y2

elupus

kzr1y2

elupus

kzr1y2