Home of the Squeezebox™ & Transporter® network music players.
Page 2 of 14 FirstFirst 123412 ... LastLast
Results 11 to 20 of 137
  1. #11
    Senior Member
    Join Date
    Oct 2005
    Location
    Ireland
    Posts
    21,883
    Just failed again with the "pls" URL- same last error message. Try the http://deliciousagony.streamguys1.com URL and see if you get problems.

    Code:
    [22-06-21 15:09:38.1483] Slim::Player::Protocols::HTTP::parseMetadata (286) Icy metadata received: StreamTitle='Elton John - Take Me To The Pilot, from the 1979 album `Live In Moscow`';
    [22-06-21 15:16:25.7084] Slim::Schema::RemotePlaylist::getNextEntry (57) Considering http://deliciousagony.streamguys1.com/ (type: mp3)
    [22-06-21 15:16:25.7089] Slim::Schema::RemotePlaylist::getNextEntry (66) Skipping http://deliciousagony.streamguys1.com/, we want the one after

  2. #12
    Senior Member
    Join Date
    Jan 2010
    Location
    Hertfordshire
    Posts
    9,621
    Quote Originally Posted by bpa View Post
    Retsart the stream. There is something odd in the MP3 stream that causes the Radio to try to play the stream a few times in quick sucession and the track never plays - see red text. Maybe just one-off situation just after stream is started or maybe something else.

    Code:
    [22-06-21 15:08:26.1429] Slim::Schema::RemotePlaylist::getNextEntry (57) Considering http://deliciousagony.streamguys1.com/ (type: mp3)
    [22-06-21 15:08:26.1432] Slim::Schema::RemotePlaylist::getNextEntry (66) Skipping http://deliciousagony.streamguys1.com/, we want the one after
    [22-06-21 15:08:26.1446] Slim::Schema::RemoteTrack::updateOrCreate (388) http://www.deliciousagony.com/listen.pls
    [22-06-21 15:08:26.1448] Slim::Schema::RemoteTrack::setAttributes (346) http://www.deliciousagony.com/listen.pls => {
      RATING => 0,
      REMOTE => 1,
      YEAR   => 0,
      urlmd5 => "4238b1ac8be7014453fcce069df82c40",
    }
    [22-06-21 15:08:26.4273] Slim::Schema::RemoteTrack::updateOrCreate (388) http://www.deliciousagony.com/listen.pls
    [22-06-21 15:08:26.4282] Slim::Schema::RemoteTrack::setAttributes (346) http://www.deliciousagony.com/listen.pls => {
      CONTENT_TYPE => "pls",
      RATING => 0,
      REMOTE => 1,
      YEAR => 0,
      urlmd5 => "4238b1ac8be7014453fcce069df82c40",
    }
    [22-06-21 15:08:26.4306] Slim::Schema::RemoteTrack::updateOrCreate (388) http://deliciousagony.streamguys1.com/
    [22-06-21 15:08:26.4315] Slim::Schema::RemoteTrack::setAttributes (346) http://deliciousagony.streamguys1.com/ => {
      RATING      => 0,
      REMOTE      => 1,
      TITLE       => "Delicious Agony",
      TITLESEARCH => "DELICIOUS AGONY",
      TITLESORT   => "DELICIOUS AGONY",
      YEAR        => 0,
      urlmd5      => "de8197d9018e83b953629e5ec0165a11",
    }
    [22-06-21 15:08:26.4323] Slim::Schema::RemotePlaylist::setTracks (25) http://deliciousagony.streamguys1.com/
    [22-06-21 15:08:26.4331] Slim::Schema::RemoteTrack::updateOrCreate (388) http://deliciousagony.streamguys1.com/
    [22-06-21 15:08:26.4337] Slim::Schema::RemoteTrack::setAttributes (346) http://deliciousagony.streamguys1.com/ => {
      RATING => 0,
      REMOTE => 1,
      YEAR   => 0,
      urlmd5 => "de8197d9018e83b953629e5ec0165a11",
    }
    [22-06-21 15:08:26.6990] Slim::Schema::RemoteTrack::updateOrCreate (388) http://deliciousagony.streamguys1.com/
    [22-06-21 15:08:26.6999] Slim::Schema::RemoteTrack::setAttributes (346) http://deliciousagony.streamguys1.com/ => {
      CONTENT_TYPE => "mp3",
      RATING => 0,
      REMOTE => 1,
      YEAR => 0,
      urlmd5 => "de8197d9018e83b953629e5ec0165a11",
    }
    [22-06-21 15:08:26.7005] Slim::Schema::RemotePlaylist::getNextEntry (57) Considering http://deliciousagony.streamguys1.com/ (type: mp3)
    [22-06-21 15:08:26.7009] Slim::Schema::RemotePlaylist::getNextEntry (74) Next playlist entry is http://deliciousagony.streamguys1.com/
    [22-06-21 15:08:26.7017] Slim::Schema::RemoteTrack::updateOrCreate (388) http://www.deliciousagony.com/listen.pls
    [22-06-21 15:08:26.7024] Slim::Schema::RemoteTrack::setAttributes (346) http://www.deliciousagony.com/listen.pls => {
      BITRATE   => 192_000,
      RATING    => 0,
      REMOTE    => 1,
      VBR_SCALE => undef,
      YEAR      => 0,
      urlmd5    => "4238b1ac8be7014453fcce069df82c40",
    }
    [22-06-21 15:08:26.7028] Slim::Schema::RemoteTrack::setAttributes (366) bitrate: 128000=>192000
    [22-06-21 15:08:26.7037] Slim::Schema::RemotePlaylist::getNextEntry (57) Considering http://deliciousagony.streamguys1.com/ (type: mp3)
    [22-06-21 15:08:26.7040] Slim::Schema::RemotePlaylist::getNextEntry (74) Next playlist entry is http://deliciousagony.streamguys1.com/
    [22-06-21 15:08:26.7084] Slim::Player::Squeezebox::stream_s (1015) Starting decoder with format: m flags: 0x0 autostart: 3 buffer threshold: 72 output threshold: 1 samplesize: ? samplerate: ? endian: ? channels: ?
    [22-06-21 15:08:27.2432] Slim::Schema::RemoteTrack::updateOrCreate (388) http://deliciousagony.streamguys1.com/
    [22-06-21 15:08:27.2439] Slim::Schema::RemoteTrack::setAttributes (346) http://deliciousagony.streamguys1.com/ => {
      CONTENT_TYPE => "mp3",
      RATING => 0,
      REMOTE => 1,
      YEAR => 0,
      urlmd5 => "de8197d9018e83b953629e5ec0165a11",
    }
    [22-06-21 15:08:27.5790] Slim::Player::Protocols::HTTP::parseMetadata (286) Icy metadata received: StreamTitle='Ambrosia - Somewhere I've Never Traveled, from the 1979 album `Wolfgang's Vault: Palladium, Dallas, TX`';
    [22-06-21 15:09:05.7854] Slim::Player::Squeezebox::stream_s (1015) Starting decoder with format: m flags: 0x0 autostart: 3 buffer threshold: 72 output threshold: 1 samplesize: ? samplerate: ? endian: ? channels: ?
    [22-06-21 15:09:11.0086] Slim::Player::Squeezebox::stream_s (1015) Starting decoder with format: m flags: 0x0 autostart: 3 buffer threshold: 72 output threshold: 1 samplesize: ? samplerate: ? endian: ? channels: ?
    [22-06-21 15:09:21.0073] Slim::Player::Squeezebox::stream_s (1015) Starting decoder with format: m flags: 0x0 autostart: 3 buffer threshold: 72 output threshold: 1 samplesize: ? samplerate: ? endian: ? channels: ?
    [22-06-21 15:09:36.0085] Slim::Player::Squeezebox::stream_s (1015) Starting decoder with format: m flags: 0x0 autostart: 3 buffer threshold: 72 output threshold: 1 samplesize: ? samplerate: ? endian: ? channels: ?
    [22-06-21 15:09:36.4133] Slim::Schema::RemoteTrack::updateOrCreate (388) http://deliciousagony.streamguys1.com/
    [22-06-21 15:09:36.4142] Slim::Schema::RemoteTrack::setAttributes (346) http://deliciousagony.streamguys1.com/ => {
      CONTENT_TYPE => "mp3",
      RATING => 0,
      REMOTE => 1,
      YEAR => 0,
      urlmd5 => "de8197d9018e83b953629e5ec0165a11",
    }
    [22-06-21 15:09:36.8218] Slim::Player::Protocols::HTTP::parseMetadata (286) Icy metadata received: StreamTitle='';
    [22-06-21 15:09:37.1262] Slim::Schema::RemoteTrack::get (431) http://www.deliciousagony.com/listen.pls, title->Delicious Agony
    [22-06-21 15:09:38.1483] Slim::Player::Protocols::HTTP::parseMetadata (286) Icy metadata received: StreamTitle='Elton John - Take Me To The Pilot, from the 1979 album `Live In Moscow`';
    Which logging setting caught the red text? I don't see it in my log.

  3. #13
    Senior Member
    Join Date
    Oct 2005
    Location
    Ireland
    Posts
    21,883
    Quote Originally Posted by slartibartfast View Post
    Which logging setting caught the red text? I don't see it in my log.
    Sorry - I had customised the routine when tracking down another problem. If you enable slimproto logging you'll get too much noise.

    I turned on player.source as I noticed "rebuffering" but I've just had another " we want the one after" stoppage and no rebuffering - this time player.source notes player state as STOPPED. Looks like rebuffering is NOT related to stoppage.
    code]
    [22-06-21 15:31:27.4976] Slim::Player::Protocols::HTTP:arseMetadata (286) Icy metadata received: StreamTitle='Heart - Dog & Butterfly, from the 1979 album `Live At The Capitol Theatre`';
    [22-06-21 15:31:27.5489] Slim::Player::Player::_buffering (1163) Buffering... 872 / 73728
    [22-06-21 15:31:27.5491] Slim::Player::Player::_buffering (1164) +output... 226168 / 1058400
    [22-06-21 15:31:27.8497] Slim::Player::Player::_buffering (1163) Buffering... 13061 / 73728
    [22-06-21 15:31:27.8499] Slim::Player::Player::_buffering (1164) +output... 327544 / 1058400
    [22-06-21 15:31:28.1508] Slim::Player::Player::_buffering (1163) Buffering... 34728 / 73728
    [22-06-21 15:31:28.1512] Slim::Player::Player::_buffering (1164) +output... 843640 / 1058400
    [22-06-21 15:31:29.0652] Slim::Player::StreamingController:layerTrackStarted (2222) 00:04:20:27:a4:99
    [22-06-21 15:31:29.0657] Slim::Player::StreamingController::_setPlayingStat e (2399) new playing state PLAYING
    [22-06-21 15:31:29.0661] Slim::Player::StreamingController::_Playing (368) Song 0 has now started playing
    [22-06-21 15:31:29.0670] Slim::Player::StreamingController::_Playing (397) Song queue is now 0
    [22-06-21 15:31:29.3694] Slim::Player::TranscodingHelper::getConvertCommand 2 (490) Error: Didn't find any command matches for type: mp3
    [22-06-21 15:35:12.3899] Slim::Player::Protocols::HTTP:arseMetadata (286) Icy metadata received: StreamTitle='Kansas - On The Other Side (Live), from the 1979 album `The Kansas Boxed Set`';
    [22-06-21 15:41:40.8735] Slim::Player::StreamingController:layerReadyToStream (2240) 00:04:20:27:a4:99
    [22-06-21 15:41:40.8737] Slim::Player::StreamingController::_setStreamingSt ate (2408) new streaming state IDLE
    [22-06-21 15:41:40.8741] Slim::Schema::RemotePlaylist::getNextEntry (57) Considering http://deliciousagony.streamguys1.com/ (type: mp3)
    [22-06-21 15:41:40.8742] Slim::Schema::RemotePlaylist::getNextEntry (66) Skipping http://deliciousagony.streamguys1.com/, we want the one after
    [22-06-21 15:41:40.8743] Slim::Player::StreamingController::nextsong (907) The next song is number 0, was 0
    [22-06-21 15:41:50.8503] Slim::Player::StreamingController:layerStopped (2199) 00:04:20:27:a4:99
    [22-06-21 15:41:50.8507] Slim::Player::StreamingController::_setPlayingStat e (2399) new playing state STOPPED
    [22-06-21 15:41:51.8549] Slim::Player::TranscodingHelper::getConvertCommand 2 (490) Error: Didn't find any command matches for type: mp3

  4. #14
    Senior Member
    Join Date
    Jan 2010
    Location
    Hertfordshire
    Posts
    9,621
    Quote Originally Posted by bpa View Post
    Just failed again with the "pls" URL- same last error message. Try the http://deliciousagony.streamguys1.com URL and see if you get problems.

    Code:
    [22-06-21 15:09:38.1483] Slim::Player::Protocols::HTTP::parseMetadata (286) Icy metadata received: StreamTitle='Elton John - Take Me To The Pilot, from the 1979 album `Live In Moscow`';
    [22-06-21 15:16:25.7084] Slim::Schema::RemotePlaylist::getNextEntry (57) Considering http://deliciousagony.streamguys1.com/ (type: mp3)
    [22-06-21 15:16:25.7089] Slim::Schema::RemotePlaylist::getNextEntry (66) Skipping http://deliciousagony.streamguys1.com/, we want the one after
    Server log using http://deliciousagony.streamguys1.com
    Attached Files Attached Files

  5. #15
    Senior Member
    Join Date
    Jan 2010
    Location
    Hertfordshire
    Posts
    9,621
    Quote Originally Posted by bpa View Post
    Just failed again with the "pls" URL- same last error message. Try the http://deliciousagony.streamguys1.com URL and see if you get problems.

    Code:
    [22-06-21 15:09:38.1483] Slim::Player::Protocols::HTTP::parseMetadata (286) Icy metadata received: StreamTitle='Elton John - Take Me To The Pilot, from the 1979 album `Live In Moscow`';
    [22-06-21 15:16:25.7084] Slim::Schema::RemotePlaylist::getNextEntry (57) Considering http://deliciousagony.streamguys1.com/ (type: mp3)
    [22-06-21 15:16:25.7089] Slim::Schema::RemotePlaylist::getNextEntry (66) Skipping http://deliciousagony.streamguys1.com/, we want the one after
    Sorry the last couple of lines were missing in the first upload. I don't understand the "About to close socket". It occurs earlier with no issue bt is also the final message.
    Attached Files Attached Files

  6. #16
    Senior Member
    Join Date
    Oct 2005
    Location
    Ireland
    Posts
    21,883
    Quote Originally Posted by slartibartfast View Post
    Are you usingf proxied streaming ?
    Did it fail ?

  7. #17
    Senior Member
    Join Date
    Jan 2010
    Location
    Hertfordshire
    Posts
    9,621
    Quote Originally Posted by bpa View Post
    Are you usingf proxied streaming ?
    Did it fail ?
    No I used direct streaming. It failed after a couple of tracks, the last two lines were missing from the log so I uploaded it again in a post you probably missed.

  8. #18
    Senior Member
    Join Date
    Oct 2005
    Location
    Ireland
    Posts
    21,883
    Quote Originally Posted by slartibartfast View Post
    No I used direct streaming. It failed after a couple of tracks, the last two lines were missing from the log so I uploaded it again in a post you probably missed.
    I can't be sure but the the messages look as if player is using proxied and woudl explain the "socket" message.

    I moved onto just player.source logging as I think at end of a track, the stream is sending chunks with zero bytes which LMS interprets as "end of stream" and so looks for next entry in playlist. The "repeat " setting may fix it.

    Also the stream behaviours may has changed since my testing earlier as if earlier it was one big pre-recorded session and now the stream is more fragmented. I've seen similar with US station where more ads/breaks during normal hours.

  9. #19
    Senior Member
    Join Date
    Oct 2005
    Location
    Ireland
    Posts
    21,883
    Playing "direct" on Radio

    I got another rebuffer near track transition, underrun where the stream sends NO data and so connection has to be remade - like following scenario

    The other stop audio situation is logged as follows. It is where LMS thinks the stream has ended ( Streaming -> PlayerReadytoStream) and wants to go onto "NextIfMore" - and there is no more so stop. Need to look around to see if cause of stop can be better determined.
    Code:
    [22-06-21 17:21:07.5761] Slim::Player::StreamingController::_eventAction (272) 00:04:20:27:a4:99: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
    [22-06-21 17:21:08.5827] Slim::Player::StreamingController::playerReadyToStream (2240) 00:04:20:27:a4:99
    [22-06-21 17:21:08.5833] Slim::Player::StreamingController::_eventAction (272) 00:04:20:27:a4:99: ReadyToStream in PLAYING-STREAMING -> Slim::Player::StreamingController::_NextIfMore
    [22-06-21 17:21:08.5837] Slim::Player::StreamingController::_setStreamingState (2408) new streaming state IDLE
    [22-06-21 17:21:08.5842] Slim::Player::StreamingController::nextsong (907) The next song is number 0, was 0

  10. #20
    Senior Member
    Join Date
    Jan 2010
    Location
    Hertfordshire
    Posts
    9,621
    Quote Originally Posted by bpa View Post
    I can't be sure but the the messages look as if player is using proxied and woudl explain the "socket" message.

    I moved onto just player.source logging as I think at end of a track, the stream is sending chunks with zero bytes which LMS interprets as "end of stream" and so looks for next entry in playlist. The "repeat " setting may fix it.

    Also the stream behaviours may has changed since my testing earlier as if earlier it was one big pre-recorded session and now the stream is more fragmented. I've seen similar with US station where more ads/breaks during normal hours.
    It is set to direct streaming, I did try proxied yesterday but it didn't change the behaviour. I'll set player.source logging and try again.

    Sent from my Pixel 3a using Tapatalk

Posting Permissions

  • You may not post new threads
  • You may not post replies
  • You may not post attachments
  • You may not edit your posts
  •