-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Sat 2025-03-08 13:43:58 GMT. -- Mar 08 13:42:00 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 13:42:00 minidsp-shd volumio[796]: info: Received Get System Info Mar 08 13:42:00 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 13:42:00 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 13:42:00 minidsp-shd volumio[796]: info: Discovery: Getting this device information Mar 08 13:42:00 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioGetState Mar 08 13:42:00 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 13:42:00 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 08 13:42:06 minidsp-shd volumio[796]: Searching all installed plugins Mar 08 13:42:06 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 13:42:06 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: , search Mar 08 13:42:06 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 08 13:42:06 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Mar 08 13:42:06 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Mar 08 13:42:06 minidsp-shd volumio[796]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Mar 08 13:42:06 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: webradio , search Mar 08 13:42:06 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: inputs , search Mar 08 13:42:06 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Mar 08 13:42:07 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 08 13:42:07 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 08 13:42:08 minidsp-shd volumio[796]: info: An error occurred while querying SHOUTCAST Mar 08 13:42:09 minidsp-shd volumio[796]: info: New access token = BQARoE0hUh7X6GtrPJarZLEcnQPvrxB8v5wk-34x0co38HhE0VMDu7wmOsaR4zGowElIWdZTbEPh122OgXeaBEwH28wVdIjYDdb1W8n4sVmUWh9o6Qz5jpMEMOq_vKp91wq0cJ3Xe07cb6WBw1mM2daoKWPazRRFWi8JxsUe3ct4n3fkkCnWdt_jds_uvM3LDAkGTjZxJYH_SaOuLaYOzyXCLrLh5voCOIQon_wczUdkRYlNJhnW96yAXzTbSD5O9rFFaNXE6eTdpy4 Mar 08 13:42:11 minidsp-shd volumio[796]: error: Search in plugin inputs timed out Mar 08 13:42:12 minidsp-shd volumio[796]: error: Search in plugin qobuz timed out Mar 08 13:42:12 minidsp-shd volumio[796]: error: Search in plugin tidal timed out Mar 08 13:42:12 minidsp-shd volumio[796]: error: Search in plugin spop timed out Mar 08 13:42:12 minidsp-shd volumio[796]: info: All search sources collected, pushing search results Mar 08 13:42:13 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 13:42:14 minidsp-shd volumio[796]: info: Received Get System Info Mar 08 13:42:14 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 13:42:14 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 13:42:14 minidsp-shd volumio[796]: info: Discovery: Getting this device information Mar 08 13:42:14 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioGetState Mar 08 13:42:14 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 13:42:14 minidsp-shd volumio[796]: info: searchQOBUZUri took 7575 milliseconds Mar 08 13:42:14 minidsp-shd volumio[796]: error: Failed search in plugin qobuz: Error: Unable to resolve or reject the same promise twice Mar 08 13:42:14 minidsp-shd volumiologrotate[484]: ls: cannot access '/var/log/samba/log.wb---': No such file or directory Mar 08 13:42:14 minidsp-shd volumiologrotate[484]: ls: cannot access 'MINIDSP': No such file or directory Mar 08 13:42:14 minidsp-shd volumiologrotate[484]: ls: cannot access 'SHD': No such file or directory Mar 08 13:42:17 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Mar 08 13:42:19 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 13:42:19 minidsp-shd volumio[796]: info: Received Get System Info Mar 08 13:42:19 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 13:42:19 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 13:42:19 minidsp-shd volumio[796]: info: Discovery: Getting this device information Mar 08 13:42:19 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioGetState Mar 08 13:42:19 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 13:42:20 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 13:42:20 minidsp-shd volumio[796]: info: Received Get System Info Mar 08 13:42:20 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 13:42:20 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 13:42:20 minidsp-shd volumio[796]: info: Discovery: Getting this device information Mar 08 13:42:20 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioGetState Mar 08 13:42:20 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 13:42:20 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 08 13:42:20 minidsp-shd volumio[796]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 08 13:42:20 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 08 13:42:20 minidsp-shd volumio[796]: info: Received Get System Version Mar 08 13:42:20 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 08 13:42:20 minidsp-shd volumio[796]: info: Received Get System Info Mar 08 13:42:20 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 13:42:20 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 13:42:20 minidsp-shd volumio[796]: info: Discovery: Getting this device information Mar 08 13:42:20 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioGetState Mar 08 13:42:20 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 13:42:20 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 13:42:20 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 13:42:20 minidsp-shd volumio[796]: info: Discovery: Getting this device information Mar 08 13:42:20 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioGetState Mar 08 13:42:20 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 13:42:21 minidsp-shd volumio[796]: error: Failed search in plugin spop: Error: Unable to resolve or reject the same promise twice Mar 08 13:42:24 minidsp-shd volumio[796]: info: MRS: Found cast device: Chromecast-51f9eef9b831fd4ed23f413c740c98a0 Mar 08 13:42:26 minidsp-shd volumio[796]: info: handleBrowseUri took 9502 milliseconds Mar 08 13:42:26 minidsp-shd volumio[796]: info: Preload queue cleared Mar 08 13:42:26 minidsp-shd volumio[796]: info: Preload queue cleared Mar 08 13:42:29 minidsp-shd volumio[796]: info: searchTIDALUri took 22735 milliseconds Mar 08 13:42:29 minidsp-shd volumio[796]: info: search took 22737 milliseconds Mar 08 13:42:29 minidsp-shd volumio[796]: error: Failed search in plugin tidal: Error: Unable to resolve or reject the same promise twice Mar 08 13:42:32 minidsp-shd volumio[796]: Searching plugin music_service/qobuz Mar 08 13:42:32 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Mar 08 13:42:34 minidsp-shd volumio[796]: info: searchQOBUZUri took 2471 milliseconds Mar 08 13:42:34 minidsp-shd volumio[796]: info: All search sources collected, pushing search results Mar 08 13:42:39 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Mar 08 13:42:42 minidsp-shd volumio[796]: info: handleBrowseUri took 2619 milliseconds Mar 08 13:42:42 minidsp-shd volumio[796]: info: Preload queue cleared Mar 08 13:42:42 minidsp-shd volumio[796]: info: Preloading song: qobuz://song/105781624 Mar 08 13:42:42 minidsp-shd volumio[796]: info: Preloading song: qobuz://song/105781625 Mar 08 13:42:42 minidsp-shd volumio[796]: info: Preloading song: qobuz://song/105781626 Mar 08 13:42:42 minidsp-shd volumio[796]: info: Preloading song: qobuz://song/105781627 Mar 08 13:42:42 minidsp-shd volumio[796]: info: Preloading song: qobuz://song/105781628 Mar 08 13:42:42 minidsp-shd volumio[796]: info: Preloading song: qobuz://song/105781629 Mar 08 13:42:42 minidsp-shd volumio[796]: info: Preloading song: qobuz://song/105781630 Mar 08 13:42:42 minidsp-shd volumio[796]: info: Preloading song: qobuz://song/105781631 Mar 08 13:42:42 minidsp-shd volumio[796]: info: Preloading song: qobuz://song/105781632 Mar 08 13:42:42 minidsp-shd volumio[796]: info: Preloading song: qobuz://song/105781633 Mar 08 13:42:42 minidsp-shd volumio[796]: info: Preloading song: qobuz://song/105781634 Mar 08 13:42:42 minidsp-shd volumio[796]: info: Preloading song: qobuz://song/105781635 Mar 08 13:42:42 minidsp-shd volumio[796]: info: Preloading song: qobuz://song/105781636 Mar 08 13:42:42 minidsp-shd volumio[796]: info: Preloading song: qobuz://song/105781637 Mar 08 13:42:42 minidsp-shd volumio[796]: info: Preloading song: qobuz://song/105781638 Mar 08 13:42:42 minidsp-shd volumio[796]: info: Preloading song: qobuz://song/105781639 Mar 08 13:42:42 minidsp-shd volumio[796]: info: Preloading song: qobuz://song/105781640 Mar 08 13:42:42 minidsp-shd volumio[796]: info: Exploding uri qobuz://song/105781624 in service qobuz Mar 08 13:42:42 minidsp-shd volumio[796]: info: Exploding uri qobuz://song/105781625 in service qobuz Mar 08 13:42:42 minidsp-shd volumio[796]: info: Exploding uri qobuz://song/105781626 in service qobuz Mar 08 13:42:42 minidsp-shd volumio[796]: info: Executing endpoint getSimilarAlbums Mar 08 13:42:42 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 08 13:42:42 minidsp-shd volumio[796]: info: Exploding uri qobuz://song/105781627 in service qobuz Mar 08 13:42:42 minidsp-shd volumio[796]: info: Executing endpoint metavolumio Mar 08 13:42:42 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 13:42:42 minidsp-shd volumio[796]: info: Executing endpoint metavolumio Mar 08 13:42:42 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 13:42:42 minidsp-shd volumio[796]: info: Exploding uri qobuz://song/105781628 in service qobuz Mar 08 13:42:42 minidsp-shd volumio[796]: info: Exploding uri qobuz://song/105781629 in service qobuz Mar 08 13:42:42 minidsp-shd volumio[796]: info: Exploding uri qobuz://song/105781630 in service qobuz Mar 08 13:42:42 minidsp-shd volumio[796]: info: Exploding uri qobuz://song/105781631 in service qobuz Mar 08 13:42:42 minidsp-shd volumio[796]: info: Exploding uri qobuz://song/105781632 in service qobuz Mar 08 13:42:42 minidsp-shd volumio[796]: info: Exploding uri qobuz://song/105781633 in service qobuz Mar 08 13:42:42 minidsp-shd volumio[796]: info: Exploding uri qobuz://song/105781634 in service qobuz Mar 08 13:42:42 minidsp-shd volumio[796]: info: Exploding uri qobuz://song/105781635 in service qobuz Mar 08 13:42:42 minidsp-shd volumio[796]: info: Exploding uri qobuz://song/105781636 in service qobuz Mar 08 13:42:43 minidsp-shd volumio[796]: info: Exploding uri qobuz://song/105781637 in service qobuz Mar 08 13:42:43 minidsp-shd volumio[796]: info: Exploding uri qobuz://song/105781638 in service qobuz Mar 08 13:42:43 minidsp-shd volumio[796]: info: Exploding uri qobuz://song/105781639 in service qobuz Mar 08 13:42:43 minidsp-shd volumio[796]: info: Exploding uri qobuz://song/105781640 in service qobuz Mar 08 13:42:44 minidsp-shd volumio[796]: info: explodeUri took 2510 milliseconds Mar 08 13:42:44 minidsp-shd volumio[796]: info: explodeUri took 2594 milliseconds Mar 08 13:42:46 minidsp-shd volumio[796]: info: Preload queue cleared Mar 08 13:42:46 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioAddQueueItems Mar 08 13:42:46 minidsp-shd volumio[796]: info: CoreStateMachine::addQueueItems Mar 08 13:42:46 minidsp-shd volumio[796]: info: CorePlayQueue::addQueueItems Mar 08 13:42:46 minidsp-shd volumio[796]: info: Preload queue cleared Mar 08 13:42:46 minidsp-shd volumio[796]: info: Adding Item to queue: qobuz://song/105781633 Mar 08 13:42:46 minidsp-shd volumio[796]: info: Exploding uri qobuz://song/105781633 in service qobuz Mar 08 13:42:46 minidsp-shd volumio[796]: info: explodeUri took 4491 milliseconds Mar 08 13:42:48 minidsp-shd volumio[796]: info: explodeUri took 6466 milliseconds Mar 08 13:42:49 minidsp-shd volumio[796]: error: Failed request for metavolumio API Mar 08 13:42:50 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 13:42:50 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 13:42:50 minidsp-shd volumio[796]: info: Discovery: Getting this device information Mar 08 13:42:50 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioGetState Mar 08 13:42:50 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 13:42:51 minidsp-shd volumio[796]: info: explodeUri took 8515 milliseconds Mar 08 13:42:53 minidsp-shd volumio[796]: info: explodeUri took 10434 milliseconds Mar 08 13:42:53 minidsp-shd volumio[796]: info: explodeUri took 10562 milliseconds Mar 08 13:42:54 minidsp-shd volumio[796]: info: Preload queue cleared Mar 08 13:42:54 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioAddQueueItems Mar 08 13:42:54 minidsp-shd volumio[796]: info: CoreStateMachine::addQueueItems Mar 08 13:42:54 minidsp-shd volumio[796]: info: CorePlayQueue::addQueueItems Mar 08 13:42:54 minidsp-shd volumio[796]: info: Preload queue cleared Mar 08 13:42:54 minidsp-shd volumio[796]: info: Adding Item to queue: qobuz://song/105781633 Mar 08 13:42:54 minidsp-shd volumio[796]: info: Exploding uri qobuz://song/105781633 in service qobuz Mar 08 13:42:55 minidsp-shd volumio[796]: info: explodeUri took 12303 milliseconds Mar 08 13:42:55 minidsp-shd volumio[796]: info: explodeUri took 12404 milliseconds Mar 08 13:42:57 minidsp-shd volumio[796]: info: explodeUri took 14402 milliseconds Mar 08 13:42:57 minidsp-shd volumio[796]: info: explodeUri took 14362 milliseconds Mar 08 13:42:58 minidsp-shd volumio[796]: info: Executing endpoint metavolumio Mar 08 13:42:58 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 13:42:59 minidsp-shd volumio[796]: info: explodeUri took 16161 milliseconds Mar 08 13:42:59 minidsp-shd volumio[796]: info: explodeUri took 16200 milliseconds Mar 08 13:43:01 minidsp-shd volumio[796]: info: Preload queue cleared Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioAddQueueItems Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreStateMachine::addQueueItems Mar 08 13:43:01 minidsp-shd volumio[796]: info: CorePlayQueue::addQueueItems Mar 08 13:43:01 minidsp-shd volumio[796]: info: Preload queue cleared Mar 08 13:43:01 minidsp-shd volumio[796]: info: Adding Item to queue: qobuz://song/105781633 Mar 08 13:43:01 minidsp-shd volumio[796]: info: Using cached record of: qobuz://song/105781633 Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioPushQueue Mar 08 13:43:01 minidsp-shd volumio[796]: info: CorePlayQueue::saveQueue Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreStateMachine::updateTrackBlock Mar 08 13:43:01 minidsp-shd volumio[796]: info: CorePlayQueue::getTrackBlock Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioPlay Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreStateMachine::play index 98 Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreStateMachine::stop Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreStateMachine::updateTrackBlock Mar 08 13:43:01 minidsp-shd volumio[796]: info: CorePlayQueue::getTrackBlock Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreStateMachine::stPlaybackTimer Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreStateMachine::pushState Mar 08 13:43:01 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 97 Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioPushState Mar 08 13:43:01 minidsp-shd volumio[796]: info: MRS: Pushing multiroomSync output update for this device Mar 08 13:43:01 minidsp-shd volumio[796]: info: MRS: Pushing multiroomSync output Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreStateMachine::serviceStop Mar 08 13:43:01 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 97 Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreCommandRouter::serviceStop Mar 08 13:43:01 minidsp-shd volumio[796]: info: [1741441381646] ControllerQobuz::stop Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 13:43:01 minidsp-shd volumio[796]: info: ControllerMpd::stop Mar 08 13:43:01 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 13:43:01 minidsp-shd volumio[796]: info: Mar 08 13:43:01 minidsp-shd volumio[796]: ---------------------------- MPD announces state update: player Mar 08 13:43:01 minidsp-shd volumio[796]: info: sendMpdCommand stop took 80 milliseconds Mar 08 13:43:01 minidsp-shd volumio[796]: info: ControllerMpd::getState Mar 08 13:43:01 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand status Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreStateMachine::play index undefined Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 13:43:01 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 98 Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreStateMachine::startPlaybackTimer Mar 08 13:43:01 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 98 Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioGetVisibleSources Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 08 13:43:01 minidsp-shd volumio[796]: info: [1741441381738] ControllerQobuz::clearAddPlayTrack Mar 08 13:43:01 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 13:43:01 minidsp-shd volumio[796]: info: Mar 08 13:43:01 minidsp-shd volumio[796]: ---------------------------- MPD announces state update: player Mar 08 13:43:01 minidsp-shd volumio[796]: info: ControllerMpd::getState Mar 08 13:43:01 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand status Mar 08 13:43:01 minidsp-shd volumio[796]: info: Mar 08 13:43:01 minidsp-shd volumio[796]: ---------------------------- MPD announces state update: player Mar 08 13:43:01 minidsp-shd volumio[796]: info: ControllerMpd::getState Mar 08 13:43:01 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand status Mar 08 13:43:01 minidsp-shd volumio[796]: info: sendMpdCommand status took 49 milliseconds Mar 08 13:43:01 minidsp-shd volumio[796]: info: sendMpdCommand stop took 39 milliseconds Mar 08 13:43:01 minidsp-shd volumio[796]: info: sendMpdCommand status took 34 milliseconds Mar 08 13:43:01 minidsp-shd volumio[796]: info: sendMpdCommand status took 30 milliseconds Mar 08 13:43:01 minidsp-shd volumio[796]: verbose: ControllerMpd::parseState Mar 08 13:43:01 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 13:43:01 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 13:43:01 minidsp-shd volumio[796]: verbose: ControllerMpd::parseState Mar 08 13:43:01 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 13:43:01 minidsp-shd volumio[796]: verbose: ControllerMpd::parseState Mar 08 13:43:01 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 13:43:01 minidsp-shd volumio[796]: error: Upnp client error: Error: This socket has been ended by the other party Mar 08 13:43:01 minidsp-shd volumio[796]: info: Mar 08 13:43:01 minidsp-shd volumio[796]: ---------------------------- MPD announces system playlist update Mar 08 13:43:01 minidsp-shd volumio[796]: info: Ignoring MPD Status Update Mar 08 13:43:01 minidsp-shd volumio[796]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 08 13:43:01 minidsp-shd volumio[796]: info: sendMpdCommand clear took 18 milliseconds Mar 08 13:43:01 minidsp-shd volumio[796]: info: sendMpdCommand playlistinfo took 18 milliseconds Mar 08 13:43:01 minidsp-shd volumio[796]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 08 13:43:01 minidsp-shd volumio[796]: verbose: ControllerMpd::parseTrackInfo Mar 08 13:43:01 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/105781633" Mar 08 13:43:01 minidsp-shd volumio[796]: verbose: ControllerMpd::parseTrackInfo Mar 08 13:43:01 minidsp-shd volumio[796]: verbose: ControllerMpd::parseTrackInfo Mar 08 13:43:01 minidsp-shd volumio[796]: info: ControllerMpd::pushState Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreCommandRouter::servicePushState Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreStateMachine::pushState Mar 08 13:43:01 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 98 Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioPushState Mar 08 13:43:01 minidsp-shd volumio[796]: info: MRS: Pushing multiroomSync output update for this device Mar 08 13:43:01 minidsp-shd volumio[796]: info: MRS: Pushing multiroomSync output Mar 08 13:43:01 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 98 Mar 08 13:43:01 minidsp-shd volumio[796]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Mar 08 13:43:01 minidsp-shd volumio[796]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 08 13:43:01 minidsp-shd volumio[796]: STREAMING PROXY: Handling url /?data=qobuz://song/105781633 Mar 08 13:43:01 minidsp-shd volumio[796]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 08 13:43:01 minidsp-shd volumio[796]: info: ------------------------------ 172ms Mar 08 13:43:01 minidsp-shd volumio[796]: info: ------------------------------ 155ms Mar 08 13:43:01 minidsp-shd volumio[796]: info: ------------------------------ 150ms Mar 08 13:43:01 minidsp-shd volumio[796]: info: Mar 08 13:43:01 minidsp-shd volumio[796]: ---------------------------- MPD announces system playlist update Mar 08 13:43:01 minidsp-shd volumio[796]: info: Ignoring MPD Status Update Mar 08 13:43:01 minidsp-shd volumio[796]: info: Mar 08 13:43:01 minidsp-shd volumio[796]: ---------------------------- MPD announces system playlist update Mar 08 13:43:01 minidsp-shd volumio[796]: info: Ignoring MPD Status Update Mar 08 13:43:01 minidsp-shd volumio[796]: error: updateQueue error: null Mar 08 13:43:01 minidsp-shd volumio[796]: info: ------------------------------ 121ms Mar 08 13:43:01 minidsp-shd volumio[796]: info: Executing endpoint getStreamUrlqobuz Mar 08 13:43:01 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 08 13:43:02 minidsp-shd volumio[796]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Mar 08 13:43:02 minidsp-shd volumio[796]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Mar 08 13:43:02 minidsp-shd kernel: vdd_arm: ramp_delay not set Mar 08 13:43:02 minidsp-shd kernel: vdd_arm: ramp_delay not set Mar 08 13:43:05 minidsp-shd volumio[796]: error: Failed request for metavolumio API Mar 08 13:43:07 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Mar 08 13:43:07 minidsp-shd volumio[796]: info: msSurfaceDial volumioupdatevolume callback: {"vol":74,"dbVolume":-33,"mute":false,"disableVolumeControl":false} Mar 08 13:43:07 minidsp-shd volumio[796]: info: CoreStateMachine::pushState Mar 08 13:43:07 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 98 Mar 08 13:43:07 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 13:43:07 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioPushState Mar 08 13:43:07 minidsp-shd volumio[796]: info: MRS: Pushing multiroomSync output update for this device Mar 08 13:43:07 minidsp-shd volumio[796]: info: MRS: Pushing multiroomSync output Mar 08 13:43:07 minidsp-shd volumio[796]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Mar 08 13:43:07 minidsp-shd volumio[796]: SPOTIFY: SPOTIFY VOLUME 75 Mar 08 13:43:07 minidsp-shd volumio[796]: SPOTIFY: VOLUMIO VOLUME 74 Mar 08 13:43:07 minidsp-shd volumio[796]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 08 13:43:07 minidsp-shd volumio[796]: info: msSurfaceDial volumioupdatevolume callback: {"vol":74,"dbVolume":-33,"mute":false,"disableVolumeControl":false} Mar 08 13:43:07 minidsp-shd volumio[796]: info: CoreStateMachine::pushState Mar 08 13:43:07 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 98 Mar 08 13:43:07 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioPushState Mar 08 13:43:07 minidsp-shd volumio[796]: info: MRS: Pushing multiroomSync output update for this device Mar 08 13:43:07 minidsp-shd volumio[796]: info: MRS: Pushing multiroomSync output Mar 08 13:43:07 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Mar 08 13:43:07 minidsp-shd volumio[796]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Mar 08 13:43:07 minidsp-shd volumio[796]: SPOTIFY: SPOTIFY VOLUME 75 Mar 08 13:43:07 minidsp-shd volumio[796]: SPOTIFY: VOLUMIO VOLUME 74 Mar 08 13:43:07 minidsp-shd volumio[796]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 08 13:43:09 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Mar 08 13:43:09 minidsp-shd volumio[796]: info: msSurfaceDial volumioupdatevolume callback: {"vol":75,"dbVolume":-32,"mute":false,"disableVolumeControl":false} Mar 08 13:43:09 minidsp-shd volumio[796]: info: CoreStateMachine::pushState Mar 08 13:43:09 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 98 Mar 08 13:43:09 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 13:43:09 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioPushState Mar 08 13:43:09 minidsp-shd volumio[796]: info: MRS: Pushing multiroomSync output update for this device Mar 08 13:43:09 minidsp-shd volumio[796]: info: MRS: Pushing multiroomSync output Mar 08 13:43:09 minidsp-shd volumio[796]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Mar 08 13:43:09 minidsp-shd volumio[796]: info: msSurfaceDial volumioupdatevolume callback: {"vol":75,"dbVolume":-32,"mute":false,"disableVolumeControl":false} Mar 08 13:43:09 minidsp-shd volumio[796]: info: CoreStateMachine::pushState Mar 08 13:43:09 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 98 Mar 08 13:43:09 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioPushState Mar 08 13:43:09 minidsp-shd volumio[796]: info: MRS: Pushing multiroomSync output update for this device Mar 08 13:43:09 minidsp-shd volumio[796]: info: MRS: Pushing multiroomSync output Mar 08 13:43:09 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Mar 08 13:43:09 minidsp-shd volumio[796]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Mar 08 13:43:11 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioPlay Mar 08 13:43:11 minidsp-shd volumio[796]: info: CoreStateMachine::play index undefined Mar 08 13:43:11 minidsp-shd volumio[796]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 13:43:11 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 98 Mar 08 13:43:11 minidsp-shd volumio[796]: info: CoreStateMachine::startPlaybackTimer Mar 08 13:43:11 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 98 Mar 08 13:43:11 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioGetVisibleSources Mar 08 13:43:11 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 13:43:11 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 08 13:43:11 minidsp-shd volumio[796]: info: [1741441391468] ControllerQobuz::clearAddPlayTrack Mar 08 13:43:11 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 13:43:15 minidsp-shd volumio[796]: info: explodeUri took 32111 milliseconds Mar 08 13:43:15 minidsp-shd volumiologrotate[484]: ls: cannot access '/var/log/samba/log.wb---': No such file or directory Mar 08 13:43:15 minidsp-shd volumiologrotate[484]: ls: cannot access 'MINIDSP': No such file or directory Mar 08 13:43:15 minidsp-shd volumiologrotate[484]: ls: cannot access 'SHD': No such file or directory Mar 08 13:43:15 minidsp-shd volumio[796]: info: explodeUri took 32549 milliseconds Mar 08 13:43:17 minidsp-shd volumio[796]: info: explodeUri took 34086 milliseconds Mar 08 13:43:17 minidsp-shd volumio[796]: info: explodeUri took 34043 milliseconds Mar 08 13:43:19 minidsp-shd volumio[796]: info: explodeUri took 32239 milliseconds Mar 08 13:43:19 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioPushQueue Mar 08 13:43:19 minidsp-shd volumio[796]: info: CorePlayQueue::saveQueue Mar 08 13:43:19 minidsp-shd volumio[796]: info: CoreStateMachine::updateTrackBlock Mar 08 13:43:19 minidsp-shd volumio[796]: info: CorePlayQueue::getTrackBlock Mar 08 13:43:19 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioPlay Mar 08 13:43:19 minidsp-shd volumio[796]: info: CoreStateMachine::play index 98 Mar 08 13:43:19 minidsp-shd volumio[796]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 13:43:19 minidsp-shd volumio[796]: info: CoreStateMachine::stop Mar 08 13:43:19 minidsp-shd volumio[796]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 13:43:19 minidsp-shd volumio[796]: info: CoreStateMachine::play index undefined Mar 08 13:43:19 minidsp-shd volumio[796]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 13:43:19 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 98 Mar 08 13:43:19 minidsp-shd volumio[796]: info: CoreStateMachine::startPlaybackTimer Mar 08 13:43:19 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 98 Mar 08 13:43:19 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioGetVisibleSources Mar 08 13:43:19 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 13:43:19 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 08 13:43:19 minidsp-shd volumio[796]: info: [1741441399186] ControllerQobuz::clearAddPlayTrack Mar 08 13:43:19 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 13:43:20 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioPlay Mar 08 13:43:20 minidsp-shd volumio[796]: info: CoreStateMachine::play index undefined Mar 08 13:43:20 minidsp-shd volumio[796]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 13:43:20 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 98 Mar 08 13:43:20 minidsp-shd volumio[796]: info: CoreStateMachine::startPlaybackTimer Mar 08 13:43:20 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 98 Mar 08 13:43:20 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioGetVisibleSources Mar 08 13:43:20 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 13:43:20 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 08 13:43:20 minidsp-shd volumio[796]: info: [1741441400334] ControllerQobuz::clearAddPlayTrack Mar 08 13:43:20 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 13:43:20 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 13:43:20 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 13:43:20 minidsp-shd volumio[796]: info: Discovery: Getting this device information Mar 08 13:43:20 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioGetState Mar 08 13:43:20 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 98 Mar 08 13:43:20 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 13:43:25 minidsp-shd volumio[796]: info: explodeUri took 30326 milliseconds Mar 08 13:43:25 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioPushQueue Mar 08 13:43:25 minidsp-shd volumio[796]: info: CorePlayQueue::saveQueue Mar 08 13:43:25 minidsp-shd volumio[796]: info: CoreStateMachine::updateTrackBlock Mar 08 13:43:25 minidsp-shd volumio[796]: info: CorePlayQueue::getTrackBlock Mar 08 13:43:25 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioPlay Mar 08 13:43:25 minidsp-shd volumio[796]: info: CoreStateMachine::play index 98 Mar 08 13:43:25 minidsp-shd volumio[796]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 13:43:25 minidsp-shd volumio[796]: info: CoreStateMachine::stop Mar 08 13:43:25 minidsp-shd volumio[796]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 13:43:25 minidsp-shd volumio[796]: info: CoreStateMachine::play index undefined Mar 08 13:43:25 minidsp-shd volumio[796]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 13:43:25 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 98 Mar 08 13:43:25 minidsp-shd volumio[796]: info: CoreStateMachine::startPlaybackTimer Mar 08 13:43:25 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 98 Mar 08 13:43:25 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioGetVisibleSources Mar 08 13:43:25 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 13:43:25 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 08 13:43:25 minidsp-shd volumio[796]: info: [1741441405253] ControllerQobuz::clearAddPlayTrack Mar 08 13:43:25 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 13:43:27 minidsp-shd volumio[796]: info: getStreamUrl took 25347 milliseconds Mar 08 13:43:27 minidsp-shd volumio[796]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3694034&eid=105781633&fmt=6&profile=raw&app_id=539451548&cid=2635222&etsp=1741445007&hmac=R4dzpH1JbrN1ESSjVsvPIEs0ql4 Mar 08 13:43:29 minidsp-shd volumio[796]: STREAMING PROXY: Response: 200, length: 6552303 Mar 08 13:43:29 minidsp-shd volumio[796]: STREAMING PROXY: Client dropped request, destroying Mar 08 13:43:29 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/105781633" Mar 08 13:43:29 minidsp-shd volumio[796]: error: updateQueue error: null Mar 08 13:43:29 minidsp-shd volumio[796]: error: updateQueue error: null Mar 08 13:43:29 minidsp-shd volumio[796]: info: Mar 08 13:43:29 minidsp-shd volumio[796]: ---------------------------- MPD announces system playlist update Mar 08 13:43:29 minidsp-shd volumio[796]: info: Ignoring MPD Status Update Mar 08 13:43:29 minidsp-shd volumio[796]: info: ------------------------------ 27622ms Mar 08 13:43:29 minidsp-shd volumio[796]: info: ------------------------------ 27619ms Mar 08 13:43:29 minidsp-shd volumio[796]: info: sendMpdCommand stop took 18059 milliseconds Mar 08 13:43:29 minidsp-shd volumio[796]: info: sendMpdCommand stop took 10342 milliseconds Mar 08 13:43:29 minidsp-shd volumio[796]: info: sendMpdCommand stop took 9195 milliseconds Mar 08 13:43:29 minidsp-shd volumio[796]: info: sendMpdCommand stop took 4276 milliseconds Mar 08 13:43:29 minidsp-shd volumio[796]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/105781633" took 11 milliseconds Mar 08 13:43:29 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 13:43:29 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 13:43:29 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 13:43:29 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 13:43:29 minidsp-shd volumio[796]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 13:43:29 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand play Mar 08 13:43:29 minidsp-shd volumio[796]: Upnp client error: Error: This socket has been ended by the other party Mar 08 13:43:29 minidsp-shd volumio[796]: info: Mar 08 13:43:29 minidsp-shd volumio[796]: ---------------------------- MPD announces system playlist update Mar 08 13:43:29 minidsp-shd volumio[796]: info: Ignoring MPD Status Update Mar 08 13:43:29 minidsp-shd volumio[796]: info: Mar 08 13:43:29 minidsp-shd volumio[796]: ---------------------------- MPD announces system playlist update Mar 08 13:43:29 minidsp-shd volumio[796]: info: Ignoring MPD Status Update Mar 08 13:43:29 minidsp-shd volumio[796]: info: Mar 08 13:43:29 minidsp-shd volumio[796]: ---------------------------- MPD announces system playlist update Mar 08 13:43:29 minidsp-shd volumio[796]: info: Ignoring MPD Status Update Mar 08 13:43:29 minidsp-shd volumio[796]: info: Mar 08 13:43:29 minidsp-shd volumio[796]: ---------------------------- MPD announces system playlist update Mar 08 13:43:29 minidsp-shd volumio[796]: info: Ignoring MPD Status Update Mar 08 13:43:29 minidsp-shd volumio[796]: info: Mar 08 13:43:29 minidsp-shd volumio[796]: ---------------------------- MPD announces system playlist update Mar 08 13:43:29 minidsp-shd volumio[796]: info: Ignoring MPD Status Update Mar 08 13:43:29 minidsp-shd volumio[796]: info: Mar 08 13:43:29 minidsp-shd volumio[796]: ---------------------------- MPD announces system playlist update Mar 08 13:43:29 minidsp-shd volumio[796]: info: Ignoring MPD Status Update Mar 08 13:43:29 minidsp-shd volumio[796]: error: updateQueue error: null Mar 08 13:43:29 minidsp-shd volumio[796]: info: ------------------------------ 33ms Mar 08 13:43:29 minidsp-shd volumio[796]: info: sendMpdCommand clear took 24 milliseconds Mar 08 13:43:29 minidsp-shd volumio[796]: info: sendMpdCommand clear took 25 milliseconds Mar 08 13:43:29 minidsp-shd volumio[796]: info: sendMpdCommand clear took 25 milliseconds Mar 08 13:43:29 minidsp-shd volumio[796]: info: sendMpdCommand clear took 24 milliseconds Mar 08 13:43:29 minidsp-shd volumio[796]: info: sendMpdCommand play took 24 milliseconds Mar 08 13:43:29 minidsp-shd volumio[796]: info: ------------------------------ 17ms Mar 08 13:43:29 minidsp-shd volumio[796]: info: ------------------------------ 14ms Mar 08 13:43:29 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/105781633" Mar 08 13:43:29 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/105781633" Mar 08 13:43:29 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/105781633" Mar 08 13:43:29 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/105781633" Mar 08 13:43:29 minidsp-shd volumio[796]: info: Mar 08 13:43:29 minidsp-shd volumio[796]: ---------------------------- MPD announces system playlist update Mar 08 13:43:29 minidsp-shd volumio[796]: info: Ignoring MPD Status Update Mar 08 13:43:29 minidsp-shd volumio[796]: STREAMING PROXY: Handling url /?data=qobuz://song/105781633 Mar 08 13:43:29 minidsp-shd volumio[796]: info: Mar 08 13:43:29 minidsp-shd volumio[796]: ---------------------------- MPD announces system playlist update Mar 08 13:43:29 minidsp-shd volumio[796]: info: Ignoring MPD Status Update Mar 08 13:43:29 minidsp-shd volumio[796]: error: updateQueue error: null Mar 08 13:43:29 minidsp-shd volumio[796]: error: updateQueue error: null Mar 08 13:43:29 minidsp-shd volumio[796]: error: updateQueue error: null Mar 08 13:43:29 minidsp-shd volumio[796]: error: updateQueue error: null Mar 08 13:43:29 minidsp-shd volumio[796]: info: ------------------------------ 31ms Mar 08 13:43:29 minidsp-shd volumio[796]: info: ------------------------------ 30ms Mar 08 13:43:29 minidsp-shd volumio[796]: info: ------------------------------ 30ms Mar 08 13:43:29 minidsp-shd volumio[796]: info: ------------------------------ 29ms Mar 08 13:43:29 minidsp-shd volumio[796]: info: Executing endpoint getStreamUrlqobuz Mar 08 13:43:29 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 08 13:43:38 minidsp-shd volumio[796]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dario%20Marianelli/Jane%20Eyre%20(Original%20Motion%20Picture%20Soundtrack)/59129796-ab2b-4448-a255-184f875e9128.jpg' Mar 08 13:43:39 minidsp-shd volumio[796]: verbose: New Socket.io Connection to 192.168.86.189 from 192.168.86.244 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 7 Mar 08 13:43:39 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 08 13:43:39 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 08 13:43:39 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioGetVisibleSources Mar 08 13:43:39 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 13:43:39 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioGetState Mar 08 13:43:39 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 08 13:43:39 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioGetQueue Mar 08 13:43:39 minidsp-shd volumio[796]: info: CoreStateMachine::getQueue Mar 08 13:43:39 minidsp-shd volumio[796]: info: CorePlayQueue::getQueue Mar 08 13:43:39 minidsp-shd volumio[796]: info: Listing playlists Mar 08 13:43:39 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 08 13:43:39 minidsp-shd volumio[796]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 08 13:43:39 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Mar 08 13:43:39 minidsp-shd volumio[796]: info: Received Get System Info Mar 08 13:43:39 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 13:43:39 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 13:43:39 minidsp-shd volumio[796]: info: Discovery: Getting this device information Mar 08 13:43:39 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioGetState Mar 08 13:43:39 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 13:43:39 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioGetState Mar 08 13:43:39 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 08 13:43:41 minidsp-shd volumio[796]: info: Executing endpoint metavolumio Mar 08 13:43:41 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 13:43:41 minidsp-shd volumio[796]: info: Executing endpoint metavolumio Mar 08 13:43:41 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 13:43:42 minidsp-shd volumio[796]: info: Preload queue cleared Mar 08 13:43:42 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioAddQueueItems Mar 08 13:43:42 minidsp-shd volumio[796]: info: CoreStateMachine::addQueueItems Mar 08 13:43:42 minidsp-shd volumio[796]: info: CorePlayQueue::addQueueItems Mar 08 13:43:42 minidsp-shd volumio[796]: info: Preload queue cleared Mar 08 13:43:42 minidsp-shd volumio[796]: info: Adding Item to queue: qobuz://song/105781633 Mar 08 13:43:42 minidsp-shd volumio[796]: info: Using cached record of: qobuz://song/105781633 Mar 08 13:43:42 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioPushQueue Mar 08 13:43:43 minidsp-shd volumio[796]: info: CorePlayQueue::saveQueue Mar 08 13:43:43 minidsp-shd volumio[796]: info: CoreStateMachine::updateTrackBlock Mar 08 13:43:43 minidsp-shd volumio[796]: info: CorePlayQueue::getTrackBlock Mar 08 13:43:43 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioPlay Mar 08 13:43:43 minidsp-shd volumio[796]: info: CoreStateMachine::play index 98 Mar 08 13:43:43 minidsp-shd volumio[796]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 13:43:43 minidsp-shd volumio[796]: info: CoreStateMachine::stop Mar 08 13:43:43 minidsp-shd volumio[796]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 13:43:43 minidsp-shd volumio[796]: info: CoreStateMachine::play index undefined Mar 08 13:43:43 minidsp-shd volumio[796]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 13:43:43 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 98 Mar 08 13:43:43 minidsp-shd volumio[796]: info: CoreStateMachine::startPlaybackTimer Mar 08 13:43:43 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 98 Mar 08 13:43:43 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioGetVisibleSources Mar 08 13:43:43 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 13:43:43 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 08 13:43:43 minidsp-shd volumio[796]: info: [1741441423033] ControllerQobuz::clearAddPlayTrack Mar 08 13:43:43 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 13:43:44 minidsp-shd volumio[796]: info: Preload queue cleared Mar 08 13:43:44 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioAddQueueItems Mar 08 13:43:44 minidsp-shd volumio[796]: info: CoreStateMachine::addQueueItems Mar 08 13:43:44 minidsp-shd volumio[796]: info: CorePlayQueue::addQueueItems Mar 08 13:43:44 minidsp-shd volumio[796]: info: Preload queue cleared Mar 08 13:43:44 minidsp-shd volumio[796]: info: Adding Item to queue: qobuz://song/105781633 Mar 08 13:43:44 minidsp-shd volumio[796]: info: Using cached record of: qobuz://song/105781633 Mar 08 13:43:44 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioPushQueue Mar 08 13:43:44 minidsp-shd volumio[796]: info: CorePlayQueue::saveQueue Mar 08 13:43:44 minidsp-shd volumio[796]: info: CoreStateMachine::updateTrackBlock Mar 08 13:43:44 minidsp-shd volumio[796]: info: CorePlayQueue::getTrackBlock Mar 08 13:43:44 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioPlay Mar 08 13:43:44 minidsp-shd volumio[796]: info: CoreStateMachine::play index 98 Mar 08 13:43:44 minidsp-shd volumio[796]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 13:43:44 minidsp-shd volumio[796]: info: CoreStateMachine::stop Mar 08 13:43:44 minidsp-shd volumio[796]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 13:43:44 minidsp-shd volumio[796]: info: CoreStateMachine::play index undefined Mar 08 13:43:44 minidsp-shd volumio[796]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 13:43:44 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 98 Mar 08 13:43:44 minidsp-shd volumio[796]: info: CoreStateMachine::startPlaybackTimer Mar 08 13:43:44 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 98 Mar 08 13:43:44 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioGetVisibleSources Mar 08 13:43:44 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 13:43:44 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 08 13:43:44 minidsp-shd volumio[796]: info: [1741441424566] ControllerQobuz::clearAddPlayTrack Mar 08 13:43:44 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 13:43:46 minidsp-shd volumio[796]: info: Preload queue cleared Mar 08 13:43:46 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioAddQueueItems Mar 08 13:43:46 minidsp-shd volumio[796]: info: CoreStateMachine::addQueueItems Mar 08 13:43:46 minidsp-shd volumio[796]: info: CorePlayQueue::addQueueItems Mar 08 13:43:46 minidsp-shd volumio[796]: info: Preload queue cleared Mar 08 13:43:46 minidsp-shd volumio[796]: info: Adding Item to queue: qobuz://song/105781633 Mar 08 13:43:46 minidsp-shd volumio[796]: info: Using cached record of: qobuz://song/105781633 Mar 08 13:43:46 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioPushQueue Mar 08 13:43:46 minidsp-shd volumio[796]: info: CorePlayQueue::saveQueue Mar 08 13:43:46 minidsp-shd volumio[796]: info: CoreStateMachine::updateTrackBlock Mar 08 13:43:46 minidsp-shd volumio[796]: info: CorePlayQueue::getTrackBlock Mar 08 13:43:46 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioPlay Mar 08 13:43:46 minidsp-shd volumio[796]: info: CoreStateMachine::play index 98 Mar 08 13:43:46 minidsp-shd volumio[796]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 13:43:46 minidsp-shd volumio[796]: info: CoreStateMachine::stop Mar 08 13:43:46 minidsp-shd volumio[796]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 13:43:46 minidsp-shd volumio[796]: info: CoreStateMachine::play index undefined Mar 08 13:43:46 minidsp-shd volumio[796]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 13:43:46 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 98 Mar 08 13:43:46 minidsp-shd volumio[796]: info: CoreStateMachine::startPlaybackTimer Mar 08 13:43:46 minidsp-shd volumio[796]: info: CorePlayQueue::getTrack 98 Mar 08 13:43:46 minidsp-shd volumio[796]: info: CoreCommandRouter::volumioGetVisibleSources Mar 08 13:43:46 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 13:43:46 minidsp-shd volumio[796]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 08 13:43:46 minidsp-shd volumio[796]: info: [1741441426324] ControllerQobuz::clearAddPlayTrack Mar 08 13:43:46 minidsp-shd volumio[796]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 13:43:48 minidsp-shd volumio[796]: error: Failed request for metavolumio API Mar 08 13:43:48 minidsp-shd volumio[796]: error: Failed request for metavolumio API Mar 08 13:43:57 minidsp-shd volumio[796]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 13:43:57 minidsp-shd volumio[796]: Error: getaddrinfo ENOTFOUND lastfm.freetls.fastly.net Mar 08 13:43:57 minidsp-shd volumio[796]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Mar 08 13:43:57 minidsp-shd volumio[796]: errno: -3007, Mar 08 13:43:57 minidsp-shd volumio[796]: code: 'ENOTFOUND', Mar 08 13:43:57 minidsp-shd volumio[796]: syscall: 'getaddrinfo', Mar 08 13:43:57 minidsp-shd volumio[796]: hostname: 'lastfm.freetls.fastly.net' Mar 08 13:43:57 minidsp-shd volumio[796]: } Mar 08 13:43:57 minidsp-shd volumio[796]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 13:43:58 minidsp-shd sudo[17994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-08 13:42 Mar 08 13:43:58 minidsp-shd sudo[17994]: 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="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:13:25 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="18533ba33f8f92d7e7e459bb30f70beb"