-- Logs begin at Tue 2024-08-06 05:38:41 CEST, end at Tue 2024-08-27 11:00:27 CEST. -- Aug 27 10:59:00 volumio volumio[1094]: info: handleBrowseUri took 458 milliseconds Aug 27 10:59:00 volumio volumio[1094]: info: Preload queue cleared Aug 27 10:59:00 volumio volumio[1094]: info: Preloading song: qobuz://song/94685008 Aug 27 10:59:00 volumio volumio[1094]: info: Exploding uri qobuz://song/94685008 in service qobuz Aug 27 10:59:00 volumio volumio[1094]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Aug 27 10:59:00 volumio volumio[1094]: info: Executing endpoint getSimilarAlbums Aug 27 10:59:00 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 27 10:59:00 volumio volumio[1094]: info: Executing endpoint getSimilarAlbums Aug 27 10:59:00 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 27 10:59:00 volumio volumio[1094]: info: Executing endpoint getSimilarAlbums Aug 27 10:59:00 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 27 10:59:00 volumio volumio[1094]: info: Executing endpoint getSimilarAlbums Aug 27 10:59:00 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 27 10:59:00 volumio volumio[1094]: info: Executing endpoint getSimilarAlbums Aug 27 10:59:00 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 27 10:59:00 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:00 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:00 volumio volumio[1094]: info: explodeUri took 318 milliseconds Aug 27 10:59:01 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:01 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:01 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:01 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:01 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:01 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:01 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:01 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:02 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:02 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:02 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:02 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:02 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:02 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:03 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:03 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:03 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:03 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:12 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 27 10:59:12 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: calmradio , search Aug 27 10:59:12 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , search Aug 27 10:59:12 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Aug 27 10:59:12 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: tidal , search Aug 27 10:59:12 volumio volumio[1094]: info: [1724749152914] ControllerCalmRadio::searchCategories Aug 27 10:59:12 volumio volumio[1094]: info: [1724749152925] ControllerCalmRadio::searchChannels Aug 27 10:59:13 volumio volumio[1094]: info: searchTIDALUri took 985 milliseconds Aug 27 10:59:13 volumio volumio[1094]: info: search took 986 milliseconds Aug 27 10:59:14 volumio volumio[1094]: info: searchQOBUZUri took 1309 milliseconds Aug 27 10:59:14 volumio volumio[1094]: info: All search sources collected, pushing search results Aug 27 10:59:14 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Aug 27 10:59:14 volumio volumio[1094]: info: handleBrowseUri took 327 milliseconds Aug 27 10:59:14 volumio volumio[1094]: info: Preload queue cleared Aug 27 10:59:14 volumio volumio[1094]: info: Executing endpoint getSimilarArtists Aug 27 10:59:14 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Aug 27 10:59:14 volumio volumio[1094]: info: Executing endpoint getSimilarArtists Aug 27 10:59:14 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Aug 27 10:59:14 volumio volumio[1094]: info: Executing endpoint getSimilarArtists Aug 27 10:59:14 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Aug 27 10:59:14 volumio volumio[1094]: info: Executing endpoint getSimilarArtists Aug 27 10:59:14 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Aug 27 10:59:14 volumio volumio[1094]: info: Executing endpoint getSimilarArtists Aug 27 10:59:14 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Aug 27 10:59:14 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:14 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:15 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:15 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:15 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 10:59:15 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 10:59:15 volumio volumio[1094]: info: Discovery: Getting this device information Aug 27 10:59:15 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Aug 27 10:59:15 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 10:59:15 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:15 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:15 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:15 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:15 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:15 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:19 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Aug 27 10:59:20 volumio volumio[1094]: info: handleBrowseUri took 369 milliseconds Aug 27 10:59:20 volumio volumio[1094]: info: Preload queue cleared Aug 27 10:59:20 volumio volumio[1094]: info: Preloading song: qobuz://song/174382441 Aug 27 10:59:20 volumio volumio[1094]: info: Preloading song: qobuz://song/174382442 Aug 27 10:59:20 volumio volumio[1094]: info: Preloading song: qobuz://song/174382443 Aug 27 10:59:20 volumio volumio[1094]: info: Preloading song: qobuz://song/174382444 Aug 27 10:59:20 volumio volumio[1094]: info: Preloading song: qobuz://song/174382445 Aug 27 10:59:20 volumio volumio[1094]: info: Preloading song: qobuz://song/174382446 Aug 27 10:59:20 volumio volumio[1094]: info: Preloading song: qobuz://song/174382447 Aug 27 10:59:20 volumio volumio[1094]: info: Preloading song: qobuz://song/174382448 Aug 27 10:59:20 volumio volumio[1094]: info: Preloading song: qobuz://song/174382449 Aug 27 10:59:20 volumio volumio[1094]: info: Exploding uri qobuz://song/174382441 in service qobuz Aug 27 10:59:20 volumio volumio[1094]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Aug 27 10:59:20 volumio volumio[1094]: info: Exploding uri qobuz://song/174382442 in service qobuz Aug 27 10:59:20 volumio volumio[1094]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Aug 27 10:59:20 volumio volumio[1094]: info: Exploding uri qobuz://song/174382443 in service qobuz Aug 27 10:59:20 volumio volumio[1094]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Aug 27 10:59:20 volumio volumio[1094]: info: Executing endpoint getSimilarAlbums Aug 27 10:59:20 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 27 10:59:20 volumio volumio[1094]: info: Executing endpoint getSimilarAlbums Aug 27 10:59:20 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 27 10:59:20 volumio volumio[1094]: info: Executing endpoint getSimilarAlbums Aug 27 10:59:20 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 27 10:59:20 volumio volumio[1094]: info: Executing endpoint getSimilarAlbums Aug 27 10:59:20 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 27 10:59:20 volumio volumio[1094]: info: Executing endpoint getSimilarAlbums Aug 27 10:59:20 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 27 10:59:20 volumio volumio[1094]: info: Exploding uri qobuz://song/174382444 in service qobuz Aug 27 10:59:20 volumio volumio[1094]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Aug 27 10:59:20 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:20 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:20 volumio volumio[1094]: info: Exploding uri qobuz://song/174382445 in service qobuz Aug 27 10:59:20 volumio volumio[1094]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Aug 27 10:59:20 volumio volumio[1094]: info: Exploding uri qobuz://song/174382446 in service qobuz Aug 27 10:59:20 volumio volumio[1094]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Aug 27 10:59:20 volumio volumio[1094]: info: Exploding uri qobuz://song/174382447 in service qobuz Aug 27 10:59:20 volumio volumio[1094]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Aug 27 10:59:20 volumio volumio[1094]: info: Exploding uri qobuz://song/174382448 in service qobuz Aug 27 10:59:20 volumio volumio[1094]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Aug 27 10:59:20 volumio volumio[1094]: info: explodeUri took 335 milliseconds Aug 27 10:59:20 volumio volumio[1094]: info: Exploding uri qobuz://song/174382449 in service qobuz Aug 27 10:59:20 volumio volumio[1094]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Aug 27 10:59:20 volumio volumio[1094]: info: explodeUri took 423 milliseconds Aug 27 10:59:20 volumio volumio[1094]: info: explodeUri took 398 milliseconds Aug 27 10:59:20 volumio volumio[1094]: info: explodeUri took 328 milliseconds Aug 27 10:59:20 volumio volumio[1094]: info: explodeUri took 328 milliseconds Aug 27 10:59:20 volumio volumio[1094]: info: explodeUri took 391 milliseconds Aug 27 10:59:20 volumio volumio[1094]: info: explodeUri took 334 milliseconds Aug 27 10:59:21 volumio volumio[1094]: info: explodeUri took 308 milliseconds Aug 27 10:59:21 volumio volumio[1094]: info: explodeUri took 413 milliseconds Aug 27 10:59:21 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:21 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:21 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:21 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:21 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:21 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:21 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:21 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:22 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:22 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:22 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:22 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:22 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:22 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:22 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:22 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:23 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 10:59:23 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 10:59:30 volumio volumio[1094]: info: Preload queue cleared Aug 27 10:59:30 volumio volumio[1094]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 27 10:59:30 volumio volumio[1094]: info: CoreStateMachine::ClearQueue Aug 27 10:59:30 volumio volumio[1094]: info: CoreStateMachine::stop Aug 27 10:59:30 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 10:59:30 volumio volumio[1094]: info: CoreStateMachine::updateTrackBlock Aug 27 10:59:30 volumio volumio[1094]: info: CorePlayQueue::getTrackBlock Aug 27 10:59:30 volumio volumio[1094]: info: CoreStateMachine::stPlaybackTimer Aug 27 10:59:30 volumio volumio[1094]: info: CoreStateMachine::pushState Aug 27 10:59:30 volumio volumio[1094]: info: CorePlayQueue::getTrack 6 Aug 27 10:59:30 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 10:59:30 volumio volumio[1094]: info: CoreCommandRouter::volumioPushState Aug 27 10:59:30 volumio volumio[1094]: info: MRS: Pushing multiroomSync output update for this device Aug 27 10:59:30 volumio volumio[1094]: info: MRS: Pushing multiroomSync output Aug 27 10:59:30 volumio volumio[1094]: info: CoreStateMachine::serviceStop Aug 27 10:59:30 volumio volumio[1094]: info: CorePlayQueue::getTrack 6 Aug 27 10:59:30 volumio volumio[1094]: info: CoreCommandRouter::serviceStop Aug 27 10:59:30 volumio volumio[1094]: info: [1724749170584] ControllerQobuz::stop Aug 27 10:59:30 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 27 10:59:30 volumio volumio[1094]: info: ControllerMpd::stop Aug 27 10:59:30 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 10:59:30 volumio volumio[1094]: info: CorePlayQueue::clearPlayQueue Aug 27 10:59:30 volumio volumio[1094]: info: CorePlayQueue::saveQueue Aug 27 10:59:30 volumio volumio[1094]: info: CoreCommandRouter::volumioPushQueue Aug 27 10:59:30 volumio volumio[1094]: info: CoreStateMachine::addQueueItems Aug 27 10:59:30 volumio volumio[1094]: info: CorePlayQueue::addQueueItems Aug 27 10:59:30 volumio volumio[1094]: info: Preload queue cleared Aug 27 10:59:30 volumio volumio[1094]: info: Adding Item to queue: qobuz://song/174382441 Aug 27 10:59:30 volumio volumio[1094]: info: Using cached record of: qobuz://song/174382441 Aug 27 10:59:30 volumio volumio[1094]: info: Adding Item to queue: qobuz://song/174382442 Aug 27 10:59:30 volumio volumio[1094]: info: Using cached record of: qobuz://song/174382442 Aug 27 10:59:30 volumio volumio[1094]: info: Adding Item to queue: qobuz://song/174382443 Aug 27 10:59:30 volumio volumio[1094]: info: Using cached record of: qobuz://song/174382443 Aug 27 10:59:30 volumio volumio[1094]: info: Adding Item to queue: qobuz://song/174382444 Aug 27 10:59:30 volumio volumio[1094]: info: Using cached record of: qobuz://song/174382444 Aug 27 10:59:30 volumio volumio[1094]: info: Adding Item to queue: qobuz://song/174382445 Aug 27 10:59:30 volumio volumio[1094]: info: Using cached record of: qobuz://song/174382445 Aug 27 10:59:30 volumio volumio[1094]: info: Adding Item to queue: qobuz://song/174382446 Aug 27 10:59:30 volumio volumio[1094]: info: Using cached record of: qobuz://song/174382446 Aug 27 10:59:30 volumio volumio[1094]: info: Adding Item to queue: qobuz://song/174382447 Aug 27 10:59:30 volumio volumio[1094]: info: Using cached record of: qobuz://song/174382447 Aug 27 10:59:30 volumio volumio[1094]: info: Adding Item to queue: qobuz://song/174382448 Aug 27 10:59:30 volumio volumio[1094]: info: Using cached record of: qobuz://song/174382448 Aug 27 10:59:30 volumio volumio[1094]: info: Adding Item to queue: qobuz://song/174382449 Aug 27 10:59:30 volumio volumio[1094]: info: Using cached record of: qobuz://song/174382449 Aug 27 10:59:30 volumio volumio[1094]: info: CoreCommandRouter::volumioPushQueue Aug 27 10:59:30 volumio volumio[1094]: info: CorePlayQueue::saveQueue Aug 27 10:59:30 volumio volumio[1094]: info: CoreStateMachine::updateTrackBlock Aug 27 10:59:30 volumio volumio[1094]: info: CorePlayQueue::getTrackBlock Aug 27 10:59:30 volumio volumio[1094]: info: CoreCommandRouter::volumioPlay Aug 27 10:59:30 volumio volumio[1094]: info: CoreStateMachine::play index 0 Aug 27 10:59:30 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 10:59:30 volumio volumio[1094]: info: CoreStateMachine::stop Aug 27 10:59:30 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 10:59:30 volumio volumio[1094]: info: CoreStateMachine::play index undefined Aug 27 10:59:30 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 10:59:30 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Aug 27 10:59:30 volumio volumio[1094]: info: CoreStateMachine::startPlaybackTimer Aug 27 10:59:30 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Aug 27 10:59:30 volumio volumio[1094]: info: [1724749170603] ControllerQobuz::clearAddPlayTrack Aug 27 10:59:30 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 10:59:30 volumio volumio[1094]: info: Aug 27 10:59:30 volumio volumio[1094]: ---------------------------- MPD announces state update: player Aug 27 10:59:30 volumio volumio[1094]: info: sendMpdCommand stop took 25 milliseconds Aug 27 10:59:30 volumio volumio[1094]: info: ControllerMpd::getState Aug 27 10:59:30 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand status Aug 27 10:59:30 volumio volumio[1094]: info: sendMpdCommand stop took 7 milliseconds Aug 27 10:59:30 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand clear Aug 27 10:59:30 volumio volumio[1094]: info: Aug 27 10:59:30 volumio volumio[1094]: ---------------------------- MPD announces system playlist update Aug 27 10:59:30 volumio volumio[1094]: info: Ignoring MPD Status Update Aug 27 10:59:30 volumio volumio[1094]: info: sendMpdCommand status took 2 milliseconds Aug 27 10:59:30 volumio volumio[1094]: info: sendMpdCommand clear took 1 milliseconds Aug 27 10:59:30 volumio volumio[1094]: verbose: ControllerMpd::parseState Aug 27 10:59:30 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 10:59:30 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/174382441" Aug 27 10:59:30 volumio volumio[1094]: error: updateQueue error: null Aug 27 10:59:30 volumio volumio[1094]: STREAMING PROXY: Handling url /?data=qobuz://song/174382441 Aug 27 10:59:30 volumio volumio[1094]: info: ------------------------------ 3ms Aug 27 10:59:30 volumio volumio[1094]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 27 10:59:30 volumio volumio[1094]: verbose: ControllerMpd::parseTrackInfo Aug 27 10:59:30 volumio volumio[1094]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 27 10:59:30 volumio volumio[1094]: info: ------------------------------ 6ms Aug 27 10:59:30 volumio volumio[1094]: info: Executing endpoint getStreamUrlqobuz Aug 27 10:59:30 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Aug 27 10:59:31 volumio volumio[1094]: info: getStreamUrl took 1154 milliseconds Aug 27 10:59:31 volumio volumio[1094]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2731211&eid=174382441&fmt=6&profile=raw&app_id=539451548&cid=1904528&etsp=1724752771&hmac=qfF6MSNu1wAIdr1ZQJHlNKNCspE Aug 27 10:59:31 volumio volumio[1094]: STREAMING PROXY: Response: 200, length: 14976291 Aug 27 10:59:31 volumio volumio[1094]: STREAMING PROXY: Client dropped request, destroying Aug 27 10:59:31 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/174382441" Aug 27 10:59:31 volumio volumio[1094]: info: Aug 27 10:59:31 volumio volumio[1094]: ---------------------------- MPD announces system playlist update Aug 27 10:59:31 volumio volumio[1094]: info: Ignoring MPD Status Update Aug 27 10:59:31 volumio volumio[1094]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/174382441" took 2 milliseconds Aug 27 10:59:31 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 27 10:59:31 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand play Aug 27 10:59:31 volumio volumio[1094]: STREAMING PROXY: Handling url /?data=qobuz://song/174382441 Aug 27 10:59:31 volumio volumio[1094]: info: ------------------------------ 3ms Aug 27 10:59:31 volumio volumio[1094]: info: sendMpdCommand play took 2 milliseconds Aug 27 10:59:31 volumio volumio[1094]: info: Executing endpoint getStreamUrlqobuz Aug 27 10:59:31 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Aug 27 10:59:32 volumio volumio[1094]: info: getStreamUrl took 404 milliseconds Aug 27 10:59:32 volumio volumio[1094]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2731211&eid=174382441&fmt=6&profile=raw&app_id=539451548&cid=1904528&etsp=1724752772&hmac=vzz9XctXmRdXQ6K6v-gY7cX-b3A Aug 27 10:59:32 volumio volumio[1094]: STREAMING PROXY: Response: 200, length: 14976291 Aug 27 10:59:32 volumio volumio[1094]: info: Aug 27 10:59:32 volumio volumio[1094]: ---------------------------- MPD announces state update: player Aug 27 10:59:32 volumio volumio[1094]: info: ControllerMpd::getState Aug 27 10:59:32 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand status Aug 27 10:59:32 volumio volumio[1094]: info: Aug 27 10:59:32 volumio volumio[1094]: ---------------------------- MPD announces state update: player Aug 27 10:59:32 volumio volumio[1094]: info: sendMpdCommand status took 4 milliseconds Aug 27 10:59:32 volumio volumio[1094]: info: ControllerMpd::getState Aug 27 10:59:32 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand status Aug 27 10:59:32 volumio volumio[1094]: verbose: ControllerMpd::parseState Aug 27 10:59:32 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 10:59:32 volumio volumio[1094]: info: sendMpdCommand status took 1 milliseconds Aug 27 10:59:32 volumio volumio[1094]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 27 10:59:32 volumio volumio[1094]: verbose: ControllerMpd::parseState Aug 27 10:59:32 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 10:59:32 volumio volumio[1094]: verbose: ControllerMpd::parseTrackInfo Aug 27 10:59:32 volumio volumio[1094]: info: ControllerMpd::pushState Aug 27 10:59:32 volumio volumio[1094]: info: CoreCommandRouter::servicePushState Aug 27 10:59:32 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Aug 27 10:59:32 volumio volumio[1094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":221,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"174382441","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/174382441","trackType":"qobuz"} Aug 27 10:59:32 volumio volumio[1094]: verbose: CURRENT POSITION 0 Aug 27 10:59:32 volumio volumio[1094]: info: CoreStateMachine::syncState stateService play Aug 27 10:59:32 volumio volumio[1094]: info: CoreStateMachine::syncState currentStatus stop Aug 27 10:59:32 volumio volumio[1094]: info: ------------------------------ 9ms Aug 27 10:59:32 volumio volumio[1094]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 27 10:59:32 volumio volumio[1094]: verbose: ControllerMpd::parseTrackInfo Aug 27 10:59:32 volumio volumio[1094]: info: ControllerMpd::pushState Aug 27 10:59:32 volumio volumio[1094]: info: CoreCommandRouter::servicePushState Aug 27 10:59:32 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Aug 27 10:59:32 volumio volumio[1094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":457,"duration":221,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"109 Kbps","isStreaming":false,"title":"174382441","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/174382441","trackType":"qobuz"} Aug 27 10:59:32 volumio volumio[1094]: verbose: CURRENT POSITION 0 Aug 27 10:59:32 volumio volumio[1094]: info: CoreStateMachine::syncState stateService play Aug 27 10:59:32 volumio volumio[1094]: info: CoreStateMachine::syncState currentStatus play Aug 27 10:59:32 volumio volumio[1094]: info: Received an update from plugin. extracting info from payload Aug 27 10:59:32 volumio volumio[1094]: info: CoreStateMachine::pushState Aug 27 10:59:32 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 10:59:32 volumio volumio[1094]: info: CoreCommandRouter::volumioPushState Aug 27 10:59:32 volumio volumio[1094]: info: MRS: Pushing multiroomSync output update for this device Aug 27 10:59:32 volumio volumio[1094]: info: MRS: Pushing multiroomSync output Aug 27 10:59:32 volumio volumio[1094]: info: CoreStateMachine::pushState Aug 27 10:59:32 volumio volumio[1094]: info: CoreCommandRouter::volumioPushState Aug 27 10:59:32 volumio volumio[1094]: info: MRS: Pushing multiroomSync output update for this device Aug 27 10:59:32 volumio volumio[1094]: info: MRS: Pushing multiroomSync output Aug 27 10:59:32 volumio volumio[1094]: info: ------------------------------ 15ms Aug 27 10:59:35 volumio volumio[1094]: STREAMING PROXY: Client dropped request, destroying Aug 27 10:59:45 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 10:59:45 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 10:59:45 volumio volumio[1094]: info: Discovery: Getting this device information Aug 27 10:59:45 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Aug 27 10:59:45 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 10:59:50 volumio volumiossh-tunnel[5814]: Connection to eu7.myvolumio.org closed by remote host. Aug 27 10:59:50 volumio autossh[5817]: ssh exited with error status 255; restarting ssh Aug 27 10:59:50 volumio autossh[5817]: starting ssh (count 24) Aug 27 10:59:50 volumio autossh[5817]: ssh child pid is 27674 Aug 27 10:59:51 volumio volumiossh-tunnel[5814]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Aug 27 10:59:51 volumio autossh[5817]: ssh exited with error status 255; restarting ssh Aug 27 10:59:51 volumio autossh[5817]: starting ssh (count 25) Aug 27 10:59:51 volumio autossh[5817]: ssh child pid is 27675 Aug 27 10:59:51 volumio volumiossh-tunnel[5814]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Aug 27 10:59:51 volumio autossh[5817]: ssh exited with error status 255; restarting ssh Aug 27 10:59:51 volumio autossh[5817]: starting ssh (count 26) Aug 27 10:59:51 volumio autossh[5817]: ssh child pid is 27676 Aug 27 10:59:51 volumio volumiossh-tunnel[5814]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Aug 27 10:59:51 volumio autossh[5817]: ssh exited with error status 255; restarting ssh Aug 27 10:59:51 volumio autossh[5817]: starting ssh (count 27) Aug 27 10:59:51 volumio autossh[5817]: ssh child pid is 27677 Aug 27 10:59:51 volumio volumiossh-tunnel[5814]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Aug 27 10:59:51 volumio autossh[5817]: ssh exited with error status 255; restarting ssh Aug 27 10:59:51 volumio autossh[5817]: starting ssh (count 28) Aug 27 10:59:51 volumio autossh[5817]: ssh child pid is 27678 Aug 27 10:59:51 volumio volumiossh-tunnel[5814]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Aug 27 10:59:51 volumio autossh[5817]: ssh exited with error status 255; restarting ssh Aug 27 10:59:51 volumio autossh[5817]: starting ssh (count 29) Aug 27 10:59:51 volumio autossh[5817]: ssh child pid is 27679 Aug 27 10:59:51 volumio volumiossh-tunnel[5814]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Aug 27 10:59:51 volumio autossh[5817]: ssh exited with error status 255; restarting ssh Aug 27 10:59:53 volumio autossh[5817]: starting ssh (count 30) Aug 27 10:59:53 volumio autossh[5817]: ssh child pid is 27681 Aug 27 10:59:57 volumio volumiossh-tunnel[5814]: Warning: Permanently added '[eu7.myvolumio.org]:2222,[167.71.43.69]:2222' (RSA) to the list of known hosts. Aug 27 11:00:06 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Aug 27 11:00:06 volumio volumio[1094]: info: handleBrowseUri took 299 milliseconds Aug 27 11:00:06 volumio volumio[1094]: info: Preload queue cleared Aug 27 11:00:06 volumio volumio[1094]: info: Executing endpoint getSimilarAlbums Aug 27 11:00:06 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 27 11:00:06 volumio volumio[1094]: info: Executing endpoint getSimilarAlbums Aug 27 11:00:06 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 27 11:00:06 volumio volumio[1094]: info: Executing endpoint getSimilarAlbums Aug 27 11:00:06 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 27 11:00:06 volumio volumio[1094]: info: Executing endpoint getSimilarAlbums Aug 27 11:00:06 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 27 11:00:06 volumio volumio[1094]: info: Executing endpoint getSimilarAlbums Aug 27 11:00:06 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 27 11:00:07 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 11:00:07 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 11:00:07 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 11:00:07 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 11:00:07 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 11:00:07 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 11:00:07 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 11:00:07 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 11:00:07 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 11:00:07 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 11:00:07 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 11:00:07 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 11:00:08 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 11:00:08 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 11:00:08 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 11:00:08 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 11:00:09 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 11:00:09 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 11:00:09 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 11:00:09 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 11:00:15 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 11:00:15 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 11:00:15 volumio volumio[1094]: info: Discovery: Getting this device information Aug 27 11:00:15 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Aug 27 11:00:15 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 11:00:18 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 11:00:18 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 11:00:24 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 11:00:24 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 11:00:24 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Aug 27 11:00:24 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: qobuz , goto Aug 27 11:00:24 volumio volumio[1094]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Aug 27 11:00:24 volumio volumio[1094]: info: explodeUri took 257 milliseconds Aug 27 11:00:25 volumio volumio[1094]: info: handleBrowseUri took 563 milliseconds Aug 27 11:00:25 volumio volumio[1094]: info: Executing endpoint getSimilarArtists Aug 27 11:00:25 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Aug 27 11:00:25 volumio volumio[1094]: info: Executing endpoint getSimilarArtists Aug 27 11:00:25 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Aug 27 11:00:25 volumio volumio[1094]: info: Executing endpoint getSimilarArtists Aug 27 11:00:25 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Aug 27 11:00:25 volumio volumio[1094]: info: Executing endpoint getSimilarArtists Aug 27 11:00:25 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Aug 27 11:00:25 volumio volumio[1094]: info: Executing endpoint getSimilarArtists Aug 27 11:00:25 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Aug 27 11:00:25 volumio volumio[1094]: info: Executing endpoint getSimilarArtists Aug 27 11:00:25 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Aug 27 11:00:25 volumio volumio[1094]: info: Executing endpoint getSimilarArtists Aug 27 11:00:25 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Aug 27 11:00:25 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 11:00:25 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 11:00:26 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 11:00:26 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 11:00:26 volumio volumio[1094]: Error in writing albumart JSON file: Error: ENOSPC: no space left on device, write Aug 27 11:00:26 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 11:00:26 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 11:00:26 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 11:00:26 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 11:00:26 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 11:00:26 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 11:00:26 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 11:00:26 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 11:00:26 volumio volumio[1094]: info: Executing endpoint metavolumio Aug 27 11:00:26 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 11:00:26 volumio volumio[1094]: Error in writing albumart JSON file: Error: ENOSPC: no space left on device, write Aug 27 11:00:27 volumio volumio[1094]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 11:00:27 volumio volumio[1094]: [Error: ENOSPC: no space left on device, write] { Aug 27 11:00:27 volumio volumio[1094]: errno: -28, Aug 27 11:00:27 volumio volumio[1094]: code: 'ENOSPC', Aug 27 11:00:27 volumio volumio[1094]: syscall: 'write' Aug 27 11:00:27 volumio volumio[1094]: } Aug 27 11:00:27 volumio volumio[1094]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 11:00:27 volumio sudo[27699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-27 10:59 Aug 27 11:00:27 volumio sudo[27699]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:35:31 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="8e206762eb84baf3ce9616e2bda58682"