Bug 15844 - Server takes 10 minutes to start up, pins cpu
: Server takes 10 minutes to start up, pins cpu
Status: UNCONFIRMED
Product: Logitech Media Server
Classification: Unclassified
Component: Misc
: 7.4.2
: PC Fedora
: -- normal (vote)
: ---
Assigned To: Unassigned bug - please assign me!
:
Depends on:
Blocks:
  Show dependency treegraph
 
Reported: 2010-03-06 07:37 UTC by Marc Reichman
Modified: 2012-03-13 04:44 UTC (History)
1 user (show)

See Also:
Category: ---


Attachments

Note You need to log in before you can comment on or make changes to this bug.
Description Marc Reichman 2010-03-06 07:37:15 UTC
This is originally posted here:
http://forums.slimdevices.com/showthread.php?t=75907

The summary is that the server takes about 10 minutes to be responsive to the web UI and to my players (slimp3, stream.mp3). I've done the best debugging I can do, and it seems to be taking a very long time pre-caching the albums, artists, genres, etc.. for the party mode (0 and 1) for Jive. Here is my library and system info:

Fedora core 3, with perl updated to 5.8.8 via SRPMs. Bundled MySQL (using tgz version), Athlon XP 1700+, 512M, dedicated server for slimserver. Same server spec since slimp3 server 4.x days, same rough library size.

Specs:
Version: 7.4.2 - r30215 @ Fri Feb 19 16:24:20 MST 2010
Hostname: <snip>
Server IP Address: <snip>
Server HTTP Port Number: 9000
Operating system: Red Hat - EN - utf8
Platform Architecture: i686-linux
Perl Version: 5.8.8 - i386-linux-thread-multi
MySQL Version: 5.0.21-standard
Total Players Recognized: 1

Total Tracks: 54,181
Total Albums: 4,131
Total Artists: 1,504
Total Genres: 170
Total Playing Time: 4036:56:05

Here are log snippets:
[10-03-04 13:31:53.3256] main::init (320) Starting Squeezebox Server (v7.4.2, r30215, Fri Feb 19 16:24:20 MST 2010) perl 5.008008
[10-03-04 13:31:53.3953] main::init (337) Squeezebox Server OS Specific init...
[10-03-04 13:31:53.3965] main::init (381) Squeezebox Server settings effective user and group if requested...
[10-03-04 13:31:53.3975] main::init (398) Squeezebox Server binary search path init...
[10-03-04 13:31:53.3993] main::init (402) Squeezebox Server PluginManager init...
[10-03-04 13:31:53.4941] main::init (405) Squeezebox Server strings init...
[10-03-04 13:31:53.5012] Slim::Utils::Strings::loadStrings (123) Retrieving string data from string cache: /usr/local/squeezeboxserver/Cache/stringcache.i686-linux.bin
[10-03-04 13:31:53.5128] main::init (409) Squeezebox Server SQL init...
[10-03-04 13:31:53.7679] main::init (413) Async DNS init...
[10-03-04 13:31:53.7692] main::init (416) Async HTTP init...
[10-03-04 13:31:53.7717] main::init (420) Firmware init...
[10-03-04 13:31:53.7739] main::init (423) Squeezebox Server Info init...
[10-03-04 13:31:54.4228] main::init (438) Squeezebox Server IR init...
[10-03-04 13:31:54.7070] main::init (441) Squeezebox Server Request init...
[10-03-04 13:31:54.7196] main::init (444) Squeezebox Server Buttons init...
[10-03-04 13:31:54.7258] main::init (452) UDP init...
[10-03-04 13:31:54.7316] main::init (455) Slimproto Init...
[10-03-04 13:31:54.7341] main::init (458) Cache init...
[10-03-04 13:31:54.7390] main::init (461) SqueezeNetwork Init...
[10-03-04 13:31:54.7402] main::init (470) Squeezebox Server HTTP init...
[10-03-04 13:31:54.8879] main::init (474) Source conversion init..
[10-03-04 13:31:54.9071] main::init (483) Squeezebox Server Web Settings init...
[10-03-04 13:31:54.9888] main::init (487) Menu init...
[10-03-04 13:31:54.9929] main::init (496) Squeezebox Server Alarms init...
[10-03-04 13:31:54.9941] main::init (500) Squeezebox Server Load Plugins...
[10-03-04 13:31:57.4148] main::init (503) Squeezebox Server Jive init...

Here is another, more debugged from a different run:
10-03-04 13:49:48.1462] main::init (503) Squeezebox Server Jive init...
[10-03-04 13:49:48.1494] Slim::Control::Jive::buildCaches (182) Begin function
[10-03-04 13:49:48.3200] Slim::Control::Jive::buildCaches (191) Pre-caching 4131 album items for partymode:0

This is where it hangs for about 5 minutes, then artists and genres take another few. Then it hangs again for the same cycle with partymode:1. I did some analysis with mysql while this is happening, and it seems like all of the relevant queries happen quickly (nothing shows up in slow query log with query time set to 2 seconds), so it seems like the handling of the resultsets (or sorting or something) is taking the time.

A few questions:
1. Can I turn off all the jive stuff? I only use a slimp3 or the stream.mp3 so it's useless code to me.
2. Can I diagnose at a deeper level? I debugged at the control request and query points and there's not much more than the albums query execution. I can't seem to diagnose at a deeper level.

This is frustrating since I don't use the code in question and it hangs my startup for over 10 minutes, every single time I launch the server.
Comment 1 Michael Herger 2012-03-12 23:38:59 UTC
Is this still an issue with latest software?
Comment 2 Marc Reichman 2012-03-13 04:44:56 UTC
No I believe this went away in like 7.5 maybe? This was quite a while ago :) I don't even have the same server HW anymore.

(In reply to comment #1)
> Is this still an issue with latest software?