Bugzilla – Bug 6698
Some streams cause SqueezeCenter to go out to lunch...
Last modified: 2008-12-18 11:12:53 UTC
Gentleones, I've been using 7.0x since early December and have noticed that some streams cause somebody to go out to lunch, but I'm not sure who and how to tell. I'm currently using trunk 2008-01-19. One stream I've been having problems with is WGBH Classical 89.7 HD2, http://streams.wgbh.org/classical.pls. I usually leave this on at bedtime (10-11pm) and like to listen to it in the morning, but most mornings I find that there's no audio output from my SliMP3. (Yes, an original SliMP3.) Most functions are still working: I can still connect to the server (via http on port 9000) and I can tell it to "stop" and "play." It responds, but no audio is forthcoming. Furthermore, the SliMP3 display scrolls (large text) "ALL-CLASSICAL WGBH". The SliMP3 also responds to remote control input, but again, no audio is produced. In neither case, not by remote control nor by server, does the "stop"/"play" sequence cause the stream to restart streaming. What fixes the problem is to connect to the server (VNC this time) and use the System Preferences SqueezeCenter prefpane to Stop Server/Start Server and then issue a "Play" to the player via http:9000. I have been unable to find anything in the logs that would show who's out to lunch, whether it's the SliMP3 or the SC. Having just turned on Debug for player.streaming.*, here's what I get: [10:29:30.9057] Slim::Formats::RemoteStream::DESTROY (318) Slim::Player::Protocols::HTTP - in DESTROY [10:29:30.9169] Slim::Formats::RemoteStream::DESTROY (319) Slim::Player::Protocols::HTTP About to close socket to: [http://64.71.145.107:8004/] [10:29:48.0709] Slim::Formats::RemoteStream::open (60) Opening connection to http://64.71.145.107:8004/: [64.71.145.107 on port 8004 with path / with timeout 5] [10:29:48.2063] Slim::Formats::RemoteStream::request (136) Request: GET / HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: 64.71.145.107:8004 User-Agent: iTunes/4.7.1 (Darwin; N; Mac OS X Server 10.5.1 (9B18); ppc; EN; utf8) SqueezeCenter/7.0/16473 Icy-Metadata: 1 [10:29:48.3145] Slim::Formats::RemoteStream::request (143) Response: ICY 200 OK [10:29:48.3184] Slim::Formats::HTTP::parseHeaders (151) Header: icy-notice1:<BR>This stream requires <a href="http://www.winamp.com/">Winamp</a><BR> [10:29:48.3190] Slim::Formats::HTTP::parseHeaders (151) Header: icy-notice2:SHOUTcast Distributed Network Audio Server/Linux v1.9.7<BR> [10:29:48.3196] Slim::Formats::HTTP::parseHeaders (151) Header: icy-name:All-Classical WGBH 89.7-HD2 [10:29:48.3202] Slim::Formats::HTTP::parseHeaders (151) Header: icy-genre:Classical [10:29:48.3208] Slim::Formats::HTTP::parseHeaders (151) Header: icy-url:http://www.wgbh.org/classical [10:29:48.3213] Slim::Formats::HTTP::parseHeaders (151) Header: content-type:audio/mpeg [10:29:48.4842] Slim::Formats::HTTP::parseHeaders (151) Header: icy-pub:1 [10:29:48.4848] Slim::Formats::HTTP::parseHeaders (151) Header: icy-metaint:32768 [10:29:48.4854] Slim::Formats::HTTP::parseHeaders (151) Header: icy-br:112 [10:29:48.5945] Slim::Formats::HTTP::parseHeaders (173) Bitrate for http://64.71.145.107:8004/ set to 112000 [10:29:48.6044] Slim::Formats::RemoteStream::request (202) Opened stream! But, again, nothing comes out of the player. If I stop and restart the server, then press stop/play via http:9000, here's what I get: [10:31:49.1391] Slim::bootstrap::sigterm (376) Got sigterm [10:32:00.3132] Slim::bootstrap::theEND (400) Got to the END [10:32:00.3289] Slim::bootstrap::sigint (364) Got sigint [10:32:00.3297] Slim::Schema::forceCommit (1363) Warning: Trying to commit transactions before DB is initialized! [10:32:00.4404] Slim::Schema::Storage::throw_exception (67) Error: DBI Connection failed: DBI connect('hostname=127.0.0.1;port=9092;database=slimserver:mysql_socket=/Users/admin/Library/Caches/SqueezeCenter/squeezecenter-mysql.sock','slimserver',...) failed: Can't connect to MySQL server on '127.0.0.1' (61) at /Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/CPAN/DBIx/Class/Storage/DBI.pm line 652 [10:32:00.4467] Slim::Schema::Storage::throw_exception (67) Backtrace: frame 0: Slim::Utils::Log::logBacktrace (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Schema/Storage.pm line 67) frame 1: Slim::Schema::Storage::throw_exception (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/CPAN/DBIx/Class/Storage/DBI.pm line 658) frame 2: DBIx::Class::Storage::DBI::_connect (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/CPAN/DBIx/Class/Storage/DBI.pm line 616) frame 3: DBIx::Class::Storage::DBI::_populate_dbh (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/CPAN/DBIx/Class/Storage/DBI.pm line 543) frame 4: DBIx::Class::Storage::DBI::ensure_connected (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Schema/Storage.pm line 27) frame 5: (eval) (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Schema/Storage.pm line 27) frame 6: Slim::Schema::Storage::dbh (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Schema.pm line 244) frame 7: (eval) (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Schema.pm line 244) frame 8: Slim::Schema::disconnect (./slimserver.pl line 962) frame 9: main::cleanup (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/bootstrap.pm line 368) frame 10: Slim::bootstrap::sigint (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/bootstrap.pm line 402) frame 11: Slim::bootstrap::theEND (./slimserver.pl line 999) frame 12: main::END (./slimserver.pl line 0) frame 13: (eval) (./slimserver.pl line 0) [10:32:00.5333] Slim::Schema::Storage::dbh (38) Warning: Unable to connect to the database - trying to bring it up! [10:32:19.8970] main::init (293) SqueezeCenter OS Specific init... [10:32:19.8975] main::init (313) SqueezeCenter daemonizing... [10:32:19.9276] Slim::bootstrap::theEND (400) Got to the END [10:32:22.1840] main::init (293) SqueezeCenter OS Specific init... [10:32:22.1847] main::init (322) SqueezeCenter settings effective user and group if requested... [10:32:22.1852] main::init (332) SqueezeCenter binary search path init... [10:32:22.1861] main::init (335) SqueezeCenter strings init... [10:32:22.1923] Slim::Utils::Strings::loadStrings (109) Retrieving string data from string cache: /Users/admin/Library/Caches/SqueezeCenter/strings.bin [10:32:22.2764] main::init (338) SqueezeCenter MySQL init... [10:32:24.5215] main::init (341) Async DNS init... [10:32:24.6275] main::init (344) Firmware init... [10:32:24.6418] main::init (347) SqueezeCenter Info init... [10:32:25.5009] main::init (350) SqueezeCenter IR init... [10:32:25.5365] main::init (353) SqueezeCenter Request init... [10:32:25.5447] main::init (356) SqueezeCenter Buttons init... [10:32:36.6295] Slim::Utils::Cache::cleanup (221) Cache purge: Artwork - 0.373047 sec [10:32:37.0853] Slim::bootstrap::theEND (400) Got to the END [10:34:04.5564] Slim::Formats::RemoteStream::open (60) Opening connection to http://64.71.145.107:8004/: [64.71.145.107 on port 8004 with path / with timeout 5] [10:34:04.6561] Slim::Formats::RemoteStream::request (136) Request: GET / HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: 64.71.145.107:8004 User-Agent: iTunes/4.7.1 (Darwin; N; Mac OS X Server 10.5.1 (9B18); ppc; EN; utf8) SqueezeCenter/7.0/16473 Icy-Metadata: 1 [10:34:04.7608] Slim::Formats::RemoteStream::request (143) Response: ICY 200 OK [10:34:04.7674] Slim::Formats::HTTP::parseHeaders (151) Header: icy-notice1:<BR>This stream requires <a href="http://www.winamp.com/">Winamp</a><BR> [10:34:04.7685] Slim::Formats::HTTP::parseHeaders (151) Header: icy-notice2:SHOUTcast Distributed Network Audio Server/Linux v1.9.7<BR> [10:34:04.7690] Slim::Formats::HTTP::parseHeaders (151) Header: icy-name:All-Classical WGBH 89.7-HD2 [10:34:04.7699] Slim::Formats::HTTP::parseHeaders (151) Header: icy-genre:Classical [10:34:04.7705] Slim::Formats::HTTP::parseHeaders (151) Header: icy-url:http://www.wgbh.org/classical [10:34:04.7710] Slim::Formats::HTTP::parseHeaders (151) Header: content-type:audio/mpeg [10:34:04.7804] Slim::Formats::HTTP::parseHeaders (151) Header: icy-pub:1 [10:34:04.7811] Slim::Formats::HTTP::parseHeaders (151) Header: icy-metaint:32768 [10:34:04.7818] Slim::Formats::HTTP::parseHeaders (151) Header: icy-br:112 [10:34:04.7909] Slim::Formats::HTTP::parseHeaders (173) Bitrate for http://64.71.145.107:8004/ set to 112000 [10:34:04.8001] Slim::Formats::RemoteStream::request (202) Opened stream! [10:34:05.3855] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 32 [10:34:05.3867] Slim::Player::Protocols::HTTP::readMetaData (105) Metadata: StreamTitle='';StreamUrl=''; [10:34:05.8190] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [10:34:06.2506] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [10:34:06.7175] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [10:34:08.2262] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [10:34:10.5753] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [10:34:12.9287] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [10:34:15.2745] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [10:34:17.5701] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [10:34:19.9152] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [10:34:22.2654] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [10:34:24.6151] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [10:34:26.9670] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [10:34:29.3195] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [10:34:31.6232] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [10:34:33.9730] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 (and lines like the last one keep repeating) ...and audio is produced. w00t! Any thoughts? /Bill
I should note that I don't know if it's the stream's crashing or disconnecting (e.g., provider says, "You've been on too long, get off." or Internet goes TU for a while) or if it's data in the stream which is causing the problem. I'll also add that I'm watching the log continuously now, so the problem won't reoccur. On the off chance that it does reoccur, I'll post the relevant log entries.
OK, the stream died this afternoon at 16:34, and the stream of messages that were being produced suddenly ended: [16:34:06.3858] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [16:34:08.7248] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [16:34:11.0672] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 Strangely, though, the SliMP3 is unresponsive at the moment. The display is completely blank, which is unusual, and there's nothing going on that I can tell. So I will restart the server. Uh oh. The server is unresponsive to the "Stop Server" button on the System Preferences pane. In fact, it has caused the spinning pizza of death. I can force quit System Preferences... and restart System Preferences... but clicking SqueezeCenter causes it to hang in the SPOD. OK, I'll kill off the several server processes... I just killed slimserver.pl and the SqueezeCenter processes died alongside it. I just used the SC prefpane to start the server. Now the SliMP3 is up and running, the server is running, and all is well. Here's all the logging associated with what just happened: [16:34:11.0672] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [17:29:38.1218] Slim::bootstrap::sigterm (376) Got sigterm [17:29:51.6990] Slim::bootstrap::theEND (400) Got to the END [17:29:51.6996] Slim::bootstrap::sigint (364) Got sigint [17:29:51.7874] Slim::Schema::forceCommit (1363) Warning: Trying to commit transactions before DB is initialized! [17:29:51.8691] Slim::Schema::Storage::throw_exception (67) Error: DBI Connection failed: DBI connect('hostname=127.0.0.1;port=9092;database=slimserver:mysql_socket=/Users/admin/Library/Caches/SqueezeCenter/squeezecenter-mysql.sock','slimserver',...) failed: Can't connect to MySQL server on '127.0.0.1' (61) at /Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/CPAN/DBIx/Class/Storage/DBI.pm line 652 [17:29:51.9440] Slim::Schema::Storage::throw_exception (67) Backtrace: frame 0: Slim::Utils::Log::logBacktrace (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Schema/Storage.pm line 67) frame 1: Slim::Schema::Storage::throw_exception (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/CPAN/DBIx/Class/Storage/DBI.pm line 658) frame 2: DBIx::Class::Storage::DBI::_connect (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/CPAN/DBIx/Class/Storage/DBI.pm line 616) frame 3: DBIx::Class::Storage::DBI::_populate_dbh (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/CPAN/DBIx/Class/Storage/DBI.pm line 543) frame 4: DBIx::Class::Storage::DBI::ensure_connected (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Schema/Storage.pm line 27) frame 5: (eval) (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Schema/Storage.pm line 27) frame 6: Slim::Schema::Storage::dbh (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Schema.pm line 244) frame 7: (eval) (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Schema.pm line 244) frame 8: Slim::Schema::disconnect (./slimserver.pl line 962) frame 9: main::cleanup (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/bootstrap.pm line 368) frame 10: Slim::bootstrap::sigint (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/bootstrap.pm line 402) frame 11: Slim::bootstrap::theEND (./slimserver.pl line 999) frame 12: main::END (./slimserver.pl line 0) frame 13: (eval) (./slimserver.pl line 0) [17:29:52.0356] Slim::Schema::Storage::dbh (38) Warning: Unable to connect to the database - trying to bring it up! [17:34:49.2944] main::init (293) SqueezeCenter OS Specific init... [17:34:49.2949] main::init (313) SqueezeCenter daemonizing... [17:34:49.3541] Slim::bootstrap::theEND (400) Got to the END [17:34:53.9433] main::init (293) SqueezeCenter OS Specific init... [17:34:53.9440] main::init (322) SqueezeCenter settings effective user and group if requested... [17:34:53.9444] main::init (332) SqueezeCenter binary search path init... [17:34:53.9456] main::init (335) SqueezeCenter strings init... [17:34:53.9862] Slim::Utils::Strings::loadStrings (109) Retrieving string data from string cache: /Users/admin/Library/Caches/SqueezeCenter/strings.bin [17:34:54.0931] main::init (338) SqueezeCenter MySQL init... [17:34:56.8284] Slim::bootstrap::sigterm (376) Got sigterm [17:34:56.8304] Slim::Schema::forceCommit (1363) Warning: Trying to commit transactions before DB is initialized! [17:34:56.9094] Slim::bootstrap::theEND (400) Got to the END [17:34:56.9099] Slim::bootstrap::sigint (364) Got sigint [17:34:56.9104] Slim::Schema::forceCommit (1363) Warning: Trying to commit transactions before DB is initialized! [17:35:23.5177] main::init (293) SqueezeCenter OS Specific init... [17:35:23.5183] main::init (313) SqueezeCenter daemonizing... [17:35:23.5396] Slim::bootstrap::theEND (400) Got to the END [17:35:25.7041] main::init (293) SqueezeCenter OS Specific init... [17:35:25.7047] main::init (322) SqueezeCenter settings effective user and group if requested... [17:35:25.7052] main::init (332) SqueezeCenter binary search path init... [17:35:25.7061] main::init (335) SqueezeCenter strings init... [17:35:25.7105] Slim::Utils::Strings::loadStrings (109) Retrieving string data from string cache: /Users/admin/Library/Caches/SqueezeCenter/strings.bin [17:35:25.7169] main::init (338) SqueezeCenter MySQL init... [17:35:25.9115] main::init (341) Async DNS init... [17:35:26.0109] main::init (344) Firmware init... [17:35:26.0350] main::init (347) SqueezeCenter Info init... [17:35:27.0625] main::init (350) SqueezeCenter IR init... [17:35:27.1349] main::init (353) SqueezeCenter Request init... [17:35:27.1428] main::init (356) SqueezeCenter Buttons init... [17:35:27.1457] main::init (359) SqueezeCenter Graphic Fonts init... [17:35:27.5277] main::init (367) UDP init... [17:35:27.5295] main::init (370) Slimproto Init... [17:35:27.5314] main::init (373) mDNS init... [17:35:27.5321] main::init (376) Cache init... [17:35:27.6107] main::init (389) SqueezeCenter HTTP init... [17:35:27.6734] main::init (392) Source conversion init.. [17:35:27.6864] main::init (397) SqueezeCenter Web Settings init... [17:35:27.7765] main::init (401) SqueezeCenter Jive init... [17:35:32.3610] main::init (404) SqueezeCenter Plugins init... [17:35:33.5973] Slim::Utils::PluginManager::enablePlugins (375) Couldn't load Slim::Plugin::PreventStandby::Plugin. Error: [-5] [17:35:33.5978] Slim::Utils::PluginManager::enablePlugins (386) Skipping plugin: Slim::Plugin::RPC::Plugin - disabled [17:35:33.5982] Slim::Utils::PluginManager::enablePlugins (386) Skipping plugin: Slim::Plugin::RS232::Plugin - disabled [17:35:34.3486] Slim::Utils::PluginManager::enablePlugins (386) Skipping plugin: Slim::Plugin::xPL::Plugin - disabled [17:35:34.3868] Slim::Formats::RemoteStream::open (60) Opening connection to http://localhost:10002/api/version: [localhost on port 10002 with path /api/version with timeout 5] [17:35:34.3895] Slim::Formats::RemoteStream::open (96) Can't open socket to [localhost:10002]: 61: Connection refused [17:35:34.3901] Slim::Plugin::MusicMagic::Plugin::initPlugin (141) Can't connect to port 10002 - MusicMagic disabled. [17:35:34.5784] main::init (413) SqueezeCenter checkDataSource... [17:35:37.2194] main::init (417) SqueezeCenter persist playlists... [17:35:37.2202] main::init (428) SqueezeCenter HTTP enable... [17:35:37.2222] main::init (432) mDNS startAdvertising... [17:35:37.2614] main::init (440) SqueezeCenter done init... [17:35:37.5774] Slim::Utils::Cache::cleanup (225) Cache purge: FileCache - skipping, purged recently [17:35:37.6320] Slim::Utils::Cache::cleanup (225) Cache purge: Artwork - skipping, purged recently Hope this is useful. /Bill
For a SliMP3, the trace you need is network.protocol.slimp3. Try this at info level to start with, as debug level is very verbose (because of the nature of the UDP-based protocol). player.source=info at the same time would also be useful. It may be that it is necessary to turn both of these up to debug level in order to find out what is going on but beware, the logfile will grow quickly. What firmware revision does the SliMP3 have?
I'll try the various logging levels and let you know what I happens. The FW version of the SliMP3 is 2.3. /Bill
Bad news. The stream crashed last night with a Sky.FM stream at 2am with nothing to show for it in the logs with network.protocol.slimp3 set to info. But for some reason I didn't have player.source set to info, so I'll get it running again and see what happens. (Sky.FM is usually very, very stable. WGBH, not so much.)
I do not understand why you see no logging with network.protocol.slimp3 set to info. There is some stuff you should always see at this level related to stream management to the SliMP3. If this is not easily reproducible and you need network.protocol.slimp3 at debug level to catch the problems, then this is going to be difficult. The logfile will be huge and you will need a reasonably fast CPU to keep up (the logging will probably cost more than the normal processing!). The fact that SliMP3 audio stops working, even after stop/play, suggests that it is nothing to do with the remote stream (unless it manages to send just the right kind of garbage to screw up the internals of the MP3 decoder in the SliMP3). When it gets into this state, can you get it to play a local audio file? I get the impression from the startup logging you have that you have quite an old version of 7.0. I don't actually know of any changes that might be relevant here, but that is not to say that there have not been any. It would be helpful if you could upgrade to the latest version.
Apparently, the stream died last night at 2:09am. The logfile is below. And I don't remember what version of SC I'm using--I think it's in the original bug report. (It was a nightly from earlier this month.) And I definitely have network.protocol.slimp3 set to info. I'll try debug... (Two G5's ought to be able to keep up with the logging plus the server's normal tasks... I hope.) /Bill [02:09:17.3822] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [02:09:19.6795] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [02:09:20.4041] Slim::Player::Source::readNextChunk (2315) Read to end of file or pipe [02:09:20.5026] Slim::Utils::Misc::msg (1235) Warning: [02:09:20.4533] Use of uninitialized value in concatenation (.) or string at /Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Player/Source.pm line 2339. [02:09:20.5033] Slim::Utils::Misc::msg (1235) Warning: [02:09:20.5028] Use of uninitialized value in concatenation (.) or string at /Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Player/Source.pm line 2339. [02:09:20.5038] Slim::Player::Source::readNextChunk (2338) end of file or error on socket, opening next song, (song pos: 302621122(tell says: . ), totalbytes: ) [02:09:20.5107] Slim::Player::Source::nextsong (1551) The next song is number 0, was 0 [02:09:20.5224] Slim::Player::Source::gotoNext (1201) Opening next song... [02:09:20.5389] Slim::Formats::RemoteStream::DESTROY (318) Slim::Player::Protocols::HTTP - in DESTROY [02:09:20.5394] Slim::Formats::RemoteStream::DESTROY (319) Slim::Player::Protocols::HTTP About to close socket to: [http://64.71.145.107:8004/] [02:09:20.5512] Slim::Player::Source::nextsong (1551) The next song is number 0, was 0 [02:09:20.6383] Slim::Utils::Prefs::maxRate (566) Setting maxBitRate for SLIMP3 to: 320 [02:09:20.7115] Slim::Player::TranscodingHelper::getConvertCommand (201) undermax = 1, type = mp3, slimp3 = 00:04:20:04:10:ed [02:09:20.7121] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-slimp3-00:04:20:04:10:ed [02:09:20.7125] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-*-00:04:20:04:10:ed [02:09:20.7129] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-slimp3-* [02:09:20.7134] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-*-* [02:09:20.7142] Slim::Player::TranscodingHelper::enabledFormat (105) Checking to see if mp3-mp3-*-* is enabled [02:09:20.7262] Slim::Player::TranscodingHelper::enabledFormat (113) There are 7 disabled formats... [02:09:20.7267] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-aif-*-* vs mp3-mp3-*-* [02:09:20.7271] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-flc-*-* vs mp3-mp3-*-* [02:09:20.7277] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-mp3-*-* vs mp3-mp3-*-* [02:09:20.7281] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-wav-*-* vs mp3-mp3-*-* [02:09:20.7285] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-flc-*-* vs mp3-mp3-*-* [02:09:20.7290] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-mp3-*-* vs mp3-mp3-*-* [02:09:20.7294] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-wav-*-* vs mp3-mp3-*-* [02:09:20.7298] Slim::Player::TranscodingHelper::checkBin (146) enabled [02:09:20.7302] Slim::Player::TranscodingHelper::checkBin (148) Found command: - [02:09:20.7309] Slim::Utils::Prefs::maxRate (566) Setting maxBitRate for SLIMP3 to: 320 [02:09:20.7325] Slim::Player::TranscodingHelper::getConvertCommand (302) Matched Format: mp3 Type: mp3 Command: - [02:09:20.7521] Slim::Player::Source::gotoNext (1288) opening next song (old format: mp3, new: mp3) current playmode: play [02:09:20.7527] Slim::Player::Source::streamingSongIndex (1345) Adding song index 0 to song queue [02:09:20.7531] Slim::Player::Source::streamingSongIndex (1349) Clearing out song queue first [02:09:20.7536] Slim::Player::Source::streamingSongIndex (1366) Song queue is now 0 [02:09:20.7541] Slim::Player::Source::resetSong (1590) Resetting song buffer. [02:09:20.7615] Slim::Player::Source::openSong (1685) Trying to open: http://64.71.145.107:8004/ [02:09:20.7750] Slim::Player::Source::openSong (1699) URL is remote (no direct streaming) [http://64.71.145.107:8004/] [02:09:20.7756] Slim::Player::ProtocolHandlers::openRemoteStream (66) Trying to open protocol stream for http://64.71.145.107:8004/ [02:09:20.7761] Slim::Player::ProtocolHandlers::openRemoteStream (70) Found handler for http://64.71.145.107:8004/ - using Slim::Player::Protocols::HTTP [02:09:20.8213] Slim::Formats::RemoteStream::open (60) Opening connection to http://64.71.145.107:8004/: [64.71.145.107 on port 8004 with path / with timeout 5] [02:09:21.0582] Slim::Formats::RemoteStream::request (136) Request: GET / HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: 64.71.145.107:8004 User-Agent: iTunes/4.7.1 (Darwin; N; Mac OS X Server 10.5.1 (9B18); ppc; EN; utf8) SqueezeCenter/7.0/16473 Icy-Metadata: 1 [02:09:21.1638] Slim::Formats::RemoteStream::request (143) Response: ICY 200 OK [02:09:21.1678] Slim::Formats::HTTP::parseHeaders (151) Header: icy-notice1:<BR>This stream requires <a href="http://www.winamp.com/">Winamp</a><BR> [02:09:21.1683] Slim::Formats::HTTP::parseHeaders (151) Header: icy-notice2:SHOUTcast Distributed Network Audio Server/Linux v1.9.7<BR> [02:09:21.1688] Slim::Formats::HTTP::parseHeaders (151) Header: icy-name:All-Classical WGBH 89.7-HD2 [02:09:21.1694] Slim::Formats::HTTP::parseHeaders (151) Header: icy-genre:Classical [02:09:21.1699] Slim::Formats::HTTP::parseHeaders (151) Header: icy-url:http://www.wgbh.org/classical [02:09:21.1704] Slim::Formats::HTTP::parseHeaders (151) Header: content-type:audio/mpeg [02:09:21.2798] Slim::Formats::HTTP::parseHeaders (151) Header: icy-pub:1 [02:09:21.2804] Slim::Formats::HTTP::parseHeaders (151) Header: icy-metaint:32768 [02:09:21.2809] Slim::Formats::HTTP::parseHeaders (151) Header: icy-br:112 [02:09:21.6122] Slim::Formats::HTTP::parseHeaders (173) Bitrate for http://64.71.145.107:8004/ set to 112000 [02:09:21.6176] Slim::Formats::RemoteStream::request (202) Opened stream! [02:09:21.6187] Slim::Player::Source::openSong (1711) remoteURL is a song (audio): http://64.71.145.107:8004/ [02:09:21.6247] Slim::Utils::Prefs::maxRate (566) Setting maxBitRate for SLIMP3 to: 320 [02:09:21.6264] Slim::Player::TranscodingHelper::getConvertCommand (201) undermax = 1, type = mp3, slimp3 = 00:04:20:04:10:ed [02:09:21.6270] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-slimp3-00:04:20:04:10:ed [02:09:21.6274] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-*-00:04:20:04:10:ed [02:09:21.6280] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-slimp3-* [02:09:21.6285] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-*-* [02:09:21.6289] Slim::Player::TranscodingHelper::enabledFormat (105) Checking to see if mp3-mp3-*-* is enabled [02:09:21.6294] Slim::Player::TranscodingHelper::enabledFormat (113) There are 7 disabled formats... [02:09:21.6298] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-aif-*-* vs mp3-mp3-*-* [02:09:21.6302] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-flc-*-* vs mp3-mp3-*-* [02:09:21.6306] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-mp3-*-* vs mp3-mp3-*-* [02:09:21.6310] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-wav-*-* vs mp3-mp3-*-* [02:09:21.6314] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-flc-*-* vs mp3-mp3-*-* [02:09:21.6318] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-mp3-*-* vs mp3-mp3-*-* [02:09:21.6322] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-wav-*-* vs mp3-mp3-*-* [02:09:21.6327] Slim::Player::TranscodingHelper::checkBin (146) enabled [02:09:21.6331] Slim::Player::TranscodingHelper::checkBin (148) Found command: - [02:09:21.6337] Slim::Utils::Prefs::maxRate (566) Setting maxBitRate for SLIMP3 to: 320 [02:09:21.6353] Slim::Player::TranscodingHelper::getConvertCommand (302) Matched Format: mp3 Type: mp3 Command: - [02:09:21.6359] Slim::Player::Source::openSong (1737) remoteURL command - type mp3 format mp3 [02:09:21.6363] Slim::Player::Source::openSong (1738) remoteURL stream format : mp3 [08:08:18.2805] Slim::Networking::Async::HTTP::_http_error (264) Error: [404 Not Found] [08:08:18.3545] Slim::Networking::SimpleAsyncHTTP::onError (183) Failed to connect to http://update.slimdevices.com/update/firmware/7.0/jive.version (404 Not Found) [08:08:18.3684] Slim::Utils::Firmware::downloadAsyncError (493) Warning: Firmware: Failed to download http://update.slimdevices.com/update/firmware/7.0/jive.version (404 Not Found), will try again in 10 minutes.
The log entries you included show it trying to make the new connection to the stream. What came before that?
Here's the logging from the stall/whatever it is prior to the one that we're looking at up to the point where it begins repeating the readMetaData(77) message. (The previous stall was at about 20:10, as the log shows. I find it odd, though, that the time for the next message seems to be three minutes prior to the previous entry, at 20:07. I don't know what the wallclock time is for the stall.) I forgot to mention that, if I remember correctly, I am unable to get audio to come out of the player when telling it via http:9000 to play a local audio file even though the web interface acts correctly. I do not know if the SliMP3 shows correct info, either, though I would imagine that it does. I'll do a thorough test on that this afternoon when I get home. [20:10:28.5420] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 .0.1' (61) at /Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/CPAN/DBIx/Class/Storage/DBI.pm line 652 [20:07:37.6895] Slim::Schema::Storage::throw_exception (67) Backtrace: frame 0: Slim::Utils::Log::logBacktrace (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Schema/Storage.pm line 67) frame 1: Slim::Schema::Storage::throw_exception (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/CPAN/DBIx/Class/Storage/DBI.pm line 658) frame 2: DBIx::Class::Storage::DBI::_connect (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/CPAN/DBIx/Class/Storage/DBI.pm line 616) frame 3: DBIx::Class::Storage::DBI::_populate_dbh (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/CPAN/DBIx/Class/Storage/DBI.pm line 543) frame 4: DBIx::Class::Storage::DBI::ensure_connected (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Schema/Storage.pm line 27) frame 5: (eval) (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Schema/Storage.pm line 27) frame 6: Slim::Schema::Storage::dbh (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Schema.pm line 244) frame 7: (eval) (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Schema.pm line 244) frame 8: Slim::Schema::disconnect (./slimserver.pl line 962) frame 9: main::cleanup (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/bootstrap.pm line 368) frame 10: Slim::bootstrap::sigint (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/bootstrap.pm line 402) frame 11: Slim::bootstrap::theEND (./slimserver.pl line 999) frame 12: main::END (./slimserver.pl line 0) frame 13: (eval) (./slimserver.pl line 0) [20:07:37.7907] Slim::Schema::Storage::dbh (38) Warning: Unable to connect to the database - trying to bring it up! [20:08:01.2852] main::init (293) SqueezeCenter OS Specific init... [20:08:01.3259] main::init (313) SqueezeCenter daemonizing... [20:08:01.3544] Slim::bootstrap::theEND (400) Got to the END [20:08:03.4445] main::init (293) SqueezeCenter OS Specific init... [20:08:03.4450] main::init (322) SqueezeCenter settings effective user and group if requested... [20:08:03.4455] main::init (332) SqueezeCenter binary search path init... [20:08:03.4465] main::init (335) SqueezeCenter strings init... [20:08:03.4517] Slim::Utils::Strings::loadStrings (109) Retrieving string data from string cache: /Users/admin/Library/Caches/SqueezeCenter/strings.bin [20:08:03.5436] main::init (338) SqueezeCenter MySQL init... [20:08:05.8679] main::init (341) Async DNS init... [20:08:05.9684] main::init (344) Firmware init... [20:08:05.9903] main::init (347) SqueezeCenter Info init... [20:08:07.1072] main::init (350) SqueezeCenter IR init... [20:08:07.1728] main::init (353) SqueezeCenter Request init... [20:08:07.1810] main::init (356) SqueezeCenter Buttons init... [20:08:07.1854] main::init (359) SqueezeCenter Graphic Fonts init... [20:08:07.4353] main::init (367) UDP init... [20:08:07.4374] main::init (370) Slimproto Init... [20:08:07.4394] main::init (373) mDNS init... [20:08:07.4403] main::init (376) Cache init... [20:08:07.4794] main::init (389) SqueezeCenter HTTP init... [20:08:07.5604] main::init (392) Source conversion init.. [20:08:07.5610] Slim::Player::TranscodingHelper::loadConversionTables (38) Loading conversion config files... [20:08:07.5729] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'mov' output: 'mp3' clienttype: '*': clientid: '*': '[mov123] $FILE$ | [lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ -r - -' [20:08:07.5735] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'alc' output: 'mp3' clienttype: '*': clientid: '*': '[alac] -r $FILE$ | [lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ -r -x - -' [20:08:07.5740] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'ogg' output: 'mp3' clienttype: '*': clientid: '*': '[sox] -t ogg $FILE$ -t raw -r 44100 -c 2 -w -s $-x$ - | [lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ -r - -' [20:08:07.5745] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'wav' output: 'mp3' clienttype: '*': clientid: '*': '[lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ $FILE$ -' [20:08:07.5750] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'aif' output: 'mp3' clienttype: '*': clientid: '*': '[lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ $FILE$ -' [20:08:07.5756] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'flc' output: 'mp3' clienttype: '*': clientid: '*': '[flac] -dcs --skip=$START$ --until=$END$ -- $FILE$ | [lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ - -' [20:08:07.5761] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'wma' output: 'mp3' clienttype: '*': clientid: '*': '[wmadec] -r 44100 -b 16 -n 2 $FILE$ | [lame] $-x$ --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ - -' [20:08:07.5766] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'mpc' output: 'mp3' clienttype: '*': clientid: '*': '[mppdec] --raw-le --silent --prev --gain 3 $FILE$ - | [lame] $-x$ --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ - -' [20:08:07.5771] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'ape' output: 'mp3' clienttype: '*': clientid: '*': '[mac] $FILE$ - -d | [lame] --resample 44100 $-x$ --silent -q $QUALITY$ --abr $BITRATE$ - -' [20:08:07.5776] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'wvp' output: 'mp3' clienttype: '*': clientid: '*': '[wvunpack] $FILE$ -wq --skip=$START$ --until=$END$ -o - | [lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ - -' [20:08:07.5780] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'mp3' output: 'mp3' clienttype: '*': clientid: '*': '-' [20:08:07.5785] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'aif' output: 'aif' clienttype: '*': clientid: '*': '-' [20:08:07.5789] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'wav' output: 'wav' clienttype: '*': clientid: '*': '-' [20:08:07.5794] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'flc' output: 'wav' clienttype: '*': clientid: '*': '[flac] -dcs --force-raw-format --endian=little --sign=signed --skip=$START$ --until=$END$ -- $FILE$' [20:08:07.5798] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'ogg' output: 'ogg' clienttype: '*': clientid: '*': '-' [20:08:07.5803] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'ogg' output: 'aif' clienttype: '*': clientid: '*': '[sox] -t ogg $FILE$ -t raw -r 44100 -c 2 -w -s $-x$ -' [20:08:07.5808] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'wma' output: 'wav' clienttype: '*': clientid: '*': '[wmadec] -r 44100 -b 16 -n 2 $FILE$' [20:08:07.5813] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'mpc' output: 'wav' clienttype: '*': clientid: '*': '[mppdec] --raw-le --silent --prev --gain 2 $FILE$ -' [20:08:07.5817] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'ape' output: 'wav' clienttype: '*': clientid: '*': '[mac] $FILE$ - -d' [20:08:07.5822] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'mpc' output: 'aif' clienttype: '*': clientid: '*': '[mppdec] --raw-be --silent --prev --gain 2 $FILE$ -' [20:08:07.5826] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'alc' output: 'wav' clienttype: '*': clientid: '*': '[alac] -r $FILE$' [20:08:07.5831] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'wvp' output: 'wav' clienttype: '*': clientid: '*': '[wvunpack] $FILE$ -rq --skip=$START$ --until=$END$ -o -' [20:08:07.5836] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'mov' output: 'aif' clienttype: '*': clientid: '*': '[mov123] $FILE$' [20:08:07.5840] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'wma' output: 'wma' clienttype: 'squeezebox2': clientid: '*': '-' [20:08:07.5848] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'flc' output: 'flc' clienttype: '*': clientid: '*': '-' [20:08:07.5853] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'aif' output: 'flc' clienttype: '*': clientid: '*': '[flac] -cs --totally-silent --compression-level-0 --skip=$START$ --until=$END$ -- $FILE$' [20:08:07.5858] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'wav' output: 'flc' clienttype: '*': clientid: '*': '[flac] -cs --totally-silent --compression-level-0 --skip=$START$ --until=$END$ -- $FILE$' [20:08:07.5864] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'ogg' output: 'flc' clienttype: '*': clientid: '*': '[sox] -t ogg $FILE$ -t raw -r 44100 -c 2 -w -s $-x$ - | [flac] -cs --compression-level-0 --totally-silent --endian big --channels 2 --bps 16 --sample-rate 44100 -- sign signed -' [20:08:07.5869] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'wma' output: 'flc' clienttype: '*': clientid: '*': '[wmadec] -r 44100 -b 16 -n 2 $FILE$ | [flac] -cs --totally-silent --compression-level-0 --endian little --sign signed --channels 2 --bps 16 --sample-rate 44100 -' [20:08:07.5874] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'mpc' output: 'flc' clienttype: '*': clientid: '*': '[mppdec] --silent --prev --gain 2 $FILE$ - | [flac] -cs --totally-silent --compression-level-0 -' [20:08:07.5879] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'ape' output: 'flc' clienttype: '*': clientid: '*': '[mac] $FILE$ - -d | [flac] -cs --totally-silent --compression-level-0 -' [20:08:07.5883] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'mov' output: 'flc' clienttype: '*': clientid: '*': '[mov123] $FILE$ | [flac] -cs --totally-silent --compression-level-0 --endian big --sign signed --channels 2 --bps 16 --sample-rate 44100 -' [20:08:07.5888] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'alc' output: 'flc' clienttype: '*': clientid: '*': '[alac] $FILE$ | [flac] -cs --totally-silent --compression-level-0 --endian big --sign signed --channels 2 --bps 16 --sample-rate 44100 -' [20:08:07.5893] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'wvp' output: 'flc' clienttype: '*': clientid: '*': '[wvunpack] $FILE$ -wq --skip=$START$ --until=$END$ -o - | [flac] -cs --totally-silent --compression-level-0 -' [20:08:07.5897] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'mp3' output: 'mp3' clienttype: 'transcode': clientid: '*': '[lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ $FILE$ -' [20:08:07.5902] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'flc' output: 'flc' clienttype: 'transcode': clientid: '*': '[flac] -dcs --skip=$START$ --until=$END$ -- $FILE$ | [flac] -cs --totally-silent --compression-level-0 -' [20:08:07.5919] main::init (397) SqueezeCenter Web Settings init... [15:11:21.3122] Slim::Player::Source::gotoNext (1288) opening next song (old format: mp3, new: mp3) current playmode: play [15:11:21.3127] Slim::Player::Source::streamingSongIndex (1345) Adding song index 0 to song queue [15:11:21.3131] Slim::Player::Source::streamingSongIndex (1349) Clearing out song queue first [15:11:21.3136] Slim::Player::Source::streamingSongIndex (1366) Song queue is now 0 [15:11:21.3266] Slim::Player::Source::resetSong (1590) Resetting song buffer. [15:11:21.3419] Slim::Player::Source::openSong (1685) Trying to open: http://64.71.145.107:8004/ [15:11:21.3519] Slim::Player::Source::openSong (1699) URL is remote (no direct streaming) [http://64.71.145.107:8004/] [15:11:21.3627] Slim::Player::ProtocolHandlers::openRemoteStream (66) Trying to open protocol stream for http://64.71.145.107:8004/ [15:11:21.3632] Slim::Player::ProtocolHandlers::openRemoteStream (70) Found handler for http://64.71.145.107:8004/ - using Slim::Player::Protocols::HTTP [15:11:21.3858] Slim::Formats::RemoteStream::open (60) Opening connection to http://64.71.145.107:8004/: [64.71.145.107 on port 8004 with path / with timeout 5] [15:11:21.7958] Slim::Formats::RemoteStream::request (136) Request: GET / HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: 64.71.145.107:8004 User-Agent: iTunes/4.7.1 (Darwin; N; Mac OS X Server 10.5.1 (9B18); ppc; EN; utf8) SqueezeCenter/7.0/16473 Icy-Metadata: 1 [15:11:21.9178] Slim::Formats::RemoteStream::request (143) Response: ICY 200 OK [15:11:21.9669] Slim::Formats::HTTP::parseHeaders (151) Header: icy-notice1:<BR>This stream requires <a href="http://www.winamp.com/">Winamp</a><BR> [15:11:21.9767] Slim::Formats::HTTP::parseHeaders (151) Header: icy-notice2:SHOUTcast Distributed Network Audio Server/Linux v1.9.7<BR> [15:11:21.9772] Slim::Formats::HTTP::parseHeaders (151) Header: icy-name:All-Classical WGBH 89.7-HD2 [15:11:21.9778] Slim::Formats::HTTP::parseHeaders (151) Header: icy-genre:Classical [15:11:21.9783] Slim::Formats::HTTP::parseHeaders (151) Header: icy-url:http://www.wgbh.org/classical [15:11:21.9788] Slim::Formats::HTTP::parseHeaders (151) Header: content-type:audio/mpeg [15:11:22.1569] Slim::Formats::HTTP::parseHeaders (151) Header: icy-pub:1 [15:11:22.1574] Slim::Formats::HTTP::parseHeaders (151) Header: icy-metaint:32768 [15:11:22.1579] Slim::Formats::HTTP::parseHeaders (151) Header: icy-br:112 [15:11:22.4251] Slim::Formats::HTTP::parseHeaders (173) Bitrate for http://64.71.145.107:8004/ set to 112000 [15:11:22.4306] Slim::Formats::RemoteStream::request (202) Opened stream! [15:11:22.4317] Slim::Player::Source::openSong (1711) remoteURL is a song (audio): http://64.71.145.107:8004/ [15:11:22.4437] Slim::Player::TranscodingHelper::getConvertCommand (302) Matched Format: mp3 Type: mp3 Command: - [15:11:22.4443] Slim::Player::Source::openSong (1737) remoteURL command - type mp3 format mp3 [15:11:22.4447] Slim::Player::Source::openSong (1738) remoteURL stream format : mp3 [20:07:04.0999] Slim::Utils::Prefs::maxRate (566) Setting maxBitRate for SLIMP3 to: 320 [20:07:28.3967] Slim::bootstrap::sigterm (376) Got sigterm [20:07:37.5597] Slim::bootstrap::theEND (400) Got to the END [20:07:37.5797] Slim::bootstrap::sigint (364) Got sigint [20:07:37.5803] Slim::Schema::forceCommit (1363) Warning: Trying to commit transactions before DB is initialized! [20:07:37.6679] Slim::Schema::Storage::throw_exception (67) Error: DBI Connection failed: DBI connect('hostname=127.0.0.1;port=9092;database=slimserver:mysql_socket=/Users/admin/Library/Caches/SqueezeCenter/squeezecenter-mysql.sock','slimserver',...) failed: Can't connect to MySQL server on '127.0 .0.1' (61) at /Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/CPAN/DBIx/Class/Storage/DBI.pm line 652 [20:07:37.6895] Slim::Schema::Storage::throw_exception (67) Backtrace: frame 0: Slim::Utils::Log::logBacktrace (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Schema/Storage.pm line 67) frame 1: Slim::Schema::Storage::throw_exception (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/CPAN/DBIx/Class/Storage/DBI.pm line 658) frame 2: DBIx::Class::Storage::DBI::_connect (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/CPAN/DBIx/Class/Storage/DBI.pm line 616) frame 3: DBIx::Class::Storage::DBI::_populate_dbh (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/CPAN/DBIx/Class/Storage/DBI.pm line 543) frame 4: DBIx::Class::Storage::DBI::ensure_connected (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Schema/Storage.pm line 27) frame 5: (eval) (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Schema/Storage.pm line 27) frame 6: Slim::Schema::Storage::dbh (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Schema.pm line 244) frame 7: (eval) (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Schema.pm line 244) frame 8: Slim::Schema::disconnect (./slimserver.pl line 962) frame 9: main::cleanup (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/bootstrap.pm line 368) frame 10: Slim::bootstrap::sigint (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/bootstrap.pm line 402) frame 11: Slim::bootstrap::theEND (./slimserver.pl line 999) frame 12: main::END (./slimserver.pl line 0) frame 13: (eval) (./slimserver.pl line 0) [20:07:37.7907] Slim::Schema::Storage::dbh (38) Warning: Unable to connect to the database - trying to bring it up! [20:08:01.2852] main::init (293) SqueezeCenter OS Specific init... [20:08:01.3259] main::init (313) SqueezeCenter daemonizing... [20:08:01.3544] Slim::bootstrap::theEND (400) Got to the END [20:08:03.4445] main::init (293) SqueezeCenter OS Specific init... [20:08:03.4450] main::init (322) SqueezeCenter settings effective user and group if requested... [20:08:03.4455] main::init (332) SqueezeCenter binary search path init... [20:08:03.4465] main::init (335) SqueezeCenter strings init... [20:08:03.4517] Slim::Utils::Strings::loadStrings (109) Retrieving string data from string cache: /Users/admin/Library/Caches/SqueezeCenter/strings.bin [20:08:03.5436] main::init (338) SqueezeCenter MySQL init... [20:08:05.8679] main::init (341) Async DNS init... [20:08:05.9684] main::init (344) Firmware init... [20:08:05.9903] main::init (347) SqueezeCenter Info init... [20:08:07.1072] main::init (350) SqueezeCenter IR init... [20:08:07.1728] main::init (353) SqueezeCenter Request init... [20:08:07.1810] main::init (356) SqueezeCenter Buttons init... [20:08:07.1854] main::init (359) SqueezeCenter Graphic Fonts init... [20:08:07.4353] main::init (367) UDP init... [20:08:07.4374] main::init (370) Slimproto Init... [20:08:07.4394] main::init (373) mDNS init... [20:08:07.4403] main::init (376) Cache init... [20:08:07.4794] main::init (389) SqueezeCenter HTTP init... [20:08:07.5604] main::init (392) Source conversion init.. [20:08:07.5610] Slim::Player::TranscodingHelper::loadConversionTables (38) Loading conversion config files... [20:08:07.5729] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'mov' output: 'mp3' clienttype: '*': clientid: '*': '[mov123] $FILE$ | [lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ -r - -' [20:08:07.5735] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'alc' output: 'mp3' clienttype: '*': clientid: '*': '[alac] -r $FILE$ | [lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ -r -x - -' [20:08:07.5740] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'ogg' output: 'mp3' clienttype: '*': clientid: '*': '[sox] -t ogg $FILE$ -t raw -r 44100 -c 2 -w -s $-x$ - | [lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ -r - -' [20:08:07.5745] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'wav' output: 'mp3' clienttype: '*': clientid: '*': '[lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ $FILE$ -' [20:08:07.5750] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'aif' output: 'mp3' clienttype: '*': clientid: '*': '[lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ $FILE$ -' [20:08:07.5756] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'flc' output: 'mp3' clienttype: '*': clientid: '*': '[flac] -dcs --skip=$START$ --until=$END$ -- $FILE$ | [lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ - -' [20:08:07.5761] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'wma' output: 'mp3' clienttype: '*': clientid: '*': '[wmadec] -r 44100 -b 16 -n 2 $FILE$ | [lame] $-x$ --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ - -' [20:08:07.5766] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'mpc' output: 'mp3' clienttype: '*': clientid: '*': '[mppdec] --raw-le --silent --prev --gain 3 $FILE$ - | [lame] $-x$ --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ - -' [20:08:07.5771] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'ape' output: 'mp3' clienttype: '*': clientid: '*': '[mac] $FILE$ - -d | [lame] --resample 44100 $-x$ --silent -q $QUALITY$ --abr $BITRATE$ - -' [20:08:07.5776] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'wvp' output: 'mp3' clienttype: '*': clientid: '*': '[wvunpack] $FILE$ -wq --skip=$START$ --until=$END$ -o - | [lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ - -' [20:08:07.5780] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'mp3' output: 'mp3' clienttype: '*': clientid: '*': '-' [20:08:07.5785] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'aif' output: 'aif' clienttype: '*': clientid: '*': '-' [20:08:07.5789] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'wav' output: 'wav' clienttype: '*': clientid: '*': '-' [20:08:07.5794] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'flc' output: 'wav' clienttype: '*': clientid: '*': '[flac] -dcs --force-raw-format --endian=little --sign=signed --skip=$START$ --until=$END$ -- $FILE$' [20:08:07.5798] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'ogg' output: 'ogg' clienttype: '*': clientid: '*': '-' [20:08:07.5803] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'ogg' output: 'aif' clienttype: '*': clientid: '*': '[sox] -t ogg $FILE$ -t raw -r 44100 -c 2 -w -s $-x$ -' [20:08:07.5808] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'wma' output: 'wav' clienttype: '*': clientid: '*': '[wmadec] -r 44100 -b 16 -n 2 $FILE$' [20:08:07.5813] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'mpc' output: 'wav' clienttype: '*': clientid: '*': '[mppdec] --raw-le --silent --prev --gain 2 $FILE$ -' [20:08:07.5817] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'ape' output: 'wav' clienttype: '*': clientid: '*': '[mac] $FILE$ - -d' [20:08:07.5822] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'mpc' output: 'aif' clienttype: '*': clientid: '*': '[mppdec] --raw-be --silent --prev --gain 2 $FILE$ -' [20:08:07.5826] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'alc' output: 'wav' clienttype: '*': clientid: '*': '[alac] -r $FILE$' [20:08:07.5831] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'wvp' output: 'wav' clienttype: '*': clientid: '*': '[wvunpack] $FILE$ -rq --skip=$START$ --until=$END$ -o -' [20:08:07.5836] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'mov' output: 'aif' clienttype: '*': clientid: '*': '[mov123] $FILE$' [20:08:07.5840] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'wma' output: 'wma' clienttype: 'squeezebox2': clientid: '*': '-' [20:08:07.5848] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'flc' output: 'flc' clienttype: '*': clientid: '*': '-' [20:08:07.5853] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'aif' output: 'flc' clienttype: '*': clientid: '*': '[flac] -cs --totally-silent --compression-level-0 --skip=$START$ --until=$END$ -- $FILE$' [20:08:07.5858] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'wav' output: 'flc' clienttype: '*': clientid: '*': '[flac] -cs --totally-silent --compression-level-0 --skip=$START$ --until=$END$ -- $FILE$' [20:08:07.5864] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'ogg' output: 'flc' clienttype: '*': clientid: '*': '[sox] -t ogg $FILE$ -t raw -r 44100 -c 2 -w -s $-x$ - | [flac] -cs --compression-level-0 --totally-silent --endian big --channels 2 --bps 16 --sample-rate 44100 -- sign signed -' [20:08:07.5869] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'wma' output: 'flc' clienttype: '*': clientid: '*': '[wmadec] -r 44100 -b 16 -n 2 $FILE$ | [flac] -cs --totally-silent --compression-level-0 --endian little --sign signed --channels 2 --bps 16 --sample-rate 44100 -' [20:08:07.5874] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'mpc' output: 'flc' clienttype: '*': clientid: '*': '[mppdec] --silent --prev --gain 2 $FILE$ - | [flac] -cs --totally-silent --compression-level-0 -' [20:08:07.5879] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'ape' output: 'flc' clienttype: '*': clientid: '*': '[mac] $FILE$ - -d | [flac] -cs --totally-silent --compression-level-0 -' [20:08:07.5883] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'mov' output: 'flc' clienttype: '*': clientid: '*': '[mov123] $FILE$ | [flac] -cs --totally-silent --compression-level-0 --endian big --sign signed --channels 2 --bps 16 --sample-rate 44100 -' [20:08:07.5888] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'alc' output: 'flc' clienttype: '*': clientid: '*': '[alac] $FILE$ | [flac] -cs --totally-silent --compression-level-0 --endian big --sign signed --channels 2 --bps 16 --sample-rate 44100 -' [20:08:07.5893] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'wvp' output: 'flc' clienttype: '*': clientid: '*': '[wvunpack] $FILE$ -wq --skip=$START$ --until=$END$ -o - | [flac] -cs --totally-silent --compression-level-0 -' [20:08:07.5897] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'mp3' output: 'mp3' clienttype: 'transcode': clientid: '*': '[lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ $FILE$ -' [20:08:07.5902] Slim::Player::TranscodingHelper::loadConversionTables (85) input: 'flc' output: 'flc' clienttype: 'transcode': clientid: '*': '[flac] -dcs --skip=$START$ --until=$END$ -- $FILE$ | [flac] -cs --totally-silent --compression-level-0 -' [20:08:07.5919] main::init (397) SqueezeCenter Web Settings init... [20:08:07.6756] main::init (401) SqueezeCenter Jive init... [20:08:12.0341] main::init (404) SqueezeCenter Plugins init... [20:08:12.9526] Slim::Utils::PluginManager::enablePlugins (375) Couldn't load Slim::Plugin::PreventStandby::Plugin. Error: [-5] [20:08:12.9534] Slim::Utils::PluginManager::enablePlugins (386) Skipping plugin: Slim::Plugin::RPC::Plugin - disabled [20:08:12.9538] Slim::Utils::PluginManager::enablePlugins (386) Skipping plugin: Slim::Plugin::RS232::Plugin - disabled [20:08:13.7096] Slim::Utils::PluginManager::enablePlugins (386) Skipping plugin: Slim::Plugin::xPL::Plugin - disabled [20:08:13.7344] Slim::Formats::RemoteStream::open (60) Opening connection to http://localhost:10002/api/version: [localhost on port 10002 with path /api/version with timeout 5] [20:08:13.7373] Slim::Formats::RemoteStream::open (96) Can't open socket to [localhost:10002]: 61: Connection refused [20:08:13.7380] Slim::Plugin::MusicMagic::Plugin::initPlugin (141) Can't connect to port 10002 - MusicMagic disabled. [20:08:14.3297] main::init (413) SqueezeCenter checkDataSource... [20:08:16.6496] main::init (417) SqueezeCenter persist playlists... [20:08:16.6505] main::init (428) SqueezeCenter HTTP enable... [20:08:16.6525] main::init (432) mDNS startAdvertising... [20:08:16.7016] main::init (440) SqueezeCenter done init... [20:08:17.5555] Slim::Utils::Cache::cleanup (225) Cache purge: FileCache - skipping, purged recently [20:08:17.5623] Slim::Utils::Cache::cleanup (225) Cache purge: Artwork - skipping, purged recently [20:08:41.9493] Slim::Networking::SliMP3::Protocol::getUdpClient (100) 00:04:20:04:10:ed (h) deviceid: 1 revision: 2.3 address: 192.168.1.99:3483 [20:08:41.9602] Slim::Player::SLIMP3::new (48) Loading module: Slim::Hardware::mas3507d [20:08:41.9780] Slim::Player::SLIMP3::new (48) Loading module: Slim::Networking::SliMP3::Stream [20:08:41.9917] Slim::Player::SLIMP3::new (48) Loading module: Slim::Display::Text [20:08:42.3891] Slim::Player::Source::streamingSongIndex (1345) Adding song index 0 to song queue [20:08:42.3897] Slim::Player::Source::streamingSongIndex (1349) Clearing out song queue first [20:08:42.3901] Slim::Player::Source::streamingSongIndex (1366) Song queue is now 0 [20:08:44.8600] Slim::Utils::Misc::msg (1235) Warning: [20:08:44.8592] Use of uninitialized value in concatenation (.) or string at /Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Player/Source.pm line 192. [20:08:44.8605] Slim::Player::Source::songTime (191) songtime=0 from byterate=0, duration=, bytesReceived=0, fullness=0, rate=1, startStream=0 [20:08:45.4868] Slim::Utils::Prefs::maxRate (566) Setting maxBitRate for SLIMP3 to: 320 [20:09:10.2611] Slim::Player::Source::playmode (298) 00:04:20:04:10:ed: Switching to mode play from stop [20:09:10.2624] Slim::Player::Source::resetSong (1590) Resetting song buffer. [20:09:10.2652] Slim::Player::Source::openSong (1685) Trying to open: http://64.71.145.107:8004/ [20:09:10.2659] Slim::Player::Source::openSong (1699) URL is remote (no direct streaming) [http://64.71.145.107:8004/] [20:09:10.2666] Slim::Player::ProtocolHandlers::openRemoteStream (66) Trying to open protocol stream for http://64.71.145.107:8004/ [20:09:10.2671] Slim::Player::ProtocolHandlers::openRemoteStream (70) Found handler for http://64.71.145.107:8004/ - using Slim::Player::Protocols::HTTP [20:09:10.2680] Slim::Formats::RemoteStream::open (60) Opening connection to http://64.71.145.107:8004/: [64.71.145.107 on port 8004 with path / with timeout 5] [20:09:10.3645] Slim::Formats::RemoteStream::request (136) Request: GET / HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: 64.71.145.107:8004 User-Agent: iTunes/4.7.1 (Darwin; N; Mac OS X Server 10.5.1 (9B18); ppc; EN; utf8) SqueezeCenter/7.0/16473 Icy-Metadata: 1 [20:09:10.4656] Slim::Formats::RemoteStream::request (143) Response: ICY 200 OK [20:09:10.4695] Slim::Formats::HTTP::parseHeaders (151) Header: icy-notice1:<BR>This stream requires <a href="http://www.winamp.com/">Winamp</a><BR> [20:09:10.4703] Slim::Formats::HTTP::parseHeaders (151) Header: icy-notice2:SHOUTcast Distributed Network Audio Server/Linux v1.9.7<BR> [20:09:10.4708] Slim::Formats::HTTP::parseHeaders (151) Header: icy-name:All-Classical WGBH 89.7-HD2 [20:09:10.4714] Slim::Formats::HTTP::parseHeaders (151) Header: icy-genre:Classical [20:09:10.4719] Slim::Formats::HTTP::parseHeaders (151) Header: icy-url:http://www.wgbh.org/classical [20:09:10.4724] Slim::Formats::HTTP::parseHeaders (151) Header: content-type:audio/mpeg [20:09:10.4764] Slim::Formats::HTTP::parseHeaders (151) Header: icy-pub:1 [20:09:10.4769] Slim::Formats::HTTP::parseHeaders (151) Header: icy-metaint:32768 [20:09:10.4774] Slim::Formats::HTTP::parseHeaders (151) Header: icy-br:112 [20:09:10.4859] Slim::Formats::HTTP::parseHeaders (173) Bitrate for http://64.71.145.107:8004/ set to 112000 [20:09:10.4913] Slim::Formats::RemoteStream::request (202) Opened stream! [20:09:10.4919] Slim::Player::Source::openSong (1711) remoteURL is a song (audio): http://64.71.145.107:8004/ [20:09:10.4930] Slim::Utils::Prefs::maxRate (566) Setting maxBitRate for SLIMP3 to: 320 [20:09:10.4946] Slim::Player::TranscodingHelper::getConvertCommand (201) undermax = 1, type = mp3, slimp3 = 00:04:20:04:10:ed [20:09:10.4953] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-slimp3-00:04:20:04:10:ed [20:09:10.4958] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-*-00:04:20:04:10:ed [20:09:10.4962] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-slimp3-* [20:09:10.4966] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-*-* [20:09:10.4973] Slim::Player::TranscodingHelper::enabledFormat (105) Checking to see if mp3-mp3-*-* is enabled [20:09:10.4978] Slim::Player::TranscodingHelper::enabledFormat (113) There are 7 disabled formats... [20:09:10.4982] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-aif-*-* vs mp3-mp3-*-* [20:09:10.4986] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-flc-*-* vs mp3-mp3-*-* [20:09:10.4990] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-mp3-*-* vs mp3-mp3-*-* [20:09:10.4994] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-wav-*-* vs mp3-mp3-*-* [20:09:10.4998] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-flc-*-* vs mp3-mp3-*-* [20:09:10.5002] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-mp3-*-* vs mp3-mp3-*-* [20:09:10.5006] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-wav-*-* vs mp3-mp3-*-* [20:09:10.5010] Slim::Player::TranscodingHelper::checkBin (146) enabled [20:09:10.5013] Slim::Player::TranscodingHelper::checkBin (148) Found command: - [20:09:10.5020] Slim::Utils::Prefs::maxRate (566) Setting maxBitRate for SLIMP3 to: 320 [20:09:10.5036] Slim::Player::TranscodingHelper::getConvertCommand (302) Matched Format: mp3 Type: mp3 Command: - [20:09:10.5041] Slim::Player::Source::openSong (1737) remoteURL command - type mp3 format mp3 [20:09:10.5046] Slim::Player::Source::openSong (1738) remoteURL stream format : mp3 [20:09:10.6141] Slim::Player::Source::playmode (397) 00:04:20:04:10:ed New play mode: play [20:09:10.6675] Slim::Networking::SliMP3::Stream::newStream (91) 00:04:20:04:10:ed new stream: [20:09:10.6691] Slim::Player::Source::readNextChunk (2166) Sending 0 bytes of silence. [20:09:10.6710] Slim::Player::Source::playmode (551) 00:04:20:04:10:ed: Current playmode: play [20:09:10.7401] Slim::Utils::Prefs::maxRate (566) Setting maxBitRate for SLIMP3 to: 320 [20:09:10.9598] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 32 [20:09:10.9603] Slim::Player::Protocols::HTTP::readMetaData (105) Metadata: StreamTitle='';StreamUrl='';^@^@^@^@ [20:09:11.3231] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [20:09:11.3311] Slim::Networking::SliMP3::Stream::sendNextChunk (659) 00:04:20:04:10:ed Buffer full, starting playback [20:09:11.6108] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [20:09:11.8986] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [20:09:13.6427] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [20:09:15.9942] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [20:09:18.2920] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [20:09:20.6365] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0
Created attachment 2734 [details] Error logging which shows events surrounding a stall/crash/something.
All I can sy is that some strag stuff seems to be happening. What happened to the log where you have SNIP in the following part? Several seconds of crucial logging are missing. [20:41:28.2531] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:16384 rptr:0 seq:25 inflight:1400 fullness:34168 latency:1953 us [20:41:28.2545] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:26, len:1400, wptr:17084, state:buffering, control:3, inflight:0 [20:41:28.2585] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:17084 rptr:0 seq:26 inflight:1400 fullness:35568 latency:1916 us [20:41:28.2600] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:27, len:1400, wptr:17784, state:buffering, control:3, inflight:0 <SNIP> <CRASH> somewhere about now, I think [02:41:37.2863] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4292, len:0, wptr:3500, state:play, control:0, inflight:0 [02:41:37.2883] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:3500 rptr:5078 seq:4292 inflight:0 fullness:127916 latency:851 us [02:41:37.2897] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4293, len:922, wptr:3500, state:play, control:0, inflight:0 [02:41:37.2931] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:3500 rptr:5078 seq:4293 inflight:922 fullness:128838 latency:1619 us [02:41:37.2940] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:37.3449] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk Perhaps you could also attach a copy of server/Slim/Networking/SliMP3/Stream.pm from your server installation. I cannot understand what is happening when, after the problem, you try to play a new track. The line Slim::Networking::SliMP3::Stream::newStream (91) 00:04:20:04:10:ed new stream should be followed by more stuff from that module before returning to Slim::Player::Source::playmode.
I'm sure that I got rid of those lines because they looked (to my untrained eye) like they were repetitive. I presume you want the seconds preceding the 02:... time and not the lines following the 20:... time? If so, here they are. (I can upload the whole 303MB log file if you'd like--it compresses to 20MB or so. The only real headache is dealing with such a large file. Strangely, perhaps sadly, only Microsloth Word can handle it well.) [02:41:33.1940] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:33.2448] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:33.2456] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4181, len:0, wptr:41168, state:play, control:0, inflight:0 [02:41:33.2476] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:41168 rptr:42268 seq:4181 inflight:0 fullness:128872 latency:856 us [02:41:33.2485] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:33.2994] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:33.3002] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4182, len:0, wptr:41168, state:play, control:0, inflight:0 [02:41:33.3022] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:41168 rptr:42692 seq:4182 inflight:0 fullness:128024 latency:849 us [02:41:33.3035] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4183, len:1400, wptr:41168, state:play, control:0, inflight:0 [02:41:33.3074] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:41168 rptr:42719 seq:4183 inflight:1400 fullness:129370 latency:1893 us [02:41:33.3083] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:33.3592] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:33.3600] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4184, len:0, wptr:41868, state:play, control:0, inflight:0 [02:41:33.3620] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:41868 rptr:43086 seq:4184 inflight:0 fullness:128636 latency:860 us [02:41:33.3629] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:33.4138] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:33.4146] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4185, len:0, wptr:41868, state:play, control:0, inflight:0 [02:41:33.4167] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:41868 rptr:43448 seq:4185 inflight:0 fullness:127912 latency:908 us [02:41:33.4181] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4186, len:1400, wptr:41868, state:play, control:0, inflight:0 [02:41:33.4221] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:41868 rptr:43550 seq:4186 inflight:1400 fullness:129108 latency:1891 us [02:41:33.4231] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:33.4740] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:33.4748] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4187, len:0, wptr:42568, state:play, control:0, inflight:0 [02:41:33.4768] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:42568 rptr:43914 seq:4187 inflight:0 fullness:128380 latency:872 us [02:41:33.4777] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:33.5284] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:33.5292] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4188, len:0, wptr:42568, state:play, control:0, inflight:0 [02:41:33.5311] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:42568 rptr:44287 seq:4188 inflight:0 fullness:127634 latency:844 us [02:41:33.5326] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4189, len:1400, wptr:42568, state:play, control:0, inflight:0 [02:41:33.5368] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:42568 rptr:44317 seq:4189 inflight:1400 fullness:128974 latency:1989 us [02:41:33.5377] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:33.5885] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:33.5893] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4190, len:0, wptr:43268, state:play, control:0, inflight:0 [02:41:33.5913] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:43268 rptr:44729 seq:4190 inflight:0 fullness:128150 latency:844 us [02:41:33.5926] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4191, len:1400, wptr:43268, state:play, control:0, inflight:0 [02:41:33.5965] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:43268 rptr:44729 seq:4191 inflight:1400 fullness:129550 latency:1878 us [02:41:33.5974] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:33.6483] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:33.6491] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4192, len:0, wptr:43968, state:play, control:0, inflight:0 [02:41:33.6510] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:43968 rptr:45097 seq:4192 inflight:0 fullness:128814 latency:850 us [02:41:33.6519] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:33.7027] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:33.7035] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4193, len:0, wptr:43968, state:play, control:0, inflight:0 [02:41:33.7055] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:43968 rptr:45464 seq:4193 inflight:0 fullness:128080 latency:843 us [02:41:33.7068] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4194, len:1400, wptr:43968, state:play, control:0, inflight:0 [02:41:33.7108] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:43968 rptr:45566 seq:4194 inflight:1400 fullness:129276 latency:1880 us [02:41:33.7117] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:33.7626] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:33.7634] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4195, len:0, wptr:44668, state:play, control:0, inflight:0 [02:41:33.7654] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:44668 rptr:45928 seq:4195 inflight:0 fullness:128552 latency:850 us [02:41:33.7663] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:33.8171] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:33.8180] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4196, len:0, wptr:44668, state:play, control:0, inflight:0 [02:41:33.8199] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:44668 rptr:46289 seq:4196 inflight:0 fullness:127830 latency:851 us [02:41:33.8213] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4197, len:1400, wptr:44668, state:play, control:0, inflight:0 [02:41:33.8255] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:44668 rptr:46370 seq:4197 inflight:1400 fullness:129068 latency:1989 us [02:41:33.8264] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:33.8772] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:33.8780] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4198, len:0, wptr:45368, state:play, control:0, inflight:0 [02:41:33.8799] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:45368 rptr:46737 seq:4198 inflight:0 fullness:128334 latency:847 us [02:41:33.8808] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:33.9317] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:33.9325] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4199, len:0, wptr:45368, state:play, control:0, inflight:0 [02:41:33.9344] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:45368 rptr:47105 seq:4199 inflight:0 fullness:127598 latency:869 us [02:41:33.9358] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4200, len:1400, wptr:45368, state:play, control:0, inflight:0 [02:41:33.9398] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:45368 rptr:47105 seq:4200 inflight:1400 fullness:128998 latency:1909 us [02:41:33.9407] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:33.9915] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:33.9924] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4201, len:0, wptr:46068, state:play, control:0, inflight:0 [02:41:33.9943] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:46068 rptr:47515 seq:4201 inflight:0 fullness:128178 latency:844 us [02:41:33.9956] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4202, len:1400, wptr:46068, state:play, control:0, inflight:0 [02:41:33.9996] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:46068 rptr:47574 seq:4202 inflight:1400 fullness:129460 latency:1873 us [02:41:34.0005] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:34.0514] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:34.0523] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4203, len:0, wptr:46768, state:play, control:0, inflight:0 [02:41:34.0543] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:46768 rptr:47942 seq:4203 inflight:0 fullness:128724 latency:872 us [02:41:34.0552] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:34.1060] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:34.1068] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4204, len:0, wptr:46768, state:play, control:0, inflight:0 [02:41:34.1088] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:46768 rptr:48309 seq:4204 inflight:0 fullness:127990 latency:886 us [02:41:34.1102] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4205, len:1400, wptr:46768, state:play, control:0, inflight:0 [02:41:34.1141] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:46768 rptr:48384 seq:4205 inflight:1400 fullness:129240 latency:1873 us [02:41:34.1150] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:34.1658] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:34.1666] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4206, len:0, wptr:47468, state:play, control:0, inflight:0 [02:41:34.1686] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:47468 rptr:48758 seq:4206 inflight:0 fullness:128492 latency:851 us [02:41:34.1695] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:34.2203] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:34.2212] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4207, len:0, wptr:47468, state:play, control:0, inflight:0 [02:41:34.2231] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:47468 rptr:49125 seq:4207 inflight:0 fullness:127758 latency:857 us [02:41:34.2244] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4208, len:1400, wptr:47468, state:play, control:0, inflight:0 [02:41:34.2284] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:47468 rptr:49127 seq:4208 inflight:1400 fullness:129154 latency:1871 us [02:41:34.2293] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:34.2802] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:34.2810] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4209, len:0, wptr:48168, state:play, control:0, inflight:0 [02:41:34.2829] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:48168 rptr:49577 seq:4209 inflight:0 fullness:128254 latency:846 us [02:41:34.2842] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4210, len:1400, wptr:48168, state:play, control:0, inflight:0 [02:41:34.2882] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:48168 rptr:49582 seq:4210 inflight:1400 fullness:129644 latency:1893 us [02:41:34.2891] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:34.3399] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:34.3407] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4211, len:0, wptr:48868, state:play, control:0, inflight:0 [02:41:34.3429] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:48868 rptr:49950 seq:4211 inflight:0 fullness:128908 latency:923 us [02:41:34.3439] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:34.3948] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:34.3956] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4212, len:0, wptr:48868, state:play, control:0, inflight:0 [02:41:34.3976] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:48868 rptr:50311 seq:4212 inflight:0 fullness:128186 latency:846 us [02:41:34.3989] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [02:41:34.3996] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4213, len:568, wptr:48868, state:play, control:0, inflight:0 [02:41:34.4024] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:48868 rptr:50400 seq:4213 inflight:568 fullness:128576 latency:1258 us [02:41:34.4033] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:34.4542] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:34.4550] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4214, len:0, wptr:49152, state:play, control:0, inflight:0 [02:41:34.4569] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:49152 rptr:50768 seq:4214 inflight:0 fullness:127840 latency:856 us [02:41:34.4583] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4215, len:1400, wptr:49152, state:play, control:0, inflight:0 [02:41:34.4623] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:49152 rptr:50768 seq:4215 inflight:1400 fullness:129240 latency:1909 us [02:41:34.4632] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:34.5141] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:34.5149] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4216, len:0, wptr:49852, state:play, control:0, inflight:0 [02:41:34.5168] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:49852 rptr:51175 seq:4216 inflight:0 fullness:128426 latency:844 us [02:41:34.5177] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:34.5686] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:34.5694] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4217, len:0, wptr:49852, state:play, control:0, inflight:0 [02:41:34.5713] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:49852 rptr:51599 seq:4217 inflight:0 fullness:127578 latency:869 us [02:41:34.5727] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4218, len:1400, wptr:49852, state:play, control:0, inflight:0 [02:41:34.5767] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:49852 rptr:51599 seq:4218 inflight:1400 fullness:128978 latency:1903 us [02:41:34.5776] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:34.6284] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:34.6292] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4219, len:0, wptr:50552, state:play, control:0, inflight:0 [02:41:34.6312] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:50552 rptr:51957 seq:4219 inflight:0 fullness:128262 latency:849 us [02:41:34.6327] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4220, len:1400, wptr:50552, state:play, control:0, inflight:0 [02:41:34.6367] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:50552 rptr:52042 seq:4220 inflight:1400 fullness:129492 latency:1914 us [02:41:34.6376] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:34.6884] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:34.6892] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4221, len:0, wptr:51252, state:play, control:0, inflight:0 [02:41:34.6912] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:51252 rptr:52410 seq:4221 inflight:0 fullness:128756 latency:850 us [02:41:34.6921] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:34.7429] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:34.7437] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4222, len:0, wptr:51252, state:play, control:0, inflight:0 [02:41:34.7456] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:51252 rptr:52774 seq:4222 inflight:0 fullness:128028 latency:839 us [02:41:34.7469] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4223, len:1400, wptr:51252, state:play, control:0, inflight:0 [02:41:34.7509] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:51252 rptr:52775 seq:4223 inflight:1400 fullness:129426 latency:1880 us [02:41:34.7517] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:34.8026] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:34.8034] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4224, len:0, wptr:51952, state:play, control:0, inflight:0 [02:41:34.8054] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:51952 rptr:53242 seq:4224 inflight:0 fullness:128492 latency:871 us [02:41:34.8063] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:34.8571] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:34.8579] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4225, len:0, wptr:51952, state:play, control:0, inflight:0 [02:41:34.8599] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:51952 rptr:53608 seq:4225 inflight:0 fullness:127760 latency:847 us [02:41:34.8612] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4226, len:1400, wptr:51952, state:play, control:0, inflight:0 [02:41:34.8651] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:51952 rptr:53608 seq:4226 inflight:1400 fullness:129160 latency:1873 us [02:41:34.8660] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:34.9168] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:34.9177] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4227, len:0, wptr:52652, state:play, control:0, inflight:0 [02:41:34.9196] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:52652 rptr:53975 seq:4227 inflight:0 fullness:128426 latency:842 us [02:41:34.9205] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:34.9712] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:34.9722] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4228, len:0, wptr:52652, state:play, control:0, inflight:0 [02:41:34.9741] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:52652 rptr:54404 seq:4228 inflight:0 fullness:127568 latency:864 us [02:41:34.9755] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4229, len:1400, wptr:52652, state:play, control:0, inflight:0 [02:41:34.9794] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:52652 rptr:54418 seq:4229 inflight:1400 fullness:128940 latency:1881 us [02:41:34.9803] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:35.0312] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:35.0320] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4230, len:0, wptr:53352, state:play, control:0, inflight:0 [02:41:35.0339] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:53352 rptr:54790 seq:4230 inflight:0 fullness:128196 latency:853 us [02:41:35.0353] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4231, len:1400, wptr:53352, state:play, control:0, inflight:0 [02:41:35.0393] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:53352 rptr:54844 seq:4231 inflight:1400 fullness:129488 latency:1935 us [02:41:35.0402] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:35.0911] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:35.0919] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4232, len:0, wptr:54052, state:play, control:0, inflight:0 [02:41:35.0938] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:54052 rptr:55254 seq:4232 inflight:0 fullness:128668 latency:848 us [02:41:35.0947] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:35.1456] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:35.1464] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4233, len:0, wptr:54052, state:play, control:0, inflight:0 [02:41:35.1483] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:54052 rptr:55621 seq:4233 inflight:0 fullness:127934 latency:844 us [02:41:35.1496] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4234, len:1400, wptr:54052, state:play, control:0, inflight:0 [02:41:35.1537] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:54052 rptr:55621 seq:4234 inflight:1400 fullness:129334 latency:1912 us [02:41:35.1546] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:35.2054] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:35.2062] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4235, len:0, wptr:54752, state:play, control:0, inflight:0 [02:41:35.2081] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:54752 rptr:56001 seq:4235 inflight:0 fullness:128574 latency:843 us [02:41:35.2090] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:35.2599] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:35.2607] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4236, len:0, wptr:54752, state:play, control:0, inflight:0 [02:41:35.2628] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:54752 rptr:56434 seq:4236 inflight:0 fullness:127708 latency:899 us [02:41:35.2642] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4237, len:1400, wptr:54752, state:play, control:0, inflight:0 [02:41:35.2681] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:54752 rptr:56434 seq:4237 inflight:1400 fullness:129108 latency:1880 us [02:41:35.2690] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:35.3199] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:35.3208] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4238, len:0, wptr:55452, state:play, control:0, inflight:0 [02:41:35.3229] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:55452 rptr:56802 seq:4238 inflight:0 fullness:128372 latency:933 us [02:41:35.3238] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:35.3748] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:35.3756] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4239, len:0, wptr:55452, state:play, control:0, inflight:0 [02:41:35.3775] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:55452 rptr:57166 seq:4239 inflight:0 fullness:127644 latency:867 us [02:41:35.3789] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4240, len:1400, wptr:55452, state:play, control:0, inflight:0 [02:41:35.3829] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:55452 rptr:57268 seq:4240 inflight:1400 fullness:128840 latency:1908 us [02:41:35.3838] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:35.4347] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:35.4355] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4241, len:0, wptr:56152, state:play, control:0, inflight:0 [02:41:35.4375] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:56152 rptr:57629 seq:4241 inflight:0 fullness:128118 latency:858 us [02:41:35.4388] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4242, len:1400, wptr:56152, state:play, control:0, inflight:0 [02:41:35.4427] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:56152 rptr:57629 seq:4242 inflight:1400 fullness:129518 latency:1877 us [02:41:35.4436] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:35.4944] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:35.4952] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4243, len:0, wptr:56852, state:play, control:0, inflight:0 [02:41:35.4972] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:56852 rptr:58073 seq:4243 inflight:0 fullness:128630 latency:851 us [02:41:35.4981] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:35.5489] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:35.5497] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4244, len:0, wptr:56852, state:play, control:0, inflight:0 [02:41:35.5516] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:56852 rptr:58448 seq:4244 inflight:0 fullness:127880 latency:852 us [02:41:35.5531] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4245, len:1400, wptr:56852, state:play, control:0, inflight:0 [02:41:35.5571] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:56852 rptr:58448 seq:4245 inflight:1400 fullness:129280 latency:1887 us [02:41:35.5580] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:35.6110] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:35.6118] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4246, len:0, wptr:57552, state:play, control:0, inflight:0 [02:41:35.6141] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:57552 rptr:58860 seq:4246 inflight:0 fullness:128456 latency:1040 us [02:41:35.6150] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:35.6658] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:35.6666] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4247, len:0, wptr:57552, state:play, control:0, inflight:0 [02:41:35.6685] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:57552 rptr:59276 seq:4247 inflight:0 fullness:127624 latency:846 us [02:41:35.6698] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4248, len:1400, wptr:57552, state:play, control:0, inflight:0 [02:41:35.6737] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:57552 rptr:59276 seq:4248 inflight:1400 fullness:129024 latency:1877 us [02:41:35.6746] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:35.7254] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:35.7262] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4249, len:0, wptr:58252, state:play, control:0, inflight:0 [02:41:35.7282] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:58252 rptr:59643 seq:4249 inflight:0 fullness:128290 latency:846 us [02:41:35.7291] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:35.7799] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:35.7807] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4250, len:0, wptr:58252, state:play, control:0, inflight:0 [02:41:35.7826] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:58252 rptr:60013 seq:4250 inflight:0 fullness:127550 latency:855 us [02:41:35.7840] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4251, len:1400, wptr:58252, state:play, control:0, inflight:0 [02:41:35.7879] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:58252 rptr:60091 seq:4251 inflight:1400 fullness:128794 latency:1868 us [02:41:35.7888] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:35.8396] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:35.8404] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4252, len:0, wptr:58952, state:play, control:0, inflight:0 [02:41:35.8425] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:58952 rptr:60459 seq:4252 inflight:0 fullness:128058 latency:881 us [02:41:35.8439] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4253, len:1400, wptr:58952, state:play, control:0, inflight:0 [02:41:35.8479] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:58952 rptr:60459 seq:4253 inflight:1400 fullness:129458 latency:1917 us [02:41:35.8488] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:35.8996] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:35.9004] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4254, len:0, wptr:59652, state:play, control:0, inflight:0 [02:41:35.9023] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:59652 rptr:60905 seq:4254 inflight:0 fullness:128566 latency:843 us [02:41:35.9032] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:35.9541] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:35.9549] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4255, len:0, wptr:59652, state:play, control:0, inflight:0 [02:41:35.9569] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:59652 rptr:61288 seq:4255 inflight:0 fullness:127800 latency:843 us [02:41:35.9582] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4256, len:1400, wptr:59652, state:play, control:0, inflight:0 [02:41:35.9621] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:59652 rptr:61288 seq:4256 inflight:1400 fullness:129200 latency:1875 us [02:41:35.9630] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:36.0139] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:36.0148] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4257, len:0, wptr:60352, state:play, control:0, inflight:0 [02:41:36.0167] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:60352 rptr:61656 seq:4257 inflight:0 fullness:128464 latency:865 us [02:41:36.0176] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:36.0685] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:36.0693] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4258, len:0, wptr:60352, state:play, control:0, inflight:0 [02:41:36.0713] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:60352 rptr:62084 seq:4258 inflight:0 fullness:127608 latency:849 us [02:41:36.0726] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4259, len:1400, wptr:60352, state:play, control:0, inflight:0 [02:41:36.0766] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:60352 rptr:62100 seq:4259 inflight:1400 fullness:128976 latency:1914 us [02:41:36.0775] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:36.1284] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:36.1292] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4260, len:0, wptr:61052, state:play, control:0, inflight:0 [02:41:36.1311] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:61052 rptr:62468 seq:4260 inflight:0 fullness:128240 latency:844 us [02:41:36.1325] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4261, len:1400, wptr:61052, state:play, control:0, inflight:0 [02:41:36.1366] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:61052 rptr:62525 seq:4261 inflight:1400 fullness:129526 latency:1953 us [02:41:36.1376] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:36.1885] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:36.1893] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4262, len:0, wptr:61752, state:play, control:0, inflight:0 [02:41:36.1912] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:61752 rptr:62938 seq:4262 inflight:0 fullness:128700 latency:845 us [02:41:36.1921] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:36.2429] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:36.2438] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4263, len:0, wptr:61752, state:play, control:0, inflight:0 [02:41:36.2457] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:61752 rptr:63304 seq:4263 inflight:0 fullness:127968 latency:848 us [02:41:36.2471] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4264, len:1400, wptr:61752, state:play, control:0, inflight:0 [02:41:36.2510] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:61752 rptr:63304 seq:4264 inflight:1400 fullness:129368 latency:1875 us [02:41:36.2519] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:36.3027] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:36.3035] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4265, len:0, wptr:62452, state:play, control:0, inflight:0 [02:41:36.3056] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:62452 rptr:63693 seq:4265 inflight:0 fullness:128590 latency:887 us [02:41:36.3065] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:36.3574] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:36.3582] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4266, len:0, wptr:62452, state:play, control:0, inflight:0 [02:41:36.3602] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:62452 rptr:64112 seq:4266 inflight:0 fullness:127752 latency:861 us [02:41:36.3616] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4267, len:1400, wptr:62452, state:play, control:0, inflight:0 [02:41:36.3655] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:62452 rptr:64112 seq:4267 inflight:1400 fullness:129152 latency:1875 us [02:41:36.3664] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:36.4173] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:36.4181] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4268, len:0, wptr:63152, state:play, control:0, inflight:0 [02:41:36.4201] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:63152 rptr:64480 seq:4268 inflight:0 fullness:128416 latency:878 us [02:41:36.4210] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:36.4719] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:36.4727] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4269, len:0, wptr:63152, state:play, control:0, inflight:0 [02:41:36.4746] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:63152 rptr:64847 seq:4269 inflight:0 fullness:127682 latency:862 us [02:41:36.4762] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4270, len:1400, wptr:63152, state:play, control:0, inflight:0 [02:41:36.4802] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:63152 rptr:64949 seq:4270 inflight:1400 fullness:128878 latency:1930 us [02:41:36.4811] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:36.5320] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:36.5328] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4271, len:0, wptr:63852, state:play, control:0, inflight:0 [02:41:36.5347] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:63852 rptr:65315 seq:4271 inflight:0 fullness:128146 latency:854 us [02:41:36.5361] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4272, len:1400, wptr:63852, state:play, control:0, inflight:0 [02:41:36.5401] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:63852 rptr:65318 seq:4272 inflight:1400 fullness:129540 latency:1897 us [02:41:36.5410] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:36.5918] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:36.5926] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4273, len:0, wptr:64552, state:play, control:0, inflight:0 [02:41:36.5945] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:64552 rptr:221 seq:4273 inflight:0 fullness:128662 latency:850 us [02:41:36.5954] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:36.6463] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:36.6471] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4274, len:0, wptr:64552, state:play, control:0, inflight:0 [02:41:36.6491] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:64552 rptr:593 seq:4274 inflight:0 fullness:127918 latency:878 us [02:41:36.6504] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4275, len:1400, wptr:64552, state:play, control:0, inflight:0 [02:41:36.6544] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:64552 rptr:593 seq:4275 inflight:1400 fullness:129318 latency:1869 us [02:41:36.6553] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:36.7061] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:36.7070] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4276, len:0, wptr:65252, state:play, control:0, inflight:0 [02:41:36.7089] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:65252 rptr:955 seq:4276 inflight:0 fullness:128594 latency:848 us [02:41:36.7098] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:36.7606] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:36.7614] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4277, len:0, wptr:65252, state:play, control:0, inflight:0 [02:41:36.7634] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:65252 rptr:1368 seq:4277 inflight:0 fullness:127768 latency:870 us [02:41:36.7647] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [02:41:36.7655] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4278, len:568, wptr:65252, state:play, control:0, inflight:0 [02:41:36.7683] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:65252 rptr:1415 seq:4278 inflight:568 fullness:128242 latency:1308 us [02:41:36.7697] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4279, len:1400, wptr:0, state:play, control:0, inflight:0 [02:41:36.7737] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:0 rptr:1415 seq:4279 inflight:1400 fullness:129642 latency:1908 us [02:41:36.7747] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:36.8255] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:36.8263] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4280, len:0, wptr:700, state:play, control:0, inflight:0 [02:41:36.8283] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:700 rptr:1815 seq:4280 inflight:0 fullness:128842 latency:848 us [02:41:36.8292] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:36.8800] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:36.8808] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4281, len:0, wptr:700, state:play, control:0, inflight:0 [02:41:36.8828] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:700 rptr:2232 seq:4281 inflight:0 fullness:128008 latency:872 us [02:41:36.8841] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4282, len:1400, wptr:700, state:play, control:0, inflight:0 [02:41:36.8880] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:700 rptr:2232 seq:4282 inflight:1400 fullness:129408 latency:1868 us [02:41:36.8889] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:36.9398] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:36.9406] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4283, len:0, wptr:1400, state:play, control:0, inflight:0 [02:41:36.9426] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:1400 rptr:2604 seq:4283 inflight:0 fullness:128664 latency:853 us [02:41:36.9435] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:36.9943] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:36.9951] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4284, len:0, wptr:1400, state:play, control:0, inflight:0 [02:41:36.9971] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:1400 rptr:2962 seq:4284 inflight:0 fullness:127948 latency:846 us [02:41:36.9985] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4285, len:1400, wptr:1400, state:play, control:0, inflight:0 [02:41:37.0025] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:1400 rptr:3072 seq:4285 inflight:1400 fullness:129128 latency:1912 us [02:41:37.0034] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:37.0543] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:37.0551] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4286, len:0, wptr:2100, state:play, control:0, inflight:0 [02:41:37.0571] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:2100 rptr:3435 seq:4286 inflight:0 fullness:128402 latency:867 us [02:41:37.0581] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:37.1110] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:37.1118] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4287, len:0, wptr:2100, state:play, control:0, inflight:0 [02:41:37.1141] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:2100 rptr:3799 seq:4287 inflight:0 fullness:127674 latency:1042 us [02:41:37.1154] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4288, len:1400, wptr:2100, state:play, control:0, inflight:0 [02:41:37.1193] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:2100 rptr:3883 seq:4288 inflight:1400 fullness:128906 latency:1874 us [02:41:37.1202] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:37.1711] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:37.1719] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4289, len:0, wptr:2800, state:play, control:0, inflight:0 [02:41:37.1738] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:2800 rptr:4251 seq:4289 inflight:0 fullness:128170 latency:844 us [02:41:37.1752] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4290, len:1400, wptr:2800, state:play, control:0, inflight:0 [02:41:37.1792] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:2800 rptr:4251 seq:4290 inflight:1400 fullness:129570 latency:1916 us [02:41:37.1801] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:37.2310] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk [02:41:37.2318] Slim::Networking::SliMP3::Stream::sendStreamPkt (348) 00:04:20:04:10:ed sending stream: seq:4291, len:0, wptr:3500, state:play, control:0, inflight:0 [02:41:37.2338] Slim::Networking::SliMP3::Stream::gotAck (460) 00:04:20:04:10:ed gotAck: wptr:3500 rptr:4662 seq:4291 inflight:0 fullness:128748 latency:852 us [02:41:37.2347] Slim::Networking::SliMP3::Stream::sendNextChunk (585) 00:04:20:04:10:ed- play - Buffer full, need to poll to see if there is space [02:41:37.2855] Slim::Networking::SliMP3::Stream::sendEmptyChunk (694) 00:04:20:04:10:ed sendEmptyChunk
Thanks. I'm afraid that didn't help. A copy of your server/Slim/Networking/SliMP3/Stream.pm may be helpful.
Created attachment 2744 [details] My Stream.pm
OK, the Stream.pm is in the attachments. I got to lookin' around a bit: what are the chances that permissions are goofy? I've always installed as the admin user on this machine (and made the control panel available to all users), and all ownership is admin:staff. And I think it's odd that it griped about not being able to find lame because lame is in /Bin/darwin--where I think it should be, right? I'm wondering if a complete cleanout and reinstall might be a good idea. I've always upgraded over the current installation. Recommendations?
What is your repeat-mode set to? Do you have a playlist with the radio-station in? If so, what is the next item in the playlist?
Actually, I have it saved off as a favorite, and there's only that one station in the playlist when I add it to the queue. Repeat is set to "All". But one would have to assume that if repeat could successfully restart the stream, then I should be able to manually restart the stream and/or play local music (from the library), which also doesn't work when this feature crops up.
I cannot work out what could be happening. The logging output does not time up with the code. Yes, I think that it would be a good idea to clean out the installation and start afresh. Can you do that? When would you likely be able to report on the success or otherwise of this? BTW, I also run (3) SliMP3s in daily use and have not had this kind of problem.
I stopped SC and then deleted /Library/PreferencePanes/SqueezeCenter.prefPane/, ~/Library/SlimDevices/, ~/Library/Logs/SqueezeCenter/, ~/Library/Preferences/com.slimdevices.slim.plist, and I think the only thing I missed was the stuff in Caches. I then installed 7.0 2008-01-28, started it up, had it delete everything and rescan, and have, this morning, fired up WGBH again. I just noticed that these stalls seem to occur most when the network pipe gets a little full. I have some nightly backups that kickoff over a VPN at 2am and it sucks up as much bandwidth as it can. I'll see what happens today. I've got logging turned down, for the most part, just to see if it dies. If it does, I'll crank up the logs again.
OK, no dice. Running the build mentioned previously (whose date I forget at this point), WGBH's stream has gotten stuck. Here's where I kicked off the stream this morning: [07:32:55.5939] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [07:32:56.3199] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [07:32:56.7638] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [07:32:58.7689] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [07:33:00.8257] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [07:33:02.9202] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [07:33:04.9477] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [07:33:07.8794] Slim::Player::Source::streamingSongIndex (1345) Adding song index 0 to song queue [07:33:07.8799] Slim::Player::Source::streamingSongIndex (1349) Clearing out song queue first [07:33:07.8897] Slim::Player::Source::streamingSongIndex (1366) Song queue is now 0 [07:33:07.9023] Slim::Player::Source::playmode (298) 00:04:20:04:10:ed: Switching to mode stop from play [07:33:07.9038] Slim::Player::Source::playmode (397) 00:04:20:04:10:ed New play mode: stop [07:33:07.9082] Slim::Player::Source::playmode (440) 00:04:20:04:10:ed: Stopping and clearing out old chunks [07:33:07.9448] Slim::Networking::SliMP3::Stream::stop (193) 00:04:20:04:10:ed stream stop [07:33:07.9702] Slim::Formats::RemoteStream::DESTROY (318) Slim::Player::Protocols::HTTP - in DESTROY [07:33:07.9708] Slim::Formats::RemoteStream::DESTROY (319) Slim::Player::Protocols::HTTP About to close socket to: [http://scfire-nyk-aa03.stream.aol.com/stream/1006] [07:33:07.9715] Slim::Player::Source::resetSong (1590) Resetting song buffer. [07:33:07.9759] Slim::Player::Source::resetSongQueue (1419) Resetting song queue [07:33:07.9765] Slim::Player::Source::resetSongQueue (1429) Song queue is now 0 [07:33:08.0073] Slim::Player::Source::playmode (551) 00:04:20:04:10:ed: Current playmode: stop [07:33:08.7308] Slim::Player::Source::playmode (298) 00:04:20:04:10:ed: Switching to mode stop from stop [07:33:08.7313] Slim::Player::Source::playmode (305) Already in playmode stop : ignoring mode change [07:33:08.7321] Slim::Player::Source::streamingSongIndex (1345) Adding song index 0 to song queue [07:33:08.7326] Slim::Player::Source::streamingSongIndex (1349) Clearing out song queue first [07:33:08.7331] Slim::Player::Source::streamingSongIndex (1366) Song queue is now 0 [07:33:09.4570] Slim::Utils::Misc::msg (1235) Warning: [07:33:09.4467] Use of uninitialized value in concatenation (.) or string at /Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Player/Source.pm line 192. [07:33:09.4576] Slim::Player::Source::songTime (191) songtime=0 from byterate=0, duration=, bytesReceived=0, fullness=104112, rate=1, startStream=0 [07:33:10.9577] Slim::Player::Source::playmode (298) 00:04:20:04:10:ed: Switching to mode stop from stop [07:33:10.9585] Slim::Player::Source::playmode (305) Already in playmode stop : ignoring mode change [07:33:10.9592] Slim::Player::Source::streamingSongIndex (1345) Adding song index 0 to song queue [07:33:10.9597] Slim::Player::Source::streamingSongIndex (1349) Clearing out song queue first [07:33:10.9605] Slim::Player::Source::streamingSongIndex (1366) Song queue is now 0 [07:33:10.9616] Slim::Player::Source::playmode (298) 00:04:20:04:10:ed: Switching to mode play from stop [07:33:10.9733] Slim::Player::Source::resetSong (1590) Resetting song buffer. [07:33:10.9971] Slim::Player::Source::openSong (1685) Trying to open: http://64.71.145.107:8004/ [07:33:10.9983] Slim::Player::Source::openSong (1699) URL is remote (no direct streaming) [http://64.71.145.107:8004/] [07:33:10.9990] Slim::Player::ProtocolHandlers::openRemoteStream (66) Trying to open protocol stream for http://64.71.145.107:8004/ [07:33:10.9996] Slim::Player::ProtocolHandlers::openRemoteStream (70) Found handler for http://64.71.145.107:8004/ - using Slim::Player::Protocols::HTTP [07:33:11.0055] Slim::Formats::RemoteStream::open (60) Opening connection to http://64.71.145.107:8004/: [64.71.145.107 on port 8004 with path / with timeout 5] [07:33:11.1043] Slim::Formats::RemoteStream::request (136) Request: GET / HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: 64.71.145.107:8004 User-Agent: iTunes/4.7.1 (Darwin; N; Mac OS X Server 10.5.1 (9B18); ppc; EN; utf8) SqueezeCenter/7.0/16819 Icy-Metadata: 1 [07:33:11.2230] Slim::Formats::RemoteStream::request (143) Response: ICY 200 OK [07:33:11.2271] Slim::Formats::HTTP::parseHeaders (151) Header: icy-notice1:<BR>This stream requires <a href="http://www.winamp.com/">Winamp</a><BR> [07:33:11.2278] Slim::Formats::HTTP::parseHeaders (151) Header: icy-notice2:SHOUTcast Distributed Network Audio Server/Linux v1.9.7<BR> [07:33:11.2284] Slim::Formats::HTTP::parseHeaders (151) Header: icy-name:All-Classical WGBH 89.7-HD2 [07:33:11.2291] Slim::Formats::HTTP::parseHeaders (151) Header: icy-genre:Classical [07:33:11.2297] Slim::Formats::HTTP::parseHeaders (151) Header: icy-url:http://www.wgbh.org/classical [07:33:11.2302] Slim::Formats::HTTP::parseHeaders (151) Header: content-type:audio/mpeg [07:33:11.2339] Slim::Formats::HTTP::parseHeaders (151) Header: icy-pub:1 [07:33:11.2345] Slim::Formats::HTTP::parseHeaders (151) Header: icy-metaint:32768 [07:33:11.2351] Slim::Formats::HTTP::parseHeaders (151) Header: icy-br:112 [07:33:11.2429] Slim::Formats::HTTP::parseHeaders (173) Bitrate for http://64.71.145.107:8004/ set to 112000 [07:33:11.2491] Slim::Formats::RemoteStream::request (202) Opened stream! [07:33:11.2498] Slim::Player::Source::openSong (1711) remoteURL is a song (audio): http://64.71.145.107:8004/ [07:33:11.2574] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [07:33:11.2597] Slim::Player::TranscodingHelper::getConvertCommand (201) undermax = 1, type = mp3, slimp3 = 00:04:20:04:10:ed [07:33:11.2608] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-slimp3-00:04:20:04:10:ed [07:33:11.2614] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-*-00:04:20:04:10:ed [07:33:11.2619] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-slimp3-* [07:33:11.2624] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-*-* [07:33:11.2755] Slim::Player::TranscodingHelper::enabledFormat (105) Checking to see if mp3-mp3-*-* is enabled [07:33:11.2761] Slim::Player::TranscodingHelper::enabledFormat (113) There are 7 disabled formats... [07:33:11.2767] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-aif-*-* vs mp3-mp3-*-* [07:33:11.2773] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-flc-*-* vs mp3-mp3-*-* [07:33:11.2778] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-mp3-*-* vs mp3-mp3-*-* [07:33:11.2783] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-wav-*-* vs mp3-mp3-*-* [07:33:11.2788] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-flc-*-* vs mp3-mp3-*-* [07:33:11.2793] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-mp3-*-* vs mp3-mp3-*-* [07:33:11.2798] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-wav-*-* vs mp3-mp3-*-* [07:33:11.2805] Slim::Player::TranscodingHelper::checkBin (146) enabled [07:33:11.2810] Slim::Player::TranscodingHelper::checkBin (148) Found command: - [07:33:11.2818] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [07:33:11.2836] Slim::Player::TranscodingHelper::getConvertCommand (302) Matched Format: mp3 Type: mp3 Command: - [07:33:11.2842] Slim::Player::Source::openSong (1737) remoteURL command - type mp3 format mp3 [07:33:11.2847] Slim::Player::Source::openSong (1738) remoteURL stream format : mp3 [07:33:11.3363] Slim::Player::Source::playmode (397) 00:04:20:04:10:ed New play mode: play [07:33:11.4518] Slim::Networking::SliMP3::Stream::newStream (91) 00:04:20:04:10:ed new stream: [07:33:11.4532] Slim::Player::Source::readNextChunk (2166) Sending 0 bytes of silence. [07:33:11.4549] Slim::Player::Source::playmode (551) 00:04:20:04:10:ed: Current playmode: play [07:33:11.6366] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 32 [07:33:11.6372] Slim::Player::Protocols::HTTP::readMetaData (105) Metadata: StreamTitle='';StreamUrl='';^@^@^@^@ [07:33:11.9209] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [07:33:11.9294] Slim::Networking::SliMP3::Stream::sendNextChunk (659) 00:04:20:04:10:ed Buffer full, starting playback [07:33:12.1509] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [07:33:12.4019] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [07:33:14.2274] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [07:33:16.5795] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [07:33:18.9250] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [07:33:21.2643] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [07:33:23.6079] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [07:33:25.9572] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [07:33:28.2508] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [07:33:30.5923] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [07:33:32.9403] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [07:33:35.2880] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 And here's where I see it quit: [13:32:47.6192] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [13:32:49.9112] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [13:32:52.2154] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [13:32:54.6397] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [13:32:56.9519] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [13:32:59.1632] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [13:33:01.6154] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [13:33:03.9569] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [13:33:06.3931] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [13:33:08.6164] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [13:33:10.9521] Slim::Player::Protocols::HTTP::readMetaData (77) Metadata size: 0 [13:33:12.2173] Slim::Player::Source::readNextChunk (2315) Read to end of file or pipe [13:33:12.4088] Slim::Utils::Misc::msg (1235) Warning: [13:33:12.3346] Use of uninitialized value in concatenation (.) or string at /Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Player/Source.pm line 2339. [13:33:12.4096] Slim::Utils::Misc::msg (1235) Warning: [13:33:12.4091] Use of uninitialized value in concatenation (.) or string at /Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Player/Source.pm line 2339. [13:33:12.4101] Slim::Player::Source::readNextChunk (2338) end of file or error on socket, opening next song, (song pos: 301682021(tell says: . ), totalbytes: ) [13:33:12.4418] Slim::Player::Source::nextsong (1551) The next song is number 0, was 0 [13:33:12.4543] Slim::Player::Source::gotoNext (1201) Opening next song... [13:33:12.6157] Slim::Formats::RemoteStream::DESTROY (318) Slim::Player::Protocols::HTTP - in DESTROY [13:33:12.6162] Slim::Formats::RemoteStream::DESTROY (319) Slim::Player::Protocols::HTTP About to close socket to: [http://64.71.145.107:8004/] [13:33:12.6257] Slim::Player::Source::nextsong (1551) The next song is number 0, was 0 [13:33:12.9144] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [13:33:13.0134] Slim::Player::TranscodingHelper::getConvertCommand (201) undermax = 1, type = mp3, slimp3 = 00:04:20:04:10:ed [13:33:13.0145] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-slimp3-00:04:20:04:10:ed [13:33:13.0151] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-*-00:04:20:04:10:ed [13:33:13.0155] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-slimp3-* [13:33:13.0159] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-*-* [13:33:13.0519] Slim::Player::TranscodingHelper::enabledFormat (105) Checking to see if mp3-mp3-*-* is enabled [13:33:13.0525] Slim::Player::TranscodingHelper::enabledFormat (113) There are 7 disabled formats... [13:33:13.0691] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-aif-*-* vs mp3-mp3-*-* [13:33:13.0697] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-flc-*-* vs mp3-mp3-*-* [13:33:13.0701] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-mp3-*-* vs mp3-mp3-*-* [13:33:13.0705] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-wav-*-* vs mp3-mp3-*-* [13:33:13.0712] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-flc-*-* vs mp3-mp3-*-* [13:33:13.0717] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-mp3-*-* vs mp3-mp3-*-* [13:33:13.0721] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-wav-*-* vs mp3-mp3-*-* [13:33:13.0726] Slim::Player::TranscodingHelper::checkBin (146) enabled [13:33:13.0730] Slim::Player::TranscodingHelper::checkBin (148) Found command: - [13:33:13.0737] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [13:33:13.0754] Slim::Player::TranscodingHelper::getConvertCommand (302) Matched Format: mp3 Type: mp3 Command: - [13:33:13.1115] Slim::Player::Source::gotoNext (1288) opening next song (old format: mp3, new: mp3) current playmode: play [13:33:13.1121] Slim::Player::Source::streamingSongIndex (1345) Adding song index 0 to song queue [13:33:13.1126] Slim::Player::Source::streamingSongIndex (1349) Clearing out song queue first [13:33:13.1131] Slim::Player::Source::streamingSongIndex (1366) Song queue is now 0 [13:33:13.1137] Slim::Player::Source::resetSong (1590) Resetting song buffer. [13:33:13.1404] Slim::Player::Source::openSong (1685) Trying to open: http://64.71.145.107:8004/ [13:33:13.1416] Slim::Player::Source::openSong (1699) URL is remote (no direct streaming) [http://64.71.145.107:8004/] [13:33:13.1423] Slim::Player::ProtocolHandlers::openRemoteStream (66) Trying to open protocol stream for http://64.71.145.107:8004/ [13:33:13.1428] Slim::Player::ProtocolHandlers::openRemoteStream (70) Found handler for http://64.71.145.107:8004/ - using Slim::Player::Protocols::HTTP [13:33:13.1778] Slim::Formats::RemoteStream::open (60) Opening connection to http://64.71.145.107:8004/: [64.71.145.107 on port 8004 with path / with timeout 5] [13:33:13.6960] Slim::Formats::RemoteStream::request (136) Request: GET / HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: 64.71.145.107:8004 User-Agent: iTunes/4.7.1 (Darwin; N; Mac OS X Server 10.5.1 (9B18); ppc; EN; utf8) SqueezeCenter/7.0/16819 Icy-Metadata: 1 [13:33:17.5317] Slim::Formats::RemoteStream::request (143) Response: ICY 200 OK [13:33:17.6153] Slim::Formats::HTTP::parseHeaders (151) Header: icy-notice1:<BR>This stream requires <a href="http://www.winamp.com/">Winamp</a><BR> [13:33:17.6211] Slim::Formats::HTTP::parseHeaders (151) Header: icy-notice2:SHOUTcast Distributed Network Audio Server/Linux v1.9.7<BR> [13:33:17.6217] Slim::Formats::HTTP::parseHeaders (151) Header: icy-name:All-Classical WGBH 89.7-HD2 [13:33:17.6496] Slim::Formats::HTTP::parseHeaders (151) Header: icy-genre:Classical [13:33:17.6501] Slim::Formats::HTTP::parseHeaders (151) Header: icy-url:http://www.wgbh.org/classical [13:33:17.6506] Slim::Formats::HTTP::parseHeaders (151) Header: content-type:audio/mpeg [13:33:18.0962] Slim::Formats::HTTP::parseHeaders (151) Header: icy-pub:1 [13:33:18.0968] Slim::Formats::HTTP::parseHeaders (151) Header: icy-metaint:32768 [13:33:18.0973] Slim::Formats::HTTP::parseHeaders (151) Header: icy-br:112 [13:33:19.2185] Slim::Formats::HTTP::parseHeaders (173) Bitrate for http://64.71.145.107:8004/ set to 112000 [13:33:19.2245] Slim::Formats::RemoteStream::request (202) Opened stream! [13:33:19.2253] Slim::Player::Source::openSong (1711) remoteURL is a song (audio): http://64.71.145.107:8004/ [13:33:19.2424] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [13:33:19.2442] Slim::Player::TranscodingHelper::getConvertCommand (201) undermax = 1, type = mp3, slimp3 = 00:04:20:04:10:ed [13:33:19.2448] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-slimp3-00:04:20:04:10:ed [13:33:19.2453] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-*-00:04:20:04:10:ed [13:33:19.2458] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-slimp3-* [13:33:19.2465] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-*-* [13:33:19.2471] Slim::Player::TranscodingHelper::enabledFormat (105) Checking to see if mp3-mp3-*-* is enabled [13:33:19.2477] Slim::Player::TranscodingHelper::enabledFormat (113) There are 7 disabled formats... [13:33:19.2483] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-aif-*-* vs mp3-mp3-*-* [13:33:19.2487] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-flc-*-* vs mp3-mp3-*-* [13:33:19.2492] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-mp3-*-* vs mp3-mp3-*-* [13:33:19.2496] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-wav-*-* vs mp3-mp3-*-* [13:33:19.2501] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-flc-*-* vs mp3-mp3-*-* [13:33:19.2506] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-mp3-*-* vs mp3-mp3-*-* [13:33:19.2510] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-wav-*-* vs mp3-mp3-*-* [13:33:19.2516] Slim::Player::TranscodingHelper::checkBin (146) enabled [13:33:19.2522] Slim::Player::TranscodingHelper::checkBin (148) Found command: - [13:33:19.2529] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [13:33:19.2546] Slim::Player::TranscodingHelper::getConvertCommand (302) Matched Format: mp3 Type: mp3 Command: - [13:33:19.2611] Slim::Player::Source::openSong (1737) remoteURL command - type mp3 format mp3 [13:33:19.2703] Slim::Player::Source::openSong (1738) remoteURL stream format : mp3 [16:34:30.8524] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [16:34:41.5833] Slim::Player::Source::playmode (298) 00:04:20:04:10:ed: Switching to mode stop from play [16:34:41.5848] Slim::Player::Source::playmode (397) 00:04:20:04:10:ed New play mode: stop [16:34:41.5933] Slim::Player::Source::playmode (440) 00:04:20:04:10:ed: Stopping and clearing out old chunks [16:34:41.6673] Slim::Networking::SliMP3::Stream::stop (193) 00:04:20:04:10:ed stream stop [16:34:41.6941] Slim::Formats::RemoteStream::DESTROY (318) Slim::Player::Protocols::HTTP - in DESTROY [16:34:41.6947] Slim::Formats::RemoteStream::DESTROY (319) Slim::Player::Protocols::HTTP About to close socket to: [http://64.71.145.107:8004/] [16:34:41.6953] Slim::Player::Source::resetSong (1590) Resetting song buffer. [16:34:41.6963] Slim::Player::Source::resetSongQueue (1419) Resetting song queue [16:34:41.6972] Slim::Player::Source::resetSongQueue (1429) Song queue is now 0 [16:34:41.7622] Slim::Player::Source::playmode (551) 00:04:20:04:10:ed: Current playmode: stop [16:34:42.1031] Slim::Utils::Misc::msg (1235) Warning: [16:34:42.0909] Use of uninitialized value in concatenation (.) or string at /Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Player/Source.pm line 192. [16:34:42.1037] Slim::Player::Source::songTime (191) songtime=0 from byterate=0, duration=, bytesReceived=0, fullness=0, rate=1, startStream=0 [16:34:42.4057] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [16:34:44.5447] Slim::Player::Source::playmode (298) 00:04:20:04:10:ed: Switching to mode play from stop [16:34:44.5502] Slim::Player::Source::resetSong (1590) Resetting song buffer. [16:34:44.5521] Slim::Player::Source::openSong (1685) Trying to open: http://64.71.145.107:8004/ [16:34:44.5527] Slim::Player::Source::openSong (1699) URL is remote (no direct streaming) [http://64.71.145.107:8004/] [16:34:44.5533] Slim::Player::ProtocolHandlers::openRemoteStream (66) Trying to open protocol stream for http://64.71.145.107:8004/ [16:34:44.5538] Slim::Player::ProtocolHandlers::openRemoteStream (70) Found handler for http://64.71.145.107:8004/ - using Slim::Player::Protocols::HTTP [16:34:44.5599] Slim::Formats::RemoteStream::open (60) Opening connection to http://64.71.145.107:8004/: [64.71.145.107 on port 8004 with path / with timeout 5] [16:34:44.6968] Slim::Formats::RemoteStream::request (136) Request: GET / HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: 64.71.145.107:8004 User-Agent: iTunes/4.7.1 (Darwin; N; Mac OS X Server 10.5.1 (9B18); ppc; EN; utf8) SqueezeCenter/7.0/16819 Icy-Metadata: 1 [16:34:44.7985] Slim::Formats::RemoteStream::request (143) Response: ICY 200 OK [16:34:44.8027] Slim::Formats::HTTP::parseHeaders (151) Header: icy-notice1:<BR>This stream requires <a href="http://www.winamp.com/">Winamp</a><BR> [16:34:44.8037] Slim::Formats::HTTP::parseHeaders (151) Header: icy-notice2:SHOUTcast Distributed Network Audio Server/Linux v1.9.7<BR> [16:34:44.8044] Slim::Formats::HTTP::parseHeaders (151) Header: icy-name:All-Classical WGBH 89.7-HD2 [16:34:44.8050] Slim::Formats::HTTP::parseHeaders (151) Header: icy-genre:Classical [16:34:44.8056] Slim::Formats::HTTP::parseHeaders (151) Header: icy-url:http://www.wgbh.org/classical [16:34:44.8061] Slim::Formats::HTTP::parseHeaders (151) Header: content-type:audio/mpeg [16:34:44.8521] Slim::Formats::HTTP::parseHeaders (151) Header: icy-pub:1 [16:34:44.8527] Slim::Formats::HTTP::parseHeaders (151) Header: icy-metaint:32768 [16:34:44.8532] Slim::Formats::HTTP::parseHeaders (151) Header: icy-br:112 [16:34:44.8610] Slim::Formats::HTTP::parseHeaders (173) Bitrate for http://64.71.145.107:8004/ set to 112000 [16:34:44.8666] Slim::Formats::RemoteStream::request (202) Opened stream! [16:34:44.8774] Slim::Player::Source::openSong (1711) remoteURL is a song (audio): http://64.71.145.107:8004/ [16:34:44.8789] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [16:34:44.8806] Slim::Player::TranscodingHelper::getConvertCommand (201) undermax = 1, type = mp3, slimp3 = 00:04:20:04:10:ed [16:34:44.8812] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-slimp3-00:04:20:04:10:ed [16:34:44.8817] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-*-00:04:20:04:10:ed [16:34:44.8822] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-slimp3-* [16:34:44.8826] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-*-* [16:34:44.8831] Slim::Player::TranscodingHelper::enabledFormat (105) Checking to see if mp3-mp3-*-* is enabled [16:34:44.8835] Slim::Player::TranscodingHelper::enabledFormat (113) There are 7 disabled formats... [16:34:44.8840] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-aif-*-* vs mp3-mp3-*-* [16:34:44.8844] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-flc-*-* vs mp3-mp3-*-* [16:34:44.8849] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-mp3-*-* vs mp3-mp3-*-* [16:34:44.8853] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-wav-*-* vs mp3-mp3-*-* [16:34:44.8857] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-flc-*-* vs mp3-mp3-*-* [16:34:44.8861] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-mp3-*-* vs mp3-mp3-*-* [16:34:44.8866] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-wav-*-* vs mp3-mp3-*-* [16:34:44.8870] Slim::Player::TranscodingHelper::checkBin (146) enabled [16:34:44.8875] Slim::Player::TranscodingHelper::checkBin (148) Found command: - [16:34:44.8881] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [16:34:44.8898] Slim::Player::TranscodingHelper::getConvertCommand (302) Matched Format: mp3 Type: mp3 Command: - [16:34:44.8904] Slim::Player::Source::openSong (1737) remoteURL command - type mp3 format mp3 [16:34:44.8909] Slim::Player::Source::openSong (1738) remoteURL stream format : mp3 [16:34:44.9544] Slim::Player::Source::playmode (397) 00:04:20:04:10:ed New play mode: play [16:34:45.1233] Slim::Networking::SliMP3::Stream::newStream (91) 00:04:20:04:10:ed new stream: [16:34:45.1249] Slim::Player::Source::playmode (551) 00:04:20:04:10:ed: Current playmode: play [16:34:45.2132] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [16:34:50.7390] Slim::Player::Source::playmode (298) 00:04:20:04:10:ed: Switching to mode stop from play [16:34:50.7400] Slim::Player::Source::playmode (397) 00:04:20:04:10:ed New play mode: stop [16:34:50.7406] Slim::Player::Source::playmode (440) 00:04:20:04:10:ed: Stopping and clearing out old chunks [16:34:50.7413] Slim::Networking::SliMP3::Stream::stop (193) 00:04:20:04:10:ed stream stop [16:34:50.7430] Slim::Formats::RemoteStream::DESTROY (318) Slim::Player::Protocols::HTTP - in DESTROY [16:34:50.7436] Slim::Formats::RemoteStream::DESTROY (319) Slim::Player::Protocols::HTTP About to close socket to: [http://64.71.145.107:8004/] [16:34:50.8076] Slim::Player::Source::resetSong (1590) Resetting song buffer. [16:34:50.8082] Slim::Player::Source::resetSongQueue (1419) Resetting song queue [16:34:50.8088] Slim::Player::Source::resetSongQueue (1429) Song queue is now 0 [16:34:50.8099] Slim::Player::Source::playmode (551) 00:04:20:04:10:ed: Current playmode: stop [16:34:50.8221] Slim::Utils::Misc::msg (1235) Warning: [16:34:50.8215] Use of uninitialized value in concatenation (.) or string at /Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Player/Source.pm line 192. [16:34:50.8227] Slim::Player::Source::songTime (191) songtime=0 from byterate=0, duration=, bytesReceived=0, fullness=0, rate=1, startStream=0 [16:34:50.8522] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [16:34:53.6267] Slim::Player::Source::playmode (298) 00:04:20:04:10:ed: Switching to mode play from stop [16:34:53.6277] Slim::Player::Source::resetSong (1590) Resetting song buffer. [16:34:53.6295] Slim::Player::Source::openSong (1685) Trying to open: http://64.71.145.107:8004/ [16:34:53.6301] Slim::Player::Source::openSong (1699) URL is remote (no direct streaming) [http://64.71.145.107:8004/] [16:34:53.6307] Slim::Player::ProtocolHandlers::openRemoteStream (66) Trying to open protocol stream for http://64.71.145.107:8004/ [16:34:53.6312] Slim::Player::ProtocolHandlers::openRemoteStream (70) Found handler for http://64.71.145.107:8004/ - using Slim::Player::Protocols::HTTP [16:34:53.6320] Slim::Formats::RemoteStream::open (60) Opening connection to http://64.71.145.107:8004/: [64.71.145.107 on port 8004 with path / with timeout 5] [16:34:53.7303] Slim::Formats::RemoteStream::request (136) Request: GET / HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: 64.71.145.107:8004 User-Agent: iTunes/4.7.1 (Darwin; N; Mac OS X Server 10.5.1 (9B18); ppc; EN; utf8) SqueezeCenter/7.0/16819 Icy-Metadata: 1 [16:34:53.8414] Slim::Formats::RemoteStream::request (143) Response: ICY 200 OK [16:34:53.8452] Slim::Formats::HTTP::parseHeaders (151) Header: icy-notice1:<BR>This stream requires <a href="http://www.winamp.com/">Winamp</a><BR> [16:34:53.8458] Slim::Formats::HTTP::parseHeaders (151) Header: icy-notice2:SHOUTcast Distributed Network Audio Server/Linux v1.9.7<BR> [16:34:53.8463] Slim::Formats::HTTP::parseHeaders (151) Header: icy-name:All-Classical WGBH 89.7-HD2 [16:34:53.8469] Slim::Formats::HTTP::parseHeaders (151) Header: icy-genre:Classical [16:34:53.8474] Slim::Formats::HTTP::parseHeaders (151) Header: icy-url:http://www.wgbh.org/classical [16:34:53.8479] Slim::Formats::HTTP::parseHeaders (151) Header: content-type:audio/mpeg [16:34:53.8514] Slim::Formats::HTTP::parseHeaders (151) Header: icy-pub:1 [16:34:53.8520] Slim::Formats::HTTP::parseHeaders (151) Header: icy-metaint:32768 [16:34:53.8525] Slim::Formats::HTTP::parseHeaders (151) Header: icy-br:112 [16:34:53.8603] Slim::Formats::HTTP::parseHeaders (173) Bitrate for http://64.71.145.107:8004/ set to 112000 [16:34:53.8659] Slim::Formats::RemoteStream::request (202) Opened stream! [16:34:53.8665] Slim::Player::Source::openSong (1711) remoteURL is a song (audio): http://64.71.145.107:8004/ [16:34:53.8676] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [16:34:53.8693] Slim::Player::TranscodingHelper::getConvertCommand (201) undermax = 1, type = mp3, slimp3 = 00:04:20:04:10:ed [16:34:53.8699] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-slimp3-00:04:20:04:10:ed [16:34:53.8704] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-*-00:04:20:04:10:ed [16:34:53.8708] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-slimp3-* [16:34:53.8713] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mp3-mp3-*-* [16:34:53.8719] Slim::Player::TranscodingHelper::enabledFormat (105) Checking to see if mp3-mp3-*-* is enabled [16:34:53.8724] Slim::Player::TranscodingHelper::enabledFormat (113) There are 7 disabled formats... [16:34:53.8729] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-aif-*-* vs mp3-mp3-*-* [16:34:53.9009] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-flc-*-* vs mp3-mp3-*-* [16:34:53.9015] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-mp3-*-* vs mp3-mp3-*-* [16:34:53.9019] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-wav-*-* vs mp3-mp3-*-* [16:34:53.9024] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-flc-*-* vs mp3-mp3-*-* [16:34:53.9029] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-mp3-*-* vs mp3-mp3-*-* [16:34:53.9033] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-wav-*-* vs mp3-mp3-*-* [16:34:53.9038] Slim::Player::TranscodingHelper::checkBin (146) enabled [16:34:53.9042] Slim::Player::TranscodingHelper::checkBin (148) Found command: - [16:34:53.9049] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [16:34:53.9066] Slim::Player::TranscodingHelper::getConvertCommand (302) Matched Format: mp3 Type: mp3 Command: - [16:34:53.9072] Slim::Player::Source::openSong (1737) remoteURL command - type mp3 format mp3 [16:34:53.9077] Slim::Player::Source::openSong (1738) remoteURL stream format : mp3 [16:34:53.9254] Slim::Player::Source::playmode (397) 00:04:20:04:10:ed New play mode: play [16:34:53.9783] Slim::Networking::SliMP3::Stream::newStream (91) 00:04:20:04:10:ed new stream: [16:34:53.9799] Slim::Player::Source::playmode (551) 00:04:20:04:10:ed: Current playmode: play [16:34:54.0226] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [16:35:24.3176] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [16:35:47.3329] Slim::Player::Source::playmode (298) 00:04:20:04:10:ed: Switching to mode stop from play [16:35:47.3339] Slim::Player::Source::playmode (397) 00:04:20:04:10:ed New play mode: stop [16:35:47.3345] Slim::Player::Source::playmode (440) 00:04:20:04:10:ed: Stopping and clearing out old chunks [16:35:47.3351] Slim::Networking::SliMP3::Stream::stop (193) 00:04:20:04:10:ed stream stop [16:35:47.3370] Slim::Formats::RemoteStream::DESTROY (318) Slim::Player::Protocols::HTTP - in DESTROY [16:35:47.3375] Slim::Formats::RemoteStream::DESTROY (319) Slim::Player::Protocols::HTTP About to close socket to: [http://64.71.145.107:8004/] [16:35:47.3388] Slim::Player::Source::resetSong (1590) Resetting song buffer. [16:35:47.3405] Slim::Player::Source::resetSongQueue (1419) Resetting song queue [16:35:47.3419] Slim::Player::Source::resetSongQueue (1429) Song queue is now 0 [16:35:47.3441] Slim::Player::Source::playmode (551) 00:04:20:04:10:ed: Current playmode: stop [16:35:47.3729] Slim::Player::Source::streamingSongIndex (1345) Adding song index 0 to song queue [16:35:47.3736] Slim::Player::Source::streamingSongIndex (1349) Clearing out song queue first [16:35:47.3741] Slim::Player::Source::streamingSongIndex (1366) Song queue is now 0 [16:35:47.9810] Slim::Player::Source::playmode (298) 00:04:20:04:10:ed: Switching to mode stop from stop [16:35:47.9817] Slim::Player::Source::playmode (305) Already in playmode stop : ignoring mode change [16:35:47.9978] Slim::Player::Source::jumpto (1157) Jumping to 0 [16:35:47.9984] Slim::Player::Source::streamingSongIndex (1345) Adding song index 0 to song queue [16:35:47.9989] Slim::Player::Source::streamingSongIndex (1349) Clearing out song queue first [16:35:47.9994] Slim::Player::Source::streamingSongIndex (1366) Song queue is now 0 [16:35:48.0003] Slim::Player::Source::playmode (298) 00:04:20:04:10:ed: Switching to mode play from stop [16:35:48.0013] Slim::Player::Source::resetSong (1590) Resetting song buffer. [16:35:48.2295] Slim::Player::Source::openSong (1685) Trying to open: file:///Volumes/Sound%20Master/Music/iTunes/iTunes%20Music/Billy%20Joel/12%20Gardens%20Live%20[Disc%201]/1-01%20Angry%20Young%20Man.m4a [16:35:48.2763] Slim::Player::Source::openSong (1863) duration: [320] size: [5115998] endian [] offset: [0] for file:///Volumes/Sound%20Master/Music/iTunes/iTunes%20Music/Billy%20Joel/12%20Gardens%20Live%20[Disc%201]/1-01%20Angry %20Young%20Man.m4a [16:35:48.2853] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [16:35:48.2867] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [16:35:48.2876] Slim::Player::TranscodingHelper::getConvertCommand (201) undermax = 0, type = mov, slimp3 = 00:04:20:04:10:ed [16:35:48.2884] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mov-mp3-slimp3-00:04:20:04:10:ed [16:35:48.2889] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mov-mp3-*-00:04:20:04:10:ed [16:35:48.2893] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mov-mp3-slimp3-* [16:35:48.2898] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: mov-mp3-*-* [16:35:48.2902] Slim::Player::TranscodingHelper::enabledFormat (105) Checking to see if mov-mp3-*-* is enabled [16:35:48.2907] Slim::Player::TranscodingHelper::enabledFormat (113) There are 7 disabled formats... [16:35:48.2911] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-aif-*-* vs mov-mp3-*-* [16:35:48.2915] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-flc-*-* vs mov-mp3-*-* [16:35:48.2919] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-mp3-*-* vs mov-mp3-*-* [16:35:48.2923] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-wav-*-* vs mov-mp3-*-* [16:35:48.2928] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-flc-*-* vs mov-mp3-*-* [16:35:48.2932] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-mp3-*-* vs mov-mp3-*-* [16:35:48.2936] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-wav-*-* vs mov-mp3-*-* [16:35:48.2940] Slim::Player::TranscodingHelper::checkBin (146) enabled [16:35:48.2944] Slim::Player::TranscodingHelper::checkBin (148) Found command: [mov123] $FILE$ | [lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ -r - - [16:35:48.2955] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [16:35:48.2974] Slim::Player::TranscodingHelper::getConvertCommand (302) Matched Format: mp3 Type: mov Command: [mov123] $FILE$ | [lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ -r - - [16:35:48.2980] Slim::Player::Source::openSong (1892) This is an mov file: file:///Volumes/Sound%20Master/Music/iTunes/iTunes%20Music/Billy%20Joel/12%20Gardens%20Live%20[Disc%201]/1-01%20Angry%20Young%20Man.m4a [16:35:48.2985] Slim::Player::Source::openSong (1893) file type: mov format: mp3 inrate: 125 maxRate: 320 [16:35:48.2990] Slim::Player::Source::openSong (1894) command: [mov123] $FILE$ | [lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ -r - - [16:35:48.3207] Slim::Player::TranscodingHelper::tokenizeConvertCommand (360) Using command for conversion: "/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Bin/darwin/mov123" "/Volumes/Sound Master/Music/iTunes/i Tunes Music/Billy Joel/12 Gardens Live [Disc 1]/1-01 Angry Young Man.m4a" | "/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Bin/darwin/lame" --resample 44100 --silent -q 6 --abr 320 -r - - & | [16:35:48.4571] Slim::Player::Source::openSong (2056) Streaming with format: mp3 [16:35:49.5336] Slim::Player::Source::playmode (397) 00:04:20:04:10:ed New play mode: play [16:35:49.5885] Slim::Networking::SliMP3::Stream::newStream (91) 00:04:20:04:10:ed new stream: [16:35:49.5915] Slim::Player::Source::playmode (551) 00:04:20:04:10:ed: Current playmode: play [16:35:51.0198] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [16:35:51.0211] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [16:36:23.9202] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [16:36:23.9214] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 [16:36:54.0948] Slim::Utils::Prefs::maxRate (569) Setting maxBitRate for SLIMP3 to: 320 shr-g5:~ admin$ As you can see, I tried restarting the stream a couple of times, but I got no audio. So I tried playing a local file, and it, too, produced no audio. I looked at my IPcop traffic graphs and, yes, there's an increase in the traffic at about 1pm, but I can't tell that it looks out of the ordinary. The stream in question is: http://streams.wgbh.org/classical.pls in case you'd like to stream it for a while. BTW, I wonder why "source.pm" seems to gripe at the same time the stream seems to stall out? I'm running only one SliMP3 (must be nice to have several more around :) and the server is running MacOS X Server 10.5.1 on a dual G5, 2GHz/1GB box.
Created attachment 2793 [details] patch
Ok, found the problem. A sort of race condition where an async callback can get invoked synchronously. Andy, please review the patch.
Andy, it's ok. I though that the new commit rules were in place but they do not start until RC is built. I'll do the commit.
Change 17077
Amazing work, Alan.
WOW. OK, the question now is, Now what? Do I need a nightly or should I apply the patch? (I think it's so cool that I got to participate in finding a... feature!)
As you wish. It will be in tomorrow's nightly, or you can apply the patch if you are comfortable with that. And no, it was not a feature, it was good old bug, or rather a new one.
Welllll... 24 hours of running WGBH without a hiccup! Nicely done! Now... to search out and find other features. :) Thanks a zillion, Bill
This bug is being closed since it was resolved for a version which is now released! Please download the new version of SqueezeCenter (formerly SlimServer) at http://www.slimdevices.com/su_downloads.html If you are still seeing this bug, please re-open it and we will consider it for a future release.