Bug 8979 - 7.1 server stops responding
: 7.1 server stops responding
Status: RESOLVED WONTFIX
Product: Logitech Media Server
Classification: Unclassified
Component: Platform Support
: 7.1
: Macintosh MacOS X 10.5
: -- normal (vote)
: ---
Assigned To: Unassigned bug - please assign me!
:
Depends on:
Blocks:
  Show dependency treegraph
 
Reported: 2008-08-01 05:46 UTC by James Rome
Modified: 2008-08-16 08:40 UTC (History)
1 user (show)

See Also:
Category: ---


Attachments
server.log (deleted)
2008-08-01 10:05 UTC, James Rome
Details
server.log.zip (346.21 KB, application/octet-stream)
2008-08-01 10:06 UTC, James Rome
Details

Note You need to log in before you can comment on or make changes to this bug.
Description James Rome 2008-08-01 05:46:46 UTC
I see this on my OS X server much more than on my Linux server. My MacPro has 8 GB memory and its a dual quad-core machine, so it should have much more oomph than the Linux server which is just a single quad-core machine.

What happens is that the server becomes slower and slower to respond to requests, either from my squeeze boxes or from SoftSqueeze. It can take 10 seconds to start playing something, for example. Then it stops responding altogether. For example, my bedroom squeezebox became unable to pause the stream. I could hold the pause button down and the screen read stopping. The stream stopped , but the stopping message remained on the screen. When the stream was going, navigating to a different selection and pressing Play did not change the stream.

Restarting the server fixed this, so it is definitely on the server end.
Comment 1 James Richardson 2008-08-01 09:49:45 UTC
James: Can you please get some log data for us to look at.  

Turn debug on for the following and attach a clean log to this bug report:

Player.source
Player.Streaming(all)
Server.memory

Other questions:
What OS version is on your MAC
What Router do you have, Model & FW version
What Players do you have
How is everything connected to the network
About how long does it take for the slowdown to occur
What type of media are you streaming (Local Music, Premium Service, Radio)
Comment 2 James Rome 2008-08-01 10:06:30 UTC
Created attachment 3728 [details]
server.log.zip

my log before debugging
Comment 3 James Rome 2008-08-01 10:14:13 UTC
What OS version is on your MAC - 10.5.4
What Router do you have, Model & FW version - Linksys WRT45gs. I use the iptables firewall

What Players do you have: 2 squeezeboxes, 1 SoftSqueeze

How is everything connected to the network: The two SqueezeBoxes are wireless using WPA2. SoftSqueeze is on the Mac itself.

About how long does it take for the slowdown to occur: Maybe 6 hours.

What type of media are you streaming (Local Music, Premium Service, Radio):
I listen to a lot of my 300 GB of MP3s, plus XM, Radioio, and AlienBBC

I am attaching the old log before I clear it.
Comment 4 James Richardson 2008-08-01 10:53:36 UTC
James: you have the players synced?
Comment 5 James Richardson 2008-08-01 10:57:56 UTC
One other question: what 3rd party plug-ins do you use
Comment 6 James Rome 2008-08-01 11:00:56 UTC
No on synching

I use

Alien BBC (v2.01b1)
The AlienBBC plugin is designed to allow you to listen to BBC Radio streams on your SqueezeBox. It also adds the ability to listen to many other radio streams.
Settings    
Command Line Interface (CLI) (v1.0)
The Command Line Interface plugin allows SqueezeCenter and the Squeezeboxen to be controlled remotely over a TCP/IP connection, for example by a third party automation system like AMX or Crestron.
Settings    
Customize Controller (v0.1)
You may customize your Squeezebox Controller by selecting additional wallpaper images and sound effects which will become available on the settings menus for your Controller.
Settings    
Date and Time Screensaver (v1.0)
Date and Time Screensaver
Settings    
Digital Inputs (v1.0)
Digital Input for Transporter
   
Favorites (v1.0)
Save and edit your favorites
Settings    
Information Browser (v1.0)
Retrieve information from internet sources and displaying via Remote, Server or Player displays. See the settings section for information about adding additional information sources.
Settings    
iTunes (v1.0)
iTunes integration
Settings    
Last.fm Audioscrobbler (v1.0)
Last.fm a music service that builds up your personal music profile by tracking the music you listen to. Based on your taste, Last.fm provides customized radio stations, music and event recommendations, free downloads and more. Join for free at www.last.fm/join or find out more at www.last.fm/tour.
Settings    
Last.fm Radio (v1.0)
Listen to customized Last.fm Radio stations based on your listening history.
   
