Bugzilla – Bug 3930
Player freezes part way through shuffled song on playlist
Last modified: 2008-09-15 14:39:24 UTC
(Pasted in from 1869, as Chris requested) I'm now thinking that my weird freeze-up problem (which was originally reported over a year ago, and has stayed broken since then) has nothing to do with synch (bug 1869) - so should be tracked in a separate bug# (now adding this one). Actually I think it had its own bug# originally but later got merged (probably wrongly) into 1869. So splitting it again. This bug behaves exactly the same on 6.3.1, so I put that release number on the bug report. However I have also recreated it with the almost-latest nightly of 6.5b1, and I assume that's where you'll want to test any fixes: I upgraded to 6.5b1 yesterday, from the Monday night build. Today I've been listening to music on one player because I couldn't get more than one player to run (they are all getting the same IP address, a Firmware 59 issue that I've posted about in the beta forum - that one may need a bug # too, if it doesn't have one already). And just now the freeze-up problem happened, about 35 songs into a roughly 5152 song playlist (shuffled by song). Since there's only one player, I assume it can't be a synch related problem. My expectation is that the log entries for this would be the same as what I was able to get last week from 6.3.1. But let me know if you'd like me to try again with 6.5b1. I may revert to 6.3.1 at some point to get multiple players working again, unless that firmware problem gets fixed in the next day or two. My configuration: -- SlimServer running on a Mac Mini with 1GB RAM (an early PowerPC version) -- Wired network, 1 Gigabit switches - effectively 100M for the Mac and Slim players -- Music stored on a separate NAS server (which does have a Gigabit interface). The Mac sees this as "DISK 1". -- Using iTunes to maintain playlists. Scanning set to pick up from that. Scanning works well now in both 6.3.1 and 6.5b1, albeit slowly. -- 4 SB2s (currently testing on one player, to show recreate is not related to synch) -- Statistics from the library: 836 albums with 10583 songs by 739 artists -- The playlist I'm shuffling for this test has 5152 songs -- The freeze-up appears to happen less often with shorter playlists, but I'm not sure. Here is the exact behavior: 1) Playing shuffled by song 2) Part way through one of the songs (sometimes just a few songs into the list, sometimes much further), the music stops. 3) When I go to the player, the display is frozen with the progress-indicator bar part way through the song. It usually shows much less progress than what I've actually heard. Meaning, the issue happened near the end of the song, but the bar shows near the beginning. 4) The player is unresponsive to the remote. 5) If I unplug the player and plug it back in, after rebooting it hangs on the "Looking for SlimServer..." display. 5) When I go back to the PC where I have a browser running to control music from my desk, the browser display is no longer refreshing, and the mouse cursor when held over the right pane shows an arrow with an hourglass - waiting for an update. 6) Then I open up the Mac UI (I run VNC from the PC to get to it) and click "Stop" on the Preferences Pane. The button stays gray, saying Stop Server. It does not change to indicate stop has occurred. 7) If you look at the long while this is happening, no further log messages appear - the server appears totally hung 8) Since the server can't be stopped, I have to reboot, but... 9) If I do a ShutDown, the machine doesn't actually shut down all the way. The Finder UI and other desktop stuff goes away like a normal shutdown, but the machine is still hung. 10) I have to go physically hold down the power switch until it shuts off 11) AFter booting back up and restarting the server, the playlist is usually positioned at the same song where it died, but the list is reshuffled (the next song in the list is different than it was before the freeze-up). 12) The song that made it die before, will play all the way through. So it's nothing about that particular song that is the problem. 13) The list goes on playing... until the next time it freezes. I'd really like to get this fixed - it's so frustrating, I haven't really been able to fully enjoy my system for about a year now. I have more than once seriously contemplated going to a Sonos, but I hate to go so proprietary...
Andy, since posting this yesterday, I turned on the "Webserver forking" option in 6.5b1, and it hasn't frozen up since. Of course it may still freeze, but I thought I'd mention this. Does it make sense to you that this forking option could affect this behavior? Also, FYI, I'm running the Activity Monitor in OSX to keep an eye on things. It may give some interesting evidence, if things freeze up again. Currently it shows perl with 1 thread, varying between 1.8% and 50% CPU, and 40.5M and 16M memory (when the CPU hops up, the memory goes down). MySql has 10 threads and 21.4M memory, varying between .1% and 15% CPU. The variation is (I supposed) associated with taking more sips of data and sending them out to the player buffer, on a periodic basis. I also tried the Audio Stream forking option, but it didn't work. Songs would get about 10% through then stop - but the player was still unfrozen. Pressing Skip on the remote would skip to the next song, which would play about 10% then stop. So I turned that option off. Note: this is not a bug report of that option! (I know you don't want those here)
Does the lockup occur if you don't have a web browser open to the web UI?
Subject: RE: Player freezes part way through shuffled song on playlist Yes. Mike Gilpin | VP, Research Director | Forrester Research, Inc. 8808 Watts Mine Terrace, Potomac, MD 20854 Phone: 301/469-4863 | Mobile: 240/899-5886 | www.forrester.com Forrester's Technology Leadership Forum 2006: Prospering In Your IT Ecosystem. Examine the dynamic changes facing our industry and learn innovative strategies, methods, and tools that will help you lead and prosper in your own IT ecosystem. September 18-20, The Phoenician, Scottsdale, AZ. http://www.forrester.com/events/overview/0,9089,1470,00.html SOA: Management Strategies For Success. Learn the knowledge you need to begin SOA implementation efforts. October 19, Foster City, CA http://www.forrester.com/events/overview/0,9089,1526,00.html Add Forrester content to your personal homepage with RSS - http://www.forrester.com/find/browse/rss -----Original Message----- From: Slim Devices Bugzilla [mailto:bugs@bugs.slimdevices.com] Sent: Thursday, August 17, 2006 10:36 AM To: Gilpin, Mike Subject: [Bug 3930] Player freezes part way through shuffled song on playlist https://bugs-archive.lyrion.org/show_bug.cgi?id=3930 ------- Comment #2 from andy@slimdevices.com 2006-08-17 07:36 ------- Does the lockup occur if you don't have a web browser open to the web UI? ------- You are receiving this mail because: ------- You reported the bug, or are watching the reporter.
Yes, it does. I haven't tested with one player and no browser, but I did test with 6.3.1 running three synched players and no browser, and it was freezing up then.
If it doesn't happen with the web open, the web forking option is not going to help. You might want to try it without having a browser open just to be sure. Also FYI: You can kill SlimServer with Activity Monitor when it locks up, you shouldn't need to shut down. Just look for and force quite all perl processes.
Sorry I meant to say the opposite: if it does happen without the web open, then it's not related to the web code.
Sure enough, it froze up a little while ago. but I was unable to force-quit Perl from Activity Monitor. It let me stop MySql and the Preferences Pane app, but despite repeated attempts to Force-quit Perl, it kept running. Note that it had 0% CPU utilization, and about 41M of memory. I guess since CPU was zero we know it's not looping? Perhaps it's in some weird wait-state from the network layer.
Are you able to reproduce this easily? Can you get a log file from when the freeze occurs? cd ~/Library/PreferencePanes/SlimServer.prefPane/Contents/server ./slimserver.pl --d_directstream --d_source --d_playlist --d_slimproto If possible, try this with the next nightly, only 1 player and no web access.
I'll try to recreate this weekend with the new nightly from tonight. As you may recall from the last attempt to get a log, it's hard because of the way the freezeup causes the Mac to freeze all file-system access, too (so I can't save the log to disk). Also, interestingly, it's been playing all day today without freezing (and no browser running). But I have had a freeze with this same build, and one player. So it's just a matter of time.
The fact that your entire Mac locks up may indicate a larger problem with your system too.
>> The fact that your entire Mac locks up may indicate a larger problem with your system too. Yeah, I've wondered about that. The only thing that makes me carry on thinking it's not time to junk the Mac is that it used to work without freezing, on an earlier release of SlimServer (whatever was current in the spring of 2005). It was later that the freeze-up started happening, when I was using more players and on a later software release in the summer. Plus, this is the only app that ever locks up like this.
Here are the log entries (the latter part of the log) from the failure, but the failure mode was different this time. Instead of the normal freezing up, the display went dark on the player. I wasn't listening at the time, I was watching a movie and left the music running silently. So I don't know what it looked like as it failed, I just noticed later that the screen had gone dark.: 2006-08-19 23:26:36.2248 new playlistindex: 184 2006-08-19 23:26:36.2257 00:04:20:05:a5:e0: Current playmode: playout-play 2006-08-19 23:26:36.2265 No pending chunks - we're dropping the streaming connection 2006-08-19 23:26:36.2428 00:04:20:05:a5:e0: Can't opennext, returning no chunk. 2006-08-19 23:26:38.8414 Squeezebox got disconnection on the data channel why: Connection closed normally 2006-08-19 23:28:35.0089 00:04:20:05:a5:e0: Decoder underrun while this mode: playout-play 2006-08-19 23:28:35.0104 **skipahead: opening next song 2006-08-19 23:28:35.0114 opening next song... 2006-08-19 23:28:35.0124 the next song is number 185, was 184 2006-08-19 23:28:35.0141 Setting maxBitRate for Family Room to: 0 2006-08-19 23:28:35.0151 Setting maxBitRate for Family Room to: 0 2006-08-19 23:28:35.0160 undermax = 1, type = mp3, squeezebox2 = 00:04:20:05:a5:e0 2006-08-19 23:28:35.0170 checking formats for: mp3-wma-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:28:35.0178 checking formats for: mp3-wma-*-00:04:20:05:a5:e0 2006-08-19 23:28:35.0187 checking formats for: mp3-wma-squeezebox2-* 2006-08-19 23:28:35.0195 checking formats for: mp3-wma-*-* 2006-08-19 23:28:35.0203 checking formats for: mp3-ogg-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:28:35.0210 checking formats for: mp3-ogg-*-00:04:20:05:a5:e0 2006-08-19 23:28:35.0217 checking formats for: mp3-ogg-squeezebox2-* 2006-08-19 23:28:35.0227 checking formats for: mp3-ogg-*-* 2006-08-19 23:28:35.0236 checking formats for: mp3-flc-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:28:35.0290 checking formats for: mp3-flc-*-00:04:20:05:a5:e0 2006-08-19 23:28:35.0301 checking formats for: mp3-flc-squeezebox2-* 2006-08-19 23:28:35.0310 checking formats for: mp3-flc-*-* 2006-08-19 23:28:35.0318 checking formats for: mp3-aif-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:28:35.0325 checking formats for: mp3-aif-*-00:04:20:05:a5:e0 2006-08-19 23:28:35.0332 checking formats for: mp3-aif-squeezebox2-* 2006-08-19 23:28:35.0353 checking formats for: mp3-aif-*-* 2006-08-19 23:28:35.0363 checking formats for: mp3-wav-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:28:35.0371 checking formats for: mp3-wav-*-00:04:20:05:a5:e0 2006-08-19 23:28:35.0379 checking formats for: mp3-wav-squeezebox2-* 2006-08-19 23:28:35.0386 checking formats for: mp3-wav-*-* 2006-08-19 23:28:35.0393 checking formats for: mp3-mp3-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:28:35.0401 checking formats for: mp3-mp3-*-00:04:20:05:a5:e0 2006-08-19 23:28:35.0408 checking formats for: mp3-mp3-squeezebox2-* 2006-08-19 23:28:35.0415 checking formats for: mp3-mp3-*-* 2006-08-19 23:28:35.0423 Checking to see if mp3-mp3-*-* is enabled 2006-08-19 23:28:35.0431 enabled 2006-08-19 23:28:35.0437 Found command: - 2006-08-19 23:28:35.0446 Setting maxBitRate for Family Room to: 0 2006-08-19 23:28:35.0455 Setting maxBitRate for Family Room to: 0 2006-08-19 23:28:35.0463 Matched Format: mp3 Type: mp3 Command: - 2006-08-19 23:28:35.1092 opening next song (old format: mp3, new: mp3) current playmode: play 2006-08-19 23:28:35.1106 Adding song index 185 to song queue 2006-08-19 23:28:35.1116 Song queue is now 185,184 2006-08-19 23:28:35.1125 **skipahead: restarting 2006-08-19 23:28:35.1134 00:04:20:05:a5:e0: Switching to mode play from playout-play 2006-08-19 23:28:35.7944 openSong on: file:///Volumes/WORKGROUP%3BLKG0F76F9/Media%20Files/The%20Carpenters%20-%20Carpenters%20Gold%20Greatest%20Hits%20[Disc%202]/10%20-%20Ticket%20To%20Ride.mp3 2006-08-19 23:28:35.7974 openSong: duration: [250.409] size: [10016391] endian [] offset: [4096] for file:///Volumes/WORKGROUP%3BLKG0F76F9/Media%20Files/The%20Carpenters%20-%20Carpenters%20Gold%20Greatest%20Hits%20[Disc%202]/10%20-%20Ticket%20To%20Ride.mp3 2006-08-19 23:28:35.7992 Setting maxBitRate for Family Room to: 0 2006-08-19 23:28:35.8002 Setting maxBitRate for Family Room to: 0 2006-08-19 23:28:35.8018 Setting maxBitRate for Family Room to: 0 2006-08-19 23:28:35.8029 Setting maxBitRate for Family Room to: 0 2006-08-19 23:28:35.8037 undermax = 1, type = mp3, squeezebox2 = 00:04:20:05:a5:e0 2006-08-19 23:28:35.8046 checking formats for: mp3-wma-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:28:35.8054 checking formats for: mp3-wma-*-00:04:20:05:a5:e0 2006-08-19 23:28:35.8061 checking formats for: mp3-wma-squeezebox2-* 2006-08-19 23:28:35.8069 checking formats for: mp3-wma-*-* 2006-08-19 23:28:35.8076 checking formats for: mp3-ogg-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:28:35.8084 checking formats for: mp3-ogg-*-00:04:20:05:a5:e0 2006-08-19 23:28:35.8091 checking formats for: mp3-ogg-squeezebox2-* 2006-08-19 23:28:35.8098 checking formats for: mp3-ogg-*-* 2006-08-19 23:28:35.8106 checking formats for: mp3-flc-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:28:35.8113 checking formats for: mp3-flc-*-00:04:20:05:a5:e0 2006-08-19 23:28:35.8120 checking formats for: mp3-flc-squeezebox2-* 2006-08-19 23:28:35.8127 checking formats for: mp3-flc-*-* 2006-08-19 23:28:35.8134 checking formats for: mp3-aif-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:28:35.8141 checking formats for: mp3-aif-*-00:04:20:05:a5:e0 2006-08-19 23:28:35.8148 checking formats for: mp3-aif-squeezebox2-* 2006-08-19 23:28:35.8155 checking formats for: mp3-aif-*-* 2006-08-19 23:28:35.8162 checking formats for: mp3-wav-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:28:35.8169 checking formats for: mp3-wav-*-00:04:20:05:a5:e0 2006-08-19 23:28:35.8176 checking formats for: mp3-wav-squeezebox2-* 2006-08-19 23:28:35.8183 checking formats for: mp3-wav-*-* 2006-08-19 23:28:35.8191 checking formats for: mp3-mp3-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:28:35.8198 checking formats for: mp3-mp3-*-00:04:20:05:a5:e0 2006-08-19 23:28:35.8205 checking formats for: mp3-mp3-squeezebox2-* 2006-08-19 23:28:35.8212 checking formats for: mp3-mp3-*-* 2006-08-19 23:28:35.8219 Checking to see if mp3-mp3-*-* is enabled 2006-08-19 23:28:35.8226 enabled 2006-08-19 23:28:35.8233 Found command: - 2006-08-19 23:28:35.8241 Setting maxBitRate for Family Room to: 0 2006-08-19 23:28:35.8250 Setting maxBitRate for Family Room to: 0 2006-08-19 23:28:35.8258 Matched Format: mp3 Type: mp3 Command: - 2006-08-19 23:28:35.8266 openSong: this is an mp3 file: file:///Volumes/WORKGROUP%3BLKG0F76F9/Media%20Files/The%20Carpenters%20-%20Carpenters%20Gold%20Greatest%20Hits%20[Disc%202]/10%20-%20Ticket%20To%20Ride.mp3 2006-08-19 23:28:35.8274 file type: mp3 format: mp3 inrate: 320 maxRate: 0 2006-08-19 23:28:35.8282 command: - 2006-08-19 23:28:35.8291 openSong: opening file /Volumes/WORKGROUP;LKG0F76F9/Media Files/The Carpenters - Carpenters Gold Greatest Hits [Disc 2]/10 - Ticket To Ride.mp3 2006-08-19 23:28:35.8319 openSong: seeking in 4096 into /Volumes/WORKGROUP;LKG0F76F9/Media Files/The Carpenters - Carpenters Gold Greatest Hits [Disc 2]/10 - Ticket To Ride.mp3 2006-08-19 23:28:35.8335 openSong: Streaming with format: mp3 2006-08-19 23:28:36.0701 00:04:20:05:a5:e0 New play mode: play 2006-08-19 23:28:36.0787 *************stream called: s paused: 0 format: mp3 url: file:///Volumes/WORKGROUP%3BLKG0F76F9/Media%20Files/The%20Carpenters%20-%20Carpenters%20Gold%20Greatest%20Hits%20[Disc%202]/10%20-%20Ticket%20To%20Ride.mp3 2006-08-19 23:28:36.0801 Backtrace: frame 0: Slim::Player::Squeezebox::stream (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Squeezebox.pm line 152) frame 1: Slim::Player::Squeezebox::play (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 487) frame 2: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 633) frame 3: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 581) frame 4: Slim::Player::Source::decoderUnderrun (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 650) frame 5: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 392) frame 6: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 7: Slim::Networking::Select::select (./slimserver.pl line 503) frame 8: main::idle (./slimserver.pl line 456) frame 9: main::main (./slimserver.pl line 1053) 2006-08-19 23:28:36.0826 starting with decoder with format: m autostart: 1 threshold: 255 samplesize: ? samplerate: ? endian: ? channels: ? 2006-08-19 23:28:36.0835 flags: 0 2006-08-19 23:28:36.0844 sending strm frame of length: 76 request string: GET /stream.mp3?player=00:04:20:05:a5:e0 HTTP/1.0 2006-08-19 23:28:36.0866 Playlist: Jumping to song index: 184 2006-08-19 23:28:36.0883 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 301) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 552) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 633) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 581) frame 5: Slim::Player::Source::decoderUnderrun (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 650) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 392) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1053) 2006-08-19 23:28:36.0903 new playlistindex: 184 2006-08-19 23:28:36.0916 00:04:20:05:a5:e0: Current playmode: play 2006-08-19 23:28:36.0980 modifyPlaylistCallback: savecurrsong is 1 2006-08-19 23:28:36.1613 We need to send 0 seconds of silence... 2006-08-19 23:28:36.1627 sending 0 bytes of silence 2006-08-19 23:28:36.6660 Squeezebox got HTTP response: HTTP/1.0 200 OK Date: Sun, 20 Aug 2006 03:28:36 GMT Server: SlimServer (6.5b1 - 9056) Connection: close Content-Type: audio/mpeg Set-Cookie: SlimServer-player=00%3A04%3A20%3A05%3Aa5%3Ae0; path=/; expires=Mon, 20-Aug-2007 03:28:36 GMT 2006-08-19 23:28:36.6676 processing headers for direct streaming: HTTP/1.0 200 OK Date: Sun, 20 Aug 2006 03:28:36 GMT Server: SlimServer (6.5b1 - 9056) Connection: close Content-Type: audio/mpeg Set-Cookie: SlimServer-player=00%3A04%3A20%3A05%3Aa5%3Ae0; path=/; expires=Mon, 20-Aug-2007 03:28:36 GMT 2006-08-19 23:28:44.9746 Got a track starting event 2006-08-19 23:28:44.9760 Song 184 had already started, so it's not longer in the queue 2006-08-19 23:28:44.9769 Song 185 has now started playing 2006-08-19 23:28:44.9781 Playlist: Jumping to song index: 185 2006-08-19 23:28:44.9792 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 301) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 1103) frame 3: Slim::Player::Source::trackStartEvent (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 650) frame 4: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 392) frame 5: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 6: Slim::Networking::Select::select (./slimserver.pl line 503) frame 7: main::idle (./slimserver.pl line 456) frame 8: main::main (./slimserver.pl line 1053) 2006-08-19 23:28:44.9807 new playlistindex: 185 2006-08-19 23:28:44.9820 Song queue is now 185 2006-08-19 23:28:44.9840 modifyPlaylistCallback: savecurrsong is 0 2006-08-19 23:31:24.6204 Reduced chunksize to 22151 at end of file (10016391 - 9994240) 2006-08-19 23:31:25.1447 Reduced chunksize to 0 at end of file (10016391 - 10016391) 2006-08-19 23:31:25.1462 end of file or error on socket, opening next song, (song pos: 10016391(tell says: . 10020487), totalbytes: 10016391) 2006-08-19 23:31:25.1473 opening next song... 2006-08-19 23:31:25.1498 the next song is number 186, was 185 2006-08-19 23:31:25.1519 Setting maxBitRate for Family Room to: 0 2006-08-19 23:31:25.1530 Setting maxBitRate for Family Room to: 0 2006-08-19 23:31:25.1539 undermax = 1, type = mp3, squeezebox2 = 00:04:20:05:a5:e0 2006-08-19 23:31:25.1549 checking formats for: mp3-wma-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:31:25.1557 checking formats for: mp3-wma-*-00:04:20:05:a5:e0 2006-08-19 23:31:25.1570 checking formats for: mp3-wma-squeezebox2-* 2006-08-19 23:31:25.1577 checking formats for: mp3-wma-*-* 2006-08-19 23:31:25.1585 checking formats for: mp3-ogg-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:31:25.1593 checking formats for: mp3-ogg-*-00:04:20:05:a5:e0 2006-08-19 23:31:25.1600 checking formats for: mp3-ogg-squeezebox2-* 2006-08-19 23:31:25.1606 checking formats for: mp3-ogg-*-* 2006-08-19 23:31:25.1614 checking formats for: mp3-flc-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:31:25.1621 checking formats for: mp3-flc-*-00:04:20:05:a5:e0 2006-08-19 23:31:25.1628 checking formats for: mp3-flc-squeezebox2-* 2006-08-19 23:31:25.1635 checking formats for: mp3-flc-*-* 2006-08-19 23:31:25.1642 checking formats for: mp3-aif-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:31:25.1650 checking formats for: mp3-aif-*-00:04:20:05:a5:e0 2006-08-19 23:31:25.1657 checking formats for: mp3-aif-squeezebox2-* 2006-08-19 23:31:25.1664 checking formats for: mp3-aif-*-* 2006-08-19 23:31:25.1675 checking formats for: mp3-wav-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:31:25.1683 checking formats for: mp3-wav-*-00:04:20:05:a5:e0 2006-08-19 23:31:25.1690 checking formats for: mp3-wav-squeezebox2-* 2006-08-19 23:31:25.1697 checking formats for: mp3-wav-*-* 2006-08-19 23:31:25.1704 checking formats for: mp3-mp3-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:31:25.1711 checking formats for: mp3-mp3-*-00:04:20:05:a5:e0 2006-08-19 23:31:25.1718 checking formats for: mp3-mp3-squeezebox2-* 2006-08-19 23:31:25.1726 checking formats for: mp3-mp3-*-* 2006-08-19 23:31:25.1733 Checking to see if mp3-mp3-*-* is enabled 2006-08-19 23:31:25.1740 enabled 2006-08-19 23:31:25.1746 Found command: - 2006-08-19 23:31:25.1756 Setting maxBitRate for Family Room to: 0 2006-08-19 23:31:25.1765 Setting maxBitRate for Family Room to: 0 2006-08-19 23:31:25.1773 Matched Format: mp3 Type: mp3 Command: - 2006-08-19 23:31:25.1783 playing out before starting next song. (old format: mp3, new: mp3) 2006-08-19 23:31:25.1791 00:04:20:05:a5:e0: Switching to mode playout-play from play 2006-08-19 23:31:25.1804 00:04:20:05:a5:e0 New play mode: playout-play 2006-08-19 23:31:25.1816 *************stream called: u paused: format: url: 2006-08-19 23:31:25.1828 Backtrace: frame 0: Slim::Player::Squeezebox::stream (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Squeezebox.pm line 179) frame 1: Slim::Player::Squeezebox::resume (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 444) frame 2: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 931) frame 3: Slim::Player::Source::gotoNext (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 1790) frame 4: Slim::Player::Source::readNextChunk (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 662) frame 5: Slim::Player::Source::nextChunk (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/HTTP.pm line 1684) frame 6: Slim::Web::HTTP::sendStreamingResponse (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 7: Slim::Networking::Select::select (./slimserver.pl line 503) frame 8: main::idle (./slimserver.pl line 456) frame 9: main::main (./slimserver.pl line 1053) 2006-08-19 23:31:25.1845 starting with decoder with format: m autostart: 1 threshold: 255 samplesize: ? samplerate: ? endian: ? channels: ? 2006-08-19 23:31:25.1854 flags: 0 2006-08-19 23:31:25.1862 sending strm frame of length: 24 request string: 2006-08-19 23:31:25.2032 Playlist: Jumping to song index: 185 2006-08-19 23:31:25.2049 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 301) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 552) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 931) frame 4: Slim::Player::Source::gotoNext (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 1790) frame 5: Slim::Player::Source::readNextChunk (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 662) frame 6: Slim::Player::Source::nextChunk (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/HTTP.pm line 1684) frame 7: Slim::Web::HTTP::sendStreamingResponse (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1053) 2006-08-19 23:31:25.2067 new playlistindex: 185 2006-08-19 23:31:25.2122 00:04:20:05:a5:e0: Current playmode: playout-play 2006-08-19 23:31:25.2134 No pending chunks - we're dropping the streaming connection 2006-08-19 23:31:25.2152 00:04:20:05:a5:e0: Can't opennext, returning no chunk. 2006-08-19 23:31:26.7119 Squeezebox got disconnection on the data channel why: Connection closed normally 2006-08-19 23:32:45.3831 00:04:20:05:a5:e0: Decoder underrun while this mode: playout-play 2006-08-19 23:32:45.3845 **skipahead: opening next song 2006-08-19 23:32:45.3854 opening next song... 2006-08-19 23:32:45.3865 the next song is number 186, was 185 2006-08-19 23:32:45.3882 Setting maxBitRate for Family Room to: 0 2006-08-19 23:32:45.3893 Setting maxBitRate for Family Room to: 0 2006-08-19 23:32:45.3901 undermax = 1, type = mp3, squeezebox2 = 00:04:20:05:a5:e0 2006-08-19 23:32:45.3911 checking formats for: mp3-wma-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:32:45.3919 checking formats for: mp3-wma-*-00:04:20:05:a5:e0 2006-08-19 23:32:45.3927 checking formats for: mp3-wma-squeezebox2-* 2006-08-19 23:32:45.3934 checking formats for: mp3-wma-*-* 2006-08-19 23:32:45.3942 checking formats for: mp3-ogg-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:32:45.3952 checking formats for: mp3-ogg-*-00:04:20:05:a5:e0 2006-08-19 23:32:45.3960 checking formats for: mp3-ogg-squeezebox2-* 2006-08-19 23:32:45.3968 checking formats for: mp3-ogg-*-* 2006-08-19 23:32:45.3976 checking formats for: mp3-flc-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:32:45.3983 checking formats for: mp3-flc-*-00:04:20:05:a5:e0 2006-08-19 23:32:45.3990 checking formats for: mp3-flc-squeezebox2-* 2006-08-19 23:32:45.3997 checking formats for: mp3-flc-*-* 2006-08-19 23:32:45.4005 checking formats for: mp3-aif-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:32:45.4012 checking formats for: mp3-aif-*-00:04:20:05:a5:e0 2006-08-19 23:32:45.4019 checking formats for: mp3-aif-squeezebox2-* 2006-08-19 23:32:45.4026 checking formats for: mp3-aif-*-* 2006-08-19 23:32:45.4034 checking formats for: mp3-wav-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:32:45.4041 checking formats for: mp3-wav-*-00:04:20:05:a5:e0 2006-08-19 23:32:45.4048 checking formats for: mp3-wav-squeezebox2-* 2006-08-19 23:32:45.4056 checking formats for: mp3-wav-*-* 2006-08-19 23:32:45.4064 checking formats for: mp3-mp3-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:32:45.4071 checking formats for: mp3-mp3-*-00:04:20:05:a5:e0 2006-08-19 23:32:45.4078 checking formats for: mp3-mp3-squeezebox2-* 2006-08-19 23:32:45.4085 checking formats for: mp3-mp3-*-* 2006-08-19 23:32:45.4092 Checking to see if mp3-mp3-*-* is enabled 2006-08-19 23:32:45.4099 enabled 2006-08-19 23:32:45.4106 Found command: - 2006-08-19 23:32:45.4114 Setting maxBitRate for Family Room to: 0 2006-08-19 23:32:45.4124 Setting maxBitRate for Family Room to: 0 2006-08-19 23:32:45.4132 Matched Format: mp3 Type: mp3 Command: - 2006-08-19 23:32:45.4141 opening next song (old format: mp3, new: mp3) current playmode: play 2006-08-19 23:32:45.4149 Adding song index 186 to song queue 2006-08-19 23:32:45.4157 Song queue is now 186,185 2006-08-19 23:32:45.4166 **skipahead: restarting 2006-08-19 23:32:45.4174 00:04:20:05:a5:e0: Switching to mode play from playout-play 2006-08-19 23:32:45.9030 openSong on: file:///Volumes/WORKGROUP%3BLKG0F76F9/Media%20Files/Simply%20Red%20-%20Life/Out%20On%20The%20Range.mp3 2006-08-19 23:32:45.9058 openSong: duration: [361.848] size: [10836304] endian [] offset: [645] for file:///Volumes/WORKGROUP%3BLKG0F76F9/Media%20Files/Simply%20Red%20-%20Life/Out%20On%20The%20Range.mp3 2006-08-19 23:32:45.9071 Setting maxBitRate for Family Room to: 0 2006-08-19 23:32:45.9081 Setting maxBitRate for Family Room to: 0 2006-08-19 23:32:45.9097 Setting maxBitRate for Family Room to: 0 2006-08-19 23:32:45.9107 Setting maxBitRate for Family Room to: 0 2006-08-19 23:32:45.9116 undermax = 1, type = mp3, squeezebox2 = 00:04:20:05:a5:e0 2006-08-19 23:32:45.9125 checking formats for: mp3-wma-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:32:45.9133 checking formats for: mp3-wma-*-00:04:20:05:a5:e0 2006-08-19 23:32:45.9140 checking formats for: mp3-wma-squeezebox2-* 2006-08-19 23:32:45.9148 checking formats for: mp3-wma-*-* 2006-08-19 23:32:45.9156 checking formats for: mp3-ogg-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:32:45.9163 checking formats for: mp3-ogg-*-00:04:20:05:a5:e0 2006-08-19 23:32:45.9170 checking formats for: mp3-ogg-squeezebox2-* 2006-08-19 23:32:45.9177 checking formats for: mp3-ogg-*-* 2006-08-19 23:32:45.9185 checking formats for: mp3-flc-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:32:45.9192 checking formats for: mp3-flc-*-00:04:20:05:a5:e0 2006-08-19 23:32:45.9199 checking formats for: mp3-flc-squeezebox2-* 2006-08-19 23:32:45.9206 checking formats for: mp3-flc-*-* 2006-08-19 23:32:45.9213 checking formats for: mp3-aif-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:32:45.9220 checking formats for: mp3-aif-*-00:04:20:05:a5:e0 2006-08-19 23:32:45.9227 checking formats for: mp3-aif-squeezebox2-* 2006-08-19 23:32:45.9234 checking formats for: mp3-aif-*-* 2006-08-19 23:32:45.9242 checking formats for: mp3-wav-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:32:45.9249 checking formats for: mp3-wav-*-00:04:20:05:a5:e0 2006-08-19 23:32:45.9256 checking formats for: mp3-wav-squeezebox2-* 2006-08-19 23:32:45.9262 checking formats for: mp3-wav-*-* 2006-08-19 23:32:45.9273 checking formats for: mp3-mp3-squeezebox2-00:04:20:05:a5:e0 2006-08-19 23:32:45.9281 checking formats for: mp3-mp3-*-00:04:20:05:a5:e0 2006-08-19 23:32:45.9289 checking formats for: mp3-mp3-squeezebox2-* 2006-08-19 23:32:45.9296 checking formats for: mp3-mp3-*-* 2006-08-19 23:32:45.9304 Checking to see if mp3-mp3-*-* is enabled 2006-08-19 23:32:45.9311 enabled 2006-08-19 23:32:45.9318 Found command: - 2006-08-19 23:32:45.9327 Setting maxBitRate for Family Room to: 0 2006-08-19 23:32:45.9336 Setting maxBitRate for Family Room to: 0 2006-08-19 23:32:45.9344 Matched Format: mp3 Type: mp3 Command: - 2006-08-19 23:32:45.9352 openSong: this is an mp3 file: file:///Volumes/WORKGROUP%3BLKG0F76F9/Media%20Files/Simply%20Red%20-%20Life/Out%20On%20The%20Range.mp3 2006-08-19 23:32:45.9360 file type: mp3 format: mp3 inrate: 239 maxRate: 0 2006-08-19 23:32:45.9367 command: - 2006-08-19 23:32:45.9378 openSong: opening file /Volumes/WORKGROUP;LKG0F76F9/Media Files/Simply Red - Life/Out On The Range.mp3 2006-08-19 23:32:45.9404 openSong: seeking in 645 into /Volumes/WORKGROUP;LKG0F76F9/Media Files/Simply Red - Life/Out On The Range.mp3 2006-08-19 23:32:45.9416 openSong: Streaming with format: mp3 2006-08-19 23:32:46.1637 00:04:20:05:a5:e0 New play mode: play 2006-08-19 23:32:46.1727 *************stream called: s paused: 0 format: mp3 url: file:///Volumes/WORKGROUP%3BLKG0F76F9/Media%20Files/Simply%20Red%20-%20Life/Out%20On%20The%20Range.mp3 2006-08-19 23:32:46.1742 Backtrace: frame 0: Slim::Player::Squeezebox::stream (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Squeezebox.pm line 152) frame 1: Slim::Player::Squeezebox::play (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 487) frame 2: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 633) frame 3: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 581) frame 4: Slim::Player::Source::decoderUnderrun (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 650) frame 5: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 392) frame 6: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 7: Slim::Networking::Select::select (./slimserver.pl line 503) frame 8: main::idle (./slimserver.pl line 456) frame 9: main::main (./slimserver.pl line 1053) 2006-08-19 23:32:46.1760 starting with decoder with format: m autostart: 1 threshold: 255 samplesize: ? samplerate: ? endian: ? channels: ? 2006-08-19 23:32:46.1770 flags: 0 2006-08-19 23:32:46.1779 sending strm frame of length: 76 request string: GET /stream.mp3?player=00:04:20:05:a5:e0 HTTP/1.0 2006-08-19 23:32:46.1801 Playlist: Jumping to song index: 185 2006-08-19 23:32:46.1819 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 301) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 552) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 633) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 581) frame 5: Slim::Player::Source::decoderUnderrun (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 650) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 392) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1053) 2006-08-19 23:32:46.1839 new playlistindex: 185 2006-08-19 23:32:46.1851 00:04:20:05:a5:e0: Current playmode: play 2006-08-19 23:32:46.2180 modifyPlaylistCallback: savecurrsong is 1 2006-08-19 23:32:46.2497 We need to send 0 seconds of silence... 2006-08-19 23:32:46.2511 sending 0 bytes of silence 2006-08-19 23:32:46.3752 Squeezebox got HTTP response: HTTP/1.0 200 OK Date: Sun, 20 Aug 2006 03:32:46 GMT Server: SlimServer (6.5b1 - 9056) Connection: close Content-Type: audio/mpeg Set-Cookie: SlimServer-player=00%3A04%3A20%3A05%3Aa5%3Ae0; path=/; expires=Mon, 20-Aug-2007 03:32:46 GMT 2006-08-19 23:32:46.3768 processing headers for direct streaming: HTTP/1.0 200 OK Date: Sun, 20 Aug 2006 03:32:46 GMT Server: SlimServer (6.5b1 - 9056) Connection: close Content-Type: audio/mpeg Set-Cookie: SlimServer-player=00%3A04%3A20%3A05%3Aa5%3Ae0; path=/; expires=Mon, 20-Aug-2007 03:32:46 GMT
So Andy, was that log I sent of any use? Since then, it has not frozen or crashed again, but that's just from playing on Sunday and Monday. Since then I've been on the road (and will be the rest of the week). If that log is not helpful, I will continue to try to recreate the freeze after I get home, and capture another log.
Subject: Out of Office AutoReply: Player freezes part way through shuffled song on playlist I am out of the office on business through Friday August 25th. If you have an urgent matter, call my mobile number 240-899-5886, or instant-message my phone at https://wmg.tmomail.net/customer_site/jsp/messaging_lo.jsp. Otherwise, I will reply to your email message at the earliest opportunity. <!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 3.2//EN"> <HTML> <HEAD> <META HTTP-EQUIV="Content-Type" CONTENT="text/html; charset=iso-8859-1"> <META NAME="Generator" CONTENT="MS Exchange Server version 6.5.7650.5"> <TITLE>Out of Office AutoReply: [Bug 3930] Player freezes part way through shuffled song on playlist</TITLE> </HEAD> <BODY> <!-- Converted from text/plain format --> <P><FONT SIZE=2>I am out of the office on business through Friday August 25th. If you have an urgent matter, call my mobile number 240-899-5886, or instant-message my phone at <A HREF="https://wmg.tmomail.net/customer_site/jsp/messaging_lo.jsp">https://wmg.tmomail.net/customer_site/jsp/messaging_lo.jsp</A>. Otherwise, I will reply to your email message at the earliest opportunity.</FONT></P> </BODY> </HTML>
Sorry but no, that log looks pretty normal. I take it once the lockup occurs there are no further log messages until you kill slimserver?
Yes, that's right. No further messages until it had to be killed.
Well, I went all weekend and it didn't freeze up. Got about 450 songs into a 3500-song list on shuffle by song. Added some new Dylan albums last night so am starting fresh today after a restart and rescan. This is on the 8-19 build of 6.5b1. Did anything change from the earlier release on which I last got a freeze-up (8-15) that would explain why it seems to be working? Of course with this bug you never know for sure because it is random and sometimes takes a long time to freeze. But 450 is about as far as I've ever gotten. Also, perhaps you know whether firmware 60 is going to make it in for the final 6.5? I need that fix to enable me to use multiple players. I'll be out of town part of this week (again), but will continue to try to recreate the freeze-up problem.
Mike - what's the status on this? As you might have seen, the firmware update has been checked in. Thanks
I think this bug is fixed. Hooray!
Great! Please reopen if you see it again.
Sadly I just got another freeze-up - classic symptoms. No log running. So I'll start one now and try to recreate.
Subject: Out of Office AutoReply: Player freezes part way through shuffled song on playlist I am out of the office for the Labor Day holiday. I will reply to your email message at the earliest opportunity. <!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 3.2//EN"> <HTML> <HEAD> <META HTTP-EQUIV="Content-Type" CONTENT="text/html; charset=iso-8859-1"> <META NAME="Generator" CONTENT="MS Exchange Server version 6.5.7650.5"> <TITLE>Out of Office AutoReply: [Bug 3930] Player freezes part way through shuffled song on playlist</TITLE> </HEAD> <BODY> <!-- Converted from text/plain format --> <P><FONT SIZE=2>I am out of the office for the Labor Day holiday. I will reply to your email message at the earliest opportunity.</FONT> </P> </BODY> </HTML>
Seeing an odd behavior, the song finished playing, but the player display is still counting down to the end, in a fitful and slow way. Seeing these log entries: 2006-09-06 10:30:20.8249 Skipping playlist build - not modified. 2006-09-06 10:30:31.6134 Setting maxBitRate for Family Room to: 0 2006-09-06 10:30:31.6150 Setting maxBitRate for Family Room to: 0 2006-09-06 10:30:31.6161 Setting maxBitRate for Living Room to: 0 2006-09-06 10:30:31.6170 Setting maxBitRate for Master Bedroom to: 0 2006-09-06 10:30:31.6179 Setting maxBitRate for Basement Theater to: 0 2006-09-06 10:30:31.6206 currentPlaylistChangeTime : Wed Sep 6 10:24:38 2006 2006-09-06 10:30:31.6216 currentPlaylistRender : Wed Sep 6 10:24:40 2006 2006-09-06 10:30:31.6225 currentPlaylistRenderSkin : 2006-09-06 10:30:31.6233 currentPlaylistRenderStart: 0 2006-09-06 10:30:31.6240 skinOverride: 2006-09-06 10:30:31.6247 start: 0 2006-09-06 10:30:31.6255 Skipping playlist build - not modified. 2006-09-06 10:30:42.4995 Setting maxBitRate for Family Room to: 0 2006-09-06 10:30:42.5009 Setting maxBitRate for Family Room to: 0 2006-09-06 10:30:42.5020 Setting maxBitRate for Living Room to: 0 2006-09-06 10:30:42.5029 Setting maxBitRate for Master Bedroom to: 0 2006-09-06 10:30:42.5037 Setting maxBitRate for Basement Theater to: 0 2006-09-06 10:30:42.5064 currentPlaylistChangeTime : Wed Sep 6 10:24:38 2006 2006-09-06 10:30:42.5074 currentPlaylistRender : Wed Sep 6 10:24:40 2006 2006-09-06 10:30:42.5084 currentPlaylistRenderSkin : 2006-09-06 10:30:42.5092 currentPlaylistRenderStart: 0 2006-09-06 10:30:42.5099 skinOverride: 2006-09-06 10:30:42.5106 start: 0 2006-09-06 10:30:42.5114 Skipping playlist build - not modified. 2006-09-06 10:30:53.6151 Setting maxBitRate for Family Room to: 0 2006-09-06 10:30:53.6164 Setting maxBitRate for Family Room to: 0 2006-09-06 10:30:53.6175 Setting maxBitRate for Living Room to: 0 2006-09-06 10:30:53.6184 Setting maxBitRate for Master Bedroom to: 0 2006-09-06 10:30:53.6193 Setting maxBitRate for Basement Theater to: 0 2006-09-06 10:30:53.6221 currentPlaylistChangeTime : Wed Sep 6 10:24:38 2006 2006-09-06 10:30:53.6231 currentPlaylistRender : Wed Sep 6 10:24:40 2006 2006-09-06 10:30:53.6239 currentPlaylistRenderSkin : 2006-09-06 10:30:53.6247 currentPlaylistRenderStart: 0 2006-09-06 10:30:53.6255 skinOverride: 2006-09-06 10:30:53.6262 start: 0 2006-09-06 10:30:53.6269 Skipping playlist build - not modified. 2006-09-06 10:31:04.6166 Setting maxBitRate for Family Room to: 0 2006-09-06 10:31:04.6180 Setting maxBitRate for Family Room to: 0 2006-09-06 10:31:04.6190 Setting maxBitRate for Living Room to: 0 2006-09-06 10:31:04.6200 Setting maxBitRate for Master Bedroom to: 0 2006-09-06 10:31:04.6209 Setting maxBitRate for Basement Theater to: 0 2006-09-06 10:31:04.6236 currentPlaylistChangeTime : Wed Sep 6 10:24:38 2006 2006-09-06 10:31:04.6247 currentPlaylistRender : Wed Sep 6 10:24:40 2006 2006-09-06 10:31:04.6255 currentPlaylistRenderSkin : 2006-09-06 10:31:04.6263 currentPlaylistRenderStart: 0 2006-09-06 10:31:04.6271 skinOverride: 2006-09-06 10:31:04.6278 start: 0 2006-09-06 10:31:04.6289 Skipping playlist build - not modified. 2006-09-06 10:31:15.5024 Setting maxBitRate for Family Room to: 0 2006-09-06 10:31:15.5038 Setting maxBitRate for Family Room to: 0 2006-09-06 10:31:15.5048 Setting maxBitRate for Living Room to: 0 2006-09-06 10:31:15.5057 Setting maxBitRate for Master Bedroom to: 0 2006-09-06 10:31:15.5066 Setting maxBitRate for Basement Theater to: 0 2006-09-06 10:31:15.5093 currentPlaylistChangeTime : Wed Sep 6 10:24:38 2006 2006-09-06 10:31:15.5104 currentPlaylistRender : Wed Sep 6 10:24:40 2006 2006-09-06 10:31:15.5113 currentPlaylistRenderSkin : 2006-09-06 10:31:15.5121 currentPlaylistRenderStart: 0 2006-09-06 10:31:15.5128 skinOverride: 2006-09-06 10:31:15.5135 start: 0 2006-09-06 10:31:15.5143 Skipping playlist build - not modified. 2006-09-06 10:31:26.4187 Setting maxBitRate for Family Room to: 0 2006-09-06 10:31:26.4201 Setting maxBitRate for Family Room to: 0 2006-09-06 10:31:26.4212 Setting maxBitRate for Living Room to: 0 2006-09-06 10:31:26.4221 Setting maxBitRate for Master Bedroom to: 0 2006-09-06 10:31:26.4229 Setting maxBitRate for Basement Theater to: 0 2006-09-06 10:31:26.4256 currentPlaylistChangeTime : Wed Sep 6 10:24:38 2006 2006-09-06 10:31:26.4269 currentPlaylistRender : Wed Sep 6 10:24:40 2006 2006-09-06 10:31:26.4278 currentPlaylistRenderSkin : 2006-09-06 10:31:26.4286 currentPlaylistRenderStart: 0 2006-09-06 10:31:26.4294 skinOverride: 2006-09-06 10:31:26.4301 start: 0 2006-09-06 10:31:26.4309 Skipping playlist build - not modified. 2006-09-06 10:31:37.2189 Setting maxBitRate for Family Room to: 0 2006-09-06 10:31:37.2202 Setting maxBitRate for Family Room to: 0 2006-09-06 10:31:37.2213 Setting maxBitRate for Living Room to: 0 2006-09-06 10:31:37.2222 Setting maxBitRate for Master Bedroom to: 0 2006-09-06 10:31:37.2231 Setting maxBitRate for Basement Theater to: 0 2006-09-06 10:31:37.2262 currentPlaylistChangeTime : Wed Sep 6 10:24:38 2006 2006-09-06 10:31:37.2272 currentPlaylistRender : Wed Sep 6 10:24:40 2006 2006-09-06 10:31:37.2281 currentPlaylistRenderSkin : 2006-09-06 10:31:37.2288 currentPlaylistRenderStart: 0 2006-09-06 10:31:37.2296 skinOverride: 2006-09-06 10:31:37.2303 start: 0 2006-09-06 10:31:37.2311 Skipping playlist build - not modified. 2006-09-06 10:31:48.1052 Setting maxBitRate for Family Room to: 0 2006-09-06 10:31:48.1066 Setting maxBitRate for Family Room to: 0 2006-09-06 10:31:48.1077 Setting maxBitRate for Living Room to: 0 2006-09-06 10:31:48.1086 Setting maxBitRate for Master Bedroom to: 0 2006-09-06 10:31:48.1095 Setting maxBitRate for Basement Theater to: 0 2006-09-06 10:31:48.1123 currentPlaylistChangeTime : Wed Sep 6 10:24:38 2006 2006-09-06 10:31:48.1135 currentPlaylistRender : Wed Sep 6 10:24:40 2006 2006-09-06 10:31:48.1144 currentPlaylistRenderSkin : 2006-09-06 10:31:48.1152 currentPlaylistRenderStart: 0 2006-09-06 10:31:48.1159 skinOverride: 2006-09-06 10:31:48.1166 start: 0 2006-09-06 10:31:48.1174 Skipping playlist build - not modified. This was repeating over and over again. Now the next song finally started playing. After that the log entries look more normal. Like this. 2006-09-06 10:37:53.8017 new playlistindex: 23 2006-09-06 10:37:53.8051 00:04:20:05:a5:e0: Current playmode: stop 2006-09-06 10:37:53.8062 **skipahead: opening next song 2006-09-06 10:37:53.8092 opening next song... 2006-09-06 10:37:53.8104 the next song is number 24, was 23 2006-09-06 10:37:53.8156 Setting maxBitRate for Family Room to: 0 2006-09-06 10:37:53.8169 Setting maxBitRate for Family Room to: 0 2006-09-06 10:37:53.8202 Setting maxBitRate for Living Room to: 0 2006-09-06 10:37:53.8213 Setting maxBitRate for Master Bedroom to: 0 2006-09-06 10:37:53.8244 Setting maxBitRate for Basement Theater to: 0 2006-09-06 10:37:53.8287 undermax = 1, type = mp3, squeezebox2 = 00:04:20:05:a5:e0 2006-09-06 10:37:53.8297 checking formats for: mp3-wma-squeezebox2-00:04:20:05:a5:e0 2006-09-06 10:37:53.8301 checking formats for: mp3-wma-*-00:04:20:05:a5:e0 2006-09-06 10:37:53.8305 checking formats for: mp3-wma-squeezebox2-* 2006-09-06 10:37:53.8308 checking formats for: mp3-wma-*-* 2006-09-06 10:37:53.8312 checking formats for: mp3-ogg-squeezebox2-00:04:20:05:a5:e0 2006-09-06 10:37:53.8315 checking formats for: mp3-ogg-*-00:04:20:05:a5:e0 2006-09-06 10:37:53.8318 checking formats for: mp3-ogg-squeezebox2-* 2006-09-06 10:37:53.8321 checking formats for: mp3-ogg-*-* 2006-09-06 10:37:53.8325 checking formats for: mp3-flc-squeezebox2-00:04:20:05:a5:e0 2006-09-06 10:37:53.8328 checking formats for: mp3-flc-*-00:04:20:05:a5:e0 2006-09-06 10:37:53.8331 checking formats for: mp3-flc-squeezebox2-* 2006-09-06 10:37:53.8337 checking formats for: mp3-flc-*-* 2006-09-06 10:37:53.8342 checking formats for: mp3-aif-squeezebox2-00:04:20:05:a5:e0 2006-09-06 10:37:53.8369 checking formats for: mp3-aif-*-00:04:20:05:a5:e0 2006-09-06 10:37:53.8462 checking formats for: mp3-aif-squeezebox2-* 2006-09-06 10:37:53.8493 checking formats for: mp3-aif-*-* 2006-09-06 10:37:53.8503 checking formats for: mp3-wav-squeezebox2-00:04:20:05:a5:e0 2006-09-06 10:37:53.8533 checking formats for: mp3-wav-*-00:04:20:05:a5:e0 2006-09-06 10:37:53.8544 checking formats for: mp3-wav-squeezebox2-* 2006-09-06 10:37:53.8574 checking formats for: mp3-wav-*-* 2006-09-06 10:37:53.8585 checking formats for: mp3-mp3-squeezebox2-00:04:20:05:a5:e0 2006-09-06 10:37:53.8614 checking formats for: mp3-mp3-*-00:04:20:05:a5:e0 2006-09-06 10:37:53.8624 checking formats for: mp3-mp3-squeezebox2-* 2006-09-06 10:37:53.8654 checking formats for: mp3-mp3-*-* 2006-09-06 10:37:53.8698 Checking to see if mp3-mp3-*-* is enabled 2006-09-06 10:37:53.8712 enabled 2006-09-06 10:37:53.8740 Found command: - 2006-09-06 10:37:53.8783 Setting maxBitRate for Family Room to: 0 2006-09-06 10:37:53.8793 Setting maxBitRate for Family Room to: 0 2006-09-06 10:37:53.8799 Setting maxBitRate for Living Room to: 0 2006-09-06 10:37:53.8804 Setting maxBitRate for Master Bedroom to: 0 2006-09-06 10:37:53.8809 Setting maxBitRate for Basement Theater to: 0 2006-09-06 10:37:53.8813 Matched Format: mp3 Type: mp3 Command: - 2006-09-06 10:37:53.8817 opening next song (old format: , new: mp3) current playmode: stop 2006-09-06 10:37:53.8821 Adding song index 24 to song queue 2006-09-06 10:37:53.8825 Clearing out song queue first 2006-09-06 10:37:53.8829 Song queue is now 24 2006-09-06 10:37:53.8833 **skipahead: restarting 2006-09-06 10:37:53.8837 00:04:20:05:a5:e0: Switching to mode play from stop 2006-09-06 10:37:53.8865 resetSong 2006-09-06 10:37:54.1421 openSong on: file:///Volumes/WORKGROUP%3BLKG0F76F9/Media%20Files/David%20Broza%20-%20It%27s%20All%20Or%20Nothing/When%20You%20Tell%20Me%20That%20You%20Love%20Me.mp3 2006-09-06 10:37:54.1473 openSong: duration: [253.622] size: [7540377] endian [] offset: [682] for file:///Volumes/WORKGROUP%3BLKG0F76F9/Media%20Files/David%20Broza%20-%20It%27s%20All%20Or%20Nothing/When%20You%20Tell%20Me%20That%20You%20Love%20Me.mp3 2006-09-06 10:37:54.1487 Setting maxBitRate for Living Room to: 0 2006-09-06 10:37:54.1519 Setting maxBitRate for Living Room to: 0 2006-09-06 10:37:54.1533 Setting maxBitRate for Family Room to: 0 2006-09-06 10:37:54.1564 Setting maxBitRate for Master Bedroom to: 0 2006-09-06 10:37:54.1576 Setting maxBitRate for Basement Theater to: 0 2006-09-06 10:37:54.1613 Setting maxBitRate for Living Room to: 0 2006-09-06 10:37:54.1625 Setting maxBitRate for Living Room to: 0 2006-09-06 10:37:54.1656 Setting maxBitRate for Family Room to: 0 2006-09-06 10:37:54.1669 Setting maxBitRate for Master Bedroom to: 0 2006-09-06 10:37:54.1699 Setting maxBitRate for Basement Theater to: 0 2006-09-06 10:37:54.1710 undermax = 1, type = mp3, squeezebox2 = 00:04:20:05:a6:0b 2006-09-06 10:37:54.1743 checking formats for: mp3-wma-squeezebox2-00:04:20:05:a6:0b 2006-09-06 10:37:54.1754 checking formats for: mp3-wma-*-00:04:20:05:a6:0b 2006-09-06 10:37:54.1785 checking formats for: mp3-wma-squeezebox2-* 2006-09-06 10:37:54.1795 checking formats for: mp3-wma-*-* 2006-09-06 10:37:54.1804 checking formats for: mp3-ogg-squeezebox2-00:04:20:05:a6:0b 2006-09-06 10:37:54.1834 checking formats for: mp3-ogg-*-00:04:20:05:a6:0b 2006-09-06 10:37:54.1864 checking formats for: mp3-ogg-squeezebox2-* 2006-09-06 10:37:54.1874 checking formats for: mp3-ogg-*-* 2006-09-06 10:37:54.1883 checking formats for: mp3-flc-squeezebox2-00:04:20:05:a6:0b 2006-09-06 10:37:54.1912 checking formats for: mp3-flc-*-00:04:20:05:a6:0b 2006-09-06 10:37:54.1943 checking formats for: mp3-flc-squeezebox2-* 2006-09-06 10:37:54.1954 checking formats for: mp3-flc-*-* 2006-09-06 10:37:54.1984 checking formats for: mp3-aif-squeezebox2-00:04:20:05:a6:0b 2006-09-06 10:37:54.1995 checking formats for: mp3-aif-*-00:04:20:05:a6:0b 2006-09-06 10:37:54.2025 checking formats for: mp3-aif-squeezebox2-* 2006-09-06 10:37:54.2035 checking formats for: mp3-aif-*-* 2006-09-06 10:37:54.2069 checking formats for: mp3-wav-squeezebox2-00:04:20:05:a6:0b 2006-09-06 10:37:54.2080 checking formats for: mp3-wav-*-00:04:20:05:a6:0b 2006-09-06 10:37:54.2110 checking formats for: mp3-wav-squeezebox2-* 2006-09-06 10:37:54.2121 checking formats for: mp3-wav-*-* 2006-09-06 10:37:54.2153 checking formats for: mp3-mp3-squeezebox2-00:04:20:05:a6:0b 2006-09-06 10:37:54.2163 checking formats for: mp3-mp3-*-00:04:20:05:a6:0b 2006-09-06 10:37:54.2192 checking formats for: mp3-mp3-squeezebox2-* 2006-09-06 10:37:54.2202 checking formats for: mp3-mp3-*-* 2006-09-06 10:37:54.2232 Checking to see if mp3-mp3-*-* is enabled 2006-09-06 10:37:54.2242 enabled 2006-09-06 10:37:54.2272 Found command: - 2006-09-06 10:37:54.2284 Setting maxBitRate for Living Room to: 0 2006-09-06 10:37:54.2317 Setting maxBitRate for Living Room to: 0 2006-09-06 10:37:54.2328 Setting maxBitRate for Family Room to: 0 2006-09-06 10:37:54.2359 Setting maxBitRate for Master Bedroom to: 0 2006-09-06 10:37:54.2370 Setting maxBitRate for Basement Theater to: 0 2006-09-06 10:37:54.2402 Matched Format: mp3 Type: mp3 Command: - 2006-09-06 10:37:54.2413 openSong: this is an mp3 file: file:///Volumes/WORKGROUP%3BLKG0F76F9/Media%20Files/David%20Broza%20-%20It%27s%20All%20Or%20Nothing/When%20You%20Tell%20Me%20That%20You%20Love%20Me.mp3 2006-09-06 10:37:54.2444 file type: mp3 format: mp3 inrate: 237 maxRate: 0 2006-09-06 10:37:54.2486 command: - 2006-09-06 10:37:54.2499 openSong: opening file /Volumes/WORKGROUP;LKG0F76F9/Media Files/David Broza - It's All Or Nothing/When You Tell Me That You Love Me.mp3 2006-09-06 10:37:54.2547 openSong: seeking in 682 into /Volumes/WORKGROUP;LKG0F76F9/Media Files/David Broza - It's All Or Nothing/When You Tell Me That You Love Me.mp3 2006-09-06 10:37:54.2561 openSong: Streaming with format: mp3 2006-09-06 10:37:54.2670 00:04:20:05:a5:e0 New play mode: play 2006-09-06 10:37:54.2736 *************stream called: s paused: 1 format: mp3 url: file:///Volumes/WORKGROUP%3BLKG0F76F9/Media%20Files/David%20Broza%20-%20It%27s%20All%20Or%20Nothing/When%20You%20Tell%20Me%20That%20You%20Love%20Me.mp3 2006-09-06 10:37:54.2750 Backtrace: frame 0: Slim::Player::Squeezebox::stream (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Squeezebox.pm line 160) frame 1: Slim::Player::Squeezebox::play (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 490) frame 2: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 637) frame 3: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 4: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 5: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 6: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 7: Slim::Networking::Select::select (./slimserver.pl line 503) frame 8: main::idle (./slimserver.pl line 456) frame 9: main::main (./slimserver.pl line 1055) 2006-09-06 10:37:54.2792 starting with decoder with format: m autostart: 0 threshold: 128 samplesize: ? samplerate: ? endian: ? channels: ? 2006-09-06 10:37:54.2823 flags: 0 2006-09-06 10:37:54.2856 sending strm frame of length: 76 request string: GET /stream.mp3?player=00:04:20:05:a5:e0 HTTP/1.0 2006-09-06 10:37:54.2909 Playlist: Jumping to song index: 24 2006-09-06 10:37:54.2926 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 637) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 5: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1055) 2006-09-06 10:37:54.2966 new playlistindex: 24 2006-09-06 10:37:54.3011 Playlist: Jumping to song index: 24 2006-09-06 10:37:54.3026 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 637) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 5: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1055) 2006-09-06 10:37:54.3067 new playlistindex: 24 2006-09-06 10:37:54.3134 Playlist: Jumping to song index: 24 2006-09-06 10:37:54.3145 Backtrace:
More strange behavior, possibly related to this issue. I noticed that the display on the browser was not showing the name of the song,. or the artist (Led Zeppelin), just the album. Went and looked on the nearest player display, and saw that it was showing the right (complete) information. So I went back to the browser and did a browser refresh, thinking that might regenerate the display of the correct information. But instead, it stopped playing altogether, and then reshuffled the playlist and started again at song 1 (0). Here's the log from the point when I did the refresh (you'll see it switch from song 92 to song 0): 2006-09-06 15:28:58.8365 new playlistindex: 92 2006-09-06 15:28:58.8376 Playlist: Jumping to song index: 92 2006-09-06 15:28:58.8388 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Commands.pm line 1023) frame 4: Slim::Control::Commands::playlistXtracksCommand (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 1463) frame 5: (eval) (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 1463) frame 6: Slim::Control::Request::execute (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 752) frame 7: Slim::Control::Request::executeRequest (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/HTTP.pm line 667) frame 8: Slim::Web::HTTP::processURL (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/HTTP.pm line 517) frame 9: Slim::Web::HTTP::processHTTP (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 10: Slim::Networking::Select::select (./slimserver.pl line 503) frame 11: main::idle (./slimserver.pl line 456) frame 12: main::main (./slimserver.pl line 1055) 2006-09-06 15:28:58.8405 new playlistindex: 92 2006-09-06 15:28:58.8415 Playlist: Jumping to song index: 92 2006-09-06 15:28:58.8427 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Commands.pm line 1023) frame 4: Slim::Control::Commands::playlistXtracksCommand (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 1463) frame 5: (eval) (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 1463) frame 6: Slim::Control::Request::execute (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 752) frame 7: Slim::Control::Request::executeRequest (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/HTTP.pm line 667) frame 8: Slim::Web::HTTP::processURL (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/HTTP.pm line 517) frame 9: Slim::Web::HTTP::processHTTP (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 10: Slim::Networking::Select::select (./slimserver.pl line 503) frame 11: main::idle (./slimserver.pl line 456) frame 12: main::main (./slimserver.pl line 1055) 2006-09-06 15:28:58.8444 new playlistindex: 92 2006-09-06 15:28:58.8455 Playlist: Jumping to song index: 92 2006-09-06 15:28:58.8466 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Commands.pm line 1023) frame 4: Slim::Control::Commands::playlistXtracksCommand (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 1463) frame 5: (eval) (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 1463) frame 6: Slim::Control::Request::execute (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 752) frame 7: Slim::Control::Request::executeRequest (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/HTTP.pm line 667) frame 8: Slim::Web::HTTP::processURL (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/HTTP.pm line 517) frame 9: Slim::Web::HTTP::processHTTP (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 10: Slim::Networking::Select::select (./slimserver.pl line 503) frame 11: main::idle (./slimserver.pl line 456) frame 12: main::main (./slimserver.pl line 1055) 2006-09-06 15:28:58.8484 new playlistindex: 92 2006-09-06 15:28:58.8494 00:04:20:05:a5:e0: Current playmode: stop 2006-09-06 15:28:59.1088 Adding song index 0 to song queue 2006-09-06 15:28:59.1102 Clearing out song queue first 2006-09-06 15:28:59.1116 Song queue is now 0 2006-09-06 15:28:59.1128 Playlist: Jumping to song index: 0 2006-09-06 15:28:59.1140 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 450) frame 3: Slim::Player::Playlist::reshuffle (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 419) frame 4: Slim::Player::Playlist::clear (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Commands.pm line 1024) frame 5: Slim::Control::Commands::playlistXtracksCommand (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 1463) frame 6: (eval) (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 1463) frame 7: Slim::Control::Request::execute (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 752) frame 8: Slim::Control::Request::executeRequest (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/HTTP.pm line 667) frame 9: Slim::Web::HTTP::processURL (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/HTTP.pm line 517) frame 10: Slim::Web::HTTP::processHTTP (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 11: Slim::Networking::Select::select (./slimserver.pl line 503) frame 12: main::idle (./slimserver.pl line 456) frame 13: main::main (./slimserver.pl line 1055) 2006-09-06 15:28:59.1157 new playlistindex: 0 2006-09-06 15:28:59.1168 Playlist: Jumping to song index: 0 2006-09-06 15:28:59.1180 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 450) frame 3: Slim::Player::Playlist::reshuffle (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 419) frame 4: Slim::Player::Playlist::clear (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Commands.pm line 1024) frame 5: Slim::Control::Commands::playlistXtracksCommand (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 1463) frame 6: (eval) (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 1463) frame 7: Slim::Control::Request::execute (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 752) frame 8: Slim::Control::Request::executeRequest (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/HTTP.pm line 667) frame 9: Slim::Web::HTTP::processURL (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/HTTP.pm line 517) frame 10: Slim::Web::HTTP::processHTTP (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 11: Slim::Networking::Select::select (./slimserver.pl line 503) frame 12: main::idle (./slimserver.pl line 456) frame 13: main::main (./slimserver.pl line 1055) 2006-09-06 15:28:59.1197 new playlistindex: 0 2006-09-06 15:28:59.1212 Playlist: Jumping to song index: 0 2006-09-06 15:28:59.1224 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 450) frame 3: Slim::Player::Playlist::reshuffle (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 419) frame 4: Slim::Player::Playlist::clear (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Commands.pm line 1024) frame 5: Slim::Control::Commands::playlistXtracksCommand (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 1463) frame 6: (eval) (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 1463) frame 7: Slim::Control::Request::execute (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 752) frame 8: Slim::Control::Request::executeRequest (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/HTTP.pm line 667) frame 9: Slim::Web::HTTP::processURL (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/HTTP.pm line 517) frame 10: Slim::Web::HTTP::processHTTP (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 11: Slim::Networking::Select::select (./slimserver.pl line 503) frame 12: main::idle (./slimserver.pl line 456) frame 13: main::main (./slimserver.pl line 1055) 2006-09-06 15:28:59.1241 new playlistindex: 0 2006-09-06 15:28:59.1252 Playlist: Jumping to song index: 0 2006-09-06 15:28:59.1263 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 450) frame 3: Slim::Player::Playlist::reshuffle (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 419) frame 4: Slim::Player::Playlist::clear (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Commands.pm line 1024) frame 5: Slim::Control::Commands::playlistXtracksCommand (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 1463) frame 6: (eval) (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 1463) frame 7: Slim::Control::Request::execute (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 752) frame 8: Slim::Control::Request::executeRequest (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/HTTP.pm line 667) frame 9: Slim::Web::HTTP::processURL (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/HTTP.pm line 517) frame 10: Slim::Web::HTTP::processHTTP (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 11: Slim::Networking::Select::select (./slimserver.pl line 503) frame 12: main::idle (./slimserver.pl line 456) frame 13: main::main (./slimserver.pl line 1055) 2006-09-06 15:28:59.1279 new playlistindex: 0 2006-09-06 15:29:01.3324 Reshuffling, current song index: -1, preserve song? no 2006-09-06 15:29:01.3519 Playlist: Jumping to song index: 0 2006-09-06 15:29:01.3534 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 622) frame 3: Slim::Player::Playlist::reshuffle (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Commands.pm line 1063) frame 4: Slim::Control::Commands::playlistXtracksCommand (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 1463) frame 5: (eval) (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 1463) frame 6: Slim::Control::Request::execute (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 752) frame 7: Slim::Control::Request::executeRequest (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/HTTP.pm line 667) frame 8: Slim::Web::HTTP::processURL (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/HTTP.pm line 517) frame 9: Slim::Web::HTTP::processHTTP (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 10: Slim::Networking::Select::select (./slimserver.pl line 503) frame 11: main::idle (./slimserver.pl line 456) frame 12: main::main (./slimserver.pl line 1055) 2006-09-06 15:29:01.3553 new playlistindex: 0 2006-09-06 15:29:01.3564 Playlist: Jumping to song index: 0 2006-09-06 15:29:01.3575 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 622) frame 3: Slim::Player::Playlist::reshuffle (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Commands.pm line 1063) frame 4: Slim::Control::Commands::playlistXtracksCommand (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 1463) frame 5: (eval) (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 1463) frame 6: Slim::Control::Request::execute (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Request.pm line 752) frame 7: Slim::Control::Request::executeRequest (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/HTTP.pm line 667) frame 8: Slim::Web::HTTP::processURL (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/HTTP.pm line 517) frame 9: Slim::Web::HTTP::processHTTP (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 10: Slim::Networking::Select::select (./slimserver.pl line 503) frame 11: main::idle (./slimserver.pl line 456) frame 12: main::main (./slimserver.pl line 1055) 2006-09-06 15:29:01.3593 new playlistindex: 0 2006-09-06 15:29:01.3603 Playlist: Jumping to song index: 0 2006-09-06 15:29:01.3615 Backtrace:
Hi Mike, We were talking about your bug earlier in our engineering meeting. Especially since you're the only one seeing these issues, our support manager felt strongly that your Mac might just be subtly broken. Do you ever notice problems with other applications on the system? I notice there's a version of memtest for OSX at http://www.memtestosx.org/ Would you be interested in running it to see if it turns up any info?
Subject: Out of Office AutoReply: Player freezes part way through shuffled song on playlist I am out of the office on business through Friday evening September 8th. If you have an urgent matter, call my mobile number 240-899-5886, or instant-message my phone at https://wmg.tmomail.net/customer_site/jsp/messaging_lo.jsp. Otherwise, I will reply to your email message at the earliest opportunity. <!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 3.2//EN"> <HTML> <HEAD> <META HTTP-EQUIV="Content-Type" CONTENT="text/html; charset=iso-8859-1"> <META NAME="Generator" CONTENT="MS Exchange Server version 6.5.7650.5"> <TITLE>Out of Office AutoReply: [Bug 3930] Player freezes part way through shuffled song on playlist</TITLE> </HEAD> <BODY> <!-- Converted from text/plain format --> <P><FONT SIZE=2>I am out of the office on business through Friday evening September 8th. If you have an urgent matter, call my mobile number 240-899-5886, or instant-message my phone at <A HREF="https://wmg.tmomail.net/customer_site/jsp/messaging_lo.jsp">https://wmg.tmomail.net/customer_site/jsp/messaging_lo.jsp</A>. Otherwise, I will reply to your email message at the earliest opportunity.</FONT></P> </BODY> </HTML>
Happy to try the memtest - but this is the only app that ever fails in any way, much less locks up the process in the way that it does. At the same time I will continue to try to get a good log on this. Still haven't really done that, I keep having other problems that are similar, but not quite the same, and got logs on those (the last two in this thread, for example).
Last login: Tue Sep 5 11:43:01 on ttyp1 /Applications/memtest/memtest; exit Welcome to Darwin! Mike-Gilpins-Computer:~ mikegilpin$ /Applications/memtest/memtest; exit Memtest version 4.13 (32-bit) Copyright (C) 2004 Charles Cazabon Copyright (C) 2004, 2005 Tony Scaminaci (Macintosh port) Licensed under the GNU General Public License version 2 only NOTE: No command-line arguments have been specified Using defaults: test all available memory, 1 pass, no logfile MacOS X (Darwin) running in multiuser mode POSIX version 200112 Pagesize is 4096 Requested memory: 596MB (625524736 bytes) Available memory: 596MB (625524736 bytes) Allocated memory: 596MB (625524736 bytes) at local address 0x02008000 Attempting memory lock... locked successfully Creating test buffers... Buffer A: 298MB (312762368 bytes) at local address 0x02008000 Buffer B: 298MB (312762368 bytes) at local address 0x14a4e000 Running 1 test sequence... Test sequence 1 of 1: Stuck Address : ok Random Value : ok Compare XOR : ok Compare SUB : ok Compare MUL : ok Compare DIV : ok Compare OR : ok Compare AND : ok Sequential Increment: ok Solid Bits : ok Block Sequential : ok Checkerboard : ok Bit Spread : ok Bit Flip : ok Walking Ones : ok Walking Zeroes : ok All tests passed. logout [Process completed]
Note that unless you reboot into single user mode, memtest can't test all available memory (as indicated by the report on the size of the memory tested). http://memtestosx.org/downloads/memtest413/Users_Guide.rtf
OK, finally got log entries from a "freeze up." I went back pretty far from the end of what was in the termnal window, although it all seemed to be about song 154 - not sure why there were so many entries about that. 2006-09-09 17:41:22.0356 new playlistindex: 154 2006-09-09 17:41:22.0369 Playlist: Jumping to song index: 154 2006-09-09 17:41:22.0381 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 637) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 5: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:22.0397 new playlistindex: 154 2006-09-09 17:41:22.0408 Playlist: Jumping to song index: 154 2006-09-09 17:41:22.0420 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 637) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 5: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:22.0435 new playlistindex: 154 2006-09-09 17:41:22.0446 Playlist: Jumping to song index: 154 2006-09-09 17:41:22.0457 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 637) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 5: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:22.0473 new playlistindex: 154 2006-09-09 17:41:22.0484 00:04:20:05:a6:0a: Current playmode: play 2006-09-09 17:41:22.0508 00:04:20:05:a6:0a checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.0648 00:04:20:05:a6:0a checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.0674 00:04:20:05:a6:0a checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.0699 00:04:20:05:a6:0a checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.0723 00:04:20:05:a6:0a checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.0747 00:04:20:05:a6:0a checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.0770 00:04:20:05:a6:0a checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.0794 00:04:20:05:a6:0a checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.0818 00:04:20:05:a6:0a checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.0842 modifyPlaylistCallback: savecurrsong is 1 2006-09-09 17:41:22.1622 00:04:20:05:bd:96 checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.1683 00:04:20:05:bd:96 checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.1708 00:04:20:05:bd:96 checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.1733 00:04:20:05:bd:96 checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.1757 00:04:20:05:bd:96 checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.1780 00:04:20:05:bd:96 checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.1804 00:04:20:05:bd:96 checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.1828 00:04:20:05:bd:96 checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.1852 00:04:20:05:a5:e0 checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.1875 00:04:20:05:a5:e0 checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.1900 00:04:20:05:a5:e0 checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.1923 00:04:20:05:a5:e0 checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.2065 00:04:20:05:a5:e0 checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.2090 00:04:20:05:a5:e0 checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.2114 00:04:20:05:a5:e0 checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.2141 00:04:20:05:a5:e0 checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.2166 00:04:20:05:a6:0b checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.2189 00:04:20:05:a6:0b checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.2213 00:04:20:05:a6:0b checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.2237 00:04:20:05:a6:0b checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.2260 00:04:20:05:a6:0b checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.2283 00:04:20:05:a6:0b checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.2310 00:04:20:05:a6:0b checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.2333 00:04:20:05:a6:0b checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.3264 00:04:20:05:a6:0a checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.3284 Squeezebox got HTTP response: HTTP/1.0 200 OK Date: Sat, 09 Sep 2006 21:41:22 GMT Server: SlimServer (6.5b1 - 9364) Connection: close Content-Type: audio/mpeg Set-Cookie: SlimServer-player=00%3A04%3A20%3A05%3Aa6%3A0a; path=/; expires=Sun, 09-Sep-2007 21:41:22 GMT 2006-09-09 17:41:22.3295 processing headers for direct streaming: HTTP/1.0 200 OK Date: Sat, 09 Sep 2006 21:41:22 GMT Server: SlimServer (6.5b1 - 9364) Connection: close Content-Type: audio/mpeg Set-Cookie: SlimServer-player=00%3A04%3A20%3A05%3Aa6%3A0a; path=/; expires=Sun, 09-Sep-2007 21:41:22 GMT 2006-09-09 17:41:22.3331 We need to send 0 seconds of silence... 2006-09-09 17:41:22.3344 sending 0 bytes of silence 2006-09-09 17:41:22.7753 00:04:20:05:a6:0b checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.7773 Squeezebox got HTTP response: HTTP/1.0 200 OK Date: Sat, 09 Sep 2006 21:41:22 GMT Server: SlimServer (6.5b1 - 9364) Connection: close Content-Type: audio/mpeg Set-Cookie: SlimServer-player=00%3A04%3A20%3A05%3Aa6%3A0b; path=/; expires=Sun, 09-Sep-2007 21:41:22 GMT 2006-09-09 17:41:22.7784 processing headers for direct streaming: HTTP/1.0 200 OK Date: Sat, 09 Sep 2006 21:41:22 GMT Server: SlimServer (6.5b1 - 9364) Connection: close Content-Type: audio/mpeg Set-Cookie: SlimServer-player=00%3A04%3A20%3A05%3Aa6%3A0b; path=/; expires=Sun, 09-Sep-2007 21:41:22 GMT 2006-09-09 17:41:22.7950 00:04:20:05:a5:e0 checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.7973 Squeezebox got HTTP response: HTTP/1.0 200 OK Date: Sat, 09 Sep 2006 21:41:22 GMT Server: SlimServer (6.5b1 - 9364) Connection: close Content-Type: audio/mpeg Set-Cookie: SlimServer-player=00%3A04%3A20%3A05%3Aa5%3Ae0; path=/; expires=Sun, 09-Sep-2007 21:41:22 GMT 2006-09-09 17:41:22.7986 processing headers for direct streaming: HTTP/1.0 200 OK Date: Sat, 09 Sep 2006 21:41:22 GMT Server: SlimServer (6.5b1 - 9364) Connection: close Content-Type: audio/mpeg Set-Cookie: SlimServer-player=00%3A04%3A20%3A05%3Aa5%3Ae0; path=/; expires=Sun, 09-Sep-2007 21:41:22 GMT 2006-09-09 17:41:22.8850 00:04:20:05:bd:96 checking buffer fullness: 0 (threshold: 128) 2006-09-09 17:41:22.8868 Squeezebox got HTTP response: HTTP/1.0 200 OK Date: Sat, 09 Sep 2006 21:41:22 GMT Server: SlimServer (6.5b1 - 9364) Connection: close Content-Type: audio/mpeg Set-Cookie: SlimServer-player=00%3A04%3A20%3A05%3Abd%3A96; path=/; expires=Sun, 09-Sep-2007 21:41:22 GMT 2006-09-09 17:41:22.8879 processing headers for direct streaming: HTTP/1.0 200 OK Date: Sat, 09 Sep 2006 21:41:22 GMT Server: SlimServer (6.5b1 - 9364) Connection: close Content-Type: audio/mpeg Set-Cookie: SlimServer-player=00%3A04%3A20%3A05%3Abd%3A96; path=/; expires=Sun, 09-Sep-2007 21:41:22 GMT 2006-09-09 17:41:22.9276 00:04:20:05:a6:0a checking buffer fullness: 131072 (threshold: 128) 2006-09-09 17:41:22.9287 00:04:20:05:a6:0a is ready to sync 1157838082.92872 2006-09-09 17:41:22.9430 00:04:20:05:a6:0b checking buffer fullness: 131072 (threshold: 128) 2006-09-09 17:41:22.9446 00:04:20:05:a6:0b is ready to sync 1157838082.94463 2006-09-09 17:41:22.9695 00:04:20:05:bd:96 checking buffer fullness: 131072 (threshold: 128) 2006-09-09 17:41:22.9709 00:04:20:05:bd:96 is ready to sync 1157838082.97094 2006-09-09 17:41:22.9740 00:04:20:05:a5:e0 checking buffer fullness: 131884 (threshold: 128) 2006-09-09 17:41:22.9754 00:04:20:05:a5:e0 is ready to sync 1157838082.97537 2006-09-09 17:41:22.9763 all clients ready to sync now. unpausing them. 2006-09-09 17:41:22.9774 *************stream called: u paused: format: url: 2006-09-09 17:41:22.9785 Backtrace: frame 0: Slim::Player::Squeezebox::stream (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Squeezebox.pm line 187) frame 1: Slim::Player::Squeezebox::resume (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 393) frame 2: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 3: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 4: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 5: Slim::Networking::Select::select (./slimserver.pl line 503) frame 6: main::idle (./slimserver.pl line 456) frame 7: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:22.9800 starting with decoder with format: m autostart: 1 threshold: 255 samplesize: ? samplerate: ? endian: ? channels: ? 2006-09-09 17:41:22.9809 flags: 0 2006-09-09 17:41:22.9817 sending strm frame of length: 24 request string: 2006-09-09 17:41:22.9836 *************stream called: u paused: format: url: 2006-09-09 17:41:22.9848 Backtrace: frame 0: Slim::Player::Squeezebox::stream (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Squeezebox.pm line 187) frame 1: Slim::Player::Squeezebox::resume (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 393) frame 2: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 3: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 4: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 5: Slim::Networking::Select::select (./slimserver.pl line 503) frame 6: main::idle (./slimserver.pl line 456) frame 7: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:22.9864 starting with decoder with format: m autostart: 1 threshold: 255 samplesize: ? samplerate: ? endian: ? channels: ? 2006-09-09 17:41:22.9872 flags: 0 2006-09-09 17:41:22.9881 sending strm frame of length: 24 request string: 2006-09-09 17:41:22.9897 *************stream called: u paused: format: url: 2006-09-09 17:41:22.9909 Backtrace: frame 0: Slim::Player::Squeezebox::stream (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Squeezebox.pm line 187) frame 1: Slim::Player::Squeezebox::resume (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 393) frame 2: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 3: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 4: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 5: Slim::Networking::Select::select (./slimserver.pl line 503) frame 6: main::idle (./slimserver.pl line 456) frame 7: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:22.9926 starting with decoder with format: m autostart: 1 threshold: 255 samplesize: ? samplerate: ? endian: ? channels: ? 2006-09-09 17:41:22.9934 flags: 0 2006-09-09 17:41:22.9945 sending strm frame of length: 24 request string: 2006-09-09 17:41:23.0070 *************stream called: u paused: format: url: 2006-09-09 17:41:23.0083 Backtrace: frame 0: Slim::Player::Squeezebox::stream (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Squeezebox.pm line 187) frame 1: Slim::Player::Squeezebox::resume (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 393) frame 2: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 3: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 4: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 5: Slim::Networking::Select::select (./slimserver.pl line 503) frame 6: main::idle (./slimserver.pl line 456) frame 7: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:23.0099 starting with decoder with format: m autostart: 1 threshold: 255 samplesize: ? samplerate: ? endian: ? channels: ? 2006-09-09 17:41:23.0108 flags: 0 2006-09-09 17:41:23.0117 sending strm frame of length: 24 request string: 2006-09-09 17:41:23.0163 Got a track starting event 2006-09-09 17:41:23.0174 Song 154 has now started playing 2006-09-09 17:41:23.0187 Playlist: Jumping to song index: 154 2006-09-09 17:41:23.0200 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 1123) frame 3: Slim::Player::Source::trackStartEvent (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 663) frame 4: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 5: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 6: Slim::Networking::Select::select (./slimserver.pl line 503) frame 7: main::idle (./slimserver.pl line 456) frame 8: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:23.0216 new playlistindex: 154 2006-09-09 17:41:23.0227 Playlist: Jumping to song index: 154 2006-09-09 17:41:23.0239 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 1123) frame 3: Slim::Player::Source::trackStartEvent (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 663) frame 4: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 5: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 6: Slim::Networking::Select::select (./slimserver.pl line 503) frame 7: main::idle (./slimserver.pl line 456) frame 8: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:23.0261 new playlistindex: 154 2006-09-09 17:41:23.0273 Playlist: Jumping to song index: 154 2006-09-09 17:41:23.0288 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 1123) frame 3: Slim::Player::Source::trackStartEvent (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 663) frame 4: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 5: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 6: Slim::Networking::Select::select (./slimserver.pl line 503) frame 7: main::idle (./slimserver.pl line 456) frame 8: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:23.0304 new playlistindex: 154 2006-09-09 17:41:23.0315 Playlist: Jumping to song index: 154 2006-09-09 17:41:23.0325 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 1123) frame 3: Slim::Player::Source::trackStartEvent (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 663) frame 4: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 5: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 6: Slim::Networking::Select::select (./slimserver.pl line 503) frame 7: main::idle (./slimserver.pl line 456) frame 8: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:23.0341 new playlistindex: 154 2006-09-09 17:41:23.0354 Song queue is now 154 2006-09-09 17:41:23.0617 modifyPlaylistCallback: savecurrsong is 0 2006-09-09 17:41:23.1053 Got a track starting event 2006-09-09 17:41:23.1067 Song 154 has now started playing 2006-09-09 17:41:23.1080 Playlist: Jumping to song index: 154 2006-09-09 17:41:23.1091 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 1123) frame 3: Slim::Player::Source::trackStartEvent (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 663) frame 4: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 5: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 6: Slim::Networking::Select::select (./slimserver.pl line 503) frame 7: main::idle (./slimserver.pl line 456) frame 8: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:23.1107 new playlistindex: 154 2006-09-09 17:41:23.1118 Playlist: Jumping to song index: 154 2006-09-09 17:41:23.1129 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 1123) frame 3: Slim::Player::Source::trackStartEvent (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 663) frame 4: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 5: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 6: Slim::Networking::Select::select (./slimserver.pl line 503) frame 7: main::idle (./slimserver.pl line 456) frame 8: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:23.1145 new playlistindex: 154 2006-09-09 17:41:23.1156 Playlist: Jumping to song index: 154 2006-09-09 17:41:23.1166 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 1123) frame 3: Slim::Player::Source::trackStartEvent (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 663) frame 4: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 5: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 6: Slim::Networking::Select::select (./slimserver.pl line 503) frame 7: main::idle (./slimserver.pl line 456) frame 8: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:23.1181 new playlistindex: 154 2006-09-09 17:41:23.1192 Playlist: Jumping to song index: 154 2006-09-09 17:41:23.1203 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 1123) frame 3: Slim::Player::Source::trackStartEvent (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 663) frame 4: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 5: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 6: Slim::Networking::Select::select (./slimserver.pl line 503) frame 7: main::idle (./slimserver.pl line 456) frame 8: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:23.1221 new playlistindex: 154 2006-09-09 17:41:23.1235 Song queue is now 154 2006-09-09 17:41:23.1270 Got a track starting event 2006-09-09 17:41:23.1281 Song 154 has now started playing 2006-09-09 17:41:23.1293 Playlist: Jumping to song index: 154 2006-09-09 17:41:23.1304 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 1123) frame 3: Slim::Player::Source::trackStartEvent (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 663) frame 4: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 5: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 6: Slim::Networking::Select::select (./slimserver.pl line 503) frame 7: main::idle (./slimserver.pl line 456) frame 8: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:23.1319 new playlistindex: 154 2006-09-09 17:41:23.1330 Playlist: Jumping to song index: 154 2006-09-09 17:41:23.1341 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 1123) frame 3: Slim::Player::Source::trackStartEvent (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 663) frame 4: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 5: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 6: Slim::Networking::Select::select (./slimserver.pl line 503) frame 7: main::idle (./slimserver.pl line 456) frame 8: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:23.2658 new playlistindex: 154 2006-09-09 17:41:23.2671 Playlist: Jumping to song index: 154 2006-09-09 17:41:23.2682 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 1123) frame 3: Slim::Player::Source::trackStartEvent (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 663) frame 4: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 5: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 6: Slim::Networking::Select::select (./slimserver.pl line 503) frame 7: main::idle (./slimserver.pl line 456) frame 8: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:23.2698 new playlistindex: 154 2006-09-09 17:41:23.2709 Playlist: Jumping to song index: 154 2006-09-09 17:41:23.2719 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 1123) frame 3: Slim::Player::Source::trackStartEvent (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 663) frame 4: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 5: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 6: Slim::Networking::Select::select (./slimserver.pl line 503) frame 7: main::idle (./slimserver.pl line 456) frame 8: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:23.2735 new playlistindex: 154 2006-09-09 17:41:23.2748 Song queue is now 154 2006-09-09 17:41:23.2783 Got a track starting event 2006-09-09 17:41:23.2793 Song 154 has now started playing 2006-09-09 17:41:23.2805 Playlist: Jumping to song index: 154 2006-09-09 17:41:23.2816 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 1123) frame 3: Slim::Player::Source::trackStartEvent (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 663) frame 4: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 5: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 6: Slim::Networking::Select::select (./slimserver.pl line 503) frame 7: main::idle (./slimserver.pl line 456) frame 8: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:23.2831 new playlistindex: 154 2006-09-09 17:41:23.2843 Playlist: Jumping to song index: 154 2006-09-09 17:41:23.2854 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 1123) frame 3: Slim::Player::Source::trackStartEvent (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 663) frame 4: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 5: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 6: Slim::Networking::Select::select (./slimserver.pl line 503) frame 7: main::idle (./slimserver.pl line 456) frame 8: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:23.2869 new playlistindex: 154 2006-09-09 17:41:23.2880 Playlist: Jumping to song index: 154 2006-09-09 17:41:23.2894 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 1123) frame 3: Slim::Player::Source::trackStartEvent (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 663) frame 4: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 5: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 6: Slim::Networking::Select::select (./slimserver.pl line 503) frame 7: main::idle (./slimserver.pl line 456) frame 8: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:23.2910 new playlistindex: 154 2006-09-09 17:41:23.2921 Playlist: Jumping to song index: 154 2006-09-09 17:41:23.2931 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 1123) frame 3: Slim::Player::Source::trackStartEvent (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 663) frame 4: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 5: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 6: Slim::Networking::Select::select (./slimserver.pl line 503) frame 7: main::idle (./slimserver.pl line 456) frame 8: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:23.2947 new playlistindex: 154 2006-09-09 17:41:23.3068 Song queue is now 154 2006-09-09 17:41:23.3206 modifyPlaylistCallback: savecurrsong is 0 2006-09-09 17:41:23.4136 modifyPlaylistCallback: savecurrsong is 0 2006-09-09 17:41:23.4559 modifyPlaylistCallback: savecurrsong is 0
and here are some more log entries from *before* those that I just posted, going back to somewhere in the region of song 153. 2006-09-09 17:41:21.2131 starting with decoder with format: m autostart: 0 threshold: 255 samplesize: ? samplerate: ? endian: ? channels: ? 2006-09-09 17:41:21.2140 flags: 0 2006-09-09 17:41:21.2148 sending strm frame of length: 24 request string: 2006-09-09 17:41:21.2161 resetSong 2006-09-09 17:41:21.2174 Resetting song queue 2006-09-09 17:41:21.2183 Song queue is now 153 2006-09-09 17:41:21.2204 Playlist: Jumping to song index: 153 2006-09-09 17:41:21.2217 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 629) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 5: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:21.2234 new playlistindex: 153 2006-09-09 17:41:21.2248 Playlist: Jumping to song index: 153 2006-09-09 17:41:21.2429 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 629) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 5: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:21.2448 new playlistindex: 153 2006-09-09 17:41:21.2463 Playlist: Jumping to song index: 153 2006-09-09 17:41:21.2475 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 629) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 5: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:21.2492 new playlistindex: 153 2006-09-09 17:41:21.2503 Playlist: Jumping to song index: 153 2006-09-09 17:41:21.2514 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 629) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 5: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:21.2530 new playlistindex: 153 2006-09-09 17:41:21.2539 00:04:20:05:a6:0a: Current playmode: stop 2006-09-09 17:41:21.2548 **skipahead: opening next song 2006-09-09 17:41:21.2557 opening next song... 2006-09-09 17:41:21.2567 the next song is number 154, was 153 2006-09-09 17:41:21.2588 Setting maxBitRate for Basement Theater to: 0 2006-09-09 17:41:21.2599 Setting maxBitRate for Basement Theater to: 0 2006-09-09 17:41:21.2609 Setting maxBitRate for Living Room to: 0 2006-09-09 17:41:21.2618 Setting maxBitRate for Family Room to: 0 2006-09-09 17:41:21.2626 Setting maxBitRate for Master Bedroom to: 0 2006-09-09 17:41:21.2634 undermax = 1, type = mp3, squeezebox2 = 00:04:20:05:a6:0a 2006-09-09 17:41:21.2644 checking formats for: mp3-wma-squeezebox2-00:04:20:05:a6:0a 2006-09-09 17:41:21.2651 checking formats for: mp3-wma-*-00:04:20:05:a6:0a 2006-09-09 17:41:21.2659 checking formats for: mp3-wma-squeezebox2-* 2006-09-09 17:41:21.2666 checking formats for: mp3-wma-*-* 2006-09-09 17:41:21.2674 checking formats for: mp3-ogg-squeezebox2-00:04:20:05:a6:0a 2006-09-09 17:41:21.2681 checking formats for: mp3-ogg-*-00:04:20:05:a6:0a 2006-09-09 17:41:21.2688 checking formats for: mp3-ogg-squeezebox2-* 2006-09-09 17:41:21.2695 checking formats for: mp3-ogg-*-* 2006-09-09 17:41:21.2703 checking formats for: mp3-flc-squeezebox2-00:04:20:05:a6:0a 2006-09-09 17:41:21.2710 checking formats for: mp3-flc-*-00:04:20:05:a6:0a 2006-09-09 17:41:21.2718 checking formats for: mp3-flc-squeezebox2-* 2006-09-09 17:41:21.2725 checking formats for: mp3-flc-*-* 2006-09-09 17:41:21.2841 checking formats for: mp3-aif-squeezebox2-00:04:20:05:a6:0a 2006-09-09 17:41:21.2851 checking formats for: mp3-aif-*-00:04:20:05:a6:0a 2006-09-09 17:41:21.2859 checking formats for: mp3-aif-squeezebox2-* 2006-09-09 17:41:21.2867 checking formats for: mp3-aif-*-* 2006-09-09 17:41:21.2875 checking formats for: mp3-wav-squeezebox2-00:04:20:05:a6:0a 2006-09-09 17:41:21.2882 checking formats for: mp3-wav-*-00:04:20:05:a6:0a 2006-09-09 17:41:21.2890 checking formats for: mp3-wav-squeezebox2-* 2006-09-09 17:41:21.2897 checking formats for: mp3-wav-*-* 2006-09-09 17:41:21.2906 checking formats for: mp3-mp3-squeezebox2-00:04:20:05:a6:0a 2006-09-09 17:41:21.2916 checking formats for: mp3-mp3-*-00:04:20:05:a6:0a 2006-09-09 17:41:21.2923 checking formats for: mp3-mp3-squeezebox2-* 2006-09-09 17:41:21.2931 checking formats for: mp3-mp3-*-* 2006-09-09 17:41:21.2938 Checking to see if mp3-mp3-*-* is enabled 2006-09-09 17:41:21.2945 enabled 2006-09-09 17:41:21.2952 Found command: - 2006-09-09 17:41:21.2960 Setting maxBitRate for Basement Theater to: 0 2006-09-09 17:41:21.2972 Setting maxBitRate for Basement Theater to: 0 2006-09-09 17:41:21.2982 Setting maxBitRate for Living Room to: 0 2006-09-09 17:41:21.2991 Setting maxBitRate for Family Room to: 0 2006-09-09 17:41:21.2999 Setting maxBitRate for Master Bedroom to: 0 2006-09-09 17:41:21.3008 Matched Format: mp3 Type: mp3 Command: - 2006-09-09 17:41:21.3016 opening next song (old format: mp3, new: mp3) current playmode: stop 2006-09-09 17:41:21.3024 Adding song index 154 to song queue 2006-09-09 17:41:21.3032 Clearing out song queue first 2006-09-09 17:41:21.3041 Song queue is now 154 2006-09-09 17:41:21.3049 **skipahead: restarting 2006-09-09 17:41:21.3057 00:04:20:05:a6:0a: Switching to mode play from stop 2006-09-09 17:41:21.3070 resetSong 2006-09-09 17:41:21.6254 openSong on: file:///Volumes/WORKGROUP%3BLKG0F76F9/Media%20Files/Herb%20Alpert%20-%20Definitive%20Hits/This%20Guy%27s%20In%20Love%20With%20You.mp3 2006-09-09 17:41:21.6283 openSong: duration: [241.475] size: [6793413] endian [] offset: [670] for file:///Volumes/WORKGROUP%3BLKG0F76F9/Media%20Files/Herb%20Alpert%20-%20Definitive%20Hits/This%20Guy%27s%20In%20Love%20With%20You.mp3 2006-09-09 17:41:21.6296 Setting maxBitRate for Living Room to: 0 2006-09-09 17:41:21.6307 Setting maxBitRate for Living Room to: 0 2006-09-09 17:41:21.6316 Setting maxBitRate for Family Room to: 0 2006-09-09 17:41:21.6325 Setting maxBitRate for Master Bedroom to: 0 2006-09-09 17:41:21.6333 Setting maxBitRate for Basement Theater to: 0 2006-09-09 17:41:21.6348 Setting maxBitRate for Living Room to: 0 2006-09-09 17:41:21.6359 Setting maxBitRate for Living Room to: 0 2006-09-09 17:41:21.6368 Setting maxBitRate for Family Room to: 0 2006-09-09 17:41:21.6377 Setting maxBitRate for Master Bedroom to: 0 2006-09-09 17:41:21.6385 Setting maxBitRate for Basement Theater to: 0 2006-09-09 17:41:21.6393 undermax = 1, type = mp3, squeezebox2 = 00:04:20:05:a6:0b 2006-09-09 17:41:21.6403 checking formats for: mp3-wma-squeezebox2-00:04:20:05:a6:0b 2006-09-09 17:41:21.6411 checking formats for: mp3-wma-*-00:04:20:05:a6:0b 2006-09-09 17:41:21.6419 checking formats for: mp3-wma-squeezebox2-* 2006-09-09 17:41:21.6426 checking formats for: mp3-wma-*-* 2006-09-09 17:41:21.6433 checking formats for: mp3-ogg-squeezebox2-00:04:20:05:a6:0b 2006-09-09 17:41:21.6441 checking formats for: mp3-ogg-*-00:04:20:05:a6:0b 2006-09-09 17:41:21.6448 checking formats for: mp3-ogg-squeezebox2-* 2006-09-09 17:41:21.6455 checking formats for: mp3-ogg-*-* 2006-09-09 17:41:21.6463 checking formats for: mp3-flc-squeezebox2-00:04:20:05:a6:0b 2006-09-09 17:41:21.6470 checking formats for: mp3-flc-*-00:04:20:05:a6:0b 2006-09-09 17:41:21.6477 checking formats for: mp3-flc-squeezebox2-* 2006-09-09 17:41:21.6484 checking formats for: mp3-flc-*-* 2006-09-09 17:41:21.6492 checking formats for: mp3-aif-squeezebox2-00:04:20:05:a6:0b 2006-09-09 17:41:21.6499 checking formats for: mp3-aif-*-00:04:20:05:a6:0b 2006-09-09 17:41:21.6506 checking formats for: mp3-aif-squeezebox2-* 2006-09-09 17:41:21.6513 checking formats for: mp3-aif-*-* 2006-09-09 17:41:21.6527 checking formats for: mp3-wav-squeezebox2-00:04:20:05:a6:0b 2006-09-09 17:41:21.6534 checking formats for: mp3-wav-*-00:04:20:05:a6:0b 2006-09-09 17:41:21.6542 checking formats for: mp3-wav-squeezebox2-* 2006-09-09 17:41:21.6549 checking formats for: mp3-wav-*-* 2006-09-09 17:41:21.6675 checking formats for: mp3-mp3-squeezebox2-00:04:20:05:a6:0b 2006-09-09 17:41:21.6685 checking formats for: mp3-mp3-*-00:04:20:05:a6:0b 2006-09-09 17:41:21.6693 checking formats for: mp3-mp3-squeezebox2-* 2006-09-09 17:41:21.6701 checking formats for: mp3-mp3-*-* 2006-09-09 17:41:21.6709 Checking to see if mp3-mp3-*-* is enabled 2006-09-09 17:41:21.6716 enabled 2006-09-09 17:41:21.6724 Found command: - 2006-09-09 17:41:21.6732 Setting maxBitRate for Living Room to: 0 2006-09-09 17:41:21.6741 Setting maxBitRate for Living Room to: 0 2006-09-09 17:41:21.6750 Setting maxBitRate for Family Room to: 0 2006-09-09 17:41:21.6758 Setting maxBitRate for Master Bedroom to: 0 2006-09-09 17:41:21.6768 Setting maxBitRate for Basement Theater to: 0 2006-09-09 17:41:21.6777 Matched Format: mp3 Type: mp3 Command: - 2006-09-09 17:41:21.6786 openSong: this is an mp3 file: file:///Volumes/WORKGROUP%3BLKG0F76F9/Media%20Files/Herb%20Alpert%20-%20Definitive%20Hits/This%20Guy%27s%20In%20Love%20With%20You.mp3 2006-09-09 17:41:21.6794 file type: mp3 format: mp3 inrate: 225 maxRate: 0 2006-09-09 17:41:21.6801 command: - 2006-09-09 17:41:21.6810 openSong: opening file /Volumes/WORKGROUP;LKG0F76F9/Media Files/Herb Alpert - Definitive Hits/This Guy's In Love With You.mp3 2006-09-09 17:41:21.6837 openSong: seeking in 670 into /Volumes/WORKGROUP;LKG0F76F9/Media Files/Herb Alpert - Definitive Hits/This Guy's In Love With You.mp3 2006-09-09 17:41:21.6851 openSong: Streaming with format: mp3 2006-09-09 17:41:21.8993 00:04:20:05:a6:0a New play mode: play 2006-09-09 17:41:21.9079 *************stream called: s paused: 1 format: mp3 url: file:///Volumes/WORKGROUP%3BLKG0F76F9/Media%20Files/Herb%20Alpert%20-%20Definitive%20Hits/This%20Guy%27s%20In%20Love%20With%20You.mp3 2006-09-09 17:41:21.9092 Backtrace: frame 0: Slim::Player::Squeezebox::stream (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Squeezebox.pm line 160) frame 1: Slim::Player::Squeezebox::play (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 490) frame 2: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 637) frame 3: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 4: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 5: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 6: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 7: Slim::Networking::Select::select (./slimserver.pl line 503) frame 8: main::idle (./slimserver.pl line 456) frame 9: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:21.9116 starting with decoder with format: m autostart: 0 threshold: 128 samplesize: ? samplerate: ? endian: ? channels: ? 2006-09-09 17:41:21.9125 flags: 0 2006-09-09 17:41:21.9134 sending strm frame of length: 76 request string: GET /stream.mp3?player=00:04:20:05:a6:0a HTTP/1.0 2006-09-09 17:41:21.9160 Playlist: Jumping to song index: 154 2006-09-09 17:41:21.9175 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 637) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 5: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:21.9196 new playlistindex: 154 2006-09-09 17:41:21.9209 Playlist: Jumping to song index: 154 2006-09-09 17:41:21.9221 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 637) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 5: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:21.9237 new playlistindex: 154 2006-09-09 17:41:21.9248 Playlist: Jumping to song index: 154 2006-09-09 17:41:21.9259 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 637) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 5: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:21.9278 new playlistindex: 154 2006-09-09 17:41:21.9289 Playlist: Jumping to song index: 154 2006-09-09 17:41:21.9408 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 637) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 5: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:21.9427 new playlistindex: 154 2006-09-09 17:41:21.9437 00:04:20:05:a6:0b New play mode: play 2006-09-09 17:41:21.9466 *************stream called: s paused: 1 format: mp3 url: file:///Volumes/WORKGROUP%3BLKG0F76F9/Media%20Files/Herb%20Alpert%20-%20Definitive%20Hits/This%20Guy%27s%20In%20Love%20With%20You.mp3 2006-09-09 17:41:21.9479 Backtrace: frame 0: Slim::Player::Squeezebox::stream (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Squeezebox.pm line 160) frame 1: Slim::Player::Squeezebox::play (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 490) frame 2: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 637) frame 3: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 4: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 5: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 6: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 7: Slim::Networking::Select::select (./slimserver.pl line 503) frame 8: main::idle (./slimserver.pl line 456) frame 9: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:21.9502 starting with decoder with format: m autostart: 0 threshold: 128 samplesize: ? samplerate: ? endian: ? channels: ? 2006-09-09 17:41:21.9512 flags: 0 2006-09-09 17:41:21.9521 sending strm frame of length: 76 request string: GET /stream.mp3?player=00:04:20:05:a6:0b HTTP/1.0 2006-09-09 17:41:21.9545 Playlist: Jumping to song index: 154 2006-09-09 17:41:21.9562 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 637) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 5: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:21.9582 new playlistindex: 154 2006-09-09 17:41:21.9595 Playlist: Jumping to song index: 154 2006-09-09 17:41:21.9607 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 637) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 5: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:21.9626 new playlistindex: 154 2006-09-09 17:41:21.9637 Playlist: Jumping to song index: 154 2006-09-09 17:41:21.9649 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 637) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 5: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:21.9664 new playlistindex: 154 2006-09-09 17:41:21.9675 Playlist: Jumping to song index: 154 2006-09-09 17:41:21.9687 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 637) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 5: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:21.9811 new playlistindex: 154 2006-09-09 17:41:21.9825 00:04:20:05:a5:e0 New play mode: play 2006-09-09 17:41:21.9855 *************stream called: s paused: 1 format: mp3 url: file:///Volumes/WORKGROUP%3BLKG0F76F9/Media%20Files/Herb%20Alpert%20-%20Definitive%20Hits/This%20Guy%27s%20In%20Love%20With%20You.mp3 2006-09-09 17:41:21.9868 Backtrace: frame 0: Slim::Player::Squeezebox::stream (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Squeezebox.pm line 160) frame 1: Slim::Player::Squeezebox::play (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 490) frame 2: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 637) frame 3: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 4: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 5: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 6: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 7: Slim::Networking::Select::select (./slimserver.pl line 503) frame 8: main::idle (./slimserver.pl line 456) frame 9: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:21.9887 starting with decoder with format: m autostart: 0 threshold: 128 samplesize: ? samplerate: ? endian: ? channels: ? 2006-09-09 17:41:21.9896 flags: 0 2006-09-09 17:41:21.9905 sending strm frame of length: 76 request string: GET /stream.mp3?player=00:04:20:05:a5:e0 HTTP/1.0 2006-09-09 17:41:21.9929 Playlist: Jumping to song index: 154 2006-09-09 17:41:21.9950 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 637) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 5: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:21.9971 new playlistindex: 154 2006-09-09 17:41:21.9983 Playlist: Jumping to song index: 154 2006-09-09 17:41:21.9995 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 637) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 5: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:22.0012 new playlistindex: 154 2006-09-09 17:41:22.0024 Playlist: Jumping to song index: 154 2006-09-09 17:41:22.0036 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 637) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 5: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:22.0052 new playlistindex: 154 2006-09-09 17:41:22.0063 Playlist: Jumping to song index: 154 2006-09-09 17:41:22.0074 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 637) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 5: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:22.0090 new playlistindex: 154 2006-09-09 17:41:22.0099 00:04:20:05:bd:96 New play mode: play 2006-09-09 17:41:22.0235 *************stream called: s paused: 1 format: mp3 url: file:///Volumes/WORKGROUP%3BLKG0F76F9/Media%20Files/Herb%20Alpert%20-%20Definitive%20Hits/This%20Guy%27s%20In%20Love%20With%20You.mp3 2006-09-09 17:41:22.0250 Backtrace: frame 0: Slim::Player::Squeezebox::stream (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Squeezebox.pm line 160) frame 1: Slim::Player::Squeezebox::play (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 490) frame 2: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 637) frame 3: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 4: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 5: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 6: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 7: Slim::Networking::Select::select (./slimserver.pl line 503) frame 8: main::idle (./slimserver.pl line 456) frame 9: main::main (./slimserver.pl line 1055) 2006-09-09 17:41:22.0269 starting with decoder with format: m autostart: 0 threshold: 128 samplesize: ? samplerate: ? endian: ? channels: ? 2006-09-09 17:41:22.0279 flags: 0 2006-09-09 17:41:22.0294 sending strm frame of length: 76 request string: GET /stream.mp3?player=00:04:20:05:bd:96 HTTP/1.0 2006-09-09 17:41:22.0321 Playlist: Jumping to song index: 154 2006-09-09 17:41:22.0336 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 555) frame 3: Slim::Player::Source::playmode (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 637) frame 4: Slim::Player::Source::skipahead (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Sync.pm line 414) frame 5: Slim::Player::Sync::checkSync (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 659) frame 6: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 7: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 8: Slim::Networking::Select::select (./slimserver.pl line 503) frame 9: main::idle (./slimserver.pl line 456) frame 10: main::main (./slimserver.pl line 1055)
Thought you might want to see what it does after I restart it, in recovering the database: 060909 17:59:14 InnoDB: Database was not shut down normally! InnoDB: Starting crash recovery. InnoDB: Reading tablespace information from the .ibd files... InnoDB: Restoring possible half-written data pages from the doublewrite InnoDB: buffer... 060909 17:59:15 InnoDB: Starting log scan based on checkpoint at InnoDB: log sequence number 0 688900512. InnoDB: Doing recovery: scanned up to log sequence number 0 688900512 060909 17:59:15 InnoDB: Started; log sequence number 0 688900512 060909 17:59:15 [Note] /Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Bin/darwin/mysqld: ready for connections. Version: '5.0.22-standard' socket: '/Users/mikegilpin/Library/Caches/SlimServer/slimserver-mysql.sock' port: 9092 MySQL Community Edition - Standard (GPL) 2006-09-09 17:59:17.1247 Squeezebox protocol listening on port 3483
By the way I do stil plan to reboot in single-user mode and run a full memtest later on tonight after music is no longer needed. But in the meantime, do those log entries show anything worthwhile?
Having trouble booting to single-user. I have a bluetooth keyboard and mouse, so I'm not sure that they are active yet at the point in the boot sequebnce when I need to be holding down command.s. On the other hand, it could be the fact that I have the display hooked up through an S-video adapter. For one of these reasons (not sure which) I can't get to a normal x-terminal screen with command-line mode.
Hate to be a pest, but is anyone going to look at, and comment on, the logs I posted this past weekend? They are really the first *real* logs of the actual problem I've been reporting. I'd just like to know whether they reveal anything useful. I still don't think this is an issue with my machine, although I will certainly continue to investigating that as well, as best I can. And on reboot-to-single-user, am I going to have to buy a wired keyboard? Or do you think I should hook up a regular monitor (rather than the current HDTV connected through S-Video)?
Yes I looked over them, but I don't see anything wrong. BTW, can you use the attachment feature for any future logs? It makes them easier to read.
(In reply to comment #36) > Yes I looked over them, but I don't see anything wrong. BTW, can you use the > attachment feature for any future logs? It makes them easier to read. > Does the attachment feature require pulling something from the file-system? Or can it also pull from the clipboard? The reason I ask is that when this freezeup happens, the file-system (but not the browser and other applications) seems to be locked in some way. When I try to save a log in a file, it hangs up. Are there any other log options I should try turning on?
Yeah attachment requires a file. So, you can't even open TextEdit to save the clipboard contents? Have you tried having SlimServer write to a log directly with --logfile ?
(In reply to comment #38) > Yeah attachment requires a file. So, you can't even open TextEdit to save the > clipboard contents? Have you tried having SlimServer write to a log directly > with --logfile ? > I can open TextEdit and paste in clipboard contents, but when I try to save the file it hangs. It's been a while since I redirected the terminal console to a file, but it seemed to work - although I was never certain whether all the blocks had actually been written to the file at the point of failure. Watching the log entries showing up in the terminal window, they just stopped at the point: 2006-09-09 17:41:23.2947 new playlistindex: 154 2006-09-09 17:41:23.3068 Song queue is now 154 2006-09-09 17:41:23.3206 modifyPlaylistCallback: savecurrsong is 0 2006-09-09 17:41:23.4136 modifyPlaylistCallback: savecurrsong is 0 2006-09-09 17:41:23.4559 modifyPlaylistCallback: savecurrsong is 0 There were no further log entries after that. And it's that situation where I can't terminate the process, either, even in the Force Quit or the Activity Monitor. But it's only that one process that's hung (along with the file-system). Other stuff on the Mac keeps working, unless it needs to save a file. If I try to open up any new Finder windows on the local or network directories, that will hang, too. In the end, even after doing a shutdown (and seeing the OSX GUI go away), the machine is still running, and has to be turned off by holding down the power switch. Note that I got this same behavior when I had 512M of RAM, as the machine came originally. I thought it might be a memory problem (like a leak), so I upgraded to 1G to see if that would make any difference. It didn't. Which is why I don't hold out much hope for memtest, since this same problem has happened with two different memory cards in the machine.
But you are able to copy/paste and use a web browser while in this locked-up state? Seems odd, if the file system was totally locked up, a web browser would likely fail too as it tries to use cache and cookie files. Just for kicks you could try taking out your original memory and running with only your newer 512 stick.
(In reply to comment #40) > But you are able to copy/paste and use a web browser while in this locked-up > state? Seems odd, if the file system was totally locked up, a web browser > would likely fail too as it tries to use cache and cookie files. > > Just for kicks you could try taking out your original memory and running with > only your newer 512 stick. > The browser is already running, on the right page. I haven't tried opening a new browser (since my objective was to try to get a log, not to hang the machine). The memory upgrade (because of the nature of the Mac Mini) involved removing the original 512M card and replacing it with a single 1G card. So we're already on "all new" memory, compared to before. I don't think the file system is *totally* locked up. There seem to be different levels at which it operates. As long as all that's needed is ongoing access to files that are already open, it's OK. It's only if you try opening a new folder (local or network) or file - which would involve access and/or updates to the directory - that it hangs. Frustrating, I know.
cc'ing Ross as well in case he has any ideas from his technician background.
Subject: Out of Office AutoReply: Player freezes part way through shuffled song on playlist I am out of the office on business at GridWorld part of the day today, September 12th. If you have an urgent matter, call my mobile number 240-899-5886, or instant-message my phone by directing your PC browser to https://wmg.tmomail.net/customer_site/jsp/messaging_lo.jsp. Otherwise, I will reply to your email message at the earliest opportunity. <!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 3.2//EN"> <HTML> <HEAD> <META HTTP-EQUIV="Content-Type" CONTENT="text/html; charset=iso-8859-1"> <META NAME="Generator" CONTENT="MS Exchange Server version 6.5.7650.5"> <TITLE>Out of Office AutoReply: [Bug 3930] Player freezes part way through shuffled song on playlist</TITLE> </HEAD> <BODY> <!-- Converted from text/plain format --> <P><FONT SIZE=2>I am out of the office on business at GridWorld part of the day today, September 12th. If you have an urgent matter, call my mobile number 240-899-5886, or instant-message my phone by directing your PC browser to <A HREF="https://wmg.tmomail.net/customer_site/jsp/messaging_lo.jsp">https://wmg.tmomail.net/customer_site/jsp/messaging_lo.jsp</A>. Otherwise, I will reply to your email message at the earliest opportunity.</FONT></P> </BODY> </HTML>
Kevin and I both feel this must have something to do with the aftermarket memory. Would it be possible for you to remove the aftermarket memory and replace it with the original Apple memory?
comment 39 mentions the problem existing with the original 512M provided in the machine.
Another log from a "freeze up," just in case there's something of interest here. This is only the very last part of the log. I also went back through and selected all the log entries for song 63, back to the last one that mentioned song 62, and tried to save those on a file - and TextEdit locked up. Sigh. But I was surprised at how many log entries there were for song 63 - thousands. 2006-09-14 11:31:54.5259 new playlistindex: 63 2006-09-14 11:31:54.5398 Playlist: Jumping to song index: 63 2006-09-14 11:31:54.5413 Backtrace: frame 0: Slim::Buttons::Playlist::browseplaylistindex (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/Playlist.pm line 351) frame 1: Slim::Buttons::Playlist::jump (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Playlist.pm line 341) frame 2: Slim::Player::Playlist::refreshPlaylist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Source.pm line 1123) frame 3: Slim::Player::Source::trackStartEvent (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 663) frame 4: Slim::Networking::Slimproto::_stat_handler (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Slimproto.pm line 393) frame 5: Slim::Networking::Slimproto::client_readable (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 6: Slim::Networking::Select::select (./slimserver.pl line 531) frame 7: main::idleStreams (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/Pages/Playlist.pm line 179) frame 8: Slim::Web::Pages::Playlist::playlist (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/Pages/Status.pm line 173) frame 9: Slim::Web::Pages::Status::status (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/HTTP.pm line 805) frame 10: Slim::Web::HTTP::generateHTTPResponse (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/HTTP.pm line 671) frame 11: Slim::Web::HTTP::processURL (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Web/HTTP.pm line 517) frame 12: Slim::Web::HTTP::processHTTP (/Users/mikegilpin/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Networking/Select.pm line 238) frame 13: Slim::Networking::Select::select (./slimserver.pl line 503) frame 14: main::idle (./slimserver.pl line 456) frame 15: main::main (./slimserver.pl line 1055) 2006-09-14 11:31:54.5436 new playlistindex: 63 2006-09-14 11:31:54.6524 Song queue is now 63 2006-09-14 11:31:55.4815 End playlist build. 50 items 2006-09-14 11:31:56.8882 modifyPlaylistCallback: savecurrsong is 0 2006-09-14 11:31:56.9244 modifyPlaylistCallback: savecurrsong is 0 2006-09-14 11:31:56.9847 modifyPlaylistCallback: savecurrsong is 0 2006-09-14 11:31:57.1196 modifyPlaylistCallback: savecurrsong is 0 2006-09-14 11:31:57.5241 Setting maxBitRate for Family Room to: 0 2006-09-14 11:31:57.5255 Setting maxBitRate for Family Room to: 0 2006-09-14 11:31:57.5265 Setting maxBitRate for Master Bedroom to: 0 2006-09-14 11:31:57.5275 Setting maxBitRate for Basement Theater to: 0 2006-09-14 11:31:57.5283 Setting maxBitRate for Living Room to: 0 2006-09-14 11:31:57.5301 currentPlaylistChangeTime : Thu Sep 14 11:31:54 2006 2006-09-14 11:31:57.5310 currentPlaylistRender : Thu Sep 14 11:31:55 2006 2006-09-14 11:31:57.5319 currentPlaylistRenderSkin : 2006-09-14 11:31:57.5326 currentPlaylistRenderStart: 50 2006-09-14 11:31:57.5334 skinOverride: 2006-09-14 11:31:57.5341 start: 50 2006-09-14 11:31:57.5349 Skipping playlist build - not modified. 2006-09-14 11:31:59.2988 Setting maxBitRate for Family Room to: 0 2006-09-14 11:31:59.3002 Setting maxBitRate for Family Room to: 0 2006-09-14 11:31:59.3013 Setting maxBitRate for Master Bedroom to: 0 2006-09-14 11:31:59.3022 Setting maxBitRate for Basement Theater to: 0 2006-09-14 11:31:59.3031 Setting maxBitRate for Living Room to: 0 2006-09-14 11:31:59.3048 currentPlaylistChangeTime : Thu Sep 14 11:31:54 2006 2006-09-14 11:31:59.3058 currentPlaylistRender : Thu Sep 14 11:31:55 2006 2006-09-14 11:31:59.3066 currentPlaylistRenderSkin : 2006-09-14 11:31:59.3074 currentPlaylistRenderStart: 50 2006-09-14 11:31:59.3082 skinOverride: 2006-09-14 11:31:59.3089 start: 50 2006-09-14 11:31:59.3097 Skipping playlist build - not modified. 2006-09-14 11:32:00.9648 Setting maxBitRate for Family Room to: 0 2006-09-14 11:32:00.9663 Setting maxBitRate for Family Room to: 0 2006-09-14 11:32:00.9673 Setting maxBitRate for Master Bedroom to: 0 2006-09-14 11:32:00.9683 Setting maxBitRate for Basement Theater to: 0 2006-09-14 11:32:00.9691 Setting maxBitRate for Living Room to: 0 2006-09-14 11:32:00.9708 currentPlaylistChangeTime : Thu Sep 14 11:31:54 2006 2006-09-14 11:32:00.9718 currentPlaylistRender : Thu Sep 14 11:31:55 2006 2006-09-14 11:32:00.9730 currentPlaylistRenderSkin : 2006-09-14 11:32:00.9738 currentPlaylistRenderStart: 50 2006-09-14 11:32:00.9746 skinOverride: 2006-09-14 11:32:00.9753 start: 50 2006-09-14 11:32:00.9762 Skipping playlist build - not modified. 2006-09-14 11:32:02.8953 Setting maxBitRate for Family Room to: 0 2006-09-14 11:32:02.8966 Setting maxBitRate for Family Room to: 0 2006-09-14 11:32:02.8976 Setting maxBitRate for Master Bedroom to: 0 2006-09-14 11:32:02.8986 Setting maxBitRate for Basement Theater to: 0 2006-09-14 11:32:02.8995 Setting maxBitRate for Living Room to: 0 2006-09-14 11:32:02.9012 currentPlaylistChangeTime : Thu Sep 14 11:31:54 2006 2006-09-14 11:32:02.9021 currentPlaylistRender : Thu Sep 14 11:31:55 2006 2006-09-14 11:32:02.9030 currentPlaylistRenderSkin : 2006-09-14 11:32:02.9038 currentPlaylistRenderStart: 50 2006-09-14 11:32:02.9045 skinOverride: 2006-09-14 11:32:02.9052 start: 50 2006-09-14 11:32:02.9060 Skipping playlist build - not modified. 2006-09-14 11:32:04.6910 Setting maxBitRate for Family Room to: 0 2006-09-14 11:32:04.6927 Setting maxBitRate for Family Room to: 0 2006-09-14 11:32:04.6938 Setting maxBitRate for Master Bedroom to: 0 2006-09-14 11:32:04.6947 Setting maxBitRate for Basement Theater to: 0 2006-09-14 11:32:04.6955 Setting maxBitRate for Living Room to: 0 2006-09-14 11:32:04.6973 currentPlaylistChangeTime : Thu Sep 14 11:31:54 2006 2006-09-14 11:32:04.6982 currentPlaylistRender : Thu Sep 14 11:31:55 2006 2006-09-14 11:32:04.6991 currentPlaylistRenderSkin : 2006-09-14 11:32:04.6998 currentPlaylistRenderStart: 50 2006-09-14 11:32:04.7006 skinOverride: 2006-09-14 11:32:04.7013 start: 50 2006-09-14 11:32:04.7021 Skipping playlist build - not modified. 2006-09-14 11:32:06.3849 Setting maxBitRate for Family Room to: 0 2006-09-14 11:32:06.3864 Setting maxBitRate for Family Room to: 0 2006-09-14 11:32:06.3875 Setting maxBitRate for Master Bedroom to: 0 2006-09-14 11:32:06.3884 Setting maxBitRate for Basement Theater to: 0 2006-09-14 11:32:06.3893 Setting maxBitRate for Living Room to: 0 2006-09-14 11:32:06.3910 currentPlaylistChangeTime : Thu Sep 14 11:31:54 2006 2006-09-14 11:32:06.3919 currentPlaylistRender : Thu Sep 14 11:31:55 2006 2006-09-14 11:32:06.3927 currentPlaylistRenderSkin : 2006-09-14 11:32:06.3935 currentPlaylistRenderStart: 50 2006-09-14 11:32:06.3943 skinOverride: 2006-09-14 11:32:06.3950 start: 50 2006-09-14 11:32:06.3957 Skipping playlist build - not modified. 2006-09-14 11:32:17.4835 Setting maxBitRate for Family Room to: 0 2006-09-14 11:32:17.4853 Setting maxBitRate for Family Room to: 0 2006-09-14 11:32:17.4865 Setting maxBitRate for Master Bedroom to: 0 2006-09-14 11:32:17.4874 Setting maxBitRate for Basement Theater to: 0 2006-09-14 11:32:17.4883 Setting maxBitRate for Living Room to: 0 2006-09-14 11:32:17.4900 currentPlaylistChangeTime : Thu Sep 14 11:31:54 2006 2006-09-14 11:32:17.4911 currentPlaylistRender : Thu Sep 14 11:31:55 2006 2006-09-14 11:32:17.4919 currentPlaylistRenderSkin : 2006-09-14 11:32:17.4927 currentPlaylistRenderStart: 50 2006-09-14 11:32:17.4935 skinOverride: 2006-09-14 11:32:17.4942 start: 50 2006-09-14 11:32:17.4950 Skipping playlist build - not modified. 2006-09-14 11:32:28.5237 Setting maxBitRate for Family Room to: 0 2006-09-14 11:32:28.5251 Setting maxBitRate for Family Room to: 0 2006-09-14 11:32:28.5261 Setting maxBitRate for Master Bedroom to: 0 2006-09-14 11:32:28.5270 Setting maxBitRate for Basement Theater to: 0 2006-09-14 11:32:28.5279 Setting maxBitRate for Living Room to: 0 2006-09-14 11:32:28.5296 currentPlaylistChangeTime : Thu Sep 14 11:31:54 2006 2006-09-14 11:32:28.5308 currentPlaylistRender : Thu Sep 14 11:31:55 2006 2006-09-14 11:32:28.5316 currentPlaylistRenderSkin : 2006-09-14 11:32:28.5324 currentPlaylistRenderStart: 50 2006-09-14 11:32:28.5332 skinOverride: 2006-09-14 11:32:28.5339 start: 50 2006-09-14 11:32:28.5347 Skipping playlist build - not modified. 2006-09-14 11:32:39.5518 Setting maxBitRate for Family Room to: 0 2006-09-14 11:32:39.5532 Setting maxBitRate for Family Room to: 0 2006-09-14 11:32:39.5543 Setting maxBitRate for Master Bedroom to: 0 2006-09-14 11:32:39.5552 Setting maxBitRate for Basement Theater to: 0 2006-09-14 11:32:39.5565 Setting maxBitRate for Living Room to: 0 2006-09-14 11:32:39.5583 currentPlaylistChangeTime : Thu Sep 14 11:31:54 2006 2006-09-14 11:32:39.5592 currentPlaylistRender : Thu Sep 14 11:31:55 2006 2006-09-14 11:32:39.5601 currentPlaylistRenderSkin : 2006-09-14 11:32:39.5609 currentPlaylistRenderStart: 50 2006-09-14 11:32:39.5616 skinOverride: 2006-09-14 11:32:39.5624 start: 50 2006-09-14 11:32:39.5632 Skipping playlist build - not modified.
One thing I've noticed is that this problem seems to happen more often on large playlists, than smaller ones (over 5000 vs around 2000-3000). Not to say it never happens on smaller ones - just not as often. I have a ReadyNAS without the SlimServer software on it, but I'm contemplating setting it up as an alternate server. Yet I've heard the processor in that box is pretty wimpy, too - so might not be any better than the Mac Mini, and therefore might have the same problem. Advice? One other thing I should mention - a lot of my newer music (MP3s) is encoded at 320K. I can't tell for sure, but it has also seemed that I get the "freeze up" more often on these, rather than the original core of my collection that was ripped by Slim Devices at a somewhat lower rate (VBR, usally around 200K). You wouldn't think this would matter, since I have a Gigabit Ethernet wired network. That should have plenty of capacity, even to handle four streams at this rate.
Escalating to Kevin to diagnose the issue. It sounds like a problem with the system. Kevin, can you get in touch with Mike and reopen if this is reproducible issue with slimserver?
Subject: Out of Office AutoReply: Player freezes part way through shuffled song on playlist I am out of the office at Forrester's Technology Leadership Forum in Scottsdale, Arizona, through Wednesday night September 20th. If you have an urgent matter, call my mobile number 240-899-5886, or instant-message my phone by clicking this link: https://wmg.tmomail.net/customer_site/jsp/messaging_lo.jsp. Otherwise, I will reply to your email message at the earliest opportunity. <!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 3.2//EN"> <HTML> <HEAD> <META HTTP-EQUIV="Content-Type" CONTENT="text/html; charset=iso-8859-1"> <META NAME="Generator" CONTENT="MS Exchange Server version 6.5.7650.5"> <TITLE>Out of Office AutoReply: [Bug 3930] Player freezes part way through shuffled song on playlist</TITLE> </HEAD> <BODY> <!-- Converted from text/plain format --> <P><FONT SIZE=2>I am out of the office at Forrester's Technology Leadership Forum in Scottsdale, Arizona, through Wednesday night September 20th. If you have an urgent matter, call my mobile number 240-899-5886, or instant-message my phone by clicking this link: <A HREF="https://wmg.tmomail.net/customer_site/jsp/messaging_lo.jsp">https://wmg.tmomail.net/customer_site/jsp/messaging_lo.jsp</A>. Otherwise, I will reply to your email message at the earliest opportunity.</FONT></P> </BODY> </HTML>