-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Wed 2025-02-19 18:29:15 CET. -- Feb 19 18:28:19 rivo volumio[3291]: Searching plugin music_service/qobuz Feb 19 18:28:19 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Feb 19 18:28:20 rivo volumio[3291]: Searching plugin music_service/qobuz Feb 19 18:28:20 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Feb 19 18:28:21 rivo volumio[3291]: info: searchQOBUZUri took 1231 milliseconds Feb 19 18:28:21 rivo volumio[3291]: info: All search sources collected, pushing search results Feb 19 18:28:21 rivo volumio[3291]: Searching plugin music_service/qobuz Feb 19 18:28:21 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Feb 19 18:28:23 rivo volumio[3291]: info: searchQOBUZUri took 1763 milliseconds Feb 19 18:28:23 rivo volumio[3291]: info: All search sources collected, pushing search results Feb 19 18:28:24 rivo volumio[3291]: Searching plugin music_service/qobuz Feb 19 18:28:24 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Feb 19 18:28:25 rivo volumio[3291]: info: searchQOBUZUri took 1168 milliseconds Feb 19 18:28:25 rivo volumio[3291]: info: All search sources collected, pushing search results Feb 19 18:28:27 rivo volumio[3291]: info: searchQOBUZUri took 6252 milliseconds Feb 19 18:28:27 rivo volumio[3291]: info: All search sources collected, pushing search results Feb 19 18:28:28 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Feb 19 18:28:33 rivo volumio[3291]: info: handleBrowseUri took 5055 milliseconds Feb 19 18:28:33 rivo volumio[3291]: info: Preload queue cleared Feb 19 18:28:34 rivo volumio[3291]: info: Executing endpoint getSimilarArtists Feb 19 18:28:34 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Feb 19 18:28:34 rivo volumio[3291]: info: Executing endpoint metavolumio Feb 19 18:28:34 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 19 18:28:47 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Feb 19 18:28:47 rivo volumio[3291]: info: handleBrowseUri took 416 milliseconds Feb 19 18:28:47 rivo volumio[3291]: info: Preload queue cleared Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795700 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795701 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795702 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795703 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795704 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795705 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795706 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795707 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795708 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795709 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795710 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795711 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795712 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795713 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795714 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795715 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795716 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795717 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795718 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795719 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795720 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795721 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795722 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795723 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795724 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795725 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795726 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795727 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795728 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795729 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795730 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795731 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795732 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795733 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795734 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795735 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795736 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795737 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795738 Feb 19 18:28:47 rivo volumio[3291]: info: Preloading song: qobuz://song/56795739 Feb 19 18:28:47 rivo volumio[3291]: info: Exploding uri qobuz://song/56795700 in service qobuz Feb 19 18:28:47 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:47 rivo volumio[3291]: info: Exploding uri qobuz://song/56795701 in service qobuz Feb 19 18:28:47 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:47 rivo volumio[3291]: info: Exploding uri qobuz://song/56795702 in service qobuz Feb 19 18:28:47 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:47 rivo volumio[3291]: info: Exploding uri qobuz://song/56795703 in service qobuz Feb 19 18:28:47 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:47 rivo volumio[3291]: info: Exploding uri qobuz://song/56795704 in service qobuz Feb 19 18:28:47 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:47 rivo volumio[3291]: info: Exploding uri qobuz://song/56795705 in service qobuz Feb 19 18:28:47 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:47 rivo volumio[3291]: info: Exploding uri qobuz://song/56795706 in service qobuz Feb 19 18:28:47 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:47 rivo volumio[3291]: info: Exploding uri qobuz://song/56795707 in service qobuz Feb 19 18:28:47 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:48 rivo volumio[3291]: info: Executing endpoint getSimilarAlbums Feb 19 18:28:48 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Feb 19 18:28:48 rivo volumio[3291]: info: Exploding uri qobuz://song/56795708 in service qobuz Feb 19 18:28:48 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:48 rivo volumio[3291]: info: Exploding uri qobuz://song/56795709 in service qobuz Feb 19 18:28:48 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:48 rivo volumio[3291]: info: explodeUri took 420 milliseconds Feb 19 18:28:48 rivo volumio[3291]: info: Executing endpoint metavolumio Feb 19 18:28:48 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 19 18:28:48 rivo volumio[3291]: info: explodeUri took 494 milliseconds Feb 19 18:28:48 rivo volumio[3291]: info: Exploding uri qobuz://song/56795710 in service qobuz Feb 19 18:28:48 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:48 rivo volumio[3291]: info: Exploding uri qobuz://song/56795711 in service qobuz Feb 19 18:28:48 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:48 rivo volumio[3291]: info: explodeUri took 432 milliseconds Feb 19 18:28:48 rivo volumio[3291]: info: Exploding uri qobuz://song/56795712 in service qobuz Feb 19 18:28:48 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:48 rivo volumio[3291]: info: Exploding uri qobuz://song/56795713 in service qobuz Feb 19 18:28:48 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:48 rivo volumio[3291]: info: explodeUri took 476 milliseconds Feb 19 18:28:48 rivo volumio[3291]: info: explodeUri took 437 milliseconds Feb 19 18:28:48 rivo volumio[3291]: info: Exploding uri qobuz://song/56795714 in service qobuz Feb 19 18:28:48 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:48 rivo volumio[3291]: info: explodeUri took 449 milliseconds Feb 19 18:28:48 rivo volumio[3291]: info: Exploding uri qobuz://song/56795715 in service qobuz Feb 19 18:28:48 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:48 rivo volumio[3291]: info: explodeUri took 692 milliseconds Feb 19 18:28:48 rivo volumio[3291]: info: Exploding uri qobuz://song/56795716 in service qobuz Feb 19 18:28:48 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:48 rivo volumio[3291]: info: explodeUri took 462 milliseconds Feb 19 18:28:48 rivo volumio[3291]: info: Exploding uri qobuz://song/56795717 in service qobuz Feb 19 18:28:48 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:48 rivo volumio[3291]: info: explodeUri took 477 milliseconds Feb 19 18:28:48 rivo volumio[3291]: info: Exploding uri qobuz://song/56795718 in service qobuz Feb 19 18:28:48 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:48 rivo volumio[3291]: info: Exploding uri qobuz://song/56795719 in service qobuz Feb 19 18:28:48 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:48 rivo volumio[3291]: info: explodeUri took 511 milliseconds Feb 19 18:28:48 rivo volumio[3291]: info: Exploding uri qobuz://song/56795720 in service qobuz Feb 19 18:28:48 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:48 rivo volumio[3291]: info: Exploding uri qobuz://song/56795721 in service qobuz Feb 19 18:28:48 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:48 rivo volumio[3291]: info: explodeUri took 511 milliseconds Feb 19 18:28:48 rivo volumio[3291]: info: explodeUri took 464 milliseconds Feb 19 18:28:48 rivo volumio[3291]: info: Exploding uri qobuz://song/56795722 in service qobuz Feb 19 18:28:48 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:48 rivo volumio[3291]: info: Exploding uri qobuz://song/56795723 in service qobuz Feb 19 18:28:48 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:48 rivo volumio[3291]: info: explodeUri took 461 milliseconds Feb 19 18:28:48 rivo volumio[3291]: info: Exploding uri qobuz://song/56795724 in service qobuz Feb 19 18:28:48 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:48 rivo volumio[3291]: info: explodeUri took 468 milliseconds Feb 19 18:28:48 rivo volumio[3291]: info: Exploding uri qobuz://song/56795725 in service qobuz Feb 19 18:28:48 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:48 rivo volumio[3291]: info: Exploding uri qobuz://song/56795726 in service qobuz Feb 19 18:28:48 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:48 rivo volumio[3291]: info: explodeUri took 502 milliseconds Feb 19 18:28:48 rivo volumio[3291]: info: explodeUri took 458 milliseconds Feb 19 18:28:48 rivo volumio[3291]: info: Exploding uri qobuz://song/56795727 in service qobuz Feb 19 18:28:48 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:49 rivo volumio[3291]: info: explodeUri took 437 milliseconds Feb 19 18:28:49 rivo volumio[3291]: info: explodeUri took 498 milliseconds Feb 19 18:28:49 rivo volumio[3291]: info: Exploding uri qobuz://song/56795728 in service qobuz Feb 19 18:28:49 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:49 rivo volumio[3291]: info: Exploding uri qobuz://song/56795729 in service qobuz Feb 19 18:28:49 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:49 rivo volumio[3291]: info: explodeUri took 447 milliseconds Feb 19 18:28:49 rivo volumio[3291]: info: Executing endpoint metavolumio Feb 19 18:28:49 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 19 18:28:49 rivo volumio[3291]: info: explodeUri took 439 milliseconds Feb 19 18:28:49 rivo volumio[3291]: info: Exploding uri qobuz://song/56795730 in service qobuz Feb 19 18:28:49 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:49 rivo volumio[3291]: info: explodeUri took 452 milliseconds Feb 19 18:28:49 rivo volumio[3291]: info: Exploding uri qobuz://song/56795731 in service qobuz Feb 19 18:28:49 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:49 rivo volumio[3291]: info: explodeUri took 444 milliseconds Feb 19 18:28:49 rivo volumio[3291]: info: Exploding uri qobuz://song/56795732 in service qobuz Feb 19 18:28:49 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:49 rivo volumio[3291]: info: explodeUri took 415 milliseconds Feb 19 18:28:49 rivo volumio[3291]: info: Exploding uri qobuz://song/56795733 in service qobuz Feb 19 18:28:49 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:49 rivo volumio[3291]: info: Exploding uri qobuz://song/56795734 in service qobuz Feb 19 18:28:49 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:49 rivo volumio[3291]: info: Exploding uri qobuz://song/56795735 in service qobuz Feb 19 18:28:49 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:49 rivo volumio[3291]: info: Exploding uri qobuz://song/56795736 in service qobuz Feb 19 18:28:49 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:49 rivo volumio[3291]: info: explodeUri took 529 milliseconds Feb 19 18:28:49 rivo volumio[3291]: info: explodeUri took 495 milliseconds Feb 19 18:28:49 rivo volumio[3291]: info: Exploding uri qobuz://song/56795737 in service qobuz Feb 19 18:28:49 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:49 rivo volumio[3291]: info: Exploding uri qobuz://song/56795738 in service qobuz Feb 19 18:28:49 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:49 rivo volumio[3291]: info: explodeUri took 543 milliseconds Feb 19 18:28:49 rivo volumio[3291]: info: explodeUri took 450 milliseconds Feb 19 18:28:49 rivo volumio[3291]: info: Exploding uri qobuz://song/56795739 in service qobuz Feb 19 18:28:49 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:49 rivo volumio[3291]: info: explodeUri took 510 milliseconds Feb 19 18:28:49 rivo volumio[3291]: info: explodeUri took 505 milliseconds Feb 19 18:28:49 rivo volumio[3291]: info: explodeUri took 1512 milliseconds Feb 19 18:28:49 rivo volumio[3291]: info: explodeUri took 496 milliseconds Feb 19 18:28:49 rivo volumio[3291]: info: explodeUri took 475 milliseconds Feb 19 18:28:49 rivo volumio[3291]: info: Preload queue cleared Feb 19 18:28:49 rivo volumio[3291]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 19 18:28:49 rivo volumio[3291]: info: CoreStateMachine::ClearQueue Feb 19 18:28:49 rivo volumio[3291]: info: CoreStateMachine::stop Feb 19 18:28:49 rivo volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:28:49 rivo volumio[3291]: info: CorePlayQueue::clearPlayQueue Feb 19 18:28:49 rivo volumio[3291]: info: CorePlayQueue::saveQueue Feb 19 18:28:49 rivo volumio[3291]: info: CoreCommandRouter::volumioPushQueue Feb 19 18:28:49 rivo volumio[3291]: info: CoreStateMachine::addQueueItems Feb 19 18:28:49 rivo volumio[3291]: info: CorePlayQueue::addQueueItems Feb 19 18:28:49 rivo volumio[3291]: info: Preload queue cleared Feb 19 18:28:49 rivo volumio[3291]: info: Adding Item to queue: qobuz://album/eqk5df8h37ssa Feb 19 18:28:49 rivo volumio[3291]: info: Exploding uri qobuz://album/eqk5df8h37ssa in service qobuz Feb 19 18:28:49 rivo volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 19 18:28:49 rivo volumio[3291]: info: explodeUri took 500 milliseconds Feb 19 18:28:49 rivo volumio[3291]: error: Failed request for metavolumio API Feb 19 18:28:49 rivo volumio[3291]: info: explodeUri took 479 milliseconds Feb 19 18:28:50 rivo volumio[3291]: info: explodeUri took 448 milliseconds Feb 19 18:28:50 rivo volumio[3291]: info: explodeUri took 498 milliseconds Feb 19 18:28:50 rivo volumio[3291]: info: explodeUri took 383 milliseconds Feb 19 18:28:50 rivo volumio[3291]: info: CoreCommandRouter::volumioPushQueue Feb 19 18:28:50 rivo volumio[3291]: info: CorePlayQueue::saveQueue Feb 19 18:28:50 rivo volumio[3291]: info: CoreStateMachine::updateTrackBlock Feb 19 18:28:50 rivo volumio[3291]: info: CorePlayQueue::getTrackBlock Feb 19 18:28:50 rivo volumio[3291]: info: CoreCommandRouter::volumioPlay Feb 19 18:28:50 rivo volumio[3291]: info: CoreStateMachine::play index 0 Feb 19 18:28:50 rivo volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:28:50 rivo volumio[3291]: info: CoreStateMachine::stop Feb 19 18:28:50 rivo volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:28:50 rivo volumio[3291]: info: CoreStateMachine::play index undefined Feb 19 18:28:50 rivo volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:28:50 rivo volumio[3291]: info: CorePlayQueue::getTrack 0 Feb 19 18:28:50 rivo volumio[3291]: info: CoreStateMachine::startPlaybackTimer Feb 19 18:28:50 rivo volumio[3291]: info: CorePlayQueue::getTrack 0 Feb 19 18:28:50 rivo volumio[3291]: info: [1739986130313] ControllerQobuz::clearAddPlayTrack Feb 19 18:28:50 rivo volumio[3291]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:28:50 rivo volumio[3291]: error: MPD error: Error: This socket has been ended by the other party Feb 19 18:28:50 rivo volumio[3291]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 19 18:28:50 rivo volumio[3291]: error: MPD error: Error: This socket has been ended by the other party Feb 19 18:28:50 rivo volumio[3291]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 19 18:28:50 rivo volumio[3291]: error: MPD error: Error: This socket has been ended by the other party Feb 19 18:28:50 rivo volumio[3291]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 19 18:28:50 rivo volumio[3291]: info: explodeUri took 1442 milliseconds Feb 19 18:28:50 rivo volumio[3291]: info: explodeUri took 1598 milliseconds Feb 19 18:28:57 rivo volumio[3291]: info: Preload queue cleared Feb 19 18:28:57 rivo volumio[3291]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 19 18:28:57 rivo volumio[3291]: info: CoreStateMachine::ClearQueue Feb 19 18:28:57 rivo volumio[3291]: info: CoreStateMachine::stop Feb 19 18:28:57 rivo volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:28:57 rivo volumio[3291]: info: CorePlayQueue::clearPlayQueue Feb 19 18:28:57 rivo volumio[3291]: info: CorePlayQueue::saveQueue Feb 19 18:28:57 rivo volumio[3291]: info: CoreCommandRouter::volumioPushQueue Feb 19 18:28:57 rivo volumio[3291]: info: CoreStateMachine::addQueueItems Feb 19 18:28:57 rivo volumio[3291]: info: CorePlayQueue::addQueueItems Feb 19 18:28:57 rivo volumio[3291]: info: Preload queue cleared Feb 19 18:28:57 rivo volumio[3291]: info: Adding Item to queue: qobuz://album/eqk5df8h37ssa Feb 19 18:28:57 rivo volumio[3291]: info: Using cached record of: qobuz://album/eqk5df8h37ssa Feb 19 18:28:57 rivo volumio[3291]: info: CoreCommandRouter::volumioPushQueue Feb 19 18:28:57 rivo volumio[3291]: info: CorePlayQueue::saveQueue Feb 19 18:28:57 rivo volumio[3291]: info: CoreStateMachine::updateTrackBlock Feb 19 18:28:57 rivo volumio[3291]: info: CorePlayQueue::getTrackBlock Feb 19 18:28:57 rivo volumio[3291]: info: CoreCommandRouter::volumioPlay Feb 19 18:28:57 rivo volumio[3291]: info: CoreStateMachine::play index 0 Feb 19 18:28:57 rivo volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:28:57 rivo volumio[3291]: info: CoreStateMachine::stop Feb 19 18:28:57 rivo volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:28:57 rivo volumio[3291]: info: CoreStateMachine::play index undefined Feb 19 18:28:57 rivo volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:28:57 rivo volumio[3291]: info: CorePlayQueue::getTrack 0 Feb 19 18:28:57 rivo volumio[3291]: info: CoreStateMachine::startPlaybackTimer Feb 19 18:28:57 rivo volumio[3291]: info: CorePlayQueue::getTrack 0 Feb 19 18:28:57 rivo volumio[3291]: info: [1739986137942] ControllerQobuz::clearAddPlayTrack Feb 19 18:28:57 rivo volumio[3291]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:28:57 rivo volumio[3291]: error: MPD error: Error: This socket has been ended by the other party Feb 19 18:28:57 rivo volumio[3291]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 19 18:28:57 rivo volumio[3291]: error: MPD error: Error: This socket has been ended by the other party Feb 19 18:28:57 rivo volumio[3291]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 19 18:28:57 rivo volumio[3291]: error: MPD error: Error: This socket has been ended by the other party Feb 19 18:28:57 rivo volumio[3291]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 19 18:29:00 rivo volumio[3291]: info: CoreCommandRouter::volumioPlay Feb 19 18:29:00 rivo volumio[3291]: info: CoreStateMachine::play index undefined Feb 19 18:29:00 rivo volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:29:00 rivo volumio[3291]: info: CorePlayQueue::getTrack 0 Feb 19 18:29:00 rivo volumio[3291]: info: CoreStateMachine::startPlaybackTimer Feb 19 18:29:00 rivo volumio[3291]: info: CorePlayQueue::getTrack 0 Feb 19 18:29:00 rivo volumio[3291]: info: [1739986140627] ControllerQobuz::clearAddPlayTrack Feb 19 18:29:00 rivo volumio[3291]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:29:00 rivo volumio[3291]: error: MPD error: Error: This socket has been ended by the other party Feb 19 18:29:00 rivo volumio[3291]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 19 18:29:00 rivo volumio[3291]: error: MPD error: Error: This socket has been ended by the other party Feb 19 18:29:00 rivo volumio[3291]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 19 18:29:00 rivo volumio[3291]: error: MPD error: Error: This socket has been ended by the other party Feb 19 18:29:00 rivo volumio[3291]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 19 18:29:04 rivo volumio[3291]: info: CoreCommandRouter::volumioGetState Feb 19 18:29:04 rivo volumio[3291]: info: CorePlayQueue::getTrack 0 Feb 19 18:29:06 rivo volumio[3291]: info: Executing endpoint metavolumio Feb 19 18:29:06 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 19 18:29:06 rivo volumio[3291]: info: Executing endpoint metavolumio Feb 19 18:29:06 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 19 18:29:06 rivo volumio[3291]: info: Executing endpoint metavolumio Feb 19 18:29:06 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 19 18:29:07 rivo volumio[3291]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 19 18:29:07 rivo volumio[3291]: info: Retrieving Cloud Streaming UI Feb 19 18:29:07 rivo volumio[3291]: info: Getting Tidal Cloud Configuration Feb 19 18:29:07 rivo volumio[3291]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 19 18:29:07 rivo volumio[3291]: info: Getting Qobuz Cloud Configuration Feb 19 18:29:07 rivo volumio[3291]: info: Asking plugin for UI Config Feb 19 18:29:07 rivo volumio[3291]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 19 18:29:07 rivo volumio[3291]: info: Getting Spotify Cloud Configuration Feb 19 18:29:07 rivo volumio[3291]: info: Asking plugin for UI Config Feb 19 18:29:07 rivo volumio[3291]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 19 18:29:07 rivo volumio[3291]: info: Saving Spotify Acccount Feb 19 18:29:07 rivo volumio[3291]: info: Got it Feb 19 18:29:07 rivo volumio[3291]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 19 18:29:07 rivo volumio[3291]: info: Got Tidal Cloud Configuration Feb 19 18:29:07 rivo volumio[3291]: info: Got it Feb 19 18:29:07 rivo volumio[3291]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 19 18:29:07 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 19 18:29:07 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 19 18:29:07 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 19 18:29:07 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 19 18:29:07 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 18:29:07 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 18:29:07 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 18:29:07 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 18:29:07 rivo volumio[3291]: info: CoreCommandRouter::volumioGetBrowseSources Feb 19 18:29:07 rivo volumio[3291]: info: CoreCommandRouter::volumioGetBrowseSources Feb 19 18:29:07 rivo volumio[3291]: info: CoreCommandRouter::volumioGetBrowseSources Feb 19 18:29:08 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 19 18:29:08 rivo volumio[3291]: error: MPD error: Error: This socket has been ended by the other party Feb 19 18:29:08 rivo volumio[3291]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 19 18:29:08 rivo volumio[3291]: error: MPD error: Error: This socket has been ended by the other party Feb 19 18:29:08 rivo volumio[3291]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 19 18:29:08 rivo volumio[3291]: error: MPD error: Error: This socket has been ended by the other party Feb 19 18:29:08 rivo volumio[3291]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 19 18:29:08 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 19 18:29:11 rivo volumio[3291]: info: Disabling MyMusic plugin multiroom Feb 19 18:29:11 rivo volumio[3291]: info: Disabling plugin multiroom Feb 19 18:29:11 rivo volumio[3291]: info: Preparing to generate the ALSA configuration file Feb 19 18:29:11 rivo volumio[3291]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 19 18:29:11 rivo volumio[3291]: info: Reading ALSA contributions from plugins. Feb 19 18:29:11 rivo volumio[3291]: info: Asound.conf file written Feb 19 18:29:11 rivo sudo[16836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 19 18:29:11 rivo sudo[16836]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 18:29:11 rivo sudo[16836]: pam_unix(sudo:session): session closed for user root Feb 19 18:29:11 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Feb 19 18:29:11 rivo kernel: Err: can not set rate to 4278967296! Feb 19 18:29:11 rivo kernel: Range[3920000 - 500000000] Feb 19 18:29:11 rivo kernel: out of value, fixed it Feb 19 18:29:11 rivo kernel: id=0 set inskew=0 Feb 19 18:29:11 rivo volumio[3291]: No protocol specified Feb 19 18:29:11 rivo volumio[3291]: xcb_connection_has_error() returned true Feb 19 18:29:11 rivo volumio[3291]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Feb 19 18:29:11 rivo volumio[3291]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Feb 19 18:29:11 rivo volumio[3291]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Feb 19 18:29:11 rivo volumio[3291]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Feb 19 18:29:11 rivo volumio[3291]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Topping Internal Clock Validity:0' : Operation not permitted Feb 19 18:29:11 rivo volumio[3291]: info: Output device has changed, restarting MPD Feb 19 18:29:11 rivo volumio[3291]: info: Output device has changed, restarting Shairport Sync Feb 19 18:29:11 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:11 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 18:29:11 rivo sudo[16841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 19 18:29:11 rivo sudo[16841]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 18:29:11 rivo sudo[16841]: pam_unix(sudo:session): session closed for user root Feb 19 18:29:11 rivo sudo[16843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 18:29:11 rivo sudo[16843]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 18:29:11 rivo systemd[1]: Stopping Music Player Daemon... Feb 19 18:29:11 rivo volumio[3291]: No protocol specified Feb 19 18:29:11 rivo volumio[3291]: xcb_connection_has_error() returned true Feb 19 18:29:11 rivo volumio[3291]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 18:29:11 rivo volumio[3291]: No protocol specified Feb 19 18:29:11 rivo volumio[3291]: xcb_connection_has_error() returned true Feb 19 18:29:11 rivo volumio[3291]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 19 18:29:11 rivo systemd[1]: mpd.service: Succeeded. Feb 19 18:29:11 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:11 rivo systemd[1]: Stopped Music Player Daemon. Feb 19 18:29:11 rivo systemd[1]: Starting Music Player Daemon... Feb 19 18:29:11 rivo vtcs[4052]: [2025-02-19 18:29:11.566] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Feb 19 18:29:11 rivo volumio[3291]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 19 18:29:11 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:11 rivo vtcs[4052]: [2025-02-19 18:29:11.568] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=7 errorno=104 error=Connection reset by peer Feb 19 18:29:11 rivo vtcs[4052]: [2025-02-19 18:29:11.568] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Feb 19 18:29:11 rivo volumio[3291]: info: Done. Feb 19 18:29:11 rivo sudo[16863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 19 18:29:11 rivo sudo[16863]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 18:29:11 rivo volumio[3291]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 19 18:29:11 rivo volumio[3291]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 19 18:29:11 rivo sudo[16867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 19 18:29:11 rivo sudo[16867]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 18:29:11 rivo systemd[1]: Stopping Volumio Tidal Connect Service... Feb 19 18:29:11 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Feb 19 18:29:11 rivo systemd[1]: vtcs.service: Succeeded. Feb 19 18:29:11 rivo systemd[1]: Stopped Volumio Tidal Connect Service. Feb 19 18:29:11 rivo volumio[3291]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 19 18:29:11 rivo volumio[3291]: info: MPD Permissions set Feb 19 18:29:11 rivo sudo[16850]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 18:29:11 rivo sudo[16850]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 18:29:11 rivo sudo[16863]: pam_unix(sudo:session): session closed for user root Feb 19 18:29:11 rivo volumio[3291]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 19 18:29:11 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:11 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:11 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:11 rivo sudo[16850]: pam_unix(sudo:session): session closed for user root Feb 19 18:29:11 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:11 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:11 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:11 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:11 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:11 rivo sudo[16867]: pam_unix(sudo:session): session closed for user root Feb 19 18:29:11 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 18:29:11 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 19 18:29:12 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:12 rivo volumio[3291]: info: Enabling MyMusic plugin multiroom Feb 19 18:29:12 rivo volumio[3291]: info: Enabling plugin multiroom Feb 19 18:29:12 rivo volumio[3291]: info: Loading plugin "multiroom"... Feb 19 18:29:12 rivo volumio[3291]: info: Applying required configuration parameters for plugin multiroom Feb 19 18:29:12 rivo sudo[16882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 19 18:29:12 rivo sudo[16882]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 18:29:12 rivo sudo[16882]: pam_unix(sudo:session): session closed for user root Feb 19 18:29:12 rivo volumio[3291]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Feb 19 18:29:12 rivo volumio[3291]: info: MRS: MultiRoom plugin initialized Feb 19 18:29:12 rivo volumio[3291]: info: MRS: STOPPING SNAPCLIENT Feb 19 18:29:12 rivo volumio[3291]: info: MRS: Snap server stop Feb 19 18:29:12 rivo volumio[3291]: info: MRS: STOPPING volumioStreaming Feb 19 18:29:12 rivo sudo[16899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 19 18:29:12 rivo sudo[16899]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 18:29:12 rivo sudo[16901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 19 18:29:12 rivo sudo[16901]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 18:29:12 rivo sudo[16899]: pam_unix(sudo:session): session closed for user root Feb 19 18:29:12 rivo sudo[16904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 19 18:29:12 rivo sudo[16904]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 18:29:12 rivo volumio[3291]: info: Preparing to generate the ALSA configuration file Feb 19 18:29:12 rivo sudo[16906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 19 18:29:12 rivo sudo[16906]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 18:29:12 rivo sudo[16901]: pam_unix(sudo:session): session closed for user root Feb 19 18:29:12 rivo sudo[16906]: pam_unix(sudo:session): session closed for user root Feb 19 18:29:12 rivo sudo[16904]: pam_unix(sudo:session): session closed for user root Feb 19 18:29:12 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:12 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:12 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:12 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:12 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:12 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:12 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:12 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:12 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 18:29:12 rivo volumio[3291]: info: Not Reporting Auto name since its the default one Feb 19 18:29:12 rivo volumio[3291]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 19 18:29:12 rivo volumio[3291]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 19 18:29:12 rivo volumio[3291]: info: Reading ALSA contributions from plugins. Feb 19 18:29:12 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 19 18:29:12 rivo volumio[3291]: error: MPD error: Error: This socket has been ended by the other party Feb 19 18:29:12 rivo volumio[3291]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 19 18:29:12 rivo volumio[3291]: error: MPD error: Error: This socket has been ended by the other party Feb 19 18:29:12 rivo volumio[3291]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 19 18:29:12 rivo volumio[3291]: error: MPD error: Error: This socket has been ended by the other party Feb 19 18:29:12 rivo volumio[3291]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 19 18:29:12 rivo volumio[3291]: info: MRS: Removed streaming files Feb 19 18:29:12 rivo volumio[3291]: info: MRS: volumioStreaming STOPPED Feb 19 18:29:12 rivo volumio[3291]: info: MRS: SNAPSERVER STOPPED Feb 19 18:29:12 rivo volumio[3291]: info: MRS: SNAPCLIENT STOPPED Feb 19 18:29:12 rivo volumio[3291]: info: Starting Shairport Sync Feb 19 18:29:12 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 19 18:29:12 rivo sudo[16915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 18:29:12 rivo sudo[16915]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 18:29:12 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 19 18:29:12 rivo systemd[1]: shairport-sync.service: Succeeded. Feb 19 18:29:12 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 19 18:29:12 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 19 18:29:12 rivo sudo[16915]: pam_unix(sudo:session): session closed for user root Feb 19 18:29:13 rivo volumio[3291]: No protocol specified Feb 19 18:29:13 rivo volumio[3291]: xcb_connection_has_error() returned true Feb 19 18:29:13 rivo volumio[3291]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 19 18:29:13 rivo volumio[3291]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 19 18:29:13 rivo volumio[3291]: info: Shairport-Sync Started Feb 19 18:29:13 rivo volumio[3291]: info: Asound.conf file written Feb 19 18:29:13 rivo sudo[16923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 19 18:29:13 rivo sudo[16923]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 18:29:13 rivo sudo[16923]: pam_unix(sudo:session): session closed for user root Feb 19 18:29:13 rivo sudo[16928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 19 18:29:13 rivo sudo[16928]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 18:29:13 rivo sudo[16929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 19 18:29:13 rivo sudo[16929]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 18:29:13 rivo sudo[16928]: pam_unix(sudo:session): session closed for user root Feb 19 18:29:13 rivo systemd[1]: Stopping RAAT DAEMON... Feb 19 18:29:13 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Feb 19 18:29:13 rivo systemd[1]: raat-daemon.service: Succeeded. Feb 19 18:29:13 rivo systemd[1]: Stopped RAAT DAEMON. Feb 19 18:29:13 rivo systemd[1]: Started RAAT DAEMON. Feb 19 18:29:13 rivo sudo[16929]: pam_unix(sudo:session): session closed for user root Feb 19 18:29:13 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Feb 19 18:29:13 rivo kernel: Err: can not set rate to 4274967296! Feb 19 18:29:13 rivo kernel: Range[3920000 - 500000000] Feb 19 18:29:13 rivo kernel: out of value, fixed it Feb 19 18:29:13 rivo kernel: id=0 set inskew=0 Feb 19 18:29:13 rivo volumio[3291]: No protocol specified Feb 19 18:29:13 rivo volumio[3291]: xcb_connection_has_error() returned true Feb 19 18:29:13 rivo volumio[3291]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Feb 19 18:29:13 rivo volumio[3291]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Feb 19 18:29:13 rivo volumio[3291]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Feb 19 18:29:13 rivo volumio[3291]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Feb 19 18:29:13 rivo volumio[3291]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Topping Internal Clock Validity:0' : Operation not permitted Feb 19 18:29:13 rivo volumio[3291]: info: Output device has changed, restarting MPD Feb 19 18:29:13 rivo volumio[3291]: info: Output device has changed, restarting Shairport Sync Feb 19 18:29:13 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:13 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 18:29:13 rivo sudo[16941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 19 18:29:13 rivo sudo[16941]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 18:29:13 rivo sudo[16941]: pam_unix(sudo:session): session closed for user root Feb 19 18:29:13 rivo sudo[16943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 18:29:13 rivo sudo[16943]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 18:29:13 rivo volumio[3291]: No protocol specified Feb 19 18:29:13 rivo volumio[3291]: xcb_connection_has_error() returned true Feb 19 18:29:13 rivo volumio[3291]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 18:29:13 rivo volumio[3291]: No protocol specified Feb 19 18:29:13 rivo volumio[3291]: xcb_connection_has_error() returned true Feb 19 18:29:13 rivo volumio[3291]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 19 18:29:13 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:13 rivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 19 18:29:13 rivo systemd[1]: mpd.service: Succeeded. Feb 19 18:29:13 rivo systemd[1]: Stopped Music Player Daemon. Feb 19 18:29:13 rivo systemd[1]: Starting Music Player Daemon... Feb 19 18:29:13 rivo sudo[16953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 19 18:29:13 rivo volumio[3291]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 19 18:29:13 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:13 rivo volumio[3291]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 19 18:29:13 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:13 rivo volumio[3291]: info: PLUGIN START: multiroom Feb 19 18:29:13 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Feb 19 18:29:13 rivo volumio[3291]: info: MRS: Pushing multiroomSync output for this device Feb 19 18:29:13 rivo volumio[3291]: info: MRS: Pushing multiroomSync output Feb 19 18:29:13 rivo volumio[3291]: info: Adding audio output: Feb 19 18:29:13 rivo volumio[3291]: error: Can't add: Feb 19 18:29:13 rivo volumio[3291]: info: Adding audio output: Feb 19 18:29:13 rivo volumio[3291]: error: Can't add: Feb 19 18:29:13 rivo sudo[16953]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 18:29:13 rivo sudo[16953]: pam_unix(sudo:session): session closed for user root Feb 19 18:29:13 rivo volumio[3291]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 19 18:29:13 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 18:29:13 rivo sudo[16950]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 18:29:13 rivo volumio[3291]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 19 18:29:14 rivo volumio[3291]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 19 18:29:14 rivo sudo[16950]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 18:29:14 rivo volumio[3291]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 19 18:29:14 rivo volumio[3291]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 19 18:29:14 rivo volumio[3291]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 19 18:29:14 rivo volumio[3291]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 19 18:29:14 rivo sudo[16950]: pam_unix(sudo:session): session closed for user root Feb 19 18:29:14 rivo sudo[16963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 19 18:29:14 rivo sudo[16963]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 18:29:14 rivo volumio[3291]: info: Disabling MyMusic plugin upnp Feb 19 18:29:14 rivo volumio[3291]: info: Enabling MyMusic plugin upnp Feb 19 18:29:14 rivo volumio[3291]: info: Enabling plugin upnp Feb 19 18:29:14 rivo volumio[3291]: info: Loading plugin "upnp"... Feb 19 18:29:14 rivo volumio[3291]: info: [1739986154108] Starting Upmpd Daemon Feb 19 18:29:14 rivo volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 18:29:14 rivo sudo[16963]: pam_unix(sudo:session): session closed for user root Feb 19 18:29:14 rivo volumio[3291]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 18:29:14 rivo sudo[16967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Feb 19 18:29:14 rivo sudo[16967]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 18:29:14 rivo volumio[3291]: Error: listen EADDRINUSE: address already in use :::6599 Feb 19 18:29:14 rivo volumio[3291]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Feb 19 18:29:14 rivo volumio[3291]: at listenInCluster (net.js:1379:12) Feb 19 18:29:14 rivo volumio[3291]: at Server.listen (net.js:1465:7) Feb 19 18:29:14 rivo volumio[3291]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Feb 19 18:29:14 rivo volumio[3291]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Feb 19 18:29:14 rivo volumio[3291]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Feb 19 18:29:14 rivo volumio[3291]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 19 18:29:14 rivo volumio[3291]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Feb 19 18:29:14 rivo volumio[3291]: code: 'EADDRINUSE', Feb 19 18:29:14 rivo volumio[3291]: errno: -98, Feb 19 18:29:14 rivo volumio[3291]: syscall: 'listen', Feb 19 18:29:14 rivo volumio[3291]: address: '::', Feb 19 18:29:14 rivo volumio[3291]: port: 6599 Feb 19 18:29:14 rivo volumio[3291]: } Feb 19 18:29:14 rivo volumio[3291]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 18:29:14 rivo systemd[1]: Stopping UPnP Renderer front-end to MPD... Feb 19 18:29:14 rivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 19 18:29:14 rivo systemd[1]: mpd.service: Succeeded. Feb 19 18:29:14 rivo systemd[1]: Stopped Music Player Daemon. Feb 19 18:29:15 rivo systemd[1]: Starting Music Player Daemon... Feb 19 18:29:15 rivo sudo[16986]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 18:29:15 rivo sudo[16986]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 18:29:15 rivo sudo[16986]: pam_unix(sudo:session): session closed for user root Feb 19 18:29:15 rivo sudo[16989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-19 18:28 Feb 19 18:29:15 rivo sudo[16989]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 05:04:03 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b3e66dc85ea2c87aca7db72b97d00dff"