-- Logs begin at Mon 2024-04-15 10:12:09 PDT, end at Mon 2024-04-22 21:28:26 PDT. -- Apr 22 21:27:01 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 22 21:27:01 volumio-brookings volumio[875]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 Apr 22 21:27:01 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioGetState Apr 22 21:27:01 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:02 volumio-brookings wpa_supplicant[790]: RRM: Ignoring radio measurement request: Not RRM network Apr 22 21:27:05 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 22 21:27:05 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 21:27:05 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 22 21:27:05 volumio-brookings volumio-remote-updater[555]: No test mode Apr 22 21:27:05 volumio-brookings volumio-remote-updater[555]: No alpha test mode Apr 22 21:27:05 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 22 21:27:06 volumio-brookings volumio[875]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 22 21:27:06 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 22 21:27:06 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:27:06 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:27:06 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 22 21:27:06 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 22 21:27:06 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 22 21:27:06 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 22 21:27:06 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioGetBrowseSources Apr 22 21:27:06 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 22 21:27:07 volumio-brookings volumio[875]: info: Discovery: A device disappeared from network Apr 22 21:27:07 volumio-brookings volumio[875]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 22 21:27:07 volumio-brookings volumio[875]: info: Completed starting MyVolumio Plugin Apr 22 21:27:07 volumio-brookings volumio[875]: info: BOOT COMPLETED Apr 22 21:27:07 volumio-brookings volumio[875]: [Metrics] CommandRouter: 67s 12.09ms Apr 22 21:27:07 volumio-brookings volumio[875]: info: CoreCommandRouter::volumiosetStartupVolume Apr 22 21:27:07 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:27:07 volumio-brookings volumio[875]: info: VolumeController:: Setting startup Volume 100 Apr 22 21:27:07 volumio-brookings volumio[875]: info: VolumeController::SetAlsaVolume100 Apr 22 21:27:07 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 21:27:07 volumio-brookings volumio[875]: info: CoreCommandRouter::Close All Modals sent Apr 22 21:27:07 volumio-brookings volumio[875]: info: CoreCommandRouter::Close All Modals sent Apr 22 21:27:07 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:07 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:07 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 21:27:07 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:07 volumio-brookings volumio[875]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 22 21:27:07 volumio-brookings volumio[875]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 22 21:27:07 volumio-brookings volumio[875]: info: camilladsp stopping service pid 1131... Apr 22 21:27:07 volumio-brookings volumio[875]: info: camilladsp service terminated, instance 1 Apr 22 21:27:07 volumio-brookings volumio[875]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 22 21:27:07 volumio-brookings volumio[875]: info: camilladsp service started and running in background, instance 1 Apr 22 21:27:07 volumio-brookings volumio[875]: info: camilladsp spawned new process with pid 1347, instance 1, run: true Apr 22 21:27:07 volumio-brookings volumio[875]: info: Discovery: adding 7770eda5-ad71-44d0-81de-cb11496dbe75 Apr 22 21:27:07 volumio-brookings volumio[875]: info: Discovery: Found device Volumio Brookings Apr 22 21:27:07 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioGetState Apr 22 21:27:07 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:07 volumio-brookings kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Apr 22 21:27:08 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 22 21:27:08 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 22 21:27:08 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 22 21:27:12 volumio-brookings volumio[875]: info: camilladsp spawned new process with pid 1354, instance 1, run: true Apr 22 21:27:12 volumio-brookings kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Apr 22 21:27:14 volumio-brookings volumio[875]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 21:27:14 volumio-brookings volumio[875]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 21:27:14 volumio-brookings volumio[875]: info: Retrieving Cloud Streaming UI Apr 22 21:27:14 volumio-brookings volumio[875]: info: Getting Tidal Cloud Configuration Apr 22 21:27:14 volumio-brookings volumio[875]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 21:27:14 volumio-brookings volumio[875]: info: Getting Qobuz Cloud Configuration Apr 22 21:27:14 volumio-brookings volumio[875]: info: Asking plugin for UI Config Apr 22 21:27:14 volumio-brookings volumio[875]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 21:27:14 volumio-brookings volumio[875]: info: Getting Spotify Cloud Configuration Apr 22 21:27:14 volumio-brookings volumio[875]: info: Asking plugin for UI Config Apr 22 21:27:14 volumio-brookings volumio[875]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 21:27:14 volumio-brookings volumio[875]: info: Saving Spotify Acccount Apr 22 21:27:14 volumio-brookings volumio[875]: info: Got Tidal Cloud Configuration Apr 22 21:27:14 volumio-brookings volumio[875]: info: Got it Apr 22 21:27:14 volumio-brookings volumio[875]: info: Got it Apr 22 21:27:14 volumio-brookings volumio[875]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 22 21:27:14 volumio-brookings volumio[875]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 21:27:14 volumio-brookings volumio[875]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 22 21:27:14 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 22 21:27:14 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 22 21:27:14 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 22 21:27:14 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 22 21:27:14 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 21:27:14 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 21:27:14 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 21:27:14 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 21:27:14 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioGetBrowseSources Apr 22 21:27:14 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioGetBrowseSources Apr 22 21:27:14 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioGetBrowseSources Apr 22 21:27:14 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 21:27:14 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 22 21:27:14 volumio-brookings volumio[1051]: ...............................................................................................................................................................................................................++++ Apr 22 21:27:14 volumio-brookings volumio[1051]: e is 65537 (0x010001) Apr 22 21:27:14 volumio-brookings volumio[1051]: writing RSA key Apr 22 21:27:18 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Apr 22 21:27:18 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand update Apr 22 21:27:18 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:18 volumio-brookings volumio[875]: info: Apr 22 21:27:18 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: update Apr 22 21:27:18 volumio-brookings volumio[875]: info: sendMpdCommand update took 6 milliseconds Apr 22 21:27:18 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:18 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:18 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:18 volumio-brookings volumio[875]: info: Apr 22 21:27:18 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: update Apr 22 21:27:18 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:18 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:18 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:18 volumio-brookings volumio[875]: info: Apr 22 21:27:18 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: update Apr 22 21:27:18 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:18 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:18 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:18 volumio-brookings volumio[875]: info: Apr 22 21:27:18 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: update Apr 22 21:27:18 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:18 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:18 volumio-brookings volumio[875]: info: sendMpdCommand status took 11 milliseconds Apr 22 21:27:18 volumio-brookings volumio[875]: info: sendMpdCommand status took 8 milliseconds Apr 22 21:27:18 volumio-brookings volumio[875]: info: sendMpdCommand status took 7 milliseconds Apr 22 21:27:18 volumio-brookings volumio[875]: info: sendMpdCommand status took 6 milliseconds Apr 22 21:27:18 volumio-brookings volumio[875]: info: sendMpdCommand status took 5 milliseconds Apr 22 21:27:18 volumio-brookings volumio[875]: info: sendMpdCommand status took 5 milliseconds Apr 22 21:27:18 volumio-brookings volumio[875]: info: sendMpdCommand status took 4 milliseconds Apr 22 21:27:18 volumio-brookings volumio[875]: info: sendMpdCommand status took 3 milliseconds Apr 22 21:27:18 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:18 volumio-brookings volumio[875]: info: Command Router : Notfying DB Updatetrue Apr 22 21:27:18 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:18 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:18 volumio-brookings volumio[875]: info: Command Router : Notfying DB Updatetrue Apr 22 21:27:18 volumio-brookings volumio[875]: info: CoreCommandRouter::Close All Modals sent Apr 22 21:27:18 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:18 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:18 volumio-brookings volumio[875]: info: Command Router : Notfying DB Updatetrue Apr 22 21:27:18 volumio-brookings volumio[875]: info: CoreCommandRouter::Close All Modals sent Apr 22 21:27:18 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:18 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:18 volumio-brookings volumio[875]: info: Command Router : Notfying DB Updatetrue Apr 22 21:27:18 volumio-brookings volumio[875]: info: CoreCommandRouter::Close All Modals sent Apr 22 21:27:18 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:18 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:18 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:18 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:18 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:18 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:18 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:18 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:18 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:18 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:18 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:18 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:18 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:18 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:18 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:18 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:18 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:18 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:18 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:18 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:18 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:18 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:18 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:18 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:18 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:18 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:18 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:18 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:18 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:18 volumio-brookings volumio[875]: info: ------------------------------ 186ms Apr 22 21:27:18 volumio-brookings volumio[875]: info: ------------------------------ 182ms Apr 22 21:27:18 volumio-brookings volumio[875]: info: ------------------------------ 180ms Apr 22 21:27:18 volumio-brookings volumio[875]: info: ------------------------------ 179ms Apr 22 21:27:18 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 21:27:22 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Apr 22 21:27:22 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand update Apr 22 21:27:22 volumio-brookings volumio[875]: info: sendMpdCommand update took 3 milliseconds Apr 22 21:27:22 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 21:27:23 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Apr 22 21:27:23 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand update Apr 22 21:27:23 volumio-brookings volumio[875]: info: sendMpdCommand update took 5 milliseconds Apr 22 21:27:26 volumio-brookings volumio[875]: info: MPD Database updated - AlbumList cache refreshed Apr 22 21:27:26 volumio-brookings volumio[875]: info: Apr 22 21:27:26 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: database Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: Apr 22 21:27:26 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: update Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: MPD Database updated - AlbumList cache refreshed Apr 22 21:27:26 volumio-brookings volumio[875]: info: Apr 22 21:27:26 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: database Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: Apr 22 21:27:26 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: update Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: MPD Database updated - AlbumList cache refreshed Apr 22 21:27:26 volumio-brookings volumio[875]: info: Apr 22 21:27:26 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: database Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: Apr 22 21:27:26 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: update Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: MPD Database updated - AlbumList cache refreshed Apr 22 21:27:26 volumio-brookings volumio[875]: info: Apr 22 21:27:26 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: database Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: Apr 22 21:27:26 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: update Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: sendMpdCommand status took 94 milliseconds Apr 22 21:27:26 volumio-brookings volumio[875]: info: sendMpdCommand status took 93 milliseconds Apr 22 21:27:26 volumio-brookings volumio[875]: info: sendMpdCommand status took 94 milliseconds Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:26 volumio-brookings volumio[875]: info: Command Router : Notfying DB Updatetrue Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::Close All Modals sent Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:26 volumio-brookings volumio[875]: info: ------------------------------ 162ms Apr 22 21:27:26 volumio-brookings volumio[875]: info: ------------------------------ 162ms Apr 22 21:27:26 volumio-brookings volumio[875]: info: sendMpdCommand status took 206 milliseconds Apr 22 21:27:26 volumio-brookings volumio[875]: info: sendMpdCommand status took 207 milliseconds Apr 22 21:27:26 volumio-brookings volumio[875]: info: sendMpdCommand status took 207 milliseconds Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:26 volumio-brookings volumio[875]: info: Command Router : Notfying DB Updatetrue Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::Close All Modals sent Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:26 volumio-brookings volumio[875]: info: ------------------------------ 281ms Apr 22 21:27:26 volumio-brookings volumio[875]: info: ------------------------------ 281ms Apr 22 21:27:26 volumio-brookings volumio[875]: info: sendMpdCommand status took 310 milliseconds Apr 22 21:27:26 volumio-brookings volumio[875]: info: sendMpdCommand status took 309 milliseconds Apr 22 21:27:26 volumio-brookings volumio[875]: info: sendMpdCommand status took 308 milliseconds Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:26 volumio-brookings volumio[875]: info: Command Router : Notfying DB Updatetrue Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::Close All Modals sent Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:26 volumio-brookings volumio[875]: info: ------------------------------ 360ms Apr 22 21:27:26 volumio-brookings volumio[875]: info: ------------------------------ 361ms Apr 22 21:27:26 volumio-brookings volumio[875]: info: sendMpdCommand status took 398 milliseconds Apr 22 21:27:26 volumio-brookings volumio[875]: info: sendMpdCommand status took 397 milliseconds Apr 22 21:27:26 volumio-brookings volumio[875]: info: sendMpdCommand status took 398 milliseconds Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:26 volumio-brookings volumio[875]: info: Command Router : Notfying DB Updatetrue Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::Close All Modals sent Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:26 volumio-brookings volumio[875]: info: ------------------------------ 455ms Apr 22 21:27:26 volumio-brookings volumio[875]: info: ------------------------------ 454ms Apr 22 21:27:26 volumio-brookings volumio[875]: info: MPD Database updated - AlbumList cache refreshed Apr 22 21:27:26 volumio-brookings volumio[875]: info: Apr 22 21:27:26 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: database Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: Apr 22 21:27:26 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: update Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: MPD Database updated - AlbumList cache refreshed Apr 22 21:27:26 volumio-brookings volumio[875]: info: Apr 22 21:27:26 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: database Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: Apr 22 21:27:26 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: update Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: MPD Database updated - AlbumList cache refreshed Apr 22 21:27:26 volumio-brookings volumio[875]: info: Apr 22 21:27:26 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: database Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: Apr 22 21:27:26 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: update Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: MPD Database updated - AlbumList cache refreshed Apr 22 21:27:26 volumio-brookings volumio[875]: info: Apr 22 21:27:26 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: database Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: Apr 22 21:27:26 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: update Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:26 volumio-brookings volumio[875]: info: sendMpdCommand status took 77 milliseconds Apr 22 21:27:26 volumio-brookings volumio[875]: info: sendMpdCommand status took 77 milliseconds Apr 22 21:27:26 volumio-brookings volumio[875]: info: sendMpdCommand status took 77 milliseconds Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:26 volumio-brookings volumio[875]: info: Command Router : Notfying DB Updatetrue Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::Close All Modals sent Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:26 volumio-brookings volumio[875]: info: ------------------------------ 133ms Apr 22 21:27:26 volumio-brookings volumio[875]: info: ------------------------------ 132ms Apr 22 21:27:26 volumio-brookings volumio[875]: info: sendMpdCommand status took 159 milliseconds Apr 22 21:27:26 volumio-brookings volumio[875]: info: sendMpdCommand status took 159 milliseconds Apr 22 21:27:26 volumio-brookings volumio[875]: info: sendMpdCommand status took 159 milliseconds Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:26 volumio-brookings volumio[875]: info: Command Router : Notfying DB Updatetrue Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::Close All Modals sent Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:26 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:26 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:26 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:26 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:26 volumio-brookings volumio[875]: info: ------------------------------ 217ms Apr 22 21:27:26 volumio-brookings volumio[875]: info: ------------------------------ 217ms Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 21:27:27 volumio-brookings volumio[875]: info: sendMpdCommand status took 289 milliseconds Apr 22 21:27:27 volumio-brookings volumio[875]: info: sendMpdCommand status took 288 milliseconds Apr 22 21:27:27 volumio-brookings volumio[875]: info: sendMpdCommand status took 289 milliseconds Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:27 volumio-brookings volumio[875]: info: Command Router : Notfying DB Updatetrue Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::Close All Modals sent Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:27 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:27 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:27 volumio-brookings volumio[875]: info: ------------------------------ 383ms Apr 22 21:27:27 volumio-brookings volumio[875]: info: ------------------------------ 384ms Apr 22 21:27:27 volumio-brookings volumio[875]: info: sendMpdCommand status took 408 milliseconds Apr 22 21:27:27 volumio-brookings volumio[875]: info: sendMpdCommand status took 408 milliseconds Apr 22 21:27:27 volumio-brookings volumio[875]: info: sendMpdCommand status took 408 milliseconds Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:27 volumio-brookings volumio[875]: info: Command Router : Notfying DB Updatetrue Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::Close All Modals sent Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:27 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:27 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:27 volumio-brookings volumio[875]: info: ------------------------------ 463ms Apr 22 21:27:27 volumio-brookings volumio[875]: info: ------------------------------ 464ms Apr 22 21:27:27 volumio-brookings volumio[875]: info: MPD Database updated - AlbumList cache refreshed Apr 22 21:27:27 volumio-brookings volumio[875]: info: Apr 22 21:27:27 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: database Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:27 volumio-brookings volumio[875]: info: Apr 22 21:27:27 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: update Apr 22 21:27:27 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:27 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:27 volumio-brookings volumio[875]: info: MPD Database updated - AlbumList cache refreshed Apr 22 21:27:27 volumio-brookings volumio[875]: info: Apr 22 21:27:27 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: database Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:27 volumio-brookings volumio[875]: info: Apr 22 21:27:27 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: update Apr 22 21:27:27 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:27 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:27 volumio-brookings volumio[875]: info: MPD Database updated - AlbumList cache refreshed Apr 22 21:27:27 volumio-brookings volumio[875]: info: Apr 22 21:27:27 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: database Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:27 volumio-brookings volumio[875]: info: Apr 22 21:27:27 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: update Apr 22 21:27:27 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:27 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:27 volumio-brookings volumio[875]: info: MPD Database updated - AlbumList cache refreshed Apr 22 21:27:27 volumio-brookings volumio[875]: info: Apr 22 21:27:27 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: database Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:27 volumio-brookings volumio[875]: info: Apr 22 21:27:27 volumio-brookings volumio[875]: ---------------------------- MPD announces state update: update Apr 22 21:27:27 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:27 volumio-brookings volumio[875]: info: ControllerMpd::getState Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:27:27 volumio-brookings volumio[875]: info: sendMpdCommand status took 53 milliseconds Apr 22 21:27:27 volumio-brookings volumio[875]: info: sendMpdCommand status took 53 milliseconds Apr 22 21:27:27 volumio-brookings volumio[875]: info: sendMpdCommand status took 53 milliseconds Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:27 volumio-brookings volumio[875]: info: Command Router : Notfying DB Updatefalse Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::Close All Modals sent Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:27 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:27 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:27 volumio-brookings volumio[875]: info: ------------------------------ 99ms Apr 22 21:27:27 volumio-brookings volumio[875]: info: ------------------------------ 100ms Apr 22 21:27:27 volumio-brookings volumio[875]: info: sendMpdCommand status took 128 milliseconds Apr 22 21:27:27 volumio-brookings volumio[875]: info: sendMpdCommand status took 128 milliseconds Apr 22 21:27:27 volumio-brookings volumio[875]: info: sendMpdCommand status took 128 milliseconds Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:27 volumio-brookings volumio[875]: info: Command Router : Notfying DB Updatefalse Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::Close All Modals sent Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:27 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:27 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:27 volumio-brookings volumio[875]: info: ------------------------------ 172ms Apr 22 21:27:27 volumio-brookings volumio[875]: info: ------------------------------ 173ms Apr 22 21:27:27 volumio-brookings volumio[875]: info: sendMpdCommand status took 207 milliseconds Apr 22 21:27:27 volumio-brookings volumio[875]: info: sendMpdCommand status took 208 milliseconds Apr 22 21:27:27 volumio-brookings volumio[875]: info: sendMpdCommand status took 207 milliseconds Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:27 volumio-brookings volumio[875]: info: Command Router : Notfying DB Updatefalse Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::Close All Modals sent Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:27 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:27 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:27 volumio-brookings volumio[875]: info: ------------------------------ 252ms Apr 22 21:27:27 volumio-brookings volumio[875]: info: ------------------------------ 251ms Apr 22 21:27:27 volumio-brookings volumio[875]: info: sendMpdCommand status took 271 milliseconds Apr 22 21:27:27 volumio-brookings volumio[875]: info: sendMpdCommand status took 270 milliseconds Apr 22 21:27:27 volumio-brookings volumio[875]: info: sendMpdCommand status took 269 milliseconds Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:27 volumio-brookings volumio[875]: info: Command Router : Notfying DB Updatefalse Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::Close All Modals sent Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:27 volumio-brookings volumio[875]: verbose: ControllerMpd::parseState Apr 22 21:27:27 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:27 volumio-brookings volumio[875]: info: ControllerMpd::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::servicePushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreStateMachine::pushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushState Apr 22 21:27:27 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:27:27 volumio-brookings volumio[875]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 22 21:27:27 volumio-brookings volumio[875]: info: ------------------------------ 314ms Apr 22 21:27:27 volumio-brookings volumio[875]: info: ------------------------------ 314ms Apr 22 21:27:30 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 21:27:34 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 21:27:38 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 21:27:40 volumio-brookings volumiologrotate[535]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Apr 22 21:27:40 volumio-brookings volumiologrotate[535]: ls: cannot access 'BROOKINGS': No such file or directory Apr 22 21:27:42 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 21:27:47 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 22 21:27:47 volumio-brookings volumio[875]: info: CURURI: music-library Apr 22 21:27:47 volumio-brookings volumio[875]: info: Preload queue cleared Apr 22 21:27:48 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 22 21:27:48 volumio-brookings volumio[875]: info: CURURI: music-library/USB Apr 22 21:27:48 volumio-brookings volumio[875]: info: Preload queue cleared Apr 22 21:27:50 volumio-brookings volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 22 21:27:50 volumio-brookings volumio[875]: info: CURURI: music-library/USB/EADB-A4C6 Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preload queue cleared Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/Allman Brothers Dreams MC1.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/allman Gregg tour MC1.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/Blind Faith.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/Beatles Abbey rd MC1.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/War all day music mc1.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/monk always know.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/aqualung MFSL.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/aqualung wrong ML.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/Art Taylor at's delight MC1.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/blood sweat tears ML.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/hayward lodge bluejays.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/blues breakers.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/grateful dead blues for Allah MC1.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/bruce cockburn dragon jaw ML.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/allman eat a peach ML.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/allman filmore east ML.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/Arlo Guthrie Hobo.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/butterfield blues live MC1.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/2L38_01_320kHz.mp3 Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/2L38_01_192kHz.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/bob james earl klugh one on one MC1.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/ELP Pictures MC1b.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/allman reach for te sky MC1.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/alice coltrane reflection.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/chick corea romntic warrior MC1.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/Beatles Sgt Pepper.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/war cisco MC1.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/allman win lose MC1.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Preloading song: music-library/USB/EADB-A4C6/beatles magical mystery MC1.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/Allman Brothers Dreams MC1.flac in service mpd Apr 22 21:27:50 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=//extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2FAllman%20Brothers%20Dreams%20MC1.flac&metadata=false Apr 22 21:27:50 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/Allman Brothers Dreams MC1.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/allman Gregg tour MC1.flac in service mpd Apr 22 21:27:50 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Gregg%20Allman%20tour%20MC%201//extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2Fallman%20Gregg%20tour%20MC1.flac&metadata=false Apr 22 21:27:50 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/allman Gregg tour MC1.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/Blind Faith.flac in service mpd Apr 22 21:27:50 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Blind%20Faith//extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2FBlind%20Faith.flac&metadata=false Apr 22 21:27:50 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/Blind Faith.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/Beatles Abbey rd MC1.flac in service mpd Apr 22 21:27:50 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Beatles%20MC1/Abbey%20Rd./extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2FBeatles%20Abbey%20rd%20MC1.flac&metadata=false Apr 22 21:27:50 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/Beatles Abbey rd MC1.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/War all day music mc1.flac in service mpd Apr 22 21:27:50 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=War%20MC1/All%20Day%20Music/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2FWar%20all%20day%20music%20%20mc1.flac&metadata=false Apr 22 21:27:50 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/War all day music mc1.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/monk always know.flac in service mpd Apr 22 21:27:50 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Thelonius%20Monk/Always%20Know/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2Fmonk%20always%20know.flac&metadata=false Apr 22 21:27:50 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/monk always know.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/aqualung MFSL.flac in service mpd Apr 22 21:27:50 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Jethro%20Tull/Aqualung/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2Faqualung%20MFSL.flac&metadata=false Apr 22 21:27:50 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/aqualung MFSL.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/aqualung wrong ML.flac in service mpd Apr 22 21:27:50 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Jethro%20Tul%20wrong%20ML/Aqualung/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2Faqualung%20wrong%20ML.flac&metadata=false Apr 22 21:27:50 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/aqualung wrong ML.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/Art Taylor at's delight MC1.flac in service mpd Apr 22 21:27:50 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Art%20Taylot%20DDM%20MC1/AT's%20Delight/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2FArt%20Taylor%20at's%20delight%20MC1.flac&metadata=false Apr 22 21:27:50 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/Art Taylor at's delight MC1.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/blood sweat tears ML.flac in service mpd Apr 22 21:27:50 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Blood%20Swaet%20%26%20Tears/Blood%20sweat%20tears/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2Fblood%20sweat%20tears%20ML.flac&metadata=false Apr 22 21:27:50 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/blood sweat tears ML.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/hayward lodge bluejays.flac in service mpd Apr 22 21:27:50 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Justin%20Haywark%20John%20Lodge/Bluejays/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2Fhayward%20lodge%20bluejays.flac&metadata=false Apr 22 21:27:50 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/hayward lodge bluejays.flac Apr 22 21:27:50 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/blues breakers.flac in service mpd Apr 22 21:27:50 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=john%20mayall/blues%20breakers/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2Fblues%20breakers.flac&metadata=false Apr 22 21:27:50 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/blues breakers.flac Apr 22 21:27:51 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/grateful dead blues for Allah MC1.flac in service mpd Apr 22 21:27:51 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Graeful%20Dead%20MC1/Blues%20for%20Allah/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2Fgrateful%20dead%20blues%20for%20Allah%20MC1.flac&metadata=false Apr 22 21:27:51 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/grateful dead blues for Allah MC1.flac Apr 22 21:27:51 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/bruce cockburn dragon jaw ML.flac in service mpd Apr 22 21:27:51 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Bruck%20Cockburn%20ML/Dancing%20in%20the%20Lion's%20Jaw/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2Fbruce%20cockburn%20dragon%20jaw%20ML.flac&metadata=false Apr 22 21:27:51 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/bruce cockburn dragon jaw ML.flac Apr 22 21:27:51 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/allman eat a peach ML.flac in service mpd Apr 22 21:27:51 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Allman%20Brothers/Eat%20a%20Peach/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2Fallman%20eat%20a%20peach%20ML.flac&metadata=false Apr 22 21:27:51 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/allman eat a peach ML.flac Apr 22 21:27:51 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/allman filmore east ML.flac in service mpd Apr 22 21:27:51 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Allman%20Brothers%20ML/Eat%20A%20Peach/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2Fallman%20filmore%20east%20ML.flac&metadata=false Apr 22 21:27:51 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/allman filmore east ML.flac Apr 22 21:27:51 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/Arlo Guthrie Hobo.flac in service mpd Apr 22 21:27:51 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Arlo%20Guthrie/Hobo's%20luliby/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2FArlo%20Guthrie%20Hobo.flac&metadata=false Apr 22 21:27:51 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/Arlo Guthrie Hobo.flac Apr 22 21:27:51 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/butterfield blues live MC1.flac in service mpd Apr 22 21:27:51 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Butterfield%20blues%20band%20MC!/live/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2Fbutterfield%20blues%20live%20MC1.flac&metadata=false Apr 22 21:27:51 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/butterfield blues live MC1.flac Apr 22 21:27:51 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/2L38_01_320kHz.mp3 in service mpd Apr 22 21:27:51 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Marianne%20Thorsen%20%2F%20TrondheimSo/MOZART/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2F2L38_01_320kHz.mp3&metadata=false Apr 22 21:27:51 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/2L38_01_320kHz.mp3 Apr 22 21:27:51 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/2L38_01_192kHz.flac in service mpd Apr 22 21:27:51 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Marianne%20Thorsen%20%2F%20TrondheimSolistene/MOZART/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2F2L38_01_192kHz.flac&metadata=false Apr 22 21:27:51 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/2L38_01_192kHz.flac Apr 22 21:27:51 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/bob james earl klugh one on one MC1.flac in service mpd Apr 22 21:27:51 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Bob%20James%20%26%20Earl%20Klugh%20MC1/one%20on%20one/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2Fbob%20james%20earl%20klugh%20one%20on%20one%20MC1.flac&metadata=false Apr 22 21:27:51 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/bob james earl klugh one on one MC1.flac Apr 22 21:27:51 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/ELP Pictures MC1b.flac in service mpd Apr 22 21:27:51 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=ELP%20MC1a/Pictures%20at%20an%20Exhibiton/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2FELP%20Pictures%20MC1b.flac&metadata=false Apr 22 21:27:51 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/ELP Pictures MC1b.flac Apr 22 21:27:51 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/allman reach for te sky MC1.flac in service mpd Apr 22 21:27:51 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=allman%20brothers%20MC1/reach%20for%20the%20sky/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2Fallman%20reach%20for%20te%20sky%20MC1.flac&metadata=false Apr 22 21:27:51 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/allman reach for te sky MC1.flac Apr 22 21:27:51 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/alice coltrane reflection.flac in service mpd Apr 22 21:27:51 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Alice%20Coltrane/reflections%20on%20creation%20and%20space/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2Falice%20coltrane%20reflection.flac&metadata=false Apr 22 21:27:51 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/alice coltrane reflection.flac Apr 22 21:27:51 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/chick corea romntic warrior MC1.flac in service mpd Apr 22 21:27:51 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Chick%20Corea%20MC1/Romantic%20Worrier/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2Fchick%20corea%20romntic%20warrior%20MC1.flac&metadata=false Apr 22 21:27:51 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/chick corea romntic warrior MC1.flac Apr 22 21:27:51 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/Beatles Sgt Pepper.flac in service mpd Apr 22 21:27:51 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Beatles/sgt%20pepper/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2FBeatles%20Sgt%20Pepper.flac&metadata=false Apr 22 21:27:51 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/Beatles Sgt Pepper.flac Apr 22 21:27:51 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/war cisco MC1.flac in service mpd Apr 22 21:27:51 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=War%20MC1/The%20world%20is%20a%20ghetto/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2Fwar%20cisco%20%20MC1.flac&metadata=false Apr 22 21:27:51 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/war cisco MC1.flac Apr 22 21:27:51 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/allman win lose MC1.flac in service mpd Apr 22 21:27:51 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Allman%20Brothers%20MC1/win%20lose%20or%20draw/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2Fallman%20win%20lose%20MC1.flac&metadata=false Apr 22 21:27:51 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/allman win lose MC1.flac Apr 22 21:27:51 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/beatles magical mystery MC1.flac in service mpd Apr 22 21:27:51 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=The%20Beatles%20MC1/Yellow%20Submarine/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2Fbeatles%20magical%20mystery%20MC1.flac&metadata=false Apr 22 21:27:51 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/beatles magical mystery MC1.flac Apr 22 21:28:02 volumio-brookings wpa_supplicant[790]: RRM: Ignoring radio measurement request: Not RRM network Apr 22 21:28:12 volumio-brookings dhcpcd[802]: wlan0: carrier lost Apr 22 21:28:12 volumio-brookings wpa_supplicant[790]: wlan0: Associated with b8:94:70:de:c2:18 Apr 22 21:28:12 volumio-brookings wpa_supplicant[790]: wlan0: CTRL-EVENT-CONNECTED - Connection to b8:94:70:de:c2:18 completed [id=0 id_str=] Apr 22 21:28:12 volumio-brookings wpa_supplicant[790]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 22 21:28:13 volumio-brookings avahi-daemon[543]: Withdrawing address record for 192.168.1.148 on wlan0. Apr 22 21:28:13 volumio-brookings avahi-daemon[543]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.148. Apr 22 21:28:13 volumio-brookings avahi-daemon[543]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 22 21:28:13 volumio-brookings dhcpcd[802]: wlan0: deleting route to 192.168.1.0/24 Apr 22 21:28:13 volumio-brookings volumio[875]: info: Discovery: A device disappeared from network Apr 22 21:28:13 volumio-brookings dhcpcd[802]: wlan0: deleting default route via 192.168.1.1 Apr 22 21:28:13 volumio-brookings dhcpcd[802]: wlan0: carrier acquired Apr 22 21:28:13 volumio-brookings dhcpcd[802]: wlan0: IAID 01:ac:78:9b Apr 22 21:28:13 volumio-brookings dhcpcd[802]: wlan0: soliciting an IPv6 router Apr 22 21:28:13 volumio-brookings dhcpcd[802]: wlan0: soliciting a DHCP lease Apr 22 21:28:13 volumio-brookings dhcpcd[802]: wlan0: offered 192.168.1.148 from 192.168.1.1 Apr 22 21:28:13 volumio-brookings dhcpcd[802]: wlan0: probing address 192.168.1.148/24 Apr 22 21:28:14 volumio-brookings ntpd[675]: Deleting interface #3 wlan0, 192.168.1.148#123, interface stats: received=139, sent=143, dropped=0, active_time=135 secs Apr 22 21:28:14 volumio-brookings ntpd[675]: 74.50.98.131 local addr 192.168.1.148 -> Apr 22 21:28:14 volumio-brookings ntpd[675]: 204.2.134.163 local addr 192.168.1.148 -> Apr 22 21:28:14 volumio-brookings ntpd[675]: 198.71.50.75 local addr 192.168.1.148 -> Apr 22 21:28:14 volumio-brookings ntpd[675]: 44.190.5.123 local addr 192.168.1.148 -> Apr 22 21:28:14 volumio-brookings ntpd[675]: 198.137.202.56 local addr 192.168.1.148 -> Apr 22 21:28:14 volumio-brookings ntpd[675]: 69.10.223.131 local addr 192.168.1.148 -> Apr 22 21:28:14 volumio-brookings ntpd[675]: 104.152.220.5 local addr 192.168.1.148 -> Apr 22 21:28:14 volumio-brookings ntpd[675]: 71.162.136.44 local addr 192.168.1.148 -> Apr 22 21:28:14 volumio-brookings ntpd[675]: 137.190.2.4 local addr 192.168.1.148 -> Apr 22 21:28:14 volumio-brookings ntpd[675]: 71.19.144.140 local addr 192.168.1.148 -> Apr 22 21:28:14 volumio-brookings ntpd[675]: 167.248.62.201 local addr 192.168.1.148 -> Apr 22 21:28:14 volumio-brookings ntpd[675]: 143.42.229.154 local addr 192.168.1.148 -> Apr 22 21:28:14 volumio-brookings ntpd[675]: 51.81.226.229 local addr 192.168.1.148 -> Apr 22 21:28:14 volumio-brookings ntpd[675]: 50.205.57.38 local addr 192.168.1.148 -> Apr 22 21:28:14 volumio-brookings ntpd[675]: 155.248.196.28 local addr 192.168.1.148 -> Apr 22 21:28:14 volumio-brookings ntpd[675]: 107.172.43.182 local addr 192.168.1.148 -> Apr 22 21:28:17 volumio-brookings dhcpcd[802]: wlan0: leased 192.168.1.148 for 43200 seconds Apr 22 21:28:17 volumio-brookings avahi-daemon[543]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.148. Apr 22 21:28:17 volumio-brookings avahi-daemon[543]: New relevant interface wlan0.IPv4 for mDNS. Apr 22 21:28:17 volumio-brookings avahi-daemon[543]: Registering new address record for 192.168.1.148 on wlan0.IPv4. Apr 22 21:28:17 volumio-brookings dhcpcd[802]: wlan0: adding route to 192.168.1.0/24 Apr 22 21:28:17 volumio-brookings dhcpcd[802]: wlan0: adding default route via 192.168.1.1 Apr 22 21:28:18 volumio-brookings volumio[875]: info: Discovery: adding 7770eda5-ad71-44d0-81de-cb11496dbe75 Apr 22 21:28:18 volumio-brookings volumio[875]: info: Discovery: Found device Volumio Brookings Apr 22 21:28:18 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioGetState Apr 22 21:28:18 volumio-brookings volumio[875]: info: CorePlayQueue::getTrack 0 Apr 22 21:28:19 volumio-brookings ntpd[675]: Listen normally on 4 wlan0 192.168.1.148:123 Apr 22 21:28:19 volumio-brookings ntpd[675]: new interface(s) found: waking up resolver Apr 22 21:28:26 volumio-brookings volumio[875]: info: Preload queue cleared Apr 22 21:28:26 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 22 21:28:26 volumio-brookings volumio[875]: info: CoreStateMachine::ClearQueue Apr 22 21:28:26 volumio-brookings volumio[875]: info: CoreStateMachine::stop Apr 22 21:28:26 volumio-brookings volumio[875]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 21:28:26 volumio-brookings volumio[875]: info: CorePlayQueue::clearPlayQueue Apr 22 21:28:26 volumio-brookings volumio[875]: info: CorePlayQueue::saveQueue Apr 22 21:28:26 volumio-brookings volumio[875]: info: CoreCommandRouter::volumioPushQueue Apr 22 21:28:26 volumio-brookings volumio[875]: info: CoreStateMachine::addQueueItems Apr 22 21:28:26 volumio-brookings volumio[875]: info: CorePlayQueue::addQueueItems Apr 22 21:28:26 volumio-brookings volumio[875]: info: Preload queue cleared Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/Allman Brothers Dreams MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/Allman Brothers Dreams MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/allman Gregg tour MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/allman Gregg tour MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/Blind Faith.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/Blind Faith.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/Beatles Abbey rd MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/Beatles Abbey rd MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/War all day music mc1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/War all day music mc1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/monk always know.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/monk always know.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/aqualung MFSL.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/aqualung MFSL.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/aqualung wrong ML.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/aqualung wrong ML.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/Art Taylor at's delight MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/Art Taylor at's delight MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/blood sweat tears ML.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/blood sweat tears ML.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/hayward lodge bluejays.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/hayward lodge bluejays.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/blues breakers.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/blues breakers.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/grateful dead blues for Allah MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/grateful dead blues for Allah MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/bruce cockburn dragon jaw ML.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/bruce cockburn dragon jaw ML.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/allman eat a peach ML.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/allman eat a peach ML.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/allman filmore east ML.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/allman filmore east ML.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/Arlo Guthrie Hobo.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/Arlo Guthrie Hobo.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/butterfield blues live MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/butterfield blues live MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/2L38_01_320kHz.mp3 Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/2L38_01_320kHz.mp3 Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/2L38_01_192kHz.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/2L38_01_192kHz.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/bob james earl klugh one on one MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/bob james earl klugh one on one MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/ELP Pictures MC1b.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/ELP Pictures MC1b.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/allman reach for te sky MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/allman reach for te sky MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/alice coltrane reflection.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/alice coltrane reflection.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/chick corea romntic warrior MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/chick corea romntic warrior MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/Beatles Sgt Pepper.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/Beatles Sgt Pepper.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/war cisco MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/war cisco MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/allman win lose MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/allman win lose MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/beatles magical mystery MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Using cached record of: music-library/USB/EADB-A4C6/beatles magical mystery MC1.flac Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/Bob Marley - Catch a Fire, disc two Apr 22 21:28:26 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/Bob Marley - Catch a Fire, disc two in service mpd Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/clapton Apr 22 21:28:26 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/clapton in service mpd Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/Frank Zappa Apr 22 21:28:26 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/Frank Zappa in service mpd Apr 22 21:28:26 volumio-brookings volumio[875]: info: Adding Item to queue: music-library/USB/EADB-A4C6/jazz Apr 22 21:28:26 volumio-brookings volumio[875]: info: Exploding uri music-library/USB/EADB-A4C6/jazz in service mpd Apr 22 21:28:26 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Bob%20Marley%20and%20The%20Wailers/Catch%20A%20Fire%20-%20Disc%202%20-%20The%20Released%20Album/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2FBob%20Marley%20-%20Catch%20a%20Fire%2C%20disc%20two%2FBob%20Marley%20and%20The%20Wailers%20-01-%20Concrete%20Jungle.mp3&metadata=false Apr 22 21:28:26 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/Bob Marley - Catch a Fire, disc two/Bob Marley and The Wailers -01- Concrete Jungle.mp3 Apr 22 21:28:26 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Bob%20Marley%20and%20The%20Wailers/Catch%20A%20Fire%20-%20Disc%202%20-%20The%20Released%20Album/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2FBob%20Marley%20-%20Catch%20a%20Fire%2C%20disc%20two%2FBob%20Marley%20and%20The%20Wailers%20-02-%20Slave%20Driver.mp3&metadata=false Apr 22 21:28:26 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/Bob Marley - Catch a Fire, disc two/Bob Marley and The Wailers -02- Slave Driver.mp3 Apr 22 21:28:26 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Bob%20Marley%20and%20The%20Wailers/Catch%20A%20Fire%20-%20Disc%202%20-%20The%20Released%20Album/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2FBob%20Marley%20-%20Catch%20a%20Fire%2C%20disc%20two%2FBob%20Marley%20and%20The%20Wailers%20-03-%20400%20Years.mp3&metadata=false Apr 22 21:28:26 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/Bob Marley - Catch a Fire, disc two/Bob Marley and The Wailers -03- 400 Years.mp3 Apr 22 21:28:26 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Bob%20Marley%20and%20The%20Wailers/Catch%20A%20Fire%20-%20Disc%202%20-%20The%20Released%20Album/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2FBob%20Marley%20-%20Catch%20a%20Fire%2C%20disc%20two%2FBob%20Marley%20and%20The%20Wailers%20-04-%20Stop%20That%20Train.mp3&metadata=false Apr 22 21:28:26 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/Bob Marley - Catch a Fire, disc two/Bob Marley and The Wailers -04- Stop That Train.mp3 Apr 22 21:28:26 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Bob%20Marley%20and%20The%20Wailers/Catch%20A%20Fire%20-%20Disc%202%20-%20The%20Released%20Album/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2FBob%20Marley%20-%20Catch%20a%20Fire%2C%20disc%20two%2FBob%20Marley%20and%20The%20Wailers%20-05-%20Baby%20We've%20Got%20A%20Date%20(Rock%20It%20Baby).mp3&metadata=false Apr 22 21:28:26 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/Bob Marley - Catch a Fire, disc two/Bob Marley and The Wailers -05- Baby We've Got A Date (Rock It Baby).mp3 Apr 22 21:28:26 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Bob%20Marley%20and%20The%20Wailers/Catch%20A%20Fire%20-%20Disc%202%20-%20The%20Released%20Album/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2FBob%20Marley%20-%20Catch%20a%20Fire%2C%20disc%20two%2FBob%20Marley%20and%20The%20Wailers%20-06-%20Stir%20It%20Up.mp3&metadata=false Apr 22 21:28:26 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/Bob Marley - Catch a Fire, disc two/Bob Marley and The Wailers -06- Stir It Up.mp3 Apr 22 21:28:26 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Bob%20Marley%20and%20The%20Wailers/Catch%20A%20Fire%20-%20Disc%202%20-%20The%20Released%20Album/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2FBob%20Marley%20-%20Catch%20a%20Fire%2C%20disc%20two%2FBob%20Marley%20and%20The%20Wailers%20-07-%20Kinky%20Reggae.mp3&metadata=false Apr 22 21:28:26 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/Bob Marley - Catch a Fire, disc two/Bob Marley and The Wailers -07- Kinky Reggae.mp3 Apr 22 21:28:26 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Bob%20Marley%20and%20The%20Wailers/Catch%20A%20Fire%20-%20Disc%202%20-%20The%20Released%20Album/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2FBob%20Marley%20-%20Catch%20a%20Fire%2C%20disc%20two%2FBob%20Marley%20and%20The%20Wailers%20-08-%20No%20More%20Trouble.mp3&metadata=false Apr 22 21:28:26 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/Bob Marley - Catch a Fire, disc two/Bob Marley and The Wailers -08- No More Trouble.mp3 Apr 22 21:28:26 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Bob%20Marley%20and%20The%20Wailers/Catch%20A%20Fire%20-%20Disc%202%20-%20The%20Released%20Album/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2FBob%20Marley%20-%20Catch%20a%20Fire%2C%20disc%20two%2FBob%20Marley%20and%20The%20Wailers%20-09-%20Midnight%20Ravers.mp3&metadata=false Apr 22 21:28:26 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/Bob Marley - Catch a Fire, disc two/Bob Marley and The Wailers -09- Midnight Ravers.mp3 Apr 22 21:28:26 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Cream/On%20The%20Radio%20CD1/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2Fclapton%2FCream%20-%20On%20The%20Radio%20(2017)%20%5BFLAC%20CD%5D%2FCream%20-%20On%20The%20Radio%20CD1.cue&metadata=false Apr 22 21:28:26 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/clapton/Cream - On The Radio (2017) [FLAC CD]/Cream - On The Radio CD1.cue Apr 22 21:28:26 volumio-brookings volumio[875]: info: ALBUMART /albumart?cacheid=846&web=Cream/On%20The%20Radio%20CD1/extralarge&path=%2Fmnt%2FUSB%2FEADB-A4C6%2Fclapton%2FCream%20-%20On%20The%20Radio%20(2017)%20%5BFLAC%20CD%5D%2FCream%20-%20On%20The%20Radio%20CD1.cue&metadata=false Apr 22 21:28:26 volumio-brookings volumio[875]: info: URI /mnt/USB/EADB-A4C6/clapton/Cream - On The Radio (2017) [FLAC CD]/Cream - On The Radio CD1.cue Apr 22 21:28:26 volumio-brookings volumio[875]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 21:28:26 volumio-brookings volumio[875]: Error: Unable to resolve or reject the same promise twice Apr 22 21:28:26 volumio-brookings volumio[875]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 22 21:28:26 volumio-brookings volumio[875]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Apr 22 21:28:26 volumio-brookings volumio[875]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 22 21:28:26 volumio-brookings volumio[875]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 22 21:28:26 volumio-brookings volumio[875]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 22 21:28:26 volumio-brookings volumio[875]: at Socket.emit (events.js:315:20) Apr 22 21:28:26 volumio-brookings volumio[875]: at addChunk (internal/streams/readable.js:309:12) Apr 22 21:28:26 volumio-brookings volumio[875]: at readableAddChunk (internal/streams/readable.js:280:11) Apr 22 21:28:26 volumio-brookings volumio[875]: at Socket.Readable.push (internal/streams/readable.js:223:10) Apr 22 21:28:26 volumio-brookings volumio[875]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 22 21:28:26 volumio-brookings volumio[875]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 21:28:26 volumio-brookings sudo[1519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-22 21:27 Apr 22 21:28:26 volumio-brookings sudo[1519]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"