-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2025-03-16 22:25:29 CET. -- Mar 16 22:24:04 musibox volumio[858]: info: VolumeController::SetAlsaVolume57 Mar 16 22:24:04 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:24:04 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 22:24:04 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:24:04 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Mar 16 22:24:04 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 100 Mar 16 22:24:04 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 57 Mar 16 22:24:04 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 16 22:24:04 musibox volumio[858]: info: Setting Spotify Volume from Volumio: 57 Mar 16 22:24:06 musibox volumio[858]: SPOTIFY: SETTING SPOTIFY VOLUME 57 Mar 16 22:24:06 musibox volumio[858]: info: Sending Spotify command with payload to local API: /player/volume Mar 16 22:24:06 musibox volumio[858]: info: VolumeController::SetAlsaVolume14 Mar 16 22:24:06 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:24:06 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 22:24:06 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:24:06 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 14 Mar 16 22:24:06 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 57 Mar 16 22:24:06 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 14 Mar 16 22:24:06 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 16 22:24:06 musibox volumio[858]: info: Setting Spotify Volume from Volumio: 14 Mar 16 22:24:07 musibox volumio[858]: info: VolumeController::SetAlsaVolume10 Mar 16 22:24:07 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:24:07 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 22:24:07 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:24:07 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 16 22:24:07 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 14 Mar 16 22:24:07 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 10 Mar 16 22:24:07 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 16 22:24:07 musibox volumio[858]: info: Setting Spotify Volume from Volumio: 10 Mar 16 22:24:07 musibox volumio[858]: info: VolumeController::SetAlsaVolume11 Mar 16 22:24:07 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:24:07 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 22:24:07 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:24:07 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 11 Mar 16 22:24:07 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 10 Mar 16 22:24:07 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 11 Mar 16 22:24:07 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 16 22:24:07 musibox volumio[858]: info: VolumeController::SetAlsaVolume12 Mar 16 22:24:07 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:24:07 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 22:24:07 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:24:07 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 12 Mar 16 22:24:07 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 10 Mar 16 22:24:07 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 12 Mar 16 22:24:07 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 16 22:24:07 musibox volumio[858]: info: Setting Spotify Volume from Volumio: 12 Mar 16 22:24:08 musibox volumio[858]: info: VolumeController::SetAlsaVolume13 Mar 16 22:24:08 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:24:08 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 22:24:08 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:24:08 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Mar 16 22:24:08 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 12 Mar 16 22:24:08 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 13 Mar 16 22:24:08 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 16 22:24:08 musibox volumio[858]: info: VolumeController::SetAlsaVolume55 Mar 16 22:24:08 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:24:08 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 22:24:08 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:24:08 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Mar 16 22:24:08 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 12 Mar 16 22:24:08 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 55 Mar 16 22:24:08 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 16 22:24:08 musibox volumio[858]: info: Setting Spotify Volume from Volumio: 55 Mar 16 22:24:10 musibox volumio[858]: SPOTIFY: SETTING SPOTIFY VOLUME 55 Mar 16 22:24:10 musibox volumio[858]: info: Sending Spotify command with payload to local API: /player/volume Mar 16 22:24:12 musibox nmbd[625]: [2025/03/16 22:24:12.092513, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 16 22:24:12 musibox nmbd[625]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.178.76 for name WORKGROUP<1d>. Mar 16 22:24:12 musibox nmbd[625]: This response was from IP 192.168.178.143, reporting an IP address of 192.168.178.143. Mar 16 22:24:15 musibox volumio[858]: info: VolumeController::SetAlsaVolume82 Mar 16 22:24:15 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:24:15 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 22:24:15 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:24:15 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Mar 16 22:24:15 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 55 Mar 16 22:24:15 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 82 Mar 16 22:24:15 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 16 22:24:15 musibox volumio[858]: info: Setting Spotify Volume from Volumio: 82 Mar 16 22:24:17 musibox volumio[858]: SPOTIFY: SETTING SPOTIFY VOLUME 82 Mar 16 22:24:17 musibox volumio[858]: info: Sending Spotify command with payload to local API: /player/volume Mar 16 22:24:17 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 16 22:24:17 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 16 22:24:17 musibox volumio[858]: info: Discovery: Getting this device information Mar 16 22:24:17 musibox volumio[858]: info: CoreCommandRouter::volumioGetState Mar 16 22:24:17 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 16 22:24:18 musibox volumio[858]: info: VolumeController::SetAlsaVolume73 Mar 16 22:24:18 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:24:18 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 22:24:18 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:24:18 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Mar 16 22:24:18 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 82 Mar 16 22:24:18 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 73 Mar 16 22:24:18 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 16 22:24:18 musibox volumio[858]: info: Setting Spotify Volume from Volumio: 73 Mar 16 22:24:20 musibox volumio[858]: SPOTIFY: SETTING SPOTIFY VOLUME 73 Mar 16 22:24:20 musibox volumio[858]: info: Sending Spotify command with payload to local API: /player/volume Mar 16 22:24:23 musibox volumio[858]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 16 22:24:24 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 16 22:24:24 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 22:24:24 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 16 22:24:24 musibox volumio[858]: info: Getting Alsa Cards List without I2S DAC Mar 16 22:24:24 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 16 22:24:24 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 22:24:24 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 22:24:24 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 22:24:24 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 22:24:24 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 22:24:24 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 22:24:24 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 16 22:24:24 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 16 22:24:47 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 16 22:24:47 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 16 22:24:47 musibox volumio[858]: info: Discovery: Getting this device information Mar 16 22:24:47 musibox volumio[858]: info: CoreCommandRouter::volumioGetState Mar 16 22:24:47 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 16 22:25:14 musibox volumio[858]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Mar 16 22:25:14 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Mar 16 22:25:14 musibox volumio[858]: info: CoreCommandRouter::volumioGetState Mar 16 22:25:14 musibox volumio[858]: info: Restoring Previous Volume level: 73 false true Mar 16 22:25:14 musibox volumio[858]: info: VolumeController::SetAlsaVolume100 Mar 16 22:25:14 musibox volumio[858]: info: Volume configurations have been set Mar 16 22:25:14 musibox volumio[858]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 16 22:25:14 musibox volumio[858]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5 Mar 16 22:25:14 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 16 22:25:14 musibox volumio[858]: info: Disabling external Volume Control Mar 16 22:25:14 musibox volumio[858]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 16 22:25:14 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:14 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 22:25:14 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 73 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 16 22:25:15 musibox volumio[858]: info: Setting Spotify Volume from Volumio: 100 Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 16 22:25:15 musibox volumio[858]: info: Getting Alsa Cards List without I2S DAC Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 16 22:25:15 musibox volumio[858]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::volumioGetState Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::volumioPause Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::pause Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::stPlaybackTimer Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::servicePause Mar 16 22:25:15 musibox volumio[858]: info: CorePlayQueue::getTrack 0 Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::servicePause Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::sendMpdCommand pause Mar 16 22:25:15 musibox volumio[858]: info: mpdhttpout --- Volumio set on pause Mar 16 22:25:15 musibox volumio[858]: info: sendMpdCommand pause took 2 milliseconds Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::servicePushState Mar 16 22:25:15 musibox volumio[858]: info: CorePlayQueue::getTrack 0 Mar 16 22:25:15 musibox volumio[858]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received radio_paradise Mar 16 22:25:15 musibox volumio[858]: info: Mar 16 22:25:15 musibox volumio[858]: ---------------------------- MPD announces state update: player Mar 16 22:25:15 musibox volumio[858]: info: ControllerMpd::getState Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 16 22:25:15 musibox volumio[858]: info: Mar 16 22:25:15 musibox volumio[858]: ---------------------------- MPD announces state update: player Mar 16 22:25:15 musibox volumio[858]: info: ControllerMpd::getState Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 16 22:25:15 musibox volumio[858]: info: Mar 16 22:25:15 musibox volumio[858]: ---------------------------- MPD announces state update: player Mar 16 22:25:15 musibox volumio[858]: info: ControllerMpd::getState Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 16 22:25:15 musibox volumio[858]: info: Mar 16 22:25:15 musibox volumio[858]: ---------------------------- MPD announces state update: player Mar 16 22:25:15 musibox volumio[858]: info: ControllerMpd::getState Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 16 22:25:15 musibox volumio[858]: info: Mar 16 22:25:15 musibox volumio[858]: ---------------------------- MPD announces state update: player Mar 16 22:25:15 musibox volumio[858]: info: ControllerMpd::getState Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 16 22:25:15 musibox volumio[858]: info: Mar 16 22:25:15 musibox volumio[858]: ---------------------------- MPD announces state update: player Mar 16 22:25:15 musibox volumio[858]: info: ControllerMpd::getState Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 16 22:25:15 musibox volumio[858]: info: Mar 16 22:25:15 musibox volumio[858]: ---------------------------- MPD announces state update: player Mar 16 22:25:15 musibox volumio[858]: info: ControllerMpd::getState Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 16 22:25:15 musibox volumio[858]: info: Mar 16 22:25:15 musibox volumio[858]: ---------------------------- MPD announces state update: player Mar 16 22:25:15 musibox volumio[858]: info: ControllerMpd::getState Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 16 22:25:15 musibox volumio[858]: info: Mar 16 22:25:15 musibox volumio[858]: ---------------------------- MPD announces state update: player Mar 16 22:25:15 musibox volumio[858]: info: ControllerMpd::getState Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 16 22:25:15 musibox volumio[858]: info: Mar 16 22:25:15 musibox volumio[858]: ---------------------------- MPD announces state update: player Mar 16 22:25:15 musibox volumio[858]: info: ControllerMpd::getState Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 16 22:25:15 musibox volumio[858]: info: sendMpdCommand status took 15 milliseconds Mar 16 22:25:15 musibox volumio[858]: info: sendMpdCommand status took 14 milliseconds Mar 16 22:25:15 musibox volumio[858]: info: sendMpdCommand status took 13 milliseconds Mar 16 22:25:15 musibox volumio[858]: info: sendMpdCommand status took 12 milliseconds Mar 16 22:25:15 musibox volumio[858]: info: sendMpdCommand status took 11 milliseconds Mar 16 22:25:15 musibox volumio[858]: info: sendMpdCommand status took 9 milliseconds Mar 16 22:25:15 musibox volumio[858]: info: sendMpdCommand status took 8 milliseconds Mar 16 22:25:15 musibox volumio[858]: info: sendMpdCommand status took 8 milliseconds Mar 16 22:25:15 musibox volumio[858]: info: sendMpdCommand status took 6 milliseconds Mar 16 22:25:15 musibox volumio[858]: info: sendMpdCommand status took 5 milliseconds Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::parseState Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::parseState Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::parseState Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::parseState Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::parseState Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::parseState Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::parseState Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::parseState Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::parseState Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::parseState Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 22:25:15 musibox volumio[858]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 16 22:25:15 musibox volumio[858]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 16 22:25:15 musibox volumio[858]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 16 22:25:15 musibox volumio[858]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 16 22:25:15 musibox volumio[858]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 16 22:25:15 musibox volumio[858]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 16 22:25:15 musibox volumio[858]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 16 22:25:15 musibox volumio[858]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 16 22:25:15 musibox volumio[858]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 16 22:25:15 musibox volumio[858]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::parseTrackInfo Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::parseTrackInfo Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::parseTrackInfo Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::parseTrackInfo Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::parseTrackInfo Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::parseTrackInfo Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::parseTrackInfo Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::parseTrackInfo Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::parseTrackInfo Mar 16 22:25:15 musibox volumio[858]: verbose: ControllerMpd::parseTrackInfo Mar 16 22:25:15 musibox volumio[858]: info: ControllerMpd::pushState Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::servicePushState Mar 16 22:25:15 musibox volumio[858]: info: CorePlayQueue::getTrack 0 Mar 16 22:25:15 musibox volumio[858]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":79279,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"321 Kbps","isStreaming":false,"title":"Bryan Ferry - Don’t Stop the Dance","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Mar 16 22:25:15 musibox volumio[858]: verbose: CURRENT POSITION 0 Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::syncState stateService pause Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::syncState currentStatus pause Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::stPlaybackTimer Mar 16 22:25:15 musibox volumio[858]: info: ControllerMpd::pushState Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::servicePushState Mar 16 22:25:15 musibox volumio[858]: info: CorePlayQueue::getTrack 0 Mar 16 22:25:15 musibox volumio[858]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":79279,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"321 Kbps","isStreaming":false,"title":"Bryan Ferry - Don’t Stop the Dance","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Mar 16 22:25:15 musibox volumio[858]: verbose: CURRENT POSITION 0 Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::syncState stateService pause Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::syncState currentStatus pause Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::stPlaybackTimer Mar 16 22:25:15 musibox volumio[858]: info: ControllerMpd::pushState Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::servicePushState Mar 16 22:25:15 musibox volumio[858]: info: CorePlayQueue::getTrack 0 Mar 16 22:25:15 musibox volumio[858]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":79279,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"321 Kbps","isStreaming":false,"title":"Bryan Ferry - Don’t Stop the Dance","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Mar 16 22:25:15 musibox volumio[858]: verbose: CURRENT POSITION 0 Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::syncState stateService pause Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::syncState currentStatus pause Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::stPlaybackTimer Mar 16 22:25:15 musibox volumio[858]: info: ControllerMpd::pushState Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::servicePushState Mar 16 22:25:15 musibox volumio[858]: info: CorePlayQueue::getTrack 0 Mar 16 22:25:15 musibox volumio[858]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":79279,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"321 Kbps","isStreaming":false,"title":"Bryan Ferry - Don’t Stop the Dance","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Mar 16 22:25:15 musibox volumio[858]: verbose: CURRENT POSITION 0 Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::syncState stateService pause Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::syncState currentStatus pause Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::stPlaybackTimer Mar 16 22:25:15 musibox volumio[858]: info: ControllerMpd::pushState Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::servicePushState Mar 16 22:25:15 musibox volumio[858]: info: CorePlayQueue::getTrack 0 Mar 16 22:25:15 musibox volumio[858]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":79279,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"321 Kbps","isStreaming":false,"title":"Bryan Ferry - Don’t Stop the Dance","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Mar 16 22:25:15 musibox volumio[858]: verbose: CURRENT POSITION 0 Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::syncState stateService pause Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::syncState currentStatus pause Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::stPlaybackTimer Mar 16 22:25:15 musibox volumio[858]: info: ControllerMpd::pushState Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::servicePushState Mar 16 22:25:15 musibox volumio[858]: info: CorePlayQueue::getTrack 0 Mar 16 22:25:15 musibox volumio[858]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":79279,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"321 Kbps","isStreaming":false,"title":"Bryan Ferry - Don’t Stop the Dance","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Mar 16 22:25:15 musibox volumio[858]: verbose: CURRENT POSITION 0 Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::syncState stateService pause Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::syncState currentStatus pause Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::stPlaybackTimer Mar 16 22:25:15 musibox volumio[858]: info: ControllerMpd::pushState Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::servicePushState Mar 16 22:25:15 musibox volumio[858]: info: CorePlayQueue::getTrack 0 Mar 16 22:25:15 musibox volumio[858]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":79279,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"321 Kbps","isStreaming":false,"title":"Bryan Ferry - Don’t Stop the Dance","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Mar 16 22:25:15 musibox volumio[858]: verbose: CURRENT POSITION 0 Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::syncState stateService pause Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::syncState currentStatus pause Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::stPlaybackTimer Mar 16 22:25:15 musibox volumio[858]: info: ControllerMpd::pushState Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::servicePushState Mar 16 22:25:15 musibox volumio[858]: info: CorePlayQueue::getTrack 0 Mar 16 22:25:15 musibox volumio[858]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":79279,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"321 Kbps","isStreaming":false,"title":"Bryan Ferry - Don’t Stop the Dance","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Mar 16 22:25:15 musibox volumio[858]: verbose: CURRENT POSITION 0 Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::syncState stateService pause Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::syncState currentStatus pause Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::stPlaybackTimer Mar 16 22:25:15 musibox volumio[858]: info: ControllerMpd::pushState Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::servicePushState Mar 16 22:25:15 musibox volumio[858]: info: CorePlayQueue::getTrack 0 Mar 16 22:25:15 musibox volumio[858]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":79279,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"321 Kbps","isStreaming":false,"title":"Bryan Ferry - Don’t Stop the Dance","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Mar 16 22:25:15 musibox volumio[858]: verbose: CURRENT POSITION 0 Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::syncState stateService pause Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::syncState currentStatus pause Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::stPlaybackTimer Mar 16 22:25:15 musibox volumio[858]: info: ControllerMpd::pushState Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::servicePushState Mar 16 22:25:15 musibox volumio[858]: info: CorePlayQueue::getTrack 0 Mar 16 22:25:15 musibox volumio[858]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":79279,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"321 Kbps","isStreaming":false,"title":"Bryan Ferry - Don’t Stop the Dance","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Mar 16 22:25:15 musibox volumio[858]: verbose: CURRENT POSITION 0 Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::syncState stateService pause Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::syncState currentStatus pause Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:15 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:15 musibox volumio[858]: info: CoreStateMachine::stPlaybackTimer Mar 16 22:25:15 musibox volumio[858]: info: ------------------------------ 179ms Mar 16 22:25:15 musibox volumio[858]: info: ------------------------------ 178ms Mar 16 22:25:15 musibox volumio[858]: info: ------------------------------ 177ms Mar 16 22:25:15 musibox volumio[858]: info: ------------------------------ 176ms Mar 16 22:25:15 musibox volumio[858]: info: ------------------------------ 175ms Mar 16 22:25:15 musibox volumio[858]: info: ------------------------------ 173ms Mar 16 22:25:15 musibox volumio[858]: info: ------------------------------ 173ms Mar 16 22:25:15 musibox volumio[858]: info: ------------------------------ 172ms Mar 16 22:25:15 musibox volumio[858]: info: ------------------------------ 171ms Mar 16 22:25:15 musibox volumio[858]: info: ------------------------------ 171ms Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 100 Mar 16 22:25:15 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 16 22:25:16 musibox volumio[858]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Mar 16 22:25:16 musibox volumio[858]: info: Sending Spotify command with payload to local API: /player/volume Mar 16 22:25:17 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Mar 16 22:25:17 musibox sudo[4530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 16 22:25:17 musibox sudo[4530]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 22:25:17 musibox systemd[1]: Stopping Music Player Daemon... Mar 16 22:25:17 musibox systemd[1]: mpd.service: Succeeded. Mar 16 22:25:17 musibox systemd[1]: Stopped Music Player Daemon. Mar 16 22:25:17 musibox systemd[1]: Starting Music Player Daemon... Mar 16 22:25:17 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 16 22:25:17 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 16 22:25:17 musibox volumio[858]: info: Discovery: Getting this device information Mar 16 22:25:17 musibox volumio[858]: info: CoreCommandRouter::volumioGetState Mar 16 22:25:17 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 16 22:25:17 musibox sudo[4532]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 16 22:25:17 musibox sudo[4532]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 22:25:17 musibox sudo[4532]: pam_unix(sudo:session): session closed for user root Mar 16 22:25:19 musibox volumio[858]: info: VolumeController::SetAlsaVolume- Mar 16 22:25:19 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:19 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 22:25:19 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:19 musibox volumio[858]: info: VolumeController::SetAlsaVolume- Mar 16 22:25:19 musibox volumio[858]: info: VolumeController::SetAlsaVolume- Mar 16 22:25:19 musibox volumio[858]: info: VolumeController::SetAlsaVolume- Mar 16 22:25:19 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:19 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 22:25:19 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:19 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:19 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:19 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:19 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 100 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 95 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 16 22:25:19 musibox volumio[858]: info: Setting Spotify Volume from Volumio: 95 Mar 16 22:25:19 musibox mpd[4534]: Mar 16 22:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 95 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 80 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 16 22:25:19 musibox volumio[858]: info: Setting Spotify Volume from Volumio: 80 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 16 22:25:19 musibox volumio[858]: info: VolumeController::SetAlsaVolume- Mar 16 22:25:19 musibox volumio[858]: info: VolumeController::SetAlsaVolume- Mar 16 22:25:19 musibox volumio[858]: info: VolumeController::SetAlsaVolume- Mar 16 22:25:19 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:19 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 22:25:19 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:19 musibox systemd[1]: Started Music Player Daemon. Mar 16 22:25:19 musibox sudo[4530]: pam_unix(sudo:session): session closed for user root Mar 16 22:25:19 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:19 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:19 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:19 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:19 musibox volumio[858]: info: VolumeController::SetAlsaVolume73 Mar 16 22:25:19 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:19 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 22:25:19 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:19 musibox volumio[858]: info: VolumeController::SetAlsaVolume- Mar 16 22:25:19 musibox volumio[858]: info: VolumeController::SetAlsaVolume- Mar 16 22:25:19 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:19 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 22:25:19 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:19 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:19 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 80 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 65 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 16 22:25:19 musibox volumio[858]: info: Setting Spotify Volume from Volumio: 65 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 65 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 73 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 16 22:25:19 musibox volumio[858]: info: Setting Spotify Volume from Volumio: 73 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 63 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 73 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 63 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 16 22:25:19 musibox volumio[858]: info: Setting Spotify Volume from Volumio: 63 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 63 Mar 16 22:25:19 musibox volumio[858]: error: updateQueue error: null Mar 16 22:25:19 musibox volumio[858]: info: VolumeController::SetAlsaVolume- Mar 16 22:25:19 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:19 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 22:25:19 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:19 musibox volumio[858]: info: VolumeController::SetAlsaVolume- Mar 16 22:25:19 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:19 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 22:25:19 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 63 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 58 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 16 22:25:19 musibox volumio[858]: info: Setting Spotify Volume from Volumio: 58 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: SPOTIFY VOLUME 58 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: VOLUMIO VOLUME 53 Mar 16 22:25:19 musibox volumio[858]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 16 22:25:19 musibox volumio[858]: info: Setting Spotify Volume from Volumio: 53 Mar 16 22:25:21 musibox volumio[858]: SPOTIFY: SETTING SPOTIFY VOLUME 53 Mar 16 22:25:21 musibox volumio[858]: info: Sending Spotify command with payload to local API: /player/volume Mar 16 22:25:28 musibox volumio[858]: info: CoreCommandRouter::volumioPlay Mar 16 22:25:28 musibox volumio[858]: info: CoreStateMachine::play index undefined Mar 16 22:25:28 musibox volumio[858]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 22:25:28 musibox volumio[858]: info: CorePlayQueue::getTrack 0 Mar 16 22:25:28 musibox volumio[858]: info: CoreStateMachine::startPlaybackTimer Mar 16 22:25:28 musibox volumio[858]: info: CorePlayQueue::getTrack 0 Mar 16 22:25:28 musibox volumio[858]: verbose: ControllerMpd::sendMpdCommand play Mar 16 22:25:28 musibox volumio[858]: info: sendMpdCommand play took 4 milliseconds Mar 16 22:25:28 musibox volumio[858]: info: CoreCommandRouter::servicePushState Mar 16 22:25:28 musibox volumio[858]: info: CorePlayQueue::getTrack 0 Mar 16 22:25:28 musibox volumio[858]: verbose: STATE SERVICE {"status":"play","seek":79223} Mar 16 22:25:28 musibox volumio[858]: verbose: CURRENT POSITION 0 Mar 16 22:25:28 musibox volumio[858]: info: CoreStateMachine::syncState stateService play Mar 16 22:25:28 musibox volumio[858]: info: CoreStateMachine::syncState currentStatus pause Mar 16 22:25:28 musibox volumio[858]: info: CoreStateMachine::pushState Mar 16 22:25:28 musibox volumio[858]: info: CorePlayQueue::getTrack 0 Mar 16 22:25:28 musibox volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 22:25:28 musibox volumio[858]: info: CoreCommandRouter::volumioPushState Mar 16 22:25:28 musibox volumio[858]: info: [1742160328769] [RadioParadise] getMetadata started with url undefined Mar 16 22:25:28 musibox volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Mar 16 22:25:28 musibox volumio[858]: info: [1742160328794] [RadioParadise] Error: connect ECONNREFUSED 127.0.0.1:443 Mar 16 22:25:28 musibox volumio[858]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 22:25:28 musibox volumio[858]: TypeError: Cannot read property 'toUpperCase' of undefined Mar 16 22:25:28 musibox volumio[858]: at ControllerRadioParadise.errorToast (/data/plugins/music_service/radio_paradise/index.js:481:41) Mar 16 22:25:28 musibox volumio[858]: at ClientRequest. (/data/plugins/music_service/radio_paradise/index.js:452:14) Mar 16 22:25:28 musibox volumio[858]: at ClientRequest.emit (events.js:315:20) Mar 16 22:25:28 musibox volumio[858]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Mar 16 22:25:28 musibox volumio[858]: at TLSSocket.emit (events.js:315:20) Mar 16 22:25:28 musibox volumio[858]: at emitErrorNT (internal/streams/destroy.js:106:8) Mar 16 22:25:28 musibox volumio[858]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Mar 16 22:25:28 musibox volumio[858]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Mar 16 22:25:28 musibox volumio[858]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 22:25:29 musibox sudo[4572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-16 22:24 Mar 16 22:25:29 musibox sudo[4572]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"