[GH-ISSUE #144] [4.x] Resume not working after some time #81

Closed
opened 2026-02-27 19:28:18 +03:00 by kerem · 2 comments
Owner

Originally created by @Re4DeR on GitHub (Jan 27, 2026).
Original GitHub issue: https://github.com/lox-audioserver/lox-audioserver/issues/144

Hi, when I stop the music and try to resume after a few minutes, it won’t play(silent). If I choose another song, it works fine.

[2026-01-27T18:35:00.460Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/recent] command received
should be playing but it is silent

[2026-01-27T18:41:31.940Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/play] command received
same

[2026-01-27T18:34:40.214Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/resume] command received
[2026-01-27T18:34:40.215Z][DEBUG][Zones|Manager] [action=resume outputCount=1 outputTypes=["snapcast"] zoneId=8] dispatchOutputs
[2026-01-27T18:34:50.888Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/queueplus] command received
[2026-01-27T18:34:56.946Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/queueplus] command received
[2026-01-27T18:35:00.460Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/recent] command received
[2026-01-27T18:35:06.669Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/serviceplay/spotify/Pane/spotify@Pane:track:5wBTPxJisiSQz9vkneZSlr/noshuffle/?q&ZW5mb3JjZVVzZXI9dHJ1ZQ] command received
[2026-01-27T18:35:06.811Z][DEBUG][Content|Spotify:Pane] [scope="playlist-read-private playlist-read-collaborative user-follow-read playlist-modify-private user-read-email user-read-private app-remote-control streaming user-follow-modify user-modify-playback-state user-library-read user-library-modify playlist-modify-public user-read-playback-state user-read-currently-playing"] spotify token refreshed
[2026-01-27T18:35:06.940Z][INFO][Zones|Manager] [hasParentContext=false normalizedTarget=spotify:track:5wBTPxJisiSQz9vkneZSlr resolvedTarget=spotify@Pane:track:5wBTPxJisiSQz9vkneZSlr station="" type=serviceplay uri=spotify@Pane:track:5wBTPxJisiSQz9vkneZSlr zoneId=8] playContent
[2026-01-27T18:35:07.116Z][DEBUG][Zones|Manager] [expandedCount=1 isAppleMusic=false isMusicAssistant=false queueSourcePath=spotify@Pane:track:5wBTPxJisiSQz9vkneZSlr resolvedTarget=spotify@Pane:track:5wBTPxJisiSQz9vkneZSlr zoneId=8] queue build resolved
[2026-01-27T18:35:07.117Z][DEBUG][Zones|Manager] [authority=spotify items=1 startIndex=0 target=spotify:track:5wBTPxJisiSQz9vkneZSlr zoneId=8] queue rebuilt
[2026-01-27T18:35:07.182Z][DEBUG][Content|Spotify:Pane] [scope="playlist-read-private playlist-read-collaborative user-follow-read playlist-modify-private user-read-email user-read-private app-remote-control streaming user-follow-modify user-modify-playback-state user-library-read user-library-modify playlist-modify-public user-read-playback-state user-read-currently-playing"] spotify token refreshed
[2026-01-27T18:35:07.593Z][DEBUG][Zones|Manager] [audiopath=spotify:track:5wBTPxJisiSQz9vkneZSlr connectEnabled=false hasPlaybackSource=true playbackKind=pipe queueSize=1 zoneId=8] startQueuePlayback spotify
[2026-01-27T18:35:07.595Z][INFO][Audio|Manager] [hasStream=true label=spotify sourceKind=pipe zoneId=8] startWithResolvedSource
[2026-01-27T18:35:07.596Z][INFO][Audio|Manager] [handoff=false kind=pipe profiles=["pcm"] zoneId=8] starting audio engine
[2026-01-27T18:35:07.598Z][INFO][Audio|Session] [maxBufferBytes=262144 outputBitDepth=16 outputChannels=2 outputSampleRate=48000 profile=pcm targetLeadMs=1000 zoneId=8] audio session buffer config
[2026-01-27T18:35:07.599Z][DEBUG][Audio|Session] [args=["-hide_banner","-loglevel","error","-fflags","nobuffer","-re","-f","s16le","-ar","44100","-ac","2","-i","pipe:0","-vn","-acodec","pcm_s16le","-ar","48000","-ac","2","-af","aresample=resampler=soxr:precision=28:cutoff=0.97:async=1","-f","s16le","pipe:1"] inputChannels=2 inputFormat=s16le inputSampleRate=44100 outputBitDepth=16 outputChannels=2 outputSampleRate=48000 profile=pcm zoneId=8] spawning ffmpeg (pipe stream)
[2026-01-27T18:35:07.607Z][INFO][Audio|Engine] [profile=pcm source=pipe zoneId=8] audio session started
[2026-01-27T18:35:07.608Z][INFO][Audio|Manager] [source=spotify stream=8-7e00ce9a-6a4d-493e-87b4-586beb901448 title="What I Need" zoneId=8] playback started
[2026-01-27T18:35:07.610Z][DEBUG][Zones|Manager] [action=play outputCount=1 outputTypes=["snapcast"] zoneId=8] dispatchOutputs
[2026-01-27T18:35:07.612Z][DEBUG][Audio|Session] [label=snapcast profile=pcm subscriberCount=1 zoneId=8] audio subscriber attached
[2026-01-27T18:35:07.613Z][DEBUG][Output|Snapcast] [channels=2 clientIds=["snapclient-room"] codec=pcm_s16le sampleRate=48000 streamId=8 zoneId=8] serving PCM to Snapclients (ws)
[2026-01-27T18:35:07.614Z][INFO][Http|Snapcast] [clientId=snapclient-room streamId=8] snapcast client reassigned to stream
[2026-01-27T18:35:07.615Z][INFO][Http|Snapcast] [bitDepth=16 channels=2 clientIds=["snapclient-room"] sampleRate=48000 streamId=8 zoneId=8] snapcast stream registered
[2026-01-27T18:35:07.616Z][DEBUG][Http|Snapcast] [count=1 streamId=8] snapcast pushed settings to clients
[2026-01-27T18:35:07.743Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/recent] command received
[2026-01-27T18:35:08.396Z][DEBUG][Audio|@lox-audioserver/node-librespot] [deviceId=lox-zone-8-stream metricMessage=undefined metricName=first_pcm_ms metricValueMs=802 sessionId=stream-18 uri=spotify:track:5wBTPxJisiSQz9vkneZSlr] librespot metric event
[2026-01-27T18:35:08.399Z][INFO][Audio|Session] [bytes=512 channels=2 format=s16le sampleRate=44100 zoneId=8] pipe source first chunk
[2026-01-27T18:35:13.526Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/volume/+1] command received
[2026-01-27T18:35:13.704Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/volume/+1] command received
[2026-01-27T18:35:13.935Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/volume/+1] command received
[2026-01-27T18:35:20.558Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/volume/-1] command received
[2026-01-27T18:35:20.764Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/volume/-1] command received
[2026-01-27T18:35:21.147Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/volume/-1] command received
[2026-01-27T18:35:22.611Z][DEBUG][Audio|Player:8] [timeoutMs=15000 zoneId=8] ticker started without first chunk
[2026-01-27T18:38:03.172Z][DEBUG][LoxoneHttp|Processor] [command=audio/cfg/miniservertime/1769539084] command received
[2026-01-27T18:39:12.423Z][INFO][Audio|Engine] [zoneId=8] audio session stopped
[2026-01-27T18:39:12.424Z][DEBUG][Audio|Manager] [source=spotify zoneId=8] playback stopped
[2026-01-27T18:39:12.425Z][DEBUG][Zones|Manager] [action=stop outputCount=1 outputTypes=["snapcast"] zoneId=8] dispatchOutputs
[2026-01-27T18:39:12.426Z][WARN][Zones|Manager] [reason="spotify end_of_track before pcm" source=output zoneId=8] playback error
[2026-01-27T18:39:12.427Z][DEBUG][Audio|Session] [label=snapcast profile=pcm subscriberCount=0 zoneId=8] audio subscriber detached
[2026-01-27T18:39:12.431Z][DEBUG][Audio|Session] [zoneId=8] ffmpeg stdout closed
[2026-01-27T18:39:12.432Z][INFO][Audio|Session] [bufferedBytes=251672 code=255 earlyExit=false runMs=244833 signal=null stderr=undefined stderrAt=undefined subscribers=0 totalBytes=45961356 zoneId=8] ffmpeg exited
[2026-01-27T18:41:31.940Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/play] command received
[2026-01-27T18:41:31.941Z][DEBUG][Zones|Manager] [action=resume outputCount=1 outputTypes=["snapcast"] zoneId=8] dispatchOutputs
Originally created by @Re4DeR on GitHub (Jan 27, 2026). Original GitHub issue: https://github.com/lox-audioserver/lox-audioserver/issues/144 Hi, when I stop the music and try to resume after a few minutes, it won’t play(silent). If I choose another song, it works fine. [2026-01-27T18:35:00.460Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/recent] command received should be playing but it is silent [2026-01-27T18:41:31.940Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/play] command received same ``` [2026-01-27T18:34:40.214Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/resume] command received [2026-01-27T18:34:40.215Z][DEBUG][Zones|Manager] [action=resume outputCount=1 outputTypes=["snapcast"] zoneId=8] dispatchOutputs [2026-01-27T18:34:50.888Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/queueplus] command received [2026-01-27T18:34:56.946Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/queueplus] command received [2026-01-27T18:35:00.460Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/recent] command received [2026-01-27T18:35:06.669Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/serviceplay/spotify/Pane/spotify@Pane:track:5wBTPxJisiSQz9vkneZSlr/noshuffle/?q&ZW5mb3JjZVVzZXI9dHJ1ZQ] command received [2026-01-27T18:35:06.811Z][DEBUG][Content|Spotify:Pane] [scope="playlist-read-private playlist-read-collaborative user-follow-read playlist-modify-private user-read-email user-read-private app-remote-control streaming user-follow-modify user-modify-playback-state user-library-read user-library-modify playlist-modify-public user-read-playback-state user-read-currently-playing"] spotify token refreshed [2026-01-27T18:35:06.940Z][INFO][Zones|Manager] [hasParentContext=false normalizedTarget=spotify:track:5wBTPxJisiSQz9vkneZSlr resolvedTarget=spotify@Pane:track:5wBTPxJisiSQz9vkneZSlr station="" type=serviceplay uri=spotify@Pane:track:5wBTPxJisiSQz9vkneZSlr zoneId=8] playContent [2026-01-27T18:35:07.116Z][DEBUG][Zones|Manager] [expandedCount=1 isAppleMusic=false isMusicAssistant=false queueSourcePath=spotify@Pane:track:5wBTPxJisiSQz9vkneZSlr resolvedTarget=spotify@Pane:track:5wBTPxJisiSQz9vkneZSlr zoneId=8] queue build resolved [2026-01-27T18:35:07.117Z][DEBUG][Zones|Manager] [authority=spotify items=1 startIndex=0 target=spotify:track:5wBTPxJisiSQz9vkneZSlr zoneId=8] queue rebuilt [2026-01-27T18:35:07.182Z][DEBUG][Content|Spotify:Pane] [scope="playlist-read-private playlist-read-collaborative user-follow-read playlist-modify-private user-read-email user-read-private app-remote-control streaming user-follow-modify user-modify-playback-state user-library-read user-library-modify playlist-modify-public user-read-playback-state user-read-currently-playing"] spotify token refreshed [2026-01-27T18:35:07.593Z][DEBUG][Zones|Manager] [audiopath=spotify:track:5wBTPxJisiSQz9vkneZSlr connectEnabled=false hasPlaybackSource=true playbackKind=pipe queueSize=1 zoneId=8] startQueuePlayback spotify [2026-01-27T18:35:07.595Z][INFO][Audio|Manager] [hasStream=true label=spotify sourceKind=pipe zoneId=8] startWithResolvedSource [2026-01-27T18:35:07.596Z][INFO][Audio|Manager] [handoff=false kind=pipe profiles=["pcm"] zoneId=8] starting audio engine [2026-01-27T18:35:07.598Z][INFO][Audio|Session] [maxBufferBytes=262144 outputBitDepth=16 outputChannels=2 outputSampleRate=48000 profile=pcm targetLeadMs=1000 zoneId=8] audio session buffer config [2026-01-27T18:35:07.599Z][DEBUG][Audio|Session] [args=["-hide_banner","-loglevel","error","-fflags","nobuffer","-re","-f","s16le","-ar","44100","-ac","2","-i","pipe:0","-vn","-acodec","pcm_s16le","-ar","48000","-ac","2","-af","aresample=resampler=soxr:precision=28:cutoff=0.97:async=1","-f","s16le","pipe:1"] inputChannels=2 inputFormat=s16le inputSampleRate=44100 outputBitDepth=16 outputChannels=2 outputSampleRate=48000 profile=pcm zoneId=8] spawning ffmpeg (pipe stream) [2026-01-27T18:35:07.607Z][INFO][Audio|Engine] [profile=pcm source=pipe zoneId=8] audio session started [2026-01-27T18:35:07.608Z][INFO][Audio|Manager] [source=spotify stream=8-7e00ce9a-6a4d-493e-87b4-586beb901448 title="What I Need" zoneId=8] playback started [2026-01-27T18:35:07.610Z][DEBUG][Zones|Manager] [action=play outputCount=1 outputTypes=["snapcast"] zoneId=8] dispatchOutputs [2026-01-27T18:35:07.612Z][DEBUG][Audio|Session] [label=snapcast profile=pcm subscriberCount=1 zoneId=8] audio subscriber attached [2026-01-27T18:35:07.613Z][DEBUG][Output|Snapcast] [channels=2 clientIds=["snapclient-room"] codec=pcm_s16le sampleRate=48000 streamId=8 zoneId=8] serving PCM to Snapclients (ws) [2026-01-27T18:35:07.614Z][INFO][Http|Snapcast] [clientId=snapclient-room streamId=8] snapcast client reassigned to stream [2026-01-27T18:35:07.615Z][INFO][Http|Snapcast] [bitDepth=16 channels=2 clientIds=["snapclient-room"] sampleRate=48000 streamId=8 zoneId=8] snapcast stream registered [2026-01-27T18:35:07.616Z][DEBUG][Http|Snapcast] [count=1 streamId=8] snapcast pushed settings to clients [2026-01-27T18:35:07.743Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/recent] command received [2026-01-27T18:35:08.396Z][DEBUG][Audio|@lox-audioserver/node-librespot] [deviceId=lox-zone-8-stream metricMessage=undefined metricName=first_pcm_ms metricValueMs=802 sessionId=stream-18 uri=spotify:track:5wBTPxJisiSQz9vkneZSlr] librespot metric event [2026-01-27T18:35:08.399Z][INFO][Audio|Session] [bytes=512 channels=2 format=s16le sampleRate=44100 zoneId=8] pipe source first chunk [2026-01-27T18:35:13.526Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/volume/+1] command received [2026-01-27T18:35:13.704Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/volume/+1] command received [2026-01-27T18:35:13.935Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/volume/+1] command received [2026-01-27T18:35:20.558Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/volume/-1] command received [2026-01-27T18:35:20.764Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/volume/-1] command received [2026-01-27T18:35:21.147Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/volume/-1] command received [2026-01-27T18:35:22.611Z][DEBUG][Audio|Player:8] [timeoutMs=15000 zoneId=8] ticker started without first chunk [2026-01-27T18:38:03.172Z][DEBUG][LoxoneHttp|Processor] [command=audio/cfg/miniservertime/1769539084] command received [2026-01-27T18:39:12.423Z][INFO][Audio|Engine] [zoneId=8] audio session stopped [2026-01-27T18:39:12.424Z][DEBUG][Audio|Manager] [source=spotify zoneId=8] playback stopped [2026-01-27T18:39:12.425Z][DEBUG][Zones|Manager] [action=stop outputCount=1 outputTypes=["snapcast"] zoneId=8] dispatchOutputs [2026-01-27T18:39:12.426Z][WARN][Zones|Manager] [reason="spotify end_of_track before pcm" source=output zoneId=8] playback error [2026-01-27T18:39:12.427Z][DEBUG][Audio|Session] [label=snapcast profile=pcm subscriberCount=0 zoneId=8] audio subscriber detached [2026-01-27T18:39:12.431Z][DEBUG][Audio|Session] [zoneId=8] ffmpeg stdout closed [2026-01-27T18:39:12.432Z][INFO][Audio|Session] [bufferedBytes=251672 code=255 earlyExit=false runMs=244833 signal=null stderr=undefined stderrAt=undefined subscribers=0 totalBytes=45961356 zoneId=8] ffmpeg exited [2026-01-27T18:41:31.940Z][DEBUG][LoxoneHttp|Processor] [command=audio/8/play] command received [2026-01-27T18:41:31.941Z][DEBUG][Zones|Manager] [action=resume outputCount=1 outputTypes=["snapcast"] zoneId=8] dispatchOutputs ```
kerem 2026-02-27 19:28:18 +03:00
  • closed this issue
  • added the
    bug
    label
Author
Owner

@rudyberends commented on GitHub (Feb 1, 2026):

Could you try again using beta4?

<!-- gh-comment-id:3830687230 --> @rudyberends commented on GitHub (Feb 1, 2026): Could you try again using beta4?
Author
Owner

@Re4DeR commented on GitHub (Feb 7, 2026):

yeap. it looks fixed to me now. Thanks!

<!-- gh-comment-id:3865163061 --> @Re4DeR commented on GitHub (Feb 7, 2026): yeap. it looks fixed to me now. Thanks!
Sign in to join this conversation.
No milestone
No project
No assignees
1 participant
Notifications
Due date
The due date is invalid or out of range. Please use the format "yyyy-mm-dd".

No due date set.

Dependencies

No dependencies set.

Reference
starred/lox-audioserver#81
No description provided.