Lazy Search Music (v3.1)
Perform searches more quickly and easily using your player's remote control.
Settings    
Live Music Archive (v1.0)
Access to the Live Music Archive.
   
Live365 (v2.0)
Access to Live365 Internet Radio stations
   
MP3tunes Music Locker (v1.0)
Access music from your MP3tunes Music Locker. This plugin requires a SqueezeNetwork account.
   
MusicIP (v1.0)
MusicIP integration
Settings    
My Radio Stations (v0.1)
Plugin to store your favorite radio stations under the Radio menu. This plugin may be enabled to allow you to store your radio stations separately from your Favorites.
   
Pandora (v3.0)
Pandora
   
Podcasts (v1.0)
Access to Podcasts
Settings    
Radio IO (v1.0)
Access to RadioIO
   
RadioTime (v1.0)
RadioTime - Local Internet Radio
Settings    
Random Mix (v1.0)
Jukebox For your Music
   
Rescan Music Library (v1.0)
Automatic Library Scanning
Settings    
Rhapsody (v1.0)
Rhapsody
   
RS232 (v1.1)
RS232 access for Transporter
   
RSS News Ticker (v1.0)
Access to RSS News Feeds
Settings    
Save Playlist (v1.0)
Save Playlists from the Player UI
   
Server & Network Health (v1.0)
Monitor Server & Network Health
   
SHOUTcast (v1.0)
Access to SHOUTcast Internet Radio.
   
SIRIUS Internet Radio (v1.0)
SIRIUS Internet Radio
   
Slacker (v1.0)
Slacker
   
SlimTris (v1.0)
Tetris for your Squeezebox or Transporter!
   
Snow Screensaver (v1.1)
Snow Screensaver
   
Song Scanner (v2.0)
Player interface for jumping to arbitrary positions within the currently playing song
   
Sounds & Effects (v1.0)
Sounds & Effects
   
Staff Picks (v1.0)
Staff Picks - Best of Internet Radio
   
SuperDateTime Screensaver (v5.5.1)
Displays local time, date, weather, sports, and stocks information.
Settings    
Visualizer Screensaver (v1.0)
Visualizer Screensaver
   
Weather, Date and Time (v2.0)
PLUGIN_SCREENSAVER_WEATHERTIME_DESC
Settings    
XM Radio Online (v2.0)
Play XM Radio Online internet radio stations
Settings    
xPL Interface (v1.0)
xPL Interface
   

Comment 7 James Rome 2008-08-01 11:28:21 UTC
So, while the Gershwin was playing , I tried to stop it and play something else. Notice the almost 1 minute delay 1t 14:07:14. SoftSqueeze was totally non-responsive, and I was hitting a lot of keys to no effect.

