[08-08-11 00:35:59.4633] Slim::Player::Squeezebox2::directHeaders (290) Processing headers for direct streaming: HTTP/1.0 200 OK Server: Apache/2.0.58 Accept-Ranges: bytes Content-Length: 4941836 Content-Type: text/plain Age: 982 Date: Mon, 11 Aug 2008 04:36:04 GMT Last-Modified: Thu, 10 Jul 2008 14:01:25 GMT Connection: close [08-08-11 00:35:59.4660] Slim::Player::Squeezebox2::directHeaders (349) Processing 8 headers [08-08-11 00:35:59.4667] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (61) RhapsodyDirect header: Server: Apache/2.0.58 [08-08-11 00:35:59.4673] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (61) RhapsodyDirect header: Accept-Ranges: bytes [08-08-11 00:35:59.4678] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (61) RhapsodyDirect header: Content-Length: 4941836 [08-08-11 00:35:59.5521] Slim::Player::Squeezebox2::directHeaders (407) Got a stream type: mp3 bitrate: 192000 title: [08-08-11 00:35:59.5530] Slim::Player::Squeezebox2::directHeaders (459) Beginning direct stream! [08-08-11 00:35:59.6067] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00720.841: Info: slim_tcpdata.c[356]: Sending HTTP response to server [08-08-11 00:35:59.6078] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00720.844: Info: slim_tcpdata.c[164]: event: h [08-08-11 00:35:59.6085] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00720.846: Info: slim_tcpdata.c[398]: Allocating new savedBody with 1225 bytes [08-08-11 00:35:59.6092] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00720.850: Info: slim_tcpdata.c[391]: Appending 1 bytes to existing savedBody of size 1225 [08-08-11 00:35:59.6098] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00720.943: Info: slim_tcpdata.c[438]: Recieved continue message with interval 0, looping 0 and guids 0 [08-08-11 00:35:59.6105] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00720.946: Info: slim_tcpdata.c[445]: queuing up saved body. [08-08-11 00:35:59.6335] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00721.013: Info: slim_tcpdata.c[164]: event: t [08-08-11 00:36:00.1720] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:07:00:01 Got RPDS packet: \"\xFF\0>soapenv:com.real.common.kani.exception.NoContentFoundException\0\x851218429365221 : No EA Found. Invalid Combination of Track Id: [Tra.1962698], Format: [RAD MP3], Bitrate: [192], and Rad Version: [3]" [08-08-11 00:36:00.1754] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (176) 00:04:20:07:00:01 Received RPDS fault: soapenv:com.real.common.kani.exception.NoContentFoundException - 1218429365221 : No EA Found. Invalid Combination of Track Id: [Tra.1962698], Format: [RAD MP3], Bitrate: [192], and Rad Version: [3] [08-08-11 00:36:00.1791] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:07:00:01 Got RPDS packet: \"\xFB" [08-08-11 00:36:00.1799] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (162) RPDS: getEA failed [08-08-11 00:36:00.1808] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00721.539: Trace: radeadecoder.c[996]: recv_getEA_response [08-08-11 00:36:00.1817] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00721.545: Error: radeadecoder.c[829]: Found [08-08-11 00:36:00.1825] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00721.549: Trace: radeadecoder.c[1002]: FAILED TO GET EA BLOCK, RETRYING [08-08-11 00:36:00.1835] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00721.552: Trace: radeadecoder.c[97]: sending error status 251 [08-08-11 00:36:00.6410] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00722.013: Info: slim_tcpdata.c[164]: event: t [08-08-11 00:36:01.0211] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00722.401: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-11 00:36:01.0221] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00722.404: Info: slim_tcpdata.c[164]: event: t [08-08-11 00:36:01.6318] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.002: Info: decode.c[410]: decode_resume start_jiffies_ms=0 [08-08-11 00:36:01.6328] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.004: Info: decode.c[397]: resume_callback [08-08-11 00:36:01.6337] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.006: Info: decode.c[762]: Decode thread: handling message [08-08-11 00:36:01.6345] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.009: Info: decode.c[119]: decode_resume [08-08-11 00:36:01.6441] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.011: Info: decode.c[134]: decoder state: 00000001 audio state: 00000001 [08-08-11 00:36:01.6465] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.013: Info: slim_tcpdata.c[164]: event: t [08-08-11 00:36:01.6488] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.014: Trace: radeadecoder.c[200]: RAD: [08-08-11 00:36:01.6533] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.016: Trace: radeadecoder.c[201]: header_size = 24 [08-08-11 00:36:01.6576] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.019: Trace: radeadecoder.c[202]: major_version = 3 [08-08-11 00:36:01.6601] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.021: Trace: radeadecoder.c[203]: sMinorVersion 0 [08-08-11 00:36:01.6623] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.023: Trace: radeadecoder.c[204]: nMediaID 5559236 [08-08-11 00:36:01.6648] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.026: Trace: radeadecoder.c[205]: nMediaCodec 1024 [08-08-11 00:36:01.6671] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.028: Trace: radeadecoder.c[206]: nMediaFormat 192 [08-08-11 00:36:01.6713] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.030: Trace: radeadecoder.c[207]: nToComplete 4 [08-08-11 00:36:01.6738] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.033: Trace: radeadecoder.c[208]: nRadFileSize 4941836 [08-08-11 00:36:01.6766] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.035: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.6791] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.038: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.6815] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.040: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.6851] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.042: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.6875] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.045: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.6900] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.047: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.6925] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.049: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.6947] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.051: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.6976] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.054: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7020] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.056: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7043] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.058: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7067] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.061: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7090] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.063: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7129] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.065: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7153] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.068: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7163] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.070: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7186] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.072: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7223] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.075: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7253] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.077: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7275] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.079: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7324] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.081: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7347] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.084: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7371] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.086: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7393] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.088: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7417] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.091: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7440] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.093: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7463] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.095: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7485] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.098: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7555] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.100: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7748] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.102: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7773] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.105: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7837] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.107: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7863] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.109: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7886] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.112: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7928] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.114: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7952] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.116: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7975] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.119: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.7999] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.121: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8022] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.123: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8045] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.126: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8068] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.128: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8091] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.131: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8211] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.133: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8215] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.135: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8231] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.137: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8235] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.140: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8238] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.142: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8253] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.145: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8256] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.147: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8260] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.149: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8274] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.152: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8282] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.154: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8285] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.157: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8303] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.159: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8307] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.161: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8324] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.164: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8327] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.166: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8331] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.168: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8345] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.171: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8349] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.173: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8352] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.175: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8367] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.178: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8371] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.180: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8374] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.182: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8377] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.185: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8393] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.187: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8419] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.189: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8423] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.192: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8440] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.194: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8444] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.196: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8447] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.199: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8462] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.201: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8466] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.203: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8469] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.205: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8488] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.208: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8492] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.210: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8510] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.212: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8514] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.215: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8529] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.217: Trace: radeadecoder.c[233]: Reached end of EA [08-08-11 00:36:01.8533] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 00723.219: Trace: radeadecoder.c[233]: Reached end of EA