Long delay when changing stations

Collapse
X
Collapse
 
  • Page of 2
  • Time
  • Show
Clear All
new posts
Previous 1 2 template Next
  • chris49
    Junior Member
    • Jul 2018
    • 26
    #16
    Thanks for staying with me on this! I'd really like to find a solution.

    Originally posted by bpa
    This is truly ancient - it practically has no https support and most streams these days start off with a redirect to https.
    Beside poor https support - it poses a security risk with the possibility of stuff being inserted into http streams such as in metadata (e.g. images which contain malware).
    I guess ancient = Beta-1. It appears to be the latest official release, but I'll try LogitechMediaServer-7.9.2-1562310110.exe

    Stable release: 7.7.6 / March 29, 2018; 15 months ago

    Operating system: Linux, Windows, macOS, BSD, Solaris

    Preview release: 7.9.1 Beta / April 17, 2018; 14 months ago

    I tried 7.9.2. It's slightly better, but still not right. Sometimes presets tune in properly, but mostly >10 seconds or more to start playing.

    Also, no difference paying from Favorites vs. Pre-Sets.

    Originally posted by bpa
    One possibility is when you play via Mysqueezebox.com (SBS = Squeezeboxserver a precursor to LMS) there is no transcoding so first playable stream is played whereas with LMS there is the possibility of transcoding so all streams in a playlist. I'll check the URls to see if there is https and/or playlist.

    Other possibility is the LMS Setting "Radio Buffer seconds" (In WebUI Settings/Advanced/Network) may be set at say 30 whereas the equivalent setting on Mysqueezebox.com may be set at a lower value 10 - check the setting.
    LMS buffer was set to 4 seconds. I changed to 3. No discernible change.

    Is there a current version of something similar to SoftSqueeze that I could use for further troubleshooting?

    Is there a way to export/save my presets to a file?

      Comment

      • bpa
        Senior Member
        • Oct 2005
        • 22881
        #17
        Stable = last tested by Logitech. 2018 is the build due to minor library updates and to enable to run with newer OS releases - not the last time LMS code was added which was about 2015.

        After Logitech left LMS to community - all releases are beta since there is nobody going through a test suite.

        Good that Favoriutes is the same as preset so not a buttong issue.

        Not sure what good Softsqueeze or Squeezelite (https://sourceforge.net/projects/lms...s/squeezelite/ ) or SQueezeplay ( https://sourceforge.net/projects/lms...s/squeezeplay/ ) would do - LMS does the initial contact with the station and it is LMS which has the delay.

        To see what LMS is doing and where time is spent you need to enable logging open WebUI Setting/Advanced/Logging and set player.source, player.streaming and player.streaming.direct all to INFO and click Apply.

        Note the time, then play the atl750 stream - once it starts, clear the playlist and note the time. Open the server.log and copy the entries between start time and stop time you noted.

        You should get a log like the following - you can see that between requesting the stream and data arriving it is about 3 secs.
        Code:
        [19-07-05 19:00:23.6709] Slim::Player::Song::new (108) index 0 -> http://streaming.cmgdigital.com/atl750/atl750.mp3
[19-07-05 19:00:23.6714] Slim::Player::StreamingController::_setStreamingState (2387) new streaming state TRACKWAIT
[19-07-05 19:00:23.6730] Slim::Player::StreamingController::_playersMessage (797) Getting stream info...: http://streaming.cmgdigital.com/atl750/atl750.mp3
[19-07-05 19:00:23.6733] Slim::Player::Song::getNextSong (229) http://streaming.cmgdigital.com/atl750/atl750.mp3
[19-07-05 19:00:23.6734] Slim::Player::Song::getNextSong (251) scanning URL http://streaming.cmgdigital.com/atl750/atl750.mp3
[19-07-05 19:00:25.4139] Slim::Player::Song::__ANON__ (267) Track updated by scan: http://streaming.cmgdigital.com/atl750/atl750.mp3 -> http://ad-oom-cmg.streamguys1.com/atl750/atl750.mp3
[19-07-05 19:00:25.4144] Slim::Player::Song::getNextSong (229) http://ad-oom-cmg.streamguys1.com/atl750/atl750.mp3
[19-07-05 19:00:25.4147] Slim::Player::StreamingController::_nextTrackReady (745) 00:04:20:16:07:0e: nextTrack will be index 0
[19-07-05 19:00:25.4151] Slim::Player::StreamingController::_Stream (1211) Song queue is now 0
[19-07-05 19:00:25.4153] Slim::Player::StreamingController::_Stream (1214) 00:04:20:16:07:0e: preparing to stream song index 0
[19-07-05 19:00:25.4156] Slim::Player::Song::open (364) http://ad-oom-cmg.streamguys1.com/atl750/atl750.mp3
[19-07-05 19:00:25.4175] Slim::Player::TranscodingHelper::getConvertCommand2 (443) Error: Didn't find any command matches for type: mp3
[19-07-05 19:00:25.4179] Slim::Player::Song::open (388) seek=false time=0 canSeek=0SEEK_ERROR_MP3_UNKNOWN_DURATION
[19-07-05 19:00:25.4185] Slim::Player::TranscodingHelper::getConvertCommand2 (446) Matched: mp3->mp3 via: -
[19-07-05 19:00:25.4188] Slim::Player::Song::open (409) Transcoder: streamMode=I, streamformat=mp3
[19-07-05 19:00:25.4191] Slim::Player::Song::open (462) Opening stream (no direct streaming) using Slim::Player::Protocols::HTTP [http://ad-oom-cmg.streamguys1.com/atl750/atl750.mp3]
[19-07-05 19:00:25.9070] Slim::Player::Song::open (483) URL is a song (audio): http://ad-oom-cmg.streamguys1.com/atl750/atl750.mp3, type=mp3
[19-07-05 19:00:25.9085] Slim::Player::StreamingController::_Stream (1283) 00:04:20:16:07:0e: stream
[19-07-05 19:00:25.9114] Slim::Player::StreamingController::_Stream (1320) Song queue is now 0
[19-07-05 19:00:25.9119] Slim::Player::StreamingController::_setPlayingState (2378) new playing state BUFFERING
[19-07-05 19:00:25.9123] Slim::Player::StreamingController::_setStreamingState (2387) new streaming state STREAMING
[19-07-05 19:00:25.9290] Slim::Player::Squeezebox2::directHeaders (471) Processing headers for direct streaming:
HTTP/1.1 200 OK
Server: Logitech Media Server (7.9.2 - TRUNK)
Connection: close
Content-Type: audio/mpeg

[19-07-05 19:00:26.0357] Slim::Player::Player::_buffering (1145) Buffering... 0 / 40960
[19-07-05 19:00:26.0359] Slim::Player::Player::_buffering (1146)   +output... 2352632 / 1764000

          Comment

          • chris49
            Junior Member
            • Jul 2018
            • 26
            #18
            I'll try logging tomorrow.

            How do I clear the playlist? I don't normally use playlists, just the pre-sets and favourites.

            If I start the stream with a pre-set, can I just stop the stream with a long press of the pause button instead?

            I do use playlists with VLC media player. How could I import a known good wpl.xspf or .wpl or .m3u into my Squeezeboxes?

              Comment

              • bpa
                Senior Member
                • Oct 2005
                • 22881
                #19
                Originally posted by chris49
                I'll try logging tomorrow.

                How do I clear the playlist? I don't normally use playlists, just the pre-sets and favourites.

                If I start the stream with a pre-set, can I just stop the stream with a long press of the pause button instead?
                When you are playing a station - no matter using pre-set or favourite, LMS shows a single item in the current playlist - that is the playlist I wanted cleared.

                Stop will be OK but you may keep getting log messages. Clear playlist can be done from WebUI - it is button with x at bottom RHS.

                I do use playlists with VLC media player. How could I import a known good wpl.xspf or .wpl or .m3u into my Squeezeboxes?
                Finish this issue first.

                LMS can read m3u directly (i.e put the file in your playlist folder as long as file paths are full ) - not sure about xspf or wpl.

                  Comment

                  • chris49
                    Junior Member
                    • Jul 2018
                    • 26
                    #20
                    It appears my problem is that my presets are not what I thought they were. (See the 2 lines with "Found a playlist" when I selected Country 108 then WCBS).

                    I changed the URLs for these 2 presets to point directly to the .aac/.mp3 and the delays are gone! (Well, not gone as they are with mysqueezebox.com, but very much reduced.)

                    So I conclude a few things:

                    1. The player needs EXPLICIT urls to the stream to avoid delays
                    2. Playlists probably also need the same
                    3. I need to examine and fix all of my presets and favorites on both radios. Tedious.


                    Thanks again for all you help.

                    Chris

                    Code:
                    [19-07-06 07:58:57.8281] Slim::Player::TranscodingHelper::getConvertCommand2 (443) Error: Didn't find any command matches for type: m3u
    [19-07-06 07:59:16.8232] Slim::Player::Song::__ANON__ (298) Found a playlist
[19-07-06 07:59:16.8240] Slim::Player::Song::getNextSong (229) http://opml.radiotime.com/Tune.ashx?id=s96815&partnerId=16&username=chrisbergerson
[19-07-06 07:59:16.8252] Slim::Player::Song::_getNextPlaylistTrack (218) Got next URL from playlist; track is: http://icepool.silvacast.com/COUNTRY108.mp3
[19-07-06 07:59:16.8260] Slim::Player::StreamingController::_nextTrackReady (745) 00:04:20:27:02:25: nextTrack will be index 0
[19-07-06 07:59:16.8267] Slim::Player::StreamingController::_Stream (1211) Song queue is now 0
[19-07-06 07:59:16.8273] Slim::Player::StreamingController::_Stream (1214) 00:04:20:27:02:25: preparing to stream song index 0
[19-07-06 07:59:16.8279] Slim::Player::Song::open (364) http://icepool.silvacast.com/COUNTRY108.mp3
[19-07-06 07:59:16.8295] Slim::Player::Song::open (388) seek=false time=0 canSeek=0SEEK_ERROR_TYPE_NOT_SUPPORTEDm3u
[19-07-06 07:59:16.8313] Slim::Player::TranscodingHelper::getConvertCommand2 (446) Matched: mp3->mp3 via: -
[19-07-06 07:59:16.8320] Slim::Player::Song::open (409) Transcoder: streamMode=I, streamformat=mp3
[19-07-06 07:59:16.8328] Slim::Player::Song::open (452) URL supports direct streaming [http://icepool.silvacast.com/COUNTRY108.mp3->http://icepool.silvacast.com/COUNTRY108.mp3]
[19-07-06 07:59:16.8348] Slim::Player::StreamingController::_Stream (1283) 00:04:20:27:02:25: stream
[19-07-06 07:59:16.8392] Slim::Player::Squeezebox::stream_s (787) This player supports direct streaming for http://icepool.silvacast.com/COUNTRY108.mp3 as http://icepool.silvacast.com/COUNTRY108.mp3, let's do it.
[19-07-06 07:59:16.8417] Slim::Player::Squeezebox::stream_s (828) setting up direct stream (1585614566:80) autostart: 3 format: m.
[19-07-06 07:59:16.8423] Slim::Player::Squeezebox::stream_s (829) request string: GET /COUNTRY108.mp3 HTTP/1.0


Cache-Control: no-cache


Connection: close


Accept: */*


Host: icepool.silvacast.com


User-Agent: iTunes/4.7.1 (Windows; N; Windows 10; 8664; EN; cp1252) SqueezeCenter, Squeezebox Server, Logitech Media Server/7.9.2/1562310110


Icy-Metadata: 1






[19-07-06 07:59:16.8444] Slim::Player::StreamingController::_Stream (1320) Song queue is now 0
[19-07-06 07:59:16.8452] Slim::Player::StreamingController::_setPlayingState (2378) new playing state BUFFERING
[19-07-06 07:59:16.8459] Slim::Player::StreamingController::_setStreamingState (2387) new streaming state STREAMING
[19-07-06 07:59:16.9635] Slim::Player::Player::_buffering (1145) Buffering... 0 / 49152
[19-07-06 07:59:17.2653] Slim::Player::Player::_buffering (1145) Buffering... 0 / 49152
[19-07-06 07:59:17.5677] Slim::Player::Player::_buffering (1145) Buffering... 0 / 49152
[19-07-06 07:59:17.6275] Slim::Player::Squeezebox2::directHeaders (471) Processing headers for direct streaming:
HTTP/1.0 200 OK[INDENT=7]<MANY LINES REMOVED>

[/INDENT]
[19-07-06 07:59:22.5805] Slim::Player::Song::new (108) index 0 -> http://provisioning.streamtheworld.com/pls/WCBSAM.pls
[19-07-06 07:59:22.5814] Slim::Player::StreamingController::_setStreamingState (2387) new streaming state TRACKWAIT
[19-07-06 07:59:22.5895] Slim::Player::StreamingController::_playersMessage (797) Fetching track details...: http://provisioning.streamtheworld.com/pls/WCBSAM.pls
[19-07-06 07:59:22.5918] Slim::Player::Song::getNextSong (229) http://provisioning.streamtheworld.com/pls/WCBSAM.pls
[19-07-06 07:59:22.5927] Slim::Player::Song::getNextSong (251) scanning URL http://provisioning.streamtheworld.com/pls/WCBSAM.pls
[19-07-06 07:59:23.0934] Slim::Player::TranscodingHelper::getConvertCommand2 (443) Error: Didn't find any command matches for type: pls[INDENT=2][19-07-06 07:59:34.9879] Slim::Player::Song::__ANON__ (298) Found a playlist

[/INDENT]
[19-07-06 07:59:34.9883] Slim::Player::Song::getNextSong (229) http://provisioning.streamtheworld.com/pls/WCBSAM.pls
[19-07-06 07:59:34.9891] Slim::Player::Song::_getNextPlaylistTrack (218) Got next URL from playlist; track is: http://18843.live.streamtheworld.com/WCBSAM_SC
[19-07-06 07:59:34.9896] Slim::Player::StreamingController::_nextTrackReady (745) 00:04:20:27:02:25: nextTrack will be index 0
[19-07-06 07:59:34.9900] Slim::Player::StreamingController::_Stream (1211) Song queue is now 0
[19-07-06 07:59:34.9905] Slim::Player::StreamingController::_Stream (1214) 00:04:20:27:02:25: preparing to stream song index 0
[19-07-06 07:59:34.9910] Slim::Player::Song::open (364) http://18843.live.streamtheworld.com/WCBSAM_SC
[19-07-06 07:59:34.9915] Slim::Player::Song::open (388) seek=false time=0 canSeek=0SEEK_ERROR_TYPE_NOT_SUPPORTEDpls
[19-07-06 07:59:34.9925] Slim::Player::TranscodingHelper::getConvertCommand2 (446) Matched: mp3->mp3 via: -
[19-07-06 07:59:34.9929] Slim::Player::Song::open (409) Transcoder: streamMode=I, streamformat=mp3
[19-07-06 07:59:34.9933] Slim::Player::Song::open (452) URL supports direct streaming [http://18843.live.streamtheworld.com/WCBSAM_SC->http://18843.live.streamtheworld.com/WCBSAM_SC]
[19-07-06 07:59:34.9945] Slim::Player::StreamingController::_Stream (1283) 00:04:20:27:02:25: stream
[19-07-06 07:59:34.9974] Slim::Player::Squeezebox::stream_s (787) This player supports direct streaming for http://18843.live.streamtheworld.com/WCBSAM_SC as http://18843.live.streamtheworld.com/WCBSAM_SC, let's do it.
[19-07-06 07:59:34.9989] Slim::Player::Squeezebox::stream_s (828) setting up direct stream (3495704365:80) autostart: 3 format: m.
[19-07-06 07:59:34.9993] Slim::Player::Squeezebox::stream_s (829) request string: GET /WCBSAM_SC HTTP/1.0


Cache-Control: no-cache


Connection: close


Accept: */*


Host: 18843.live.streamtheworld.com


User-Agent: iTunes/4.7.1 (Windows; N; Windows 10; 8664; EN; cp1252) SqueezeCenter, Squeezebox Server, Logitech Media Server/7.9.2/1562310110


Cookie: uuid=c4bc4025-11d2-4948-a9a1-036cee5d26ab


Cookie2: $Version="1"


Icy-Metadata: 1






[19-07-06 07:59:35.0007] Slim::Player::StreamingController::_Stream (1320) Song queue is now 0
[19-07-06 07:59:35.0012] Slim::Player::StreamingController::_setPlayingState (2378) new playing state BUFFERING
[19-07-06 07:59:35.0017] Slim::Player::StreamingController::_setStreamingState (2387) new streaming state STREAMING
[19-07-06 07:59:35.1223] Slim::Player::Player::_buffering (1145) Buffering... 0 / 24576
[19-07-06 07:59:35.2886] Slim::Player::Squeezebox2::directHeaders (471) Processing headers for direct streaming:
HTTP/1.0 200 OK


Expires: Thu, 01 Dec 2003 16:00:00 GMT


Cache-Control: no-cache, must-revalidate


Pragma: no-cache


Access-Control-Allow-Methods: GET, HEAD


Access-Control-Allow-Origin: *


Access-Control-Allow-Credentials: true


Set-Cookie: uuid=c4bc4025-11d2-4948-a9a1-036cee5d26ab; expires=Mon, 05 Aug 2019 11:59:32 GMT; path=/; domain=.live.streamtheworld.com


Content-Type: audio/mpeg


Connection: close


icy-name: WCBS Newsradio 880


icy-description: 


icy-url: https://wcbs880.radio.com


icy-genre: News


icy-br: 64


icy-metaint: 16000


Server: MediaGateway 5.2.2-0124






[19-07-06 07:59:35.2899] Slim::Player::Squeezebox2::directHeaders (545) Processing 16 headers
[19-07-06 07:59:35.2906] Slim::Player::Squeezebox2::directHeaders (551) Calling Slim::Player::Protocols::HTTP ::parseDirectHeaders
[19-07-06 07:59:35.2933] Slim::Player::Squeezebox2::directHeaders (570) Setting new title for http://18843.live.streamtheworld.com/WCBSAM_SC, WCBS Newsradio 880
[19-07-06 07:59:35.2949] Slim::Player::Squeezebox2::directHeaders (613) Got a stream type: mp3 bitrate: 64000 title: WCBS Newsradio 880
[19-07-06 07:59:35.2957] Slim::Player::Squeezebox2::directHeaders (664) Beginning direct stream!
[19-07-06 07:59:35.4240] Slim::Player::Player::_buffering (1145) Buffering... 0 / 24576
[19-07-06 07:59:35.7258] Slim::Player::Player::_buffering (1145) Buffering... 90530 / 24576
[19-07-06 07:59:35.7265] Slim::Player::Player::_buffering (1146)   +output... 70576 / 1058400
[19-07-06 07:59:35.7505] Slim::Player::StreamingController::playerTrackStarted (2201) 00:04:20:27:02:25
[19-07-06 07:59:35.7513] Slim::Player::StreamingController::_setPlayingState (2378) new playing state PLAYING
[19-07-06 07:59:35.7527] Slim::Player::StreamingController::_Playing (369) Song 0 has now started playing
[19-07-06 07:59:35.7546] Slim::Player::StreamingController::_Playing (398) Song queue is now 0
[19-07-06 07:59:36.1361] Slim::Player::TranscodingHelper::checkBin (275)    couldn't find binary for: lame
[19-07-06 07:59:36.1365] Slim::Player::TranscodingHelper::getConvertCommand2 (443) Error: Didn't find any command matches for type: mp3
[19-07-06 07:59:40.1450] Slim::Player::StreamingController::stop (2118) 00:04:20:27:02:25
[19-07-06 07:59:40.1467] Slim::Player::StreamingController::_Stop (611) Song queue is now 0
[19-07-06 07:59:40.1474] Slim::Player::StreamingController::_setPlayingState (2378) new playing state STOPPED
[19-07-06 07:59:40.1483] Slim::Player::StreamingController::_setStreamingState (2387) new streaming state IDLE
[19-07-06 07:59:40.1499] Slim::Player::StreamingController::resetSongqueue (1797) Song queue is now
                    Last edited by chris49; 2019-07-06, 22:29.

                      Comment

                      • Man in a van
                        Senior Member
                        • Jan 2011
                        • 6902
                        #21
                        Originally posted by chris49
                        1. I need to examine and fix all of my presets and favorites on both radios. Tedious.



                        from the lms webUI Go to Settings > Player > Presets Editor (in the rhs dorpdown)

                          Comment

                          • chris49
                            Junior Member
                            • Jul 2018
                            • 26
                            #22
                            Thanks! That will be a tremendous help!


                            Originally posted by Man in a van
                            from the lms webUI Go to Settings > Player > Presets Editor (in the rhs dorpdown)

                              Comment

                              Previous 1 2 template Next
                              Working...

                              We process personal data about users of our site, through the use of cookies and other technologies, to deliver our services, personalize advertising, and to analyze site activity. We may share certain information about our users with our advertising and analytics partners. For additional details, refer to our Privacy Policy.

                              By clicking "I AGREE" below, you agree to our Privacy Policy and our personal data processing and cookie practices as described therein. You also acknowledge that this forum may be hosted outside your country and you consent to the collection, storage, and processing of your data in the country where this forum is hosted.

                              I Agree