[08-08-01 14:07:13.9189] Slim::Player::Source::openSong (2080)   command: -
[08-08-01 14:07:13.9191] Slim::Player::Source::openSong (2099) Streaming with format: mp3
[08-08-01 14:07:13.9194] Slim::Player::Source::openSong (2113) Opening file /Volumes/Mac HD1/music/Gershwin, George/Overtures; McGlinn, New Princess Th. Orch/01 - A damsel in distress.mp3
[08-08-01 14:07:13.9196] Slim::Player::Source::openSong (2139) Seeking in 38912 into /Volumes/Mac HD1/music/Gershwin, George/Overtures; McGlinn, New Princess Th. Orch/01 - A damsel in distress.mp3
[08-08-01 14:07:14.4423] Slim::Player::Source::playmode (412) 3b:02:a5:9e:b7:75 New play mode: play
[08-08-01 14:07:14.4436] Slim::Player::Transporter::play (131) Setting DigitalInput to 0 for [file:///Volumes/Mac%20HD1/music/Gershwin,%20George/Overtures%3B%20McGlinn,%20New%20Princess%20Th.%20Orch/01%20-%20A%20damsel%20in%20distress.mp3]
[08-08-01 14:07:14.4443] Slim::Player::Transporter::setDigitalInput (211) Switching to digital input 0
[08-08-01 14:07:14.4520] Slim::Player::Source::playmode (581) 3b:02:a5:9e:b7:75: Current playmode: play
[08-08-01 14:07:14.4987] Slim::Player::Source::readNextChunk (2331) Sending 0 bytes of silence.
[08-08-01 14:07:14.6227] Slim::Player::Source::trackStartEvent (1605) Got a track starting event
[08-08-01 14:07:14.6229] Slim::Player::Source::trackStartEvent (1621) Song 0 has now started playing
[08-08-01 14:07:14.6236] Slim::Player::Source::trackStartEvent (1649) Song queue is now 0
[08-08-01 14:08:07.5640] Slim::Player::Source::playmode (309) 3b:02:a5:9e:b7:75: Switching to mode pause from play
[08-08-01 14:08:07.5646] Slim::Player::Source::playmode (412) 3b:02:a5:9e:b7:75 New play mode: pause
[08-08-01 14:08:07.5651] Slim::Player::Source::playmode (581) 3b:02:a5:9e:b7:75: Current playmode: pause
[08-08-01 14:08:57.3460] Slim::Player::Source::playmode (309) 3b:02:a5:9e:b7:75: Switching to mode stop from pause
[08-08-01 14:08:57.3467] Slim::Player::Source::playmode (412) 3b:02:a5:9e:b7:75 New play mode: stop
[08-08-01 14:08:57.3469] Slim::Player::Source::playmode (461) 3b:02:a5:9e:b7:75: Stopping and clearing out old chunks
[08-08-01 14:08:57.3486] Slim::Player::Source::resetSong (1739) Resetting song buffer.
[08-08-01 14:08:57.3491] Slim::Player::Source::resetSongQueue (1568) Resetting song queue
[08-08-01 14:08:57.3495] Slim::Player::Source::resetSongQueue (1578) Song queue is now 0
[08-08-01 14:08:57.3501] Slim::Player::Source::playmode (581) 3b:02:a5:9e:b7:75: Current playmode: stop
[08-08-01 14:08:57.6104] Slim::Player::Source::streamingSongIndex (1494) Adding song index 0 to song queue
[08-08-01 14:08:57.6110] Slim::Player::Source::streamingSongIndex (1498) Clearing out song queue first
[08-08-01 14:08:57.6112] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 0
[08-08-01 14:08:57.6573] Slim::Player::Source::playmode (309) 3b:02:a5:9e:b7:75: Switching to mode stop from stop
[08-08-01 14:08:57.6576] Slim::Player::Source::playmode (316) Already in playmode stop : ignoring mode change
[08-08-01 14:08:57.6578] Slim::Player::Source::jumpto (1306) Jumping to 0
[08-08-01 14:08:57.6580] Slim::Player::Source::streamingSongIndex (1494) Adding song index 0 to song queue
[08-08-01 14:08:57.6582] Slim::Player::Source::streamingSongIndex (1498) Clearing out song queue first
[08-08-01 14:08:57.6583] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 0
[08-08-01 14:08:57.6587] Slim::Player::Source::playmode (309) 3b:02:a5:9e:b7:75: Switching to mode play from stop
[08-08-01 14:08:57.6590] Slim::Player::Source::resetSong (1739) Resetting song buffer.
[08-08-01 14:08:57.6616] Slim::Player::Source::openSong (1840) Trying to open: file:///Volumes/Mac%20HD1/music/Achron,%20Isador/Piano%20Concertos%3B%20Goldsmith,%20Royal%20Scottish%20National%20Orch,%20David%20Amos/01%20Isador%20Achron_%20Concerto%20No.%201%20For%20Piano%20And%20Orch%20(Barry%20Goldsmith).mp3
[08-08-01 14:08:57.6624] Slim::Player::Source::openSong (2049) duration: [1002.63] size: [27769075] endian [] offset: [1573] for file:///Volumes/Mac
Comment 8 James Richardson 2008-08-01 12:11:12 UTC
Andy: Your comments or thoughts?
Comment 9 Andy Grundman 2008-08-01 12:20:01 UTC
Please try disabling all 3rd party plugins (AlienBBC, SuperDateTime, Weather, XM Radio), restart the server, and see if the problem improves.
Comment 10 James Rome 2008-08-01 12:47:00 UTC
Now with those plugins disabled, when I go to the advanced tab, all I see is
404 Not Found: plugins/SuperDateTime/settings/basic.html 
Comment 11 James Rome 2008-08-01 12:48:48 UTC
Oops. It fixed that problem when I reloaded the page.
Comment 12 Blackketter Dean 2008-08-06 12:37:05 UTC
James:  So is the problem better with the plugins removed?
Comment 13 James Rome 2008-08-06 13:06:18 UTC
It was superdate time I believe that caused the problem.
I added the others back, and so far it has been OK>
Comment 14 James Richardson 2008-08-08 07:37:46 UTC
James: Now that we have figured out where the problem is, can you please contact the plug-in builder via the forums.

Please direct all feedback and support questions to GoCubs on the SlimDevices public forums at forums.slimdevices.com.

Reference this bug report in your communications to GoCubs, that way he can re-open the bug if he has issues or questions.

Thanks
Comment 15 James Rome 2008-08-16 08:40:13 UTC
This came back again last evening. I really think it is the server because all the plugins worked flawlessly before 7.1.