Bugzilla – Bug 1811
Playback of ShoutCast Radio Stations works only on the second press of PLAY
Last modified: 2009-09-08 09:16:59 UTC
When I try to play any of the predefined ShoutCast radio stations, after pressing PLAY on the remote playback does not start. Only the second press on PLAY starts the playback. Once a station has been successfully played, the next time this station plays successfully already on the first press of PLAY. I use SlimServer 6.1b2 and a Sqeezebox2 (I don't use SqueezeNetwork until now).
Can you confirm this only the very first time after a rescan? Subsequent server restarts seem to be working fine.
I tried several stations which worked only after the second PLAY, stopped the server and made a restart of the server. After the restart the stations which I had selected before played at once after the first PLAY. New stations which I didn't play before still neede two PLAYS. Note: In some cases it seems that I had to press PLAY more than twice to get the station to play. I have the impression that this happens when I wait some time after the first PLAY until I press PLAY the second time (although I am not completey sure). Also when I pressed after the first PLAY the now playing key and (playlist was empty) and then returned to the station and pressed PLAY a second time the station did not play. It seems that the two PLAYs must be pressed relatively shortly after another.
I guess this is due to the new playlist handling. The radio stations are only played when they're found in the DB. Dan? Any idea?
can you list some that are giving you trouble? I have tried HitzRadio and it plays just fine. It took a while (probably due to asyncHTTP or the need for careful use of timeouts). Are you waiting enough to account for 'net delays, buffering? can you provide d_remotestream log output? You might try increasing the pref for radio timeout (server settings->network). the default of 5 seconds might not be enough for your location.
The problem arises will ALL ShoutCast stations I used (meanwhile I may have tried about 20-30), so it seems not to be a problem specific for a certain station. I usually wait for about 20 to 30 seconds and if then playback does not start I press PLAY again and playback starts then after about 2-5 seconds. I tried already to wait for 2 minutes after the first press of PLAY but nothing happend. I also increased the radio timeout in the server's network settings from 5 to 10 seconds but this did also not help. When I switch after the first press of PLAY to the now playing display the status of the station is shown as "stopped". How do I produce a d_remotestream log output? I checked the corresponding box in the server's debugging settings but where do I find the output? I looked for a *.log file in the server's program folder but did not find any (I am using Windows XP).
I can confirm Dieter's experience. Kevin, just try a channel you'd never listen to (Hindi is a good choice ;- )). Play a stream: it will scan through all the playlist entries and then stop. Go to "Now playing..." and you'll see it's stopped. Then hit play again - it will start. Here's my log: 2005-07-14 08:42:42.6120 Opening connection to http://www.shoutcast.com/sbin/tunein-station.pls? id=3499&filename=playlist.pls: [www.shoutcast.com on port 80 with path /sbin/tunein-station.pls? id=3499&filename=playlist.pls with timeout 15] 2005-07-14 08:42:43.2156 Request: GET /sbin/tunein-station.pls?id=3499&filename=playlist.pls HTTP/1.0 Accept: */* Cache-Control: no-cache User-Agent: iTunes/4.7.1 (Linux; N; Linux; i686-linux; DE; iso-8859-1) SlimServer/6.1b2/trunk Icy-MetaData: 1 Connection: close Host: www.shoutcast.com 2005-07-14 08:42:43.5742 Response: HTTP/1.1 200 OK 2005-07-14 08:42:43.6193 header: Date: Thu, 14 Jul 2005 06:42:43 GMT 2005-07-14 08:42:43.6211 header: Server: Apache/1.3.33 (Unix) PHP/4.3.11 2005-07-14 08:42:43.6224 header: X-Powered-By: PHP/4.3.11 2005-07-14 08:42:43.6254 header: Connection: close 2005-07-14 08:42:43.6267 header: Content-Type: audio/x-scpls 2005-07-14 08:42:43.6550 header: 2005-07-14 08:42:43.6558 Recieved final blank line... 2005-07-14 08:42:43.6559 opened stream! 2005-07-14 08:42:43.6590 Slim::Player::Protocols::HTTP - in DESTROY 2005-07-14 08:42:43.6598 Slim::Player::Protocols::HTTP About to close socket to: [http://www.shoutcast. com/sbin/tunein-station.pls?id=3499&filename=playlist.pls] 2005-07-14 08:42:44.1046 Opening connection to http://205.188.234.39:8012: [205.188.234.39 on port 8012 with path / with timeout 15] 2005-07-14 08:42:44.2277 Request: GET / HTTP/1.0 Accept: */* Cache-Control: no-cache User-Agent: iTunes/4.7.1 (Linux; N; Linux; i686-linux; DE; iso-8859-1) SlimServer/6.1b2/trunk Icy-MetaData: 1 Connection: close Host: 205.188.234.39:8012 2005-07-14 08:42:44.3727 Response: ICY 200 OK 2005-07-14 08:42:44.4121 header: icy-notice1:<BR>This stream requires <a href="http://www.winamp. com/">Winamp</a><BR> 2005-07-14 08:42:44.4139 header: icy-notice2:SHOUTcast Distributed Network Audio Server/SolarisSparc v1.9.5<BR> 2005-07-14 08:42:44.4157 header: icy-name:Megarock Radio - This is Real Rock Radio! Requests via web 24/7, live DJ's, specialty programming! 2005-07-14 08:42:44.4209 header: icy-genre:Rock, Metal, Alternative, Classic Rock 2005-07-14 08:42:44.4223 header: icy-url:http://www.megarockradio.com 2005-07-14 08:42:44.4235 header: content-type:audio/mpeg 2005-07-14 08:42:44.4537 header: icy-pub:1 2005-07-14 08:42:44.4552 header: icy-metaint:24576 2005-07-14 08:42:44.4564 header: icy-br:64 2005-07-14 08:42:44.4734 header: 2005-07-14 08:42:44.4736 Recieved final blank line... 2005-07-14 08:42:44.4737 opened stream! 2005-07-14 08:42:44.4746 Slim::Player::Protocols::HTTP - in DESTROY 2005-07-14 08:42:44.4751 Slim::Player::Protocols::HTTP About to close socket to: [http://205.188.234.39: 8012] 2005-07-14 08:42:44.5452 Opening connection to http://70.85.111.122:8000: [70.85.111.122 on port 8000 with path / with timeout 15] 2005-07-14 08:42:44.7362 Request: GET / HTTP/1.0 Accept: */* Cache-Control: no-cache User-Agent: iTunes/4.7.1 (Linux; N; Linux; i686-linux; DE; iso-8859-1) SlimServer/6.1b2/trunk Icy-MetaData: 1 Connection: close Host: 70.85.111.122:8000 [This last block is repeated identically for every entry in the playlist, only the address changes] 2005-07-14 08:42:47.3111 Response: ICY 200 OK 2005-07-14 08:42:47.3459 header: icy-notice1:<BR>This stream requires <a href="http://www.winamp. com/">Winamp</a><BR> 2005-07-14 08:42:47.3476 header: icy-notice2:SHOUTcast Distributed Network Audio Server/win32 v1.9. 5<BR> 2005-07-14 08:42:47.3492 header: icy-name:Megarock Radio - This is Real Rock Radio! Requests via web 24/7, live DJ's, specialty programming! 2005-07-14 08:42:47.3528 header: icy-genre:Rock, Metal, Alternative, Classic Rock 2005-07-14 08:42:47.3543 header: icy-url:http://www.megarockradio.com 2005-07-14 08:42:47.3555 header: content-type:audio/mpeg 2005-07-14 08:42:47.4013 header: icy-pub:1 2005-07-14 08:42:47.4027 header: icy-metaint:8192 2005-07-14 08:42:47.4039 header: icy-br:64 2005-07-14 08:42:47.4155 header: 2005-07-14 08:42:47.4166 Recieved final blank line... 2005-07-14 08:42:47.4167 opened stream! 2005-07-14 08:42:47.4177 Slim::Player::Protocols::HTTP - in DESTROY 2005-07-14 08:42:47.4184 Slim::Player::Protocols::HTTP About to close socket to: [http://70.84.81.78:8158] 2005-07-14 08:42:47.4669 Opening connection to http://67.19.108.130:8000: [67.19.108.130 on port 8000 with path / with timeout 15] 2005-07-14 08:42:47.6205 Request: GET / HTTP/1.0 Accept: */* Cache-Control: no-cache User-Agent: iTunes/4.7.1 (Linux; N; Linux; i686-linux; DE; iso-8859-1) SlimServer/6.1b2/trunk Icy-MetaData: 1 Connection: close Host: 67.19.108.130:8000 2005-07-14 08:42:47.7838 Response: ICY 200 OK 2005-07-14 08:42:47.8085 header: icy-notice1:<BR>This stream requires <a href="http://www.winamp. com/">Winamp</a><BR> 2005-07-14 08:42:47.8101 header: icy-notice2:SHOUTcast Distributed Network Audio Server/Linux v1.9. 5<BR> 2005-07-14 08:42:47.8145 header: icy-name:Megarock Radio - This is Real Rock Radio! Requests via web 24/7, live DJ's, specialty programming! 2005-07-14 08:42:47.8184 header: icy-genre:Rock, Metal, Alternative, Classic Rock 2005-07-14 08:42:47.8199 header: icy-url:http://www.megarockradio.com 2005-07-14 08:42:47.8213 header: content-type:audio/mpeg 2005-07-14 08:42:47.8456 header: icy-pub:1 2005-07-14 08:42:47.8471 header: icy-metaint:32768 2005-07-14 08:42:47.8505 header: icy-br:64 2005-07-14 08:42:47.8595 header: 2005-07-14 08:42:47.8604 Recieved final blank line... 2005-07-14 08:42:47.8605 opened stream! 2005-07-14 08:42:47.8642 Slim::Player::Protocols::HTTP - in DESTROY 2005-07-14 08:42:47.8656 Slim::Player::Protocols::HTTP About to close socket to: [http://67.19.108.130: 8000] That's it from our first try. The playback definitely never started. Now I hit play again: 2005-07-14 08:43:06.0350 Opening connection to http://205.188.234.39:8012: [205.188.234.39 on port 8012 with path / with timeout 15] 2005-07-14 08:43:06.1613 Request: GET / HTTP/1.0 Accept: */* Cache-Control: no-cache User-Agent: iTunes/4.7.1 (Linux; N; Linux; i686-linux; DE; iso-8859-1) SlimServer/6.1b2/trunk Icy-MetaData: 1 Connection: close Host: 205.188.234.39:8012 2005-07-14 08:43:06.3107 Response: ICY 200 OK 2005-07-14 08:43:06.3949 header: icy-notice1:<BR>This stream requires <a href="http://www.winamp. com/">Winamp</a><BR> 2005-07-14 08:43:06.3971 header: icy-notice2:SHOUTcast Distributed Network Audio Server/SolarisSparc v1.9.5<BR> 2005-07-14 08:43:06.3988 header: icy-name:Megarock Radio - This is Real Rock Radio! Requests via web 24/7, live DJ's, specialty programming! 2005-07-14 08:43:06.4020 header: icy-genre:Rock, Metal, Alternative, Classic Rock 2005-07-14 08:43:06.4033 header: icy-url:http://www.megarockradio.com 2005-07-14 08:43:06.4047 header: content-type:audio/mpeg 2005-07-14 08:43:06.4854 header: icy-pub:1 2005-07-14 08:43:06.4869 header: icy-metaint:24576 2005-07-14 08:43:06.4881 header: icy-br:64 2005-07-14 08:43:06.5117 header: 2005-07-14 08:43:06.5125 Recieved final blank line... 2005-07-14 08:43:06.5130 opened stream! 2005-07-14 08:43:06.7228 metadata size: 0 2005-07-14 08:43:06.8955 metadata size: 0 2005-07-14 08:43:07.1069 metadata size: 0 2005-07-14 08:43:07.2662 metadata size: 0 2005-07-14 08:43:07.4259 metadata size: 0 2005-07-14 08:43:07.5892 metadata size: 0 2005-07-14 08:43:07.7498 metadata size: 0 2005-07-14 08:43:07.9192 metadata size: 112 2005-07-14 08:43:07.9195 metadata: StreamTitle='Green Day - Holiday - (American Idiot-Advance)'; StreamUrl='http://www.megarockradio.com'; 2005-07-14 08:43:07.9286 shoutcast title = Green Day - Holiday - (American Idiot-Advance) 2005-07-14 08:43:08.0362 metadata size: 0 2005-07-14 08:43:08.1980 metadata size: 0 2005-07-14 08:43:08.4097 metadata size: 0 2005-07-14 08:43:11.2671 Metadata byte not read, trying again: Resource temporarily unavailable 2005-07-14 08:43:11.2675 Metadata byte not read, trying again: Resource temporarily unavailable 2005-07-14 08:43:11.2677 Metadata byte not read, trying again: Resource temporarily unavailable ... Playing.
Dieter: there's a link to the log file in the introductory text of that page: http://yourserver:9000/log.txt
Michael, I just found the link (I must have been blind not to see it). Here is my log file (with the first not working play and the second working play). I used Softsqueeze since I am not at home at the moment. 0:43.1648 Metadata byte not read, trying again: Unknown error 2005-07-14 09:00:43.1650 Metadata byte not read, trying again: Unknown error 2005-07-14 09:00:43.1655 metadata size: 0 2005-07-14 09:00:44.2054 metadata size: 0 2005-07-14 09:00:45.1938 Metadata byte not read, trying again: Unknown error 2005-07-14 09:00:45.1943 Metadata byte not read, trying again: Unknown error 2005-07-14 09:00:45.1945 Metadata byte not read, trying again: Unknown error 2005-07-14 09:00:45.1947 Metadata byte not read, trying again: Unknown error 2005-07-14 09:00:45.1960 Metadata byte not read, trying again: Unknown error (I cut off a lot of almost identical lines) ...... 2005-07-14 09:00:45.2013 Metadata byte not read, trying again: Unknown error 2005-07-14 09:00:45.2016 Metadata byte not read, trying again: Unknown error 2005-07-14 09:00:45.2021 Metadata byte not read, trying again: Unknown error 2005-07-14 09:00:45.2028 metadata size: 0 2005-07-14 09:00:46.2504 Metadata byte not read, trying again: Unknown error 2005-07-14 09:00:46.2509 Metadata byte not read, trying again: Unknown error 2005-07-14 09:00:46.2511 Metadata byte not read, trying again: Unknown error 2005-07-14 09:00:46.2514 Metadata byte not read, trying again: Unknown error 2005-07-14 09:00:46.2516 Metadata byte not read, trying again: Unknown error 2005-07-14 09:00:46.2518 Metadata byte not read, trying again: Unknown error ..... (cut off again) 2005-07-14 09:00:46.2599 Metadata byte not read, trying again: Unknown error 2005-07-14 09:00:46.2606 metadata size: 0 2005-07-14 09:00:47.2666 metadata size: 0 2005-07-14 09:00:48.2598 Metadata byte not read, trying again: Unknown error 2005-07-14 09:00:48.2605 Metadata byte not read, trying again: Unknown error 2005-07-14 09:00:48.2610 Metadata byte not read, trying again: Unknown error ..... 2005-07-14 09:01:21.0489 Metadata byte not read, trying again: Unknown error 2005-07-14 09:01:21.0491 Metadata byte not read, trying again: Unknown error 2005-07-14 09:01:21.0500 Metadata byte not read, trying again: Unknown error 2005-07-14 09:01:21.0504 Metadata byte not read, trying again: Unknown error 2005-07-14 09:01:21.0509 Metadata byte not read, trying again: Unknown error 2005-07-14 09:01:21.0527 Metadata byte not read, trying again: Unknown error 2005-07-14 09:01:21.0535 metadata size: 0 2005-07-14 09:02:49.6468 Slim::Player::Protocols::HTTP - in DESTROY 2005-07-14 09:02:49.6477 Slim::Player::Protocols::HTTP About to close socket to: [http://205.188.234.1:8008] 2005-07-14 09:02:49.6670 Opening connection to http://www.shoutcast.com/sbin/tunein-station.pls?id=591986&filename=playlist.pls: [www.shoutcast.com on port 80 with path /sbin/tunein-station.pls?id=591986&filename=playlist.pls with timeout 5] 2005-07-14 09:02:49.8199 Request: GET /sbin/tunein-station.pls?id=591986&filename=playlist.pls HTTP/1.0 Accept: */* Cache-Control: no-cache User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SlimServer/6.1b2/trunk Icy-MetaData: 1 Connection: close Host: www.shoutcast.com 2005-07-14 09:02:50.1335 Response: HTTP/1.1 200 OK 2005-07-14 09:02:50.3640 header: Date: Thu, 14 Jul 2005 07:02:49 GMT 2005-07-14 09:02:50.3650 header: Server: Apache/1.3.33 (Unix) PHP/4.3.11 2005-07-14 09:02:50.3662 header: X-Powered-By: PHP/4.3.11 2005-07-14 09:02:50.3674 header: Connection: close 2005-07-14 09:02:50.3688 header: Content-Type: audio/x-scpls 2005-07-14 09:02:50.5980 header: 2005-07-14 09:02:50.5985 Recieved final blank line... 2005-07-14 09:02:50.5986 opened stream! 2005-07-14 09:02:50.6015 Slim::Player::Protocols::HTTP - in DESTROY 2005-07-14 09:02:50.6019 Slim::Player::Protocols::HTTP About to close socket to: [http://www.shoutcast.com/sbin/tunein-station.pls?id=591986&filename=playlist.pls] 2005-07-14 09:02:50.8785 Opening connection to http://69.28.128.148:80/stream/wamu_bluegrasscountry: [69.28.128.148 on port 80 with path /stream/wamu_bluegrasscountry with timeout 5] 2005-07-14 09:02:51.0984 Request: GET /stream/wamu_bluegrasscountry HTTP/1.0 Accept: */* Cache-Control: no-cache User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SlimServer/6.1b2/trunk Icy-MetaData: 1 Connection: close Host: 69.28.128.148 2005-07-14 09:02:51.3300 Response: HTTP/1.1 302 Found 2005-07-14 09:02:51.6297 header: Date: Thu, 14 Jul 2005 07:02:50 GMT 2005-07-14 09:02:51.6307 header: Server: Apache/2.0.40 (Red Hat Linux) 2005-07-14 09:02:51.6316 header: Location: http://wamu.sc.llnwd.net:12205/ 2005-07-14 09:02:51.6328 header: Content-Length: 287 2005-07-14 09:02:51.6337 header: Connection: close 2005-07-14 09:02:51.6357 header: Content-Type: text/html; charset=iso-8859-1 2005-07-14 09:02:51.8776 header: 2005-07-14 09:02:51.8785 Recieved final blank line... 2005-07-14 09:02:51.8792 Redirect to: http://wamu.sc.llnwd.net:12205/ 2005-07-14 09:02:51.8811 Opening connection to http://wamu.sc.llnwd.net:12205/: [wamu.sc.llnwd.net on port 12205 with path / with timeout 5] 2005-07-14 09:02:52.1900 Request: GET / HTTP/1.0 Accept: */* Cache-Control: no-cache User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SlimServer/6.1b2/trunk Icy-MetaData: 1 Connection: close Host: wamu.sc.llnwd.net:12205 2005-07-14 09:02:52.3449 Response: ICY 200 OK 2005-07-14 09:02:52.5845 header: icy-notice1:<BR>This stream requires <a href="http://www.winamp.com/">Winamp</a><BR> 2005-07-14 09:02:52.5858 header: icy-notice2:SHOUTcast Distributed Network Audio Server/Linux v1.9.2<BR> 2005-07-14 09:02:52.5865 header: icy-name:BluegrassCountry 2005-07-14 09:02:52.5886 header: icy-genre:Country 2005-07-14 09:02:52.5894 header: icy-url:http://www.audiorealm.com 2005-07-14 09:02:52.5904 header: Content-Type:audio/mpeg 2005-07-14 09:02:52.8312 header: icy-pub:1 2005-07-14 09:02:52.8322 header: icy-metaint:8192 2005-07-14 09:02:52.8330 header: icy-br:56 2005-07-14 09:02:52.8401 header: 2005-07-14 09:02:52.8405 Recieved final blank line... 2005-07-14 09:02:52.8407 opened stream! 2005-07-14 09:02:52.8429 Saving old title: (#1 - 71/1000) BluegrassCountry for http://wamu.sc.llnwd.net:12205/ 2005-07-14 09:02:52.8473 Content type (mp3) of http://69.28.128.148:80/stream/wamu_bluegrasscountry is being set to the contentType of: http://wamu.sc.llnwd.net:12205/ 2005-07-14 09:02:53.0797 Slim::Player::Protocols::HTTP - in DESTROY 2005-07-14 09:02:53.0806 Slim::Player::Protocols::HTTP About to close socket to: [http://69.28.128.148:80/stream/wamu_bluegrasscountry] 2005-07-14 09:02:53.1462 Opening connection to http://69.28.128.148:80/stream/wamu_bluegrasscountry: [69.28.128.148 on port 80 with path /stream/wamu_bluegrasscountry with timeout 5] 2005-07-14 09:02:53.3717 Request: GET /stream/wamu_bluegrasscountry HTTP/1.0 Accept: */* Cache-Control: no-cache User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SlimServer/6.1b2/trunk Icy-MetaData: 1 Connection: close Host: 69.28.128.148 2005-07-14 09:02:53.6093 Response: HTTP/1.1 302 Found 2005-07-14 09:02:53.8547 header: Date: Thu, 14 Jul 2005 07:02:52 GMT 2005-07-14 09:02:53.8564 header: Server: Apache/2.0.40 (Red Hat Linux) 2005-07-14 09:02:53.8574 header: Location: http://wamu.sc.llnwd.net:12205/ 2005-07-14 09:02:53.8584 header: Content-Length: 287 2005-07-14 09:02:53.8593 header: Connection: close 2005-07-14 09:02:53.8603 header: Content-Type: text/html; charset=iso-8859-1 2005-07-14 09:02:54.1030 header: 2005-07-14 09:02:54.1035 Recieved final blank line... 2005-07-14 09:02:54.1041 Redirect to: http://wamu.sc.llnwd.net:12205/ 2005-07-14 09:02:54.1061 Opening connection to http://wamu.sc.llnwd.net:12205/: [wamu.sc.llnwd.net on port 12205 with path / with timeout 5] 2005-07-14 09:02:54.2552 Request: GET / HTTP/1.0 Accept: */* Cache-Control: no-cache User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SlimServer/6.1b2/trunk Icy-MetaData: 1 Connection: close Host: wamu.sc.llnwd.net:12205 2005-07-14 09:02:54.4153 Response: ICY 200 OK 2005-07-14 09:02:54.6941 header: icy-notice1:<BR>This stream requires <a href="http://www.winamp.com/">Winamp</a><BR> 2005-07-14 09:02:54.6950 header: icy-notice2:SHOUTcast Distributed Network Audio Server/Linux v1.9.2<BR> 2005-07-14 09:02:54.6960 header: icy-name:BluegrassCountry 2005-07-14 09:02:54.6970 header: icy-genre:Country 2005-07-14 09:02:54.6985 header: icy-url:http://www.audiorealm.com 2005-07-14 09:02:54.6993 header: Content-Type:audio/mpeg 2005-07-14 09:02:54.9420 header: icy-pub:1 2005-07-14 09:02:54.9427 header: icy-metaint:8192 2005-07-14 09:02:54.9436 header: icy-br:56 2005-07-14 09:02:54.9503 header: 2005-07-14 09:02:54.9508 Recieved final blank line... 2005-07-14 09:02:54.9510 opened stream! 2005-07-14 09:02:54.9561 Content type (mp3) of http://69.28.128.148:80/stream/wamu_bluegrasscountry is being set to the contentType of: http://wamu.sc.llnwd.net:12205/ 2005-07-14 09:02:55.1905 Slim::Player::Protocols::HTTP - in DESTROY 2005-07-14 09:02:55.1912 Slim::Player::Protocols::HTTP About to close socket to: [http://69.28.128.148:80/stream/wamu_bluegrasscountry] 2005-07-14 09:03:12.2607 Opening connection to http://www.shoutcast.com/sbin/tunein-station.pls?id=591986&filename=playlist.pls: [www.shoutcast.com on port 80 with path /sbin/tunein-station.pls?id=591986&filename=playlist.pls with timeout 5] 2005-07-14 09:03:12.4265 Request: GET /sbin/tunein-station.pls?id=591986&filename=playlist.pls HTTP/1.0 Accept: */* Cache-Control: no-cache User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SlimServer/6.1b2/trunk Icy-MetaData: 1 Connection: close Host: www.shoutcast.com 2005-07-14 09:03:13.2432 Response: HTTP/1.1 200 OK 2005-07-14 09:03:13.5512 header: Date: Thu, 14 Jul 2005 07:03:11 GMT 2005-07-14 09:03:13.5524 header: Server: Apache/1.3.33 (Unix) PHP/4.3.11 2005-07-14 09:03:13.5538 header: X-Powered-By: PHP/4.3.11 2005-07-14 09:03:13.5564 header: Connection: close 2005-07-14 09:03:13.5573 header: Content-Type: audio/x-scpls 2005-07-14 09:03:13.8174 header: 2005-07-14 09:03:13.8178 Recieved final blank line... 2005-07-14 09:03:13.8199 opened stream! 2005-07-14 09:03:13.8225 Slim::Player::Protocols::HTTP - in DESTROY 2005-07-14 09:03:13.8229 Slim::Player::Protocols::HTTP About to close socket to: [http://www.shoutcast.com/sbin/tunein-station.pls?id=591986&filename=playlist.pls] 2005-07-14 09:03:14.0927 Opening connection to http://69.28.128.148:80/stream/wamu_bluegrasscountry: [69.28.128.148 on port 80 with path /stream/wamu_bluegrasscountry with timeout 5] 2005-07-14 09:03:14.3135 Request: GET /stream/wamu_bluegrasscountry HTTP/1.0 Accept: */* Cache-Control: no-cache User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SlimServer/6.1b2/trunk Icy-MetaData: 1 Connection: close Host: 69.28.128.148 2005-07-14 09:03:14.5451 Response: HTTP/1.1 302 Found 2005-07-14 09:03:14.7732 header: Date: Thu, 14 Jul 2005 07:03:13 GMT 2005-07-14 09:03:14.7742 header: Server: Apache/2.0.40 (Red Hat Linux) 2005-07-14 09:03:14.7758 header: Location: http://wamu.sc.llnwd.net:12205/ 2005-07-14 09:03:14.7767 header: Content-Length: 287 2005-07-14 09:03:14.7775 header: Connection: close 2005-07-14 09:03:14.7787 header: Content-Type: text/html; charset=iso-8859-1 2005-07-14 09:03:15.0211 header: 2005-07-14 09:03:15.0215 Recieved final blank line... 2005-07-14 09:03:15.0232 Redirect to: http://wamu.sc.llnwd.net:12205/ 2005-07-14 09:03:15.0251 Opening connection to http://wamu.sc.llnwd.net:12205/: [wamu.sc.llnwd.net on port 12205 with path / with timeout 5] 2005-07-14 09:03:15.1709 Request: GET / HTTP/1.0 Accept: */* Cache-Control: no-cache User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SlimServer/6.1b2/trunk Icy-MetaData: 1 Connection: close Host: wamu.sc.llnwd.net:12205 2005-07-14 09:03:15.3233 Response: ICY 200 OK 2005-07-14 09:03:15.5671 header: icy-notice1:<BR>This stream requires <a href="http://www.winamp.com/">Winamp</a><BR> 2005-07-14 09:03:15.5682 header: icy-notice2:SHOUTcast Distributed Network Audio Server/Linux v1.9.2<BR> 2005-07-14 09:03:15.5690 header: icy-name:BluegrassCountry 2005-07-14 09:03:15.5708 header: icy-genre:Country 2005-07-14 09:03:15.5717 header: icy-url:http://www.audiorealm.com 2005-07-14 09:03:15.5724 header: Content-Type:audio/mpeg 2005-07-14 09:03:15.8146 header: icy-pub:1 2005-07-14 09:03:15.8153 header: icy-metaint:8192 2005-07-14 09:03:15.8163 header: icy-br:56 2005-07-14 09:03:15.8243 header: 2005-07-14 09:03:15.8247 Recieved final blank line... 2005-07-14 09:03:15.8249 opened stream! 2005-07-14 09:03:15.8297 Content type (mp3) of http://69.28.128.148:80/stream/wamu_bluegrasscountry is being set to the contentType of: http://wamu.sc.llnwd.net:12205/ 2005-07-14 09:03:16.8669 metadata size: 32 2005-07-14 09:03:16.8674 metadata: StreamTitle='';StreamUrl='';
Created attachment 647 [details] possible fix The problem turns out to be an issue that gives me a little confirm. Post-6.0.2 it became possible for the playlist to contain database Track objects and not just URLs. Since Track objects stringify to their URLs, it isn't much of an issue for much of the existing code to get the former instead of the latter. The only place where it becomes an issue is if code does a Slim::DataStores::DBI::DBIStore::objectForURL() passing in an object. The returned object *may not* actually be the same as the original one passed in if the $lightweight parameter is different from the one used to retrieve the original object. The attached patch checks the type of the $url parameter to Slim::DataStores::DBI::DBIStore::objectForURL() and returns immediately if we already have an object. This isn't wrong...but maybe the right thing to do is fix the callers. Holding off checking in till we discuss our comfort level with this.
The problem turns out to be an issue that gives me a little *concern*, not "confirm".
Vidur, I did not quite understand your concern. What do you want us to test (besides shoutcast)? What problem should we expect?
Is it correct that simply inserting the lines of the fix into DBIStore.pm does not have any effect for the Windows version and that I therefore cannot test the fix as long as it is not checked in in the nightly builds? Or is there a possibility to test the fix even in the Windows version?
If you download ActiveState Perl (http://www.activestate.com/) - and a nightly zip build, you can run 'perl slimserver.pl' from the command prompt, and changes to the perl files will be picked up.
I'm looking for discussion, more than testing. What are the various ramifications of having objects rather than strings returned from methods like Slim::Player::Playlist::song()? Should we be concerned about having two versions of Track objects around (since they are in memory, they will not track changes between each other)? The objectForURL() issue was unexpected - are there are other cases that we can/should anticipate?
Seems like the better fix would be to share object caches for LightWeightTrack and Track. That would ensure that we don't have one of both in memory.
With change 3740, vidur has patched this well enough for 6.1. Punting cleanup post 6.1
This particular problem is fixed. Let's open new bugs if there are other cases.
This issue is back. Could someone re-open it or do I open a new bug? http://forums.slimdevices.com/showthread.php?t=17812 The start of the thread is confusing. But in the end dfk confirm's that it's just the first try to play a stream that won't work.
wonder if this has to do with the recent chagnes to teh live objects for DBI
*** Bug 2481 has been marked as a duplicate of this bug. ***
Created attachment 992 [details] debug info some debug, to hopefully track what is happening. this is d_info d_parse d_directstream d_remotestrea and d_source. What you see here is the opensong on one of the urls of a shoutcast station. you can see the d_info line saying Content Type is cached as mp3. However,there is none of the d_info that should follow for the updateOrCreate called from Info::setcontentType. I added a dump in objectForUrl and it shows that ct is clearly not defined. This makes it rather difficult for Source::opensong to actually do anything. Thus, playback fails. However, trying to play again, seems to have put the content type into the object by that time, so it works. not sure how to get a ct in there for the first time.
Dan: any thoughts on this?
Fixed in change 5079