-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Fri 2026-04-10 12:59:01 CEST. -- Apr 10 12:58:00 primo-plus volumio[829]: Searching plugin music_service/qobuz Apr 10 12:58:00 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 10 12:58:00 primo-plus volumio[829]: info: searchQOBUZUri took 810 milliseconds Apr 10 12:58:00 primo-plus volumio[829]: info: All search sources collected, pushing search results Apr 10 12:58:01 primo-plus volumio[829]: Searching plugin music_service/qobuz Apr 10 12:58:01 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 10 12:58:01 primo-plus volumio[829]: info: searchQOBUZUri took 1112 milliseconds Apr 10 12:58:01 primo-plus volumio[829]: info: All search sources collected, pushing search results Apr 10 12:58:02 primo-plus volumio[829]: Searching plugin music_service/qobuz Apr 10 12:58:02 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 10 12:58:02 primo-plus volumio[829]: info: searchQOBUZUri took 1107 milliseconds Apr 10 12:58:02 primo-plus volumio[829]: info: All search sources collected, pushing search results Apr 10 12:58:03 primo-plus volumio[829]: info: searchQOBUZUri took 1144 milliseconds Apr 10 12:58:03 primo-plus volumio[829]: info: All search sources collected, pushing search results Apr 10 12:58:14 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Apr 10 12:58:14 primo-plus volumio[829]: info: handleBrowseUri took 343 milliseconds Apr 10 12:58:14 primo-plus volumio[829]: info: Preload queue cleared Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639256 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639257 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639258 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639259 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639260 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639261 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639262 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639263 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639264 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639265 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639266 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639267 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639268 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639269 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639270 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639271 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639272 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639273 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639274 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639275 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639276 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639277 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639278 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639279 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639280 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639281 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639282 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639283 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639284 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639285 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639286 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639287 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639288 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639289 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639290 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639291 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639292 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639293 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639294 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639295 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639296 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639297 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639298 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639299 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639300 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639301 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639302 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639303 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639304 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639305 Apr 10 12:58:14 primo-plus volumio[829]: info: Preloading song: qobuz://song/3639306 Apr 10 12:58:14 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639256 in service qobuz Apr 10 12:58:14 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:14 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639257 in service qobuz Apr 10 12:58:14 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:15 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639258 in service qobuz Apr 10 12:58:15 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:15 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639259 in service qobuz Apr 10 12:58:15 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:15 primo-plus volumio[829]: info: Executing endpoint getSimilarAlbums Apr 10 12:58:15 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 10 12:58:15 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639260 in service qobuz Apr 10 12:58:15 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:15 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639261 in service qobuz Apr 10 12:58:15 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:15 primo-plus volumio[829]: info: Executing endpoint metavolumio Apr 10 12:58:15 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 10 12:58:15 primo-plus volumio[829]: info: Executing endpoint metavolumio Apr 10 12:58:15 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 10 12:58:15 primo-plus volumio[829]: info: explodeUri took 249 milliseconds Apr 10 12:58:15 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639262 in service qobuz Apr 10 12:58:15 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:15 primo-plus volumio[829]: info: explodeUri took 238 milliseconds Apr 10 12:58:15 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639263 in service qobuz Apr 10 12:58:15 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:15 primo-plus volumio[829]: info: explodeUri took 356 milliseconds Apr 10 12:58:15 primo-plus volumio[829]: info: explodeUri took 264 milliseconds Apr 10 12:58:15 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639264 in service qobuz Apr 10 12:58:15 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:15 primo-plus volumio[829]: info: explodeUri took 234 milliseconds Apr 10 12:58:15 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639265 in service qobuz Apr 10 12:58:15 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:15 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639266 in service qobuz Apr 10 12:58:15 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:15 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639267 in service qobuz Apr 10 12:58:15 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:15 primo-plus volumio[829]: info: explodeUri took 253 milliseconds Apr 10 12:58:15 primo-plus volumio[829]: info: explodeUri took 317 milliseconds Apr 10 12:58:15 primo-plus volumio[829]: info: explodeUri took 226 milliseconds Apr 10 12:58:15 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639268 in service qobuz Apr 10 12:58:15 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:15 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639269 in service qobuz Apr 10 12:58:15 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:15 primo-plus volumio[829]: info: explodeUri took 205 milliseconds Apr 10 12:58:15 primo-plus volumio[829]: info: explodeUri took 279 milliseconds Apr 10 12:58:15 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639270 in service qobuz Apr 10 12:58:15 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:15 primo-plus volumio[829]: info: explodeUri took 219 milliseconds Apr 10 12:58:15 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639271 in service qobuz Apr 10 12:58:15 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:15 primo-plus volumio[829]: info: explodeUri took 221 milliseconds Apr 10 12:58:15 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639272 in service qobuz Apr 10 12:58:15 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:15 primo-plus volumio[829]: info: explodeUri took 233 milliseconds Apr 10 12:58:15 primo-plus volumio[829]: info: explodeUri took 196 milliseconds Apr 10 12:58:15 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639273 in service qobuz Apr 10 12:58:15 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:15 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639274 in service qobuz Apr 10 12:58:15 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:15 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639275 in service qobuz Apr 10 12:58:15 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:15 primo-plus volumio[829]: info: explodeUri took 272 milliseconds Apr 10 12:58:15 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639276 in service qobuz Apr 10 12:58:15 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:15 primo-plus volumio[829]: info: explodeUri took 249 milliseconds Apr 10 12:58:15 primo-plus volumio[829]: info: explodeUri took 207 milliseconds Apr 10 12:58:15 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639277 in service qobuz Apr 10 12:58:15 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:15 primo-plus volumio[829]: info: explodeUri took 218 milliseconds Apr 10 12:58:16 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639278 in service qobuz Apr 10 12:58:16 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:16 primo-plus volumio[829]: info: explodeUri took 212 milliseconds Apr 10 12:58:16 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639279 in service qobuz Apr 10 12:58:16 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:16 primo-plus volumio[829]: info: explodeUri took 219 milliseconds Apr 10 12:58:16 primo-plus volumio[829]: info: explodeUri took 196 milliseconds Apr 10 12:58:16 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639280 in service qobuz Apr 10 12:58:16 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:16 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639281 in service qobuz Apr 10 12:58:16 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:16 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639282 in service qobuz Apr 10 12:58:16 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:16 primo-plus volumio[829]: info: explodeUri took 263 milliseconds Apr 10 12:58:16 primo-plus volumio[829]: info: explodeUri took 221 milliseconds Apr 10 12:58:16 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639283 in service qobuz Apr 10 12:58:16 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:16 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639284 in service qobuz Apr 10 12:58:16 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:16 primo-plus volumio[829]: info: explodeUri took 235 milliseconds Apr 10 12:58:16 primo-plus volumio[829]: info: explodeUri took 282 milliseconds Apr 10 12:58:16 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639285 in service qobuz Apr 10 12:58:16 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:16 primo-plus volumio[829]: info: explodeUri took 215 milliseconds Apr 10 12:58:16 primo-plus volumio[829]: info: explodeUri took 190 milliseconds Apr 10 12:58:16 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639286 in service qobuz Apr 10 12:58:16 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:16 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639287 in service qobuz Apr 10 12:58:16 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:16 primo-plus volumio[829]: info: explodeUri took 222 milliseconds Apr 10 12:58:16 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639288 in service qobuz Apr 10 12:58:16 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:16 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639289 in service qobuz Apr 10 12:58:16 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:16 primo-plus volumio[829]: info: explodeUri took 214 milliseconds Apr 10 12:58:16 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639290 in service qobuz Apr 10 12:58:16 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:16 primo-plus volumio[829]: info: explodeUri took 316 milliseconds Apr 10 12:58:16 primo-plus volumio[829]: info: explodeUri took 225 milliseconds Apr 10 12:58:16 primo-plus volumio[829]: info: explodeUri took 198 milliseconds Apr 10 12:58:16 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639291 in service qobuz Apr 10 12:58:16 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:16 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639292 in service qobuz Apr 10 12:58:16 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:16 primo-plus volumio[829]: info: explodeUri took 219 milliseconds Apr 10 12:58:16 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639293 in service qobuz Apr 10 12:58:16 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:16 primo-plus volumio[829]: info: explodeUri took 217 milliseconds Apr 10 12:58:16 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639294 in service qobuz Apr 10 12:58:16 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:16 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639295 in service qobuz Apr 10 12:58:16 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:16 primo-plus volumio[829]: info: explodeUri took 260 milliseconds Apr 10 12:58:16 primo-plus volumio[829]: info: explodeUri took 213 milliseconds Apr 10 12:58:16 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639296 in service qobuz Apr 10 12:58:16 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:16 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639297 in service qobuz Apr 10 12:58:16 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:17 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639298 in service qobuz Apr 10 12:58:17 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:17 primo-plus volumio[829]: info: explodeUri took 323 milliseconds Apr 10 12:58:17 primo-plus volumio[829]: info: explodeUri took 283 milliseconds Apr 10 12:58:17 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639299 in service qobuz Apr 10 12:58:17 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:17 primo-plus volumio[829]: info: explodeUri took 219 milliseconds Apr 10 12:58:17 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639300 in service qobuz Apr 10 12:58:17 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:17 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639301 in service qobuz Apr 10 12:58:17 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:17 primo-plus volumio[829]: info: explodeUri took 267 milliseconds Apr 10 12:58:17 primo-plus volumio[829]: info: explodeUri took 222 milliseconds Apr 10 12:58:17 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639302 in service qobuz Apr 10 12:58:17 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:17 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639303 in service qobuz Apr 10 12:58:17 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:17 primo-plus volumio[829]: info: explodeUri took 459 milliseconds Apr 10 12:58:17 primo-plus volumio[829]: info: explodeUri took 274 milliseconds Apr 10 12:58:17 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639304 in service qobuz Apr 10 12:58:17 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:17 primo-plus volumio[829]: info: explodeUri took 207 milliseconds Apr 10 12:58:17 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639305 in service qobuz Apr 10 12:58:17 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:17 primo-plus volumio[829]: info: Exploding uri qobuz://song/3639306 in service qobuz Apr 10 12:58:17 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:17 primo-plus volumio[829]: info: explodeUri took 267 milliseconds Apr 10 12:58:17 primo-plus volumio[829]: info: explodeUri took 372 milliseconds Apr 10 12:58:17 primo-plus volumio[829]: info: explodeUri took 256 milliseconds Apr 10 12:58:17 primo-plus volumio[829]: info: explodeUri took 249 milliseconds Apr 10 12:58:17 primo-plus volumio[829]: info: explodeUri took 195 milliseconds Apr 10 12:58:17 primo-plus volumio[829]: info: explodeUri took 214 milliseconds Apr 10 12:58:17 primo-plus volumio[829]: info: explodeUri took 317 milliseconds Apr 10 12:58:21 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Apr 10 12:58:21 primo-plus volumio[829]: info: handleBrowseUri took 376 milliseconds Apr 10 12:58:21 primo-plus volumio[829]: info: Preload queue cleared Apr 10 12:58:21 primo-plus volumio[829]: info: Preloading song: qobuz://song/138731308 Apr 10 12:58:21 primo-plus volumio[829]: info: Preloading song: qobuz://song/138731309 Apr 10 12:58:21 primo-plus volumio[829]: info: Preloading song: qobuz://song/138731310 Apr 10 12:58:21 primo-plus volumio[829]: info: Preloading song: qobuz://song/138731311 Apr 10 12:58:21 primo-plus volumio[829]: info: Preloading song: qobuz://song/138731312 Apr 10 12:58:21 primo-plus volumio[829]: info: Preloading song: qobuz://song/138731313 Apr 10 12:58:21 primo-plus volumio[829]: info: Preloading song: qobuz://song/138731314 Apr 10 12:58:21 primo-plus volumio[829]: info: Preloading song: qobuz://song/138731315 Apr 10 12:58:21 primo-plus volumio[829]: info: Preloading song: qobuz://song/138731316 Apr 10 12:58:21 primo-plus volumio[829]: info: Preloading song: qobuz://song/138731317 Apr 10 12:58:21 primo-plus volumio[829]: info: Preloading song: qobuz://song/138731318 Apr 10 12:58:21 primo-plus volumio[829]: info: Preloading song: qobuz://song/138731319 Apr 10 12:58:21 primo-plus volumio[829]: info: Exploding uri qobuz://song/138731308 in service qobuz Apr 10 12:58:21 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:21 primo-plus volumio[829]: info: Executing endpoint getSimilarAlbums Apr 10 12:58:21 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 10 12:58:21 primo-plus volumio[829]: info: Exploding uri qobuz://song/138731309 in service qobuz Apr 10 12:58:21 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:21 primo-plus volumio[829]: info: Exploding uri qobuz://song/138731310 in service qobuz Apr 10 12:58:21 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:21 primo-plus volumio[829]: info: Executing endpoint metavolumio Apr 10 12:58:21 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 10 12:58:21 primo-plus volumio[829]: info: Executing endpoint metavolumio Apr 10 12:58:21 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 10 12:58:21 primo-plus volumio[829]: info: Exploding uri qobuz://song/138731311 in service qobuz Apr 10 12:58:21 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:21 primo-plus volumio[829]: info: Exploding uri qobuz://song/138731312 in service qobuz Apr 10 12:58:21 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:22 primo-plus volumio[829]: info: explodeUri took 224 milliseconds Apr 10 12:58:22 primo-plus volumio[829]: info: Exploding uri qobuz://song/138731313 in service qobuz Apr 10 12:58:22 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:22 primo-plus volumio[829]: info: explodeUri took 215 milliseconds Apr 10 12:58:22 primo-plus volumio[829]: info: Exploding uri qobuz://song/138731314 in service qobuz Apr 10 12:58:22 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:22 primo-plus volumio[829]: info: explodeUri took 219 milliseconds Apr 10 12:58:22 primo-plus volumio[829]: info: Exploding uri qobuz://song/138731315 in service qobuz Apr 10 12:58:22 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:22 primo-plus volumio[829]: info: explodeUri took 206 milliseconds Apr 10 12:58:22 primo-plus volumio[829]: info: Exploding uri qobuz://song/138731316 in service qobuz Apr 10 12:58:22 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:22 primo-plus volumio[829]: info: explodeUri took 226 milliseconds Apr 10 12:58:22 primo-plus volumio[829]: info: Exploding uri qobuz://song/138731317 in service qobuz Apr 10 12:58:22 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:22 primo-plus volumio[829]: info: Exploding uri qobuz://song/138731318 in service qobuz Apr 10 12:58:22 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:22 primo-plus volumio[829]: info: explodeUri took 262 milliseconds Apr 10 12:58:22 primo-plus volumio[829]: info: Exploding uri qobuz://song/138731319 in service qobuz Apr 10 12:58:22 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:22 primo-plus volumio[829]: info: explodeUri took 266 milliseconds Apr 10 12:58:22 primo-plus volumio[829]: info: explodeUri took 236 milliseconds Apr 10 12:58:22 primo-plus volumio[829]: info: explodeUri took 224 milliseconds Apr 10 12:58:22 primo-plus volumio[829]: info: explodeUri took 245 milliseconds Apr 10 12:58:22 primo-plus volumio[829]: info: explodeUri took 202 milliseconds Apr 10 12:58:22 primo-plus volumio[829]: info: explodeUri took 251 milliseconds Apr 10 12:58:32 primo-plus volumio[829]: info: Preload queue cleared Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::ClearQueue Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::stop Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::stPlaybackTimer Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::updateTrackBlock Apr 10 12:58:32 primo-plus volumio[829]: info: CorePlayQueue::getTrackBlock Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:32 primo-plus volumio[829]: info: CorePlayQueue::getTrack 1 Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:32 primo-plus volumio[829]: info: CorePlayQueue::getTrack 1 Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::serviceStop Apr 10 12:58:32 primo-plus volumio[829]: info: CorePlayQueue::getTrack 1 Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::serviceStop Apr 10 12:58:32 primo-plus volumio[829]: info: [1775818712241] ControllerQobuz::stop Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 10 12:58:32 primo-plus volumio[829]: info: ControllerMpd::stop Apr 10 12:58:32 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand stop Apr 10 12:58:32 primo-plus volumio[829]: info: CorePlayQueue::clearPlayQueue Apr 10 12:58:32 primo-plus volumio[829]: info: CorePlayQueue::saveQueue Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushQueue Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::addQueueItems Apr 10 12:58:32 primo-plus volumio[829]: info: CorePlayQueue::addQueueItems Apr 10 12:58:32 primo-plus volumio[829]: info: Preload queue cleared Apr 10 12:58:32 primo-plus volumio[829]: info: Adding Item to queue: qobuz://album/qjfuz8y34vbta Apr 10 12:58:32 primo-plus volumio[829]: info: Exploding uri qobuz://album/qjfuz8y34vbta in service qobuz Apr 10 12:58:32 primo-plus volumio[829]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 10 12:58:32 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:32.250+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_STOPPED positionMs=0 volume=12 Apr 10 12:58:32 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:32.250+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/5999572 title="Shake It Out" Apr 10 12:58:32 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:32 primo-plus volumio[829]: info: Apr 10 12:58:32 primo-plus volumio[829]: ---------------------------- MPD announces state update: player Apr 10 12:58:32 primo-plus volumio[829]: info: sendMpdCommand stop took 30 milliseconds Apr 10 12:58:32 primo-plus volumio[829]: info: ControllerMpd::getState Apr 10 12:58:32 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand status Apr 10 12:58:32 primo-plus volumio[829]: info: sendMpdCommand status took 2 milliseconds Apr 10 12:58:32 primo-plus volumio[829]: verbose: ControllerMpd::parseState Apr 10 12:58:32 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 12:58:32 primo-plus volumio[829]: info: Apr 10 12:58:32 primo-plus volumio[829]: ---------------------------- MPD announces state update: player Apr 10 12:58:32 primo-plus volumio[829]: info: ControllerMpd::getState Apr 10 12:58:32 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand status Apr 10 12:58:32 primo-plus volumio[829]: info: Apr 10 12:58:32 primo-plus volumio[829]: ---------------------------- MPD announces state update: player Apr 10 12:58:32 primo-plus volumio[829]: info: ControllerMpd::getState Apr 10 12:58:32 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand status Apr 10 12:58:32 primo-plus volumio[829]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 10 12:58:32 primo-plus volumio[829]: info: sendMpdCommand status took 5 milliseconds Apr 10 12:58:32 primo-plus volumio[829]: verbose: ControllerMpd::parseTrackInfo Apr 10 12:58:32 primo-plus volumio[829]: verbose: ControllerMpd::parseState Apr 10 12:58:32 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 12:58:32 primo-plus volumio[829]: info: ControllerMpd::pushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::servicePushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:32 primo-plus volumio[829]: info: CorePlayQueue::getTrack 1 Apr 10 12:58:32 primo-plus volumio[829]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5999572","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/5999572","trackType":"qobuz"} Apr 10 12:58:32 primo-plus volumio[829]: verbose: CURRENT POSITION 1 Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::syncState stateService stop Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::syncState currentStatus stop Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:32 primo-plus volumio[829]: info: No code Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:32 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:32.305+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=249 volume=12 Apr 10 12:58:32 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:32.308+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/8606356 title="Dog Days Are Over" Apr 10 12:58:32 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:32.311+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=249 volume=12 Apr 10 12:58:32 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:32.312+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=249 volume=12 Apr 10 12:58:32 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:32.314+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/8606356 title="Dog Days Are Over" Apr 10 12:58:32 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:32.314+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/8606356 title="Dog Days Are Over" Apr 10 12:58:32 primo-plus volumio[829]: info: ------------------------------ 48ms Apr 10 12:58:32 primo-plus volumio[829]: info: sendMpdCommand status took 41 milliseconds Apr 10 12:58:32 primo-plus volumio[829]: info: sendMpdCommand playlistinfo took 38 milliseconds Apr 10 12:58:32 primo-plus volumio[829]: verbose: ControllerMpd::parseState Apr 10 12:58:32 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 12:58:32 primo-plus volumio[829]: verbose: ControllerMpd::parseTrackInfo Apr 10 12:58:32 primo-plus volumio[829]: info: ControllerMpd::pushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::servicePushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:32 primo-plus volumio[829]: info: CorePlayQueue::getTrack 1 Apr 10 12:58:32 primo-plus volumio[829]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5999572","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/5999572","trackType":"qobuz"} Apr 10 12:58:32 primo-plus volumio[829]: verbose: CURRENT POSITION 1 Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::syncState stateService stop Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::syncState currentStatus stop Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:32 primo-plus volumio[829]: info: No code Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:32 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:32.339+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=249 volume=12 Apr 10 12:58:32 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:32.340+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=249 volume=12 Apr 10 12:58:32 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:32.340+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/8606356 title="Dog Days Are Over" Apr 10 12:58:32 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:32.344+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=249 volume=12 Apr 10 12:58:32 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:32.344+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/8606356 title="Dog Days Are Over" Apr 10 12:58:32 primo-plus volumio[829]: info: ------------------------------ 75ms Apr 10 12:58:32 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:32 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:32 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:32 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:32 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:32 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:32 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:32 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:32 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:32 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:32 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:32 primo-plus volumio[829]: info: sendMpdCommand playlistinfo took 60 milliseconds Apr 10 12:58:32 primo-plus volumio[829]: verbose: ControllerMpd::parseTrackInfo Apr 10 12:58:32 primo-plus volumio[829]: info: ControllerMpd::pushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::servicePushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:32 primo-plus volumio[829]: info: CorePlayQueue::getTrack 1 Apr 10 12:58:32 primo-plus volumio[829]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5999572","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/5999572","trackType":"qobuz"} Apr 10 12:58:32 primo-plus volumio[829]: verbose: CURRENT POSITION 1 Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::syncState stateService stop Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::syncState currentStatus stop Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:32 primo-plus volumio[829]: info: No code Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:32 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:32.406+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=249 volume=12 Apr 10 12:58:32 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:32.407+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/8606356 title="Dog Days Are Over" Apr 10 12:58:32 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:32.407+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=249 volume=12 Apr 10 12:58:32 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:32.407+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/8606356 title="Dog Days Are Over" Apr 10 12:58:32 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:32.408+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=249 volume=12 Apr 10 12:58:32 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:32.409+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/8606356 title="Dog Days Are Over" Apr 10 12:58:32 primo-plus volumio[829]: info: ------------------------------ 138ms Apr 10 12:58:32 primo-plus volumio[829]: info: MCU Signalled Playback Inactive Apr 10 12:58:32 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:32 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:32 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:32 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:32 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:32 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:32 primo-plus volumio[829]: info: MCU Signalled Playback Active Apr 10 12:58:32 primo-plus volumio[829]: info: explodeUri took 286 milliseconds Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushQueue Apr 10 12:58:32 primo-plus volumio[829]: info: CorePlayQueue::saveQueue Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::updateTrackBlock Apr 10 12:58:32 primo-plus volumio[829]: info: CorePlayQueue::getTrackBlock Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::volumioPlay Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::play index 0 Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::stop Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::play index undefined Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 12:58:32 primo-plus volumio[829]: info: CorePlayQueue::getTrack 0 Apr 10 12:58:32 primo-plus volumio[829]: info: CoreStateMachine::startPlaybackTimer Apr 10 12:58:32 primo-plus volumio[829]: info: CorePlayQueue::getTrack 0 Apr 10 12:58:32 primo-plus volumio[829]: info: [1775818712545] ControllerQobuz::clearAddPlayTrack Apr 10 12:58:32 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand stop Apr 10 12:58:32 primo-plus volumio[829]: info: sendMpdCommand stop took 4 milliseconds Apr 10 12:58:32 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand clear Apr 10 12:58:32 primo-plus volumio[829]: info: Apr 10 12:58:32 primo-plus volumio[829]: ---------------------------- MPD announces system playlist update Apr 10 12:58:32 primo-plus volumio[829]: info: Ignoring MPD Status Update Apr 10 12:58:32 primo-plus volumio[829]: info: sendMpdCommand clear took 1 milliseconds Apr 10 12:58:32 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/138731308" Apr 10 12:58:32 primo-plus volumio[829]: info: Apr 10 12:58:32 primo-plus volumio[829]: ---------------------------- MPD announces system playlist update Apr 10 12:58:32 primo-plus volumio[829]: info: Ignoring MPD Status Update Apr 10 12:58:32 primo-plus volumio[829]: info: Apr 10 12:58:32 primo-plus volumio[829]: ---------------------------- MPD announces system playlist update Apr 10 12:58:32 primo-plus volumio[829]: info: Ignoring MPD Status Update Apr 10 12:58:32 primo-plus volumio[829]: STREAMING PROXY: Handling url /?data=qobuz://song/138731308 Apr 10 12:58:32 primo-plus volumio[829]: info: Executing endpoint getStreamUrlqobuz Apr 10 12:58:32 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 10 12:58:32 primo-plus volumio[829]: info: getStreamUrl took 189 milliseconds Apr 10 12:58:32 primo-plus volumio[829]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=570029&eid=138731308&fmt=7&profile=raw&app_id=539451548&cid=3004959&etsp=1775822312&hmac=84P2nP_pC4vD-wdtkYvmdvVfl9c Apr 10 12:58:32 primo-plus volumio[829]: STREAMING PROXY: Requesting upstream with range: none Apr 10 12:58:33 primo-plus volumio[829]: STREAMING PROXY: Upstream status code: 200 Apr 10 12:58:33 primo-plus volumio[829]: error: updateQueue error: null Apr 10 12:58:33 primo-plus volumio[829]: STREAMING PROXY: Client closed connection Apr 10 12:58:33 primo-plus volumio[829]: info: ------------------------------ 753ms Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/138731308" Apr 10 12:58:33 primo-plus volumio[829]: STREAMING PROXY: Upstream closed. Received 460 bytes Apr 10 12:58:33 primo-plus volumio[829]: STREAMING PROXY: Incomplete transfer. Expected 84700407, got 460 Apr 10 12:58:33 primo-plus volumio[829]: STREAMING PROXY: Retrying with range: bytes=460- (attempt 1) Apr 10 12:58:33 primo-plus volumio[829]: error: updateQueue error: null Apr 10 12:58:33 primo-plus volumio[829]: error: updateQueue error: null Apr 10 12:58:33 primo-plus volumio[829]: info: ------------------------------ 754ms Apr 10 12:58:33 primo-plus volumio[829]: info: ------------------------------ 753ms Apr 10 12:58:33 primo-plus volumio[829]: info: Apr 10 12:58:33 primo-plus volumio[829]: ---------------------------- MPD announces system playlist update Apr 10 12:58:33 primo-plus volumio[829]: info: Ignoring MPD Status Update Apr 10 12:58:33 primo-plus volumio[829]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/138731308" took 3 milliseconds Apr 10 12:58:33 primo-plus volumio[829]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand play Apr 10 12:58:33 primo-plus volumio[829]: info: Apr 10 12:58:33 primo-plus volumio[829]: ---------------------------- MPD announces system playlist update Apr 10 12:58:33 primo-plus volumio[829]: info: Ignoring MPD Status Update Apr 10 12:58:33 primo-plus volumio[829]: info: Apr 10 12:58:33 primo-plus volumio[829]: ---------------------------- MPD announces system playlist update Apr 10 12:58:33 primo-plus volumio[829]: STREAMING PROXY: Handling url /?data=qobuz://song/138731308 Apr 10 12:58:33 primo-plus volumio[829]: info: Ignoring MPD Status Update Apr 10 12:58:33 primo-plus volumio[829]: info: ------------------------------ 6ms Apr 10 12:58:33 primo-plus volumio[829]: info: sendMpdCommand play took 6 milliseconds Apr 10 12:58:33 primo-plus volumio[829]: info: ------------------------------ 4ms Apr 10 12:58:33 primo-plus volumio[829]: info: ------------------------------ 9ms Apr 10 12:58:33 primo-plus volumio[829]: info: Executing endpoint getStreamUrlqobuz Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 10 12:58:33 primo-plus volumio[829]: info: getStreamUrl took 219 milliseconds Apr 10 12:58:33 primo-plus volumio[829]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=570029&eid=138731308&fmt=7&profile=raw&app_id=539451548&cid=3004959&etsp=1775822313&hmac=Ato_KWljGI0L7H0K-5caGsrZPy4 Apr 10 12:58:33 primo-plus volumio[829]: STREAMING PROXY: Requesting upstream with range: none Apr 10 12:58:33 primo-plus volumio[829]: STREAMING PROXY: Upstream status code: 200 Apr 10 12:58:33 primo-plus volumio[829]: info: Apr 10 12:58:33 primo-plus volumio[829]: ---------------------------- MPD announces state update: player Apr 10 12:58:33 primo-plus volumio[829]: info: ControllerMpd::getState Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand status Apr 10 12:58:33 primo-plus volumio[829]: info: Apr 10 12:58:33 primo-plus volumio[829]: ---------------------------- MPD announces state update: player Apr 10 12:58:33 primo-plus volumio[829]: info: ControllerMpd::getState Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand status Apr 10 12:58:33 primo-plus volumio[829]: info: Apr 10 12:58:33 primo-plus volumio[829]: ---------------------------- MPD announces state update: player Apr 10 12:58:33 primo-plus volumio[829]: info: ControllerMpd::getState Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand status Apr 10 12:58:33 primo-plus volumio[829]: info: Apr 10 12:58:33 primo-plus volumio[829]: ---------------------------- MPD announces state update: player Apr 10 12:58:33 primo-plus volumio[829]: info: sendMpdCommand status took 8 milliseconds Apr 10 12:58:33 primo-plus volumio[829]: info: ControllerMpd::getState Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand status Apr 10 12:58:33 primo-plus volumio[829]: info: sendMpdCommand status took 6 milliseconds Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::parseState Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::parseState Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 12:58:33 primo-plus volumio[829]: info: Apr 10 12:58:33 primo-plus volumio[829]: ---------------------------- MPD announces state update: player Apr 10 12:58:33 primo-plus volumio[829]: info: ControllerMpd::getState Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand status Apr 10 12:58:33 primo-plus volumio[829]: info: Apr 10 12:58:33 primo-plus volumio[829]: ---------------------------- MPD announces state update: player Apr 10 12:58:33 primo-plus volumio[829]: info: ControllerMpd::getState Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand status Apr 10 12:58:33 primo-plus volumio[829]: info: sendMpdCommand status took 11 milliseconds Apr 10 12:58:33 primo-plus volumio[829]: info: sendMpdCommand status took 9 milliseconds Apr 10 12:58:33 primo-plus volumio[829]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 10 12:58:33 primo-plus volumio[829]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 10 12:58:33 primo-plus volumio[829]: info: sendMpdCommand status took 5 milliseconds Apr 10 12:58:33 primo-plus volumio[829]: info: sendMpdCommand status took 3 milliseconds Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::parseState Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::parseState Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::parseTrackInfo Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::parseTrackInfo Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::parseState Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::parseState Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 12:58:33 primo-plus volumio[829]: info: ControllerMpd::pushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::servicePushState Apr 10 12:58:33 primo-plus volumio[829]: info: CorePlayQueue::getTrack 0 Apr 10 12:58:33 primo-plus volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"138731308","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138731308","trackType":"qobuz"} Apr 10 12:58:33 primo-plus volumio[829]: verbose: CURRENT POSITION 0 Apr 10 12:58:33 primo-plus volumio[829]: info: CoreStateMachine::syncState stateService play Apr 10 12:58:33 primo-plus volumio[829]: info: CoreStateMachine::syncState currentStatus stop Apr 10 12:58:33 primo-plus volumio[829]: info: ControllerMpd::pushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::servicePushState Apr 10 12:58:33 primo-plus volumio[829]: info: CorePlayQueue::getTrack 0 Apr 10 12:58:33 primo-plus volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"138731308","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138731308","trackType":"qobuz"} Apr 10 12:58:33 primo-plus volumio[829]: verbose: CURRENT POSITION 0 Apr 10 12:58:33 primo-plus volumio[829]: info: CoreStateMachine::syncState stateService play Apr 10 12:58:33 primo-plus volumio[829]: info: CoreStateMachine::syncState currentStatus play Apr 10 12:58:33 primo-plus volumio[829]: info: Received an update from plugin. extracting info from payload Apr 10 12:58:33 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:33 primo-plus volumio[829]: info: ------------------------------ 40ms Apr 10 12:58:33 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:33.656+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=0 volume=12 Apr 10 12:58:33 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:33.656+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:33 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:33.657+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:33 primo-plus volumio[829]: info: ------------------------------ 46ms Apr 10 12:58:33 primo-plus volumio[829]: info: sendMpdCommand playlistinfo took 29 milliseconds Apr 10 12:58:33 primo-plus volumio[829]: info: sendMpdCommand playlistinfo took 28 milliseconds Apr 10 12:58:33 primo-plus volumio[829]: info: sendMpdCommand playlistinfo took 28 milliseconds Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::parseTrackInfo Apr 10 12:58:33 primo-plus volumio[829]: verbose: ControllerMpd::parseTrackInfo Apr 10 12:58:33 primo-plus volumio[829]: info: ControllerMpd::pushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::servicePushState Apr 10 12:58:33 primo-plus volumio[829]: info: CorePlayQueue::getTrack 0 Apr 10 12:58:33 primo-plus volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"138731308","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138731308","trackType":"qobuz"} Apr 10 12:58:33 primo-plus volumio[829]: verbose: CURRENT POSITION 0 Apr 10 12:58:33 primo-plus volumio[829]: info: CoreStateMachine::syncState stateService play Apr 10 12:58:33 primo-plus volumio[829]: info: CoreStateMachine::syncState currentStatus play Apr 10 12:58:33 primo-plus volumio[829]: info: Received an update from plugin. extracting info from payload Apr 10 12:58:33 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:33 primo-plus volumio[829]: info: ControllerMpd::pushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::servicePushState Apr 10 12:58:33 primo-plus volumio[829]: info: CorePlayQueue::getTrack 0 Apr 10 12:58:33 primo-plus volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"138731308","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138731308","trackType":"qobuz"} Apr 10 12:58:33 primo-plus volumio[829]: verbose: CURRENT POSITION 0 Apr 10 12:58:33 primo-plus volumio[829]: info: CoreStateMachine::syncState stateService play Apr 10 12:58:33 primo-plus volumio[829]: info: CoreStateMachine::syncState currentStatus play Apr 10 12:58:33 primo-plus volumio[829]: info: Received an update from plugin. extracting info from payload Apr 10 12:58:33 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:33 primo-plus volumio[829]: info: ControllerMpd::pushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::servicePushState Apr 10 12:58:33 primo-plus volumio[829]: info: CorePlayQueue::getTrack 0 Apr 10 12:58:33 primo-plus volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"138731308","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138731308","trackType":"qobuz"} Apr 10 12:58:33 primo-plus volumio[829]: verbose: CURRENT POSITION 0 Apr 10 12:58:33 primo-plus volumio[829]: info: CoreStateMachine::syncState stateService play Apr 10 12:58:33 primo-plus volumio[829]: info: CoreStateMachine::syncState currentStatus play Apr 10 12:58:33 primo-plus volumio[829]: info: Received an update from plugin. extracting info from payload Apr 10 12:58:33 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:33 primo-plus volumio[829]: info: ControllerMpd::pushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::servicePushState Apr 10 12:58:33 primo-plus volumio[829]: info: CorePlayQueue::getTrack 0 Apr 10 12:58:33 primo-plus volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"138731308","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138731308","trackType":"qobuz"} Apr 10 12:58:33 primo-plus volumio[829]: verbose: CURRENT POSITION 0 Apr 10 12:58:33 primo-plus volumio[829]: info: CoreStateMachine::syncState stateService play Apr 10 12:58:33 primo-plus volumio[829]: info: CoreStateMachine::syncState currentStatus play Apr 10 12:58:33 primo-plus volumio[829]: info: Received an update from plugin. extracting info from payload Apr 10 12:58:33 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:33 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:33 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:33.728+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=0 volume=12 Apr 10 12:58:33 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:33.728+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:33 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:33.729+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=0 volume=12 Apr 10 12:58:33 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:33.730+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=0 volume=12 Apr 10 12:58:33 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:33.730+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:33 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:33.731+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=0 volume=12 Apr 10 12:58:33 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:33.731+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:33 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:33.732+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:33 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:33.733+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=0 volume=12 Apr 10 12:58:33 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:33.733+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:33 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:33.734+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=0 volume=12 Apr 10 12:58:33 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:33.734+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:33 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:33.735+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=0 volume=12 Apr 10 12:58:33 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:33.735+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:33 primo-plus volumio[829]: info: ------------------------------ 134ms Apr 10 12:58:33 primo-plus volumio[829]: info: ------------------------------ 132ms Apr 10 12:58:33 primo-plus volumio[829]: info: ------------------------------ 127ms Apr 10 12:58:33 primo-plus volumio[829]: info: ------------------------------ 127ms Apr 10 12:58:33 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:33 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:33 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:33 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:33 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:33 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:33 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:33 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:33 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:33 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:33 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:33 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:33 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:33 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:33 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:33 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:33 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:33 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:33 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:34 primo-plus volumio[829]: STREAMING PROXY: Resuming with range: bytes=460- Apr 10 12:58:34 primo-plus volumio[829]: STREAMING PROXY: Resume upstream status code: 206 Apr 10 12:58:35 primo-plus volumio[829]: info: Executing endpoint metavolumio Apr 10 12:58:35 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 10 12:58:35 primo-plus volumio[829]: info: Executing endpoint metavolumio Apr 10 12:58:35 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 10 12:58:35 primo-plus volumio[829]: info: Executing endpoint metavolumio Apr 10 12:58:35 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 10 12:58:36 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Apr 10 12:58:36 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:36 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 12:58:36 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:36 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:36 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:36.059+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=2269 volume=13 Apr 10 12:58:36 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:36.059+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:36 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:36 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:36 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:36 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 12:58:36 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:36 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:36 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:36.136+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=2519 volume=13 Apr 10 12:58:36 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:36.136+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:36 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:36 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:36 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Apr 10 12:58:36 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:36 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 12:58:36 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:36 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:36 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:36.573+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=2769 volume=14 Apr 10 12:58:36 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:36.573+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:36 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:36 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:36 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:36 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 12:58:36 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:36 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:36 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:36.648+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=3026 volume=14 Apr 10 12:58:36 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:36.649+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:36 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:36 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:37 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Apr 10 12:58:37 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:37 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 12:58:37 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:37 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:37 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:37.031+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=3276 volume=15 Apr 10 12:58:37 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:37.031+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:37 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:37 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:37 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:37 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 12:58:37 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:37 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:37 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:37.109+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=3525 volume=15 Apr 10 12:58:37 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:37.109+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:37 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:37 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:37 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Apr 10 12:58:37 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:37 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 12:58:37 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:37 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:37 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:37.468+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=3775 volume=16 Apr 10 12:58:37 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:37.468+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:37 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:37 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:37 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:37 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:37 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:37 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:37.547+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=3775 volume=16 Apr 10 12:58:37 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:37.547+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:37 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:37 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:37 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Apr 10 12:58:37 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:37 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 12:58:37 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:37 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:37 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:37.934+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=4276 volume=17 Apr 10 12:58:37 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:37.934+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:37 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:37 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:38 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:38 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:38 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:38 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:38.011+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=4276 volume=17 Apr 10 12:58:38 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:38.011+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:38 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:38 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:38 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Apr 10 12:58:38 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:38 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 12:58:38 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:38 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:38 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:38.368+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=4777 volume=18 Apr 10 12:58:38 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:38.369+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:38 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:38 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:38 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:38 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:38 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:38 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:38.448+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=4777 volume=18 Apr 10 12:58:38 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:38.449+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:38 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:38 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:38 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Apr 10 12:58:38 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:38 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 12:58:38 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:38 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:38 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:38.785+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=5027 volume=19 Apr 10 12:58:38 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:38.786+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:38 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:38 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:38 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:38 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 12:58:38 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:38 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:38 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:38.862+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=5277 volume=19 Apr 10 12:58:38 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:38.862+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:38 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:38 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:39 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Apr 10 12:58:39 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:39 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 12:58:39 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:39 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:39 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:39.255+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=5527 volume=20 Apr 10 12:58:39 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:39.255+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:39 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:39 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:39 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:39 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 12:58:39 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:39 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:39 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:39.345+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=5777 volume=20 Apr 10 12:58:39 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:39.346+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:39 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:39 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:39 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Apr 10 12:58:39 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:39 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 12:58:39 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:39 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:39 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:39.698+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=6028 volume=21 Apr 10 12:58:39 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:39.698+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:39 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:39 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:39 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:39 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:39 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:39 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:39.776+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=6028 volume=21 Apr 10 12:58:39 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:39.776+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:39 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:39 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:40 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Apr 10 12:58:40 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:40 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 12:58:40 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:40 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:40 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:40.164+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=6529 volume=22 Apr 10 12:58:40 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:40.165+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:40 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:40 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:40 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:40 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:40 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:40 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:40.252+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=6529 volume=22 Apr 10 12:58:40 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:40.252+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:40 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:40 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:40 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Apr 10 12:58:40 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:40 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 12:58:40 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:40 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:40 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:40.836+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=7280 volume=23 Apr 10 12:58:40 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:40.836+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:40 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:40 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:40 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:40 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:40 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:40 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:40.914+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=7280 volume=23 Apr 10 12:58:40 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:40.915+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:40 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:40 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:41 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Apr 10 12:58:41 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:41 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 12:58:41 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:41 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:41 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:41.347+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=7780 volume=24 Apr 10 12:58:41 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:41.347+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:41 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:41 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:41 primo-plus volumio[829]: info: CoreStateMachine::pushState Apr 10 12:58:41 primo-plus volumio[829]: info: CoreCommandRouter::volumioPushState Apr 10 12:58:41 primo-plus volumio[829]: info: CoreCommandRouter::volumioGetState Apr 10 12:58:41 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:41.423+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" state=STATUS_PLAYING positionMs=7780 volume=24 Apr 10 12:58:41 primo-plus volumio5-onboarding[1451]: time=2026-04-10T12:58:41.424+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:59214 @ 0x36cc2d0" id=qobuz://song/138731308 title="Death On Two Legs (Dedicated To...)" Apr 10 12:58:41 primo-plus volumio[829]: info: Signalling Playback active due to playback status change Apr 10 12:58:41 primo-plus volumio[829]: info: Updating RAAT Signal Path Apr 10 12:58:42 primo-plus volumiologrotate[589]: ls: cannot access '/var/log/samba/log.wb-PRIMO': No such file or directory Apr 10 12:58:42 primo-plus volumiologrotate[589]: ls: cannot access 'PLUS': No such file or directory Apr 10 12:58:51 primo-plus volumio[829]: STREAMING PROXY: Resume ended. Received 84699947 bytes Apr 10 12:58:57 primo-plus volumio[829]: STREAMING PROXY: Upstream closed. Received 84700407 bytes Apr 10 12:58:57 primo-plus volumio[829]: STREAMING PROXY: Client closed connection Apr 10 12:58:58 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Apr 10 12:58:59 primo-plus volumio[829]: info: handleBrowseUri took 1162 milliseconds Apr 10 12:58:59 primo-plus volumio[829]: info: Preload queue cleared Apr 10 12:58:59 primo-plus volumio[829]: info: Executing endpoint getSimilarArtists Apr 10 12:58:59 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Apr 10 12:59:00 primo-plus volumio[829]: info: Executing endpoint metavolumio Apr 10 12:59:00 primo-plus volumio[829]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 10 12:59:01 primo-plus volumio[829]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 10 12:59:01 primo-plus volumio[829]: Error: connect ECONNREFUSED 158.69.210.98:443 Apr 10 12:59:01 primo-plus volumio[829]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Apr 10 12:59:01 primo-plus volumio[829]: errno: -111, Apr 10 12:59:01 primo-plus volumio[829]: code: 'ECONNREFUSED', Apr 10 12:59:01 primo-plus volumio[829]: syscall: 'connect', Apr 10 12:59:01 primo-plus volumio[829]: address: '158.69.210.98', Apr 10 12:59:01 primo-plus volumio[829]: port: 443 Apr 10 12:59:01 primo-plus volumio[829]: } Apr 10 12:59:01 primo-plus volumio[829]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 10 12:59:01 primo-plus sudo[4915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-10 12:58 Apr 10 12:59:01 primo-plus sudo[4915]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="primoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 01:08:28 PM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Primo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo Plus" VOLUMIO_HASH="2aa40c31709b83c0e790f52b483672b5"