PDA

View Full Version : Re: 6.0b1 - Web interface hogging CPU



James Dunn
2005-03-12, 16:02
Dan,

Sorry - had to stop for dinner...

Sorry for the longish dump but I've cut it down to what I think is the minimum relevant section.

You can see the initial connection request at the start of the dump (Music was playing on one slimp3 at this point) but the server was almost certainly scanning at the time. The home page was loaded (fishbone skin) at this point, presenting the listing of the playlist on the playing slimp3. The playlist has 58 tracks in it. New music is showing in the left window (probably about 90 items). It looks like it takes about 17 seconds between the initial request and final disconnect but it might only be 7 seconds to render with 10 secs to time-out and disconnect.

BTW - it's still slow but considerably quicker to display without music playing.

Cheers,

James

----8-X----------

2005-03-12 22:32:21.7141 Couldn't find Template::Stash::XS - falling back to pure perl version.
2005-03-12 22:32:21.7331 Server slimserver accepting http connections on port 9000
....
....
....
....
2005-03-12 22:34:38.3112 Accepted connection 1 from 192.168.5.30
2005-03-12 22:34:38.3178 reading request...
2005-03-12 22:34:38.3203 HTTP request: from 192.168.5.30 (HTTP::Daemon::ClientConn=GLOB(0x9c7aa40)) for GET HTTP/1.1 /
2005-03-12 22:34:38.3254 processURL Clients: 192.168.5.18:3483 192.168.5.16:3483 192.168.5.15:3483 192.168.5.17:3483 192.168.5.20:3483 192.168.5.19:3483
2005-03-12 22:34:38.3286 Generating response for (htm, text/html)
2005-03-12 22:34:38.3311 generating from include.html
2005-03-12 22:34:38.3378 generating from homeplayer_list.html
2005-03-12 22:34:38.3446 generating from homeplayer_list.html
2005-03-12 22:34:38.3513 generating from homeplayer_list.html
2005-03-12 22:34:38.3580 generating from homeplayer_list.html
2005-03-12 22:34:38.3646 generating from homeplayer_list.html
2005-03-12 22:34:38.3720 generating from homeplayer_list.html
2005-03-12 22:34:38.3829 generating from select_option.html
2005-03-12 22:34:38.3886 generating from select_option.html
2005-03-12 22:34:38.3941 generating from select_option.html
2005-03-12 22:34:38.3997 generating from select_option.html
2005-03-12 22:34:38.4052 generating from select_option.html
2005-03-12 22:34:38.4107 generating from select_option.html
2005-03-12 22:34:38.4240 generating from index.html
2005-03-12 22:34:38.4373 End request: keepAlive: [1] - waiting for next request on connection = Keep-Alive

2005-03-12 22:34:38.4647 No more messages to send to 192.168.5.30
2005-03-12 22:34:38.4713 No segment to send to 192.168.5.30, waiting for next request..
2005-03-12 22:34:38.5835 reading request...
2005-03-12 22:34:38.5861 HTTP request: from 192.168.5.30 (HTTP::Daemon::ClientConn=GLOB(0x9c7aa40)) for GET HTTP/1.1 /home.html?player=00:04:20:02:01:97&page=browsedb.html?hierarchy=age,track&level=0&&page=BROWSE_NEW_MUSIC&searchchoice=browse.html?
2005-03-12 22:34:38.5900 HTTP parameter player = 00:04:20:02:01:97
2005-03-12 22:34:38.5927 HTTP parameter page = browsedb.html?hierarchy=age,track
2005-03-12 22:34:38.5954 HTTP parameter level = 0
2005-03-12 22:34:38.5979 HTTP parameter from = 1
2005-03-12 22:34:38.6006 HTTP parameter page = BROWSE_NEW_MUSIC
2005-03-12 22:34:38.6034 HTTP parameter searchchoice = browse.html?
2005-03-12 22:34:38.6081 processURL Clients: 192.168.5.18:3483 192.168.5.16:3483 192.168.5.15:3483 192.168.5.17:3483 192.168.5.20:3483 192.168.5.19:3483
2005-03-12 22:34:38.6114 Generating response for (htm, text/html) home.html
2005-03-12 22:34:38.6140 generating from include.html
2005-03-12 22:34:38.6209 generating from homeplayer_list.html
2005-03-12 22:34:38.6276 generating from homeplayer_list.html
2005-03-12 22:34:38.6343 generating from homeplayer_list.html
2005-03-12 22:34:38.6411 generating from homeplayer_list.html
2005-03-12 22:34:38.6479 generating from homeplayer_list.html
2005-03-12 22:34:38.6546 generating from homeplayer_list.html
2005-03-12 22:34:38.6657 generating from select_option.html
2005-03-12 22:34:38.6714 generating from select_option.html
2005-03-12 22:34:38.6770 generating from select_option.html
2005-03-12 22:34:38.6826 generating from select_option.html
2005-03-12 22:34:38.6896 generating from select_option.html
2005-03-12 22:34:38.6952 generating from select_option.html
2005-03-12 22:34:38.7073 generating from home.html
2005-03-12 22:34:38.7384 End request: keepAlive: [2] - waiting for next request on connection = Keep-Alive

2005-03-12 22:34:38.7611 Accepted connection 2 from 192.168.5.30
2005-03-12 22:34:38.7668 No more messages to send to 192.168.5.30
2005-03-12 22:34:38.7762 reading request...
2005-03-12 22:34:38.7789 HTTP request: from 192.168.5.30 (HTTP::Daemon::ClientConn=GLOB(0x9b227bc)) for GET HTTP/1.1 /browsedb.html?hierarchy=age,track&level=0&&page=BROWSE_NEW_MUSIC&player=00:04:20:02:01:97
2005-03-12 22:34:38.7828 HTTP parameter hierarchy = age,track
2005-03-12 22:34:38.7855 HTTP parameter level = 0
2005-03-12 22:34:38.7881 HTTP parameter from = 1
2005-03-12 22:34:38.7909 HTTP parameter page = BROWSE_NEW_MUSIC
2005-03-12 22:34:38.7937 HTTP parameter player = 00:04:20:02:01:97
2005-03-12 22:34:38.7992 processURL Clients: 192.168.5.18:3483 192.168.5.16:3483 192.168.5.15:3483 192.168.5.17:3483 192.168.5.20:3483 192.168.5.19:3483
2005-03-12 22:34:38.8026 Generating response for (htm, text/html) browsedb.html
2005-03-12 22:34:38.8054 generating from include.html
2005-03-12 22:34:38.8184 generating from browsedb_pwdlist.html
2005-03-12 22:34:38.8256 generating from browsedb_list.html
2005-03-12 22:34:38.8778 generating from browsedb_list.html
2005-03-12 22:34:38.8913 reading request...
2005-03-12 22:34:38.8939 HTTP request: from 192.168.5.30 (HTTP::Daemon::ClientConn=GLOB(0x9c7aa40)) for GET HTTP/1.1 /status_header.html?player=00:04:20:02:01:97
2005-03-12 22:34:38.8980 HTTP parameter player = 00:04:20:02:01:97
2005-03-12 22:34:38.9027 processURL Clients: 192.168.5.18:3483 192.168.5.16:3483 192.168.5.15:3483 192.168.5.17:3483 192.168.5.20:3483 192.168.5.19:3483
2005-03-12 22:34:38.9061 Generating response for (htm, text/html) status_header.html
2005-03-12 22:34:38.9114 generating from include.html
2005-03-12 22:34:38.9182 generating from select_option.html
2005-03-12 22:34:38.9238 generating from select_option.html
2005-03-12 22:34:38.9295 generating from select_option.html
2005-03-12 22:34:38.9352 generating from select_option.html
2005-03-12 22:34:38.9409 generating from select_option.html
2005-03-12 22:34:38.9465 generating from select_option.html
2005-03-12 22:34:38.9633 generating from status_header.html
2005-03-12 22:34:39.0733 End request: keepAlive: [3] - waiting for next request on connection = Keep-Alive

2005-03-12 22:34:39.1058 generating from browsedb_list.html
2005-03-12 22:34:39.1202 No more messages to send to 192.168.5.30
2005-03-12 22:34:39.1603 generating from browsedb_list.html
2005-03-12 22:34:39.1737 reading request...
2005-03-12 22:34:39.1765 HTTP request: from 192.168.5.30 (HTTP::Daemon::ClientConn=GLOB(0x9c7aa40)) for GET HTTP/1.1 /playlist.html?player=00:04:20:02:01:97
2005-03-12 22:34:39.1805 HTTP parameter player = 00:04:20:02:01:97
2005-03-12 22:34:39.1853 processURL Clients: 192.168.5.18:3483 192.168.5.16:3483 192.168.5.15:3483 192.168.5.17:3483 192.168.5.20:3483 192.168.5.19:3483
2005-03-12 22:34:39.1888 Generating response for (htm, text/html) playlist.html
2005-03-12 22:34:39.1915 generating from include.html
2005-03-12 22:34:39.2641 generating from status_list.html
2005-03-12 22:34:39.3408 generating from status_list.html
2005-03-12 22:34:39.4191 generating from status_list.html
2005-03-12 22:34:39.4974 generating from status_list.html
2005-03-12 22:34:39.5120 No segment to send to 192.168.5.30, waiting for next request..
2005-03-12 22:34:39.5853 generating from status_list.html
2005-03-12 22:34:39.6002 End request: keepAlive: [4] - waiting for next request on connection = Keep-Alive

2005-03-12 22:34:39.6499 generating from browsedb_list.html
2005-03-12 22:34:39.7109 generating from browsedb_list.html
2005-03-12 22:34:39.7619 generating from browsedb_list.html
2005-03-12 22:34:39.8238 generating from browsedb_list.html
2005-03-12 22:34:39.8792 generating from browsedb_list.html
2005-03-12 22:34:39.9290 generating from browsedb_list.html
2005-03-12 22:34:39.9984 generating from browsedb_list.html
2005-03-12 22:34:40.1221 generating from browsedb_list.html
2005-03-12 22:34:40.2108 generating from browsedb_list.html
2005-03-12 22:34:40.2720 generating from browsedb_list.html
2005-03-12 22:34:40.3942 generating from browsedb_list.html
2005-03-12 22:34:40.4406 generating from browsedb_list.html
2005-03-12 22:34:40.5082 generating from browsedb_list.html
2005-03-12 22:34:40.5636 generating from browsedb_list.html
2005-03-12 22:34:40.6074 generating from browsedb_list.html
2005-03-12 22:34:40.6603 generating from browsedb_list.html
2005-03-12 22:34:40.7189 generating from browsedb_list.html
2005-03-12 22:34:40.7793 generating from browsedb_list.html
2005-03-12 22:34:40.8540 generating from browsedb_list.html
2005-03-12 22:34:41.0156 generating from browsedb_list.html
2005-03-12 22:34:41.0648 generating from browsedb_list.html
2005-03-12 22:34:41.1168 generating from browsedb_list.html
2005-03-12 22:34:41.1680 generating from browsedb_list.html
2005-03-12 22:34:41.2151 generating from browsedb_list.html
2005-03-12 22:34:41.2598 generating from browsedb_list.html
2005-03-12 22:34:41.3048 generating from browsedb_list.html
2005-03-12 22:34:41.3592 generating from browsedb_list.html
2005-03-12 22:34:41.4110 generating from browsedb_list.html
2005-03-12 22:34:41.4417 generating from browsedb_list.html
2005-03-12 22:34:41.4925 generating from browsedb_list.html
2005-03-12 22:34:41.5542 generating from browsedb_list.html
2005-03-12 22:34:41.6165 generating from browsedb_list.html
2005-03-12 22:34:41.6570 generating from browsedb_list.html
2005-03-12 22:34:41.7208 generating from browsedb_list.html
2005-03-12 22:34:41.9382 generating from browsedb_list.html
2005-03-12 22:34:41.9837 generating from browsedb_list.html
2005-03-12 22:34:42.0434 generating from browsedb_list.html
2005-03-12 22:34:42.0982 generating from browsedb_list.html
2005-03-12 22:34:42.1468 generating from browsedb_list.html
2005-03-12 22:34:42.1961 generating from browsedb_list.html
2005-03-12 22:34:42.2437 generating from browsedb_list.html
2005-03-12 22:34:42.2830 generating from browsedb_list.html
2005-03-12 22:34:42.3395 generating from browsedb_list.html
2005-03-12 22:34:42.4005 generating from browsedb_list.html
2005-03-12 22:34:42.4353 generating from browsedb_list.html
2005-03-12 22:34:42.5040 generating from browsedb_list.html
2005-03-12 22:34:42.6102 generating from browsedb_list.html
2005-03-12 22:34:42.6730 generating from browsedb_list.html
2005-03-12 22:34:42.7274 generating from browsedb_list.html
2005-03-12 22:34:42.7939 generating from browsedb_list.html
2005-03-12 22:34:42.8321 generating from browsedb_list.html
2005-03-12 22:34:42.8804 generating from browsedb_list.html
2005-03-12 22:34:42.9284 generating from browsedb_list.html
2005-03-12 22:34:42.9808 generating from browsedb_list.html
2005-03-12 22:34:43.0377 generating from browsedb_list.html
2005-03-12 22:34:43.0751 generating from browsedb_list.html
2005-03-12 22:34:43.1698 generating from browsedb_list.html
2005-03-12 22:34:43.2195 generating from browsedb_list.html
2005-03-12 22:34:43.2717 generating from browsedb_list.html
2005-03-12 22:34:43.3201 generating from browsedb_list.html
2005-03-12 22:34:43.3712 generating from browsedb_list.html
2005-03-12 22:34:43.4232 generating from browsedb_list.html
2005-03-12 22:34:43.4812 generating from browsedb_list.html
2005-03-12 22:34:43.5340 generating from browsedb_list.html
2005-03-12 22:34:43.5851 generating from browsedb_list.html
2005-03-12 22:34:43.6382 generating from browsedb_list.html
2005-03-12 22:34:43.6950 generating from browsedb_list.html
2005-03-12 22:34:43.7786 generating from browsedb_list.html
2005-03-12 22:34:43.8297 generating from browsedb_list.html
2005-03-12 22:34:43.9156 generating from browsedb_list.html
2005-03-12 22:34:43.9662 generating from browsedb_list.html
2005-03-12 22:34:44.0243 generating from browsedb_list.html
2005-03-12 22:34:44.1047 generating from browsedb_list.html
2005-03-12 22:34:44.1935 generating from browsedb_list.html
2005-03-12 22:34:44.2438 generating from browsedb_list.html
2005-03-12 22:34:44.2981 generating from browsedb_list.html
2005-03-12 22:34:44.3601 generating from browsedb_list.html
2005-03-12 22:34:44.4271 generating from browsedb_list.html
2005-03-12 22:34:44.5041 generating from browsedb_list.html
2005-03-12 22:34:44.5673 generating from browsedb_list.html
2005-03-12 22:34:44.6209 generating from browsedb_list.html
2005-03-12 22:34:44.6766 generating from browsedb_list.html
2005-03-12 22:34:44.7420 generating from browsedb_list.html
2005-03-12 22:34:44.7887 generating from browsedb_list.html
2005-03-12 22:34:44.8398 generating from browsedb_list.html
2005-03-12 22:34:44.8845 generating from browsedb_list.html
2005-03-12 22:34:44.9315 generating from browsedb_list.html
2005-03-12 22:34:44.9832 generating from browsedb_list.html
2005-03-12 22:34:45.0283 generating from browsedb_list.html
2005-03-12 22:34:45.0677 generating from browsedb_list.html
2005-03-12 22:34:45.1149 generating from browsedb_list.html
2005-03-12 22:34:45.1655 generating from browsedb_list.html
2005-03-12 22:34:45.2204 generating from browsedb_list.html
2005-03-12 22:34:45.2712 generating from browsedb_list.html
2005-03-12 22:34:45.3210 generating from browsedb_list.html
2005-03-12 22:34:45.3715 generating from browsedb_list.html
2005-03-12 22:34:45.4245 generating from browsedb_list.html
2005-03-12 22:34:45.4420 generating from browsedb.html
2005-03-12 22:34:45.5146 End request: keepAlive: [1] - waiting for next request on connection = Keep-Alive

2005-03-12 22:34:55.5159 reading request...
2005-03-12 22:34:55.5186 Client at 192.168.5.30 disconnected. (half-closed)

-----Original Message-----
From: discuss-bounces (AT) lists (DOT) slimdevices.com [mailto:discuss-bounces (AT) lists (DOT) slimdevices.com]On Behalf Of Dan Sully
Sent: Saturday, March 12, 2005 8:54 PM
To: Slim Devices Discussion
Subject: [slim] Re: 6.0b1 - Web interface hogging CPU

* James Dunn shaped the electrons to say...

>Server info:
>RH 7.1
>Celeron 1.1
>512Mb memory.
>About 5500 tracks.
>6 x Slimp3s
>1 x Squeezebox

Ah, sorry - I missed that before. :)

>Playing music in one room only (I think - unless my wife was doing something). The server is dedicated to Slimserver and the process is niced to prioritise over everything else. No other apps or UI interaction.
>
>I've just re-started the server service and I have the same problem (even after leaving for about 20 mins).

Could you run the server with --d_http debugging turned on? It will produce a decent amount of output.

-D
--
This knob controls the thing that changes when you turn it. - noah