starred/lox-audioserver
1
0
Fork 0
mirror of https://github.com/lox-audioserver/lox-audioserver.git synced 2026-04-25 22:35:53 +03:00
Code Issues 23 Projects Releases 6 Packages Wiki Activity

[GH-ISSUE #84] Playing state not always correctly reflected after starting a playlist #34

New issue
Closed
opened 2026-02-27 19:28:02 +03:00 by kerem · 1 comment
kerem commented 2026-02-27 19:28:02 +03:00
Owner
Copy link

Originally created by @ds556 on GitHub (Nov 19, 2025).
Original GitHub issue: https://github.com/lox-audioserver/lox-audioserver/issues/84

After starting a playlist from the library the playing state sometimes shows as "paused" even though the playlist is being played. It is not consistent and seems to be related to timing. Below a log where it showed as "playing" after starting the first playlist but then got stuck in "paused" after starting the second playlist.

[2025-11-19 06:38:15.663][debug] [LoxoneHttp][appHttp] WS message: audio/18/serviceplay/spotify/nouser/spotify@nouser:playlist:bGlicmFyeTovL3BsYXlsaXN0LzE=/?q&ZW5mb3JjZVVzZXI9dHJ1ZQ
[2025-11-19 06:38:15.663][debug] [LoxoneHttp][appHttp] Command: audio/18/serviceplay/spotify/nouser/spotify@nouser:playlist:bGlicmFyeTovL3BsYXlsaXN0LzE=/?q&ZW5mb3JjZVVzZXI9dHJ1ZQ
[2025-11-19 06:38:15.663][debug] [RequestHandler] Received command: audio/18/serviceplay/spotify/nouser/spotify@nouser:playlist:bGlicmFyeTovL3BsYXlsaXN0LzE=/?q&ZW5mb3JjZVVzZXI9dHJ1ZQ
[2025-11-19 06:38:15.663][debug] [ZoneRuntime][Room] → contentplay ["spotify@nouser:playlist:bGlicmFyeTovL3BsYXlsaXN0LzE=/?q&ZW5mb3JjZVVzZXI9dHJ1ZQ"]
[2025-11-19 06:38:15.663][debug] [MA-Playback][Room] Playback → contentplay, uri=library://playlist/1, start=-, shuffle=true
[2025-11-19 06:38:15.663][debug] [MusicAssistantApi] RPC request player_queues/play_media → params={"queue_id":"RINCON_B8E937806BED02300","media":"library://playlist/1","option":"replace"}
[2025-11-19 06:38:15.676][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250}
[2025-11-19 06:38:15.677][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250}
[2025-11-19 06:38:15.687][debug] [WebSocketNotifier] Queue updated for zone 18 (0 items)
[2025-11-19 06:38:15.687][debug] [MusicAssistantStateMapper][Room] Queue refreshed (0 items)
[2025-11-19 06:38:15.690][debug] [WebSocketNotifier] Queue updated for zone 18 (0 items)
[2025-11-19 06:38:15.690][debug] [MusicAssistantStateMapper][Room] Queue updated (0 items)
[2025-11-19 06:38:15.815][debug] [MusicAssistantApi] RPC request player_queues/shuffle → params={"queue_id":"RINCON_B8E937806BED02300","shuffle_enabled":true}
[2025-11-19 06:38:15.821][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250}
[2025-11-19 06:38:15.901][info] [MA-Playback][Room] Started contentplay → library://playlist/1
[2025-11-19 06:38:15.933][debug] [WebSocketNotifier] Queue updated for zone 18 (131 items)
[2025-11-19 06:38:15.933][debug] [MusicAssistantStateMapper][Room] Queue refreshed (131 items)
[2025-11-19 06:38:15.935][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250}
[2025-11-19 06:38:15.935][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250}
[2025-11-19 06:38:16.021][debug] [MusicAssistantStateMapper][Room] Queue refreshed (131 items)
[2025-11-19 06:38:16.044][debug] [WebSocketNotifier] Queue updated for zone 18 (131 items)
[2025-11-19 06:38:16.045][debug] [MusicAssistantStateMapper][Room] Queue updated (131 items)
[2025-11-19 06:38:16.870][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250}
[2025-11-19 06:38:16.945][debug] [WebSocketNotifier] Queue updated for zone 18 (131 items)
[2025-11-19 06:38:16.946][debug] [MusicAssistantStateMapper][Room] Queue updated (131 items)
[2025-11-19 06:38:19.424][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250}
[2025-11-19 06:38:19.490][debug] [WebSocketNotifier] Queue updated for zone 18 (131 items)
[2025-11-19 06:38:19.490][debug] [MusicAssistantStateMapper][Room] Queue updated (131 items)
[2025-11-19 06:38:46.297][debug] [ServerHeartbeat] Broadcast hw_event (19 total across 1 extensions)
[2025-11-19 06:38:53.231][debug] [LoxoneHttp][appHttp] WS message: audio/18/serviceplay/spotify/nouser/spotify@nouser:playlist:bGlicmFyeTovL3BsYXlsaXN0Lzc=/?q&ZW5mb3JjZVVzZXI9dHJ1ZQ
[2025-11-19 06:38:53.231][debug] [LoxoneHttp][appHttp] Command: audio/18/serviceplay/spotify/nouser/spotify@nouser:playlist:bGlicmFyeTovL3BsYXlsaXN0Lzc=/?q&ZW5mb3JjZVVzZXI9dHJ1ZQ
[2025-11-19 06:38:53.232][debug] [RequestHandler] Received command: audio/18/serviceplay/spotify/nouser/spotify@nouser:playlist:bGlicmFyeTovL3BsYXlsaXN0Lzc=/?q&ZW5mb3JjZVVzZXI9dHJ1ZQ
[2025-11-19 06:38:53.232][debug] [ZoneRuntime][Room] → contentplay ["spotify@nouser:playlist:bGlicmFyeTovL3BsYXlsaXN0Lzc=/?q&ZW5mb3JjZVVzZXI9dHJ1ZQ"]
[2025-11-19 06:38:53.232][debug] [MA-Playback][Room] Playback → contentplay, uri=library://playlist/7, start=-, shuffle=true
[2025-11-19 06:38:53.232][debug] [MusicAssistantApi] RPC request player_queues/play_media → params={"queue_id":"RINCON_B8E937806BED02300","media":"library://playlist/7","option":"replace"}
[2025-11-19 06:38:53.264][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250}
[2025-11-19 06:38:53.270][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250}
[2025-11-19 06:38:53.278][debug] [WebSocketNotifier] Queue updated for zone 18 (0 items)
[2025-11-19 06:38:53.279][debug] [MusicAssistantStateMapper][Room] Queue refreshed (0 items)
[2025-11-19 06:38:53.281][debug] [WebSocketNotifier] Queue updated for zone 18 (0 items)
[2025-11-19 06:38:53.281][debug] [MusicAssistantStateMapper][Room] Queue updated (0 items)
[2025-11-19 06:38:53.387][debug] [MusicAssistantApi] RPC request player_queues/shuffle → params={"queue_id":"RINCON_B8E937806BED02300","shuffle_enabled":true}
[2025-11-19 06:38:53.396][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250}
[2025-11-19 06:38:53.419][info] [MA-Playback][Room] Started contentplay → library://playlist/7
[2025-11-19 06:38:53.430][debug] [WebSocketNotifier] Queue updated for zone 18 (50 items)
[2025-11-19 06:38:53.430][debug] [MusicAssistantStateMapper][Room] Queue refreshed (50 items)
[2025-11-19 06:39:01.369][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250}
[2025-11-19 06:39:01.400][debug] [WebSocketNotifier] Queue updated for zone 18 (50 items)
[2025-11-19 06:39:01.401][debug] [MusicAssistantStateMapper][Room] Queue updated (50 items)
[2025-11-19 06:39:02.523][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250}
[2025-11-19 06:39:02.576][debug] [WebSocketNotifier] Queue updated for zone 18 (50 items)
[2025-11-19 06:39:02.576][debug] [MusicAssistantStateMapper][Room] Queue updated (50 items)
Originally created by @ds556 on GitHub (Nov 19, 2025). Original GitHub issue: https://github.com/lox-audioserver/lox-audioserver/issues/84 After starting a playlist from the library the playing state sometimes shows as "paused" even though the playlist is being played. It is not consistent and seems to be related to timing. Below a log where it showed as "playing" after starting the first playlist but then got stuck in "paused" after starting the second playlist. ``` [2025-11-19 06:38:15.663][debug] [LoxoneHttp][appHttp] WS message: audio/18/serviceplay/spotify/nouser/spotify@nouser:playlist:bGlicmFyeTovL3BsYXlsaXN0LzE=/?q&ZW5mb3JjZVVzZXI9dHJ1ZQ [2025-11-19 06:38:15.663][debug] [LoxoneHttp][appHttp] Command: audio/18/serviceplay/spotify/nouser/spotify@nouser:playlist:bGlicmFyeTovL3BsYXlsaXN0LzE=/?q&ZW5mb3JjZVVzZXI9dHJ1ZQ [2025-11-19 06:38:15.663][debug] [RequestHandler] Received command: audio/18/serviceplay/spotify/nouser/spotify@nouser:playlist:bGlicmFyeTovL3BsYXlsaXN0LzE=/?q&ZW5mb3JjZVVzZXI9dHJ1ZQ [2025-11-19 06:38:15.663][debug] [ZoneRuntime][Room] → contentplay ["spotify@nouser:playlist:bGlicmFyeTovL3BsYXlsaXN0LzE=/?q&ZW5mb3JjZVVzZXI9dHJ1ZQ"] [2025-11-19 06:38:15.663][debug] [MA-Playback][Room] Playback → contentplay, uri=library://playlist/1, start=-, shuffle=true [2025-11-19 06:38:15.663][debug] [MusicAssistantApi] RPC request player_queues/play_media → params={"queue_id":"RINCON_B8E937806BED02300","media":"library://playlist/1","option":"replace"} [2025-11-19 06:38:15.676][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250} [2025-11-19 06:38:15.677][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250} [2025-11-19 06:38:15.687][debug] [WebSocketNotifier] Queue updated for zone 18 (0 items) [2025-11-19 06:38:15.687][debug] [MusicAssistantStateMapper][Room] Queue refreshed (0 items) [2025-11-19 06:38:15.690][debug] [WebSocketNotifier] Queue updated for zone 18 (0 items) [2025-11-19 06:38:15.690][debug] [MusicAssistantStateMapper][Room] Queue updated (0 items) [2025-11-19 06:38:15.815][debug] [MusicAssistantApi] RPC request player_queues/shuffle → params={"queue_id":"RINCON_B8E937806BED02300","shuffle_enabled":true} [2025-11-19 06:38:15.821][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250} [2025-11-19 06:38:15.901][info] [MA-Playback][Room] Started contentplay → library://playlist/1 [2025-11-19 06:38:15.933][debug] [WebSocketNotifier] Queue updated for zone 18 (131 items) [2025-11-19 06:38:15.933][debug] [MusicAssistantStateMapper][Room] Queue refreshed (131 items) [2025-11-19 06:38:15.935][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250} [2025-11-19 06:38:15.935][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250} [2025-11-19 06:38:16.021][debug] [MusicAssistantStateMapper][Room] Queue refreshed (131 items) [2025-11-19 06:38:16.044][debug] [WebSocketNotifier] Queue updated for zone 18 (131 items) [2025-11-19 06:38:16.045][debug] [MusicAssistantStateMapper][Room] Queue updated (131 items) [2025-11-19 06:38:16.870][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250} [2025-11-19 06:38:16.945][debug] [WebSocketNotifier] Queue updated for zone 18 (131 items) [2025-11-19 06:38:16.946][debug] [MusicAssistantStateMapper][Room] Queue updated (131 items) [2025-11-19 06:38:19.424][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250} [2025-11-19 06:38:19.490][debug] [WebSocketNotifier] Queue updated for zone 18 (131 items) [2025-11-19 06:38:19.490][debug] [MusicAssistantStateMapper][Room] Queue updated (131 items) [2025-11-19 06:38:46.297][debug] [ServerHeartbeat] Broadcast hw_event (19 total across 1 extensions) [2025-11-19 06:38:53.231][debug] [LoxoneHttp][appHttp] WS message: audio/18/serviceplay/spotify/nouser/spotify@nouser:playlist:bGlicmFyeTovL3BsYXlsaXN0Lzc=/?q&ZW5mb3JjZVVzZXI9dHJ1ZQ [2025-11-19 06:38:53.231][debug] [LoxoneHttp][appHttp] Command: audio/18/serviceplay/spotify/nouser/spotify@nouser:playlist:bGlicmFyeTovL3BsYXlsaXN0Lzc=/?q&ZW5mb3JjZVVzZXI9dHJ1ZQ [2025-11-19 06:38:53.232][debug] [RequestHandler] Received command: audio/18/serviceplay/spotify/nouser/spotify@nouser:playlist:bGlicmFyeTovL3BsYXlsaXN0Lzc=/?q&ZW5mb3JjZVVzZXI9dHJ1ZQ [2025-11-19 06:38:53.232][debug] [ZoneRuntime][Room] → contentplay ["spotify@nouser:playlist:bGlicmFyeTovL3BsYXlsaXN0Lzc=/?q&ZW5mb3JjZVVzZXI9dHJ1ZQ"] [2025-11-19 06:38:53.232][debug] [MA-Playback][Room] Playback → contentplay, uri=library://playlist/7, start=-, shuffle=true [2025-11-19 06:38:53.232][debug] [MusicAssistantApi] RPC request player_queues/play_media → params={"queue_id":"RINCON_B8E937806BED02300","media":"library://playlist/7","option":"replace"} [2025-11-19 06:38:53.264][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250} [2025-11-19 06:38:53.270][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250} [2025-11-19 06:38:53.278][debug] [WebSocketNotifier] Queue updated for zone 18 (0 items) [2025-11-19 06:38:53.279][debug] [MusicAssistantStateMapper][Room] Queue refreshed (0 items) [2025-11-19 06:38:53.281][debug] [WebSocketNotifier] Queue updated for zone 18 (0 items) [2025-11-19 06:38:53.281][debug] [MusicAssistantStateMapper][Room] Queue updated (0 items) [2025-11-19 06:38:53.387][debug] [MusicAssistantApi] RPC request player_queues/shuffle → params={"queue_id":"RINCON_B8E937806BED02300","shuffle_enabled":true} [2025-11-19 06:38:53.396][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250} [2025-11-19 06:38:53.419][info] [MA-Playback][Room] Started contentplay → library://playlist/7 [2025-11-19 06:38:53.430][debug] [WebSocketNotifier] Queue updated for zone 18 (50 items) [2025-11-19 06:38:53.430][debug] [MusicAssistantStateMapper][Room] Queue refreshed (50 items) [2025-11-19 06:39:01.369][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250} [2025-11-19 06:39:01.400][debug] [WebSocketNotifier] Queue updated for zone 18 (50 items) [2025-11-19 06:39:01.401][debug] [MusicAssistantStateMapper][Room] Queue updated (50 items) [2025-11-19 06:39:02.523][debug] [MusicAssistantApi] RPC request player_queues/items → params={"queue_id":"RINCON_B8E937806BED02300","offset":0,"limit":250} [2025-11-19 06:39:02.576][debug] [WebSocketNotifier] Queue updated for zone 18 (50 items) [2025-11-19 06:39:02.576][debug] [MusicAssistantStateMapper][Room] Queue updated (50 items) ```
kerem closed this issue 2026-02-27 19:28:02 +03:00
kerem commented 2026-02-27 19:28:03 +03:00
Author
Owner
Copy link

@rudyberends commented on GitHub (Jan 4, 2026):

Can you reproduce this using a 4.x version?

<!-- gh-comment-id:3708095940 --> @rudyberends commented on GitHub (Jan 4, 2026): Can you reproduce this using a 4.x version?
Sign in to join this conversation.
No Branch/Tag specified
Branches Tags
beta
dev
dependabot/npm_and_yarn/npm_and_yarn-0a68a62365
v4.0.0-beta.10
v4.0.0-beta.9
v4.0.0-beta.8
v4.0.0-beta.7
v4.0.0-beta.6
v4.0.0-beta.5
v4.0.0-beta.4
v4.0.0-beta.3
v4.0.0-beta.2
v3.1.0
Labels
Clear labels   
bug

   
enhancement

   
pull-request

Mirrored from GitHub Pull Request

  
released

   
released on @beta
No labels
bug
enhancement
pull-request
released
released on @beta
Milestone
Clear milestone
No items
No milestone
Projects
Clear projects
No items
No project
Assignees
Clear assignees
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#34
No description provided.