Dec 26 10:27:09 volumio go-librespot[1680]: time="2025-12-26T10:27:09+02:00" level=trace msg="sent dealer ping" Dec 26 10:27:09 volumio go-librespot[1680]: time="2025-12-26T10:27:09+02:00" level=trace msg="received dealer pong" Dec 26 10:27:11 volumio volumio[3941]: libpng warning: iCCP: known incorrect sRGB profile Dec 26 10:27:11 volumio volumio[3941]: libpng warning: iCCP: known incorrect sRGB profile Dec 26 10:27:11 volumio volumio[3941]: libpng warning: iCCP: known incorrect sRGB profile Dec 26 10:27:14 volumio volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 10:27:14 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 26 10:27:14 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:27:14 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 10:27:14 volumio volumio[1145]: Invalid card number '5'. Dec 26 10:27:14 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:14 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:14 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:14 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:14 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:14 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:14 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:14 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 26 10:27:14 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 26 10:27:18 volumio volumio[1145]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 26 10:27:18 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 26 10:27:18 volumio volumio[1145]: info: Preparing to save Alsa Options, stopping services first Dec 26 10:27:18 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 26 10:27:18 volumio volumio[1145]: info: CoreCommandRouter::volumioStop Dec 26 10:27:18 volumio volumio[1145]: info: CoreStateMachine::stop Dec 26 10:27:18 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 10:27:18 volumio volumio[1145]: info: CoreStateMachine::stPlaybackTimer Dec 26 10:27:18 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock Dec 26 10:27:18 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock Dec 26 10:27:18 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:27:18 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:18 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:27:18 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:27:18 volumio volumio[1145]: info: CoreStateMachine::serviceStop Dec 26 10:27:18 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:18 volumio volumio[1145]: info: CoreCommandRouter::serviceStop Dec 26 10:27:18 volumio volumio[1145]: info: [1766737638387] ControllerWebradio::stop Dec 26 10:27:18 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 10:27:18 volumio volumio[1145]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"SMSL USB AUDIO"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Dec 26 10:27:18 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 10:27:18 volumio volumio[1145]: warn: Unable to locate the device SMSL USB AUDIO a reboot may be required. Dec 26 10:27:18 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 10:27:18 volumio volumio[1145]: Invalid card number '5'. Dec 26 10:27:18 volumio volumio[1145]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 26 10:27:18 volumio volumio[1145]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 26 10:27:18 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 26 10:27:18 volumio volumio[1145]: info: Disabling external Volume Control Dec 26 10:27:18 volumio volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 10:27:18 volumio volumio[1145]: info: Enable softmixer device for audio device undefined Dec 26 10:27:18 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 26 10:27:18 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:27:18 volumio volumio[1145]: info: Dec 26 10:27:18 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:27:18 volumio volumio[1145]: info: sendMpdCommand stop took 513 milliseconds Dec 26 10:27:18 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:27:18 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:27:18 volumio volumio[1145]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 26 10:27:18 volumio volumio[1145]: info: sendMpdCommand status took 44 milliseconds Dec 26 10:27:18 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:27:18 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:27:18 volumio volumio[1145]: info: VolumeController:: Volume=undefined Mute =false Dec 26 10:27:18 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:27:18 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:18 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:27:18 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:27:18 volumio sudo[5495]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 26 10:27:18 volumio sudo[5495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:19 volumio volumio[1145]: info: Software Volume ALSA configuration written Dec 26 10:27:19 volumio volumio[1145]: info: Preparing to generate the ALSA configuration file Dec 26 10:27:19 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Dec 26 10:27:19 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 190 milliseconds Dec 26 10:27:19 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:27:19 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:27:19 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:27:19 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:27:19 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:19 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:27:19 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:19 volumio volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 26 10:27:19 volumio volumio[1145]: info: ------------------------------ 300ms Dec 26 10:27:19 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 26 10:27:19 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Dec 26 10:27:19 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Dec 26 10:27:19 volumio systemd[1]: peppymeterbasic.service: Consumed 10.625s CPU time. Dec 26 10:27:19 volumio sudo[5495]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:19 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 26 10:27:19 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:27:19 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:27:19 volumio sudo[5507]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 26 10:27:19 volumio sudo[5507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:19 volumio sudo[5509]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 26 10:27:19 volumio sudo[5509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:19 volumio sudo[5507]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:19 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 26 10:27:19 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:27:19 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 10:27:19 volumio sudo[5509]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:19 volumio volumio[1145]: Invalid card number '5'. Dec 26 10:27:19 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:19 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:19 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:19 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:19 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:19 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:19 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:19 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 26 10:27:19 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 26 10:27:19 volumio volumio[1145]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 26 10:27:19 volumio volumio[1145]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 26 10:27:19 volumio volumio[1145]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 26 10:27:19 volumio volumio[1145]: info: Reading ALSA contributions from plugins. Dec 26 10:27:19 volumio volumio[1145]: info: peppymeterbasic Daemon Stop Dec 26 10:27:19 volumio volumio[1145]: info: peppymeterbasic Daemon Stop Dec 26 10:27:19 volumio volumio[1145]: info: peppymeterbasic Daemon Stop Dec 26 10:27:20 volumio volumio[1145]: info: Asound.conf file written Dec 26 10:27:20 volumio sudo[5536]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 26 10:27:20 volumio sudo[5536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:20 volumio sudo[5536]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:20 volumio volumio[1145]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 26 10:27:20 volumio volumio[1145]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 26 10:27:20 volumio volumio[1145]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Dec 26 10:27:20 volumio volumio[1145]: info: Output device has changed, restarting MPD Dec 26 10:27:20 volumio volumio[1145]: info: Output device has changed, restarting Shairport Sync Dec 26 10:27:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:27:20 volumio sudo[5543]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 10:27:20 volumio sudo[5543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:20 volumio sudo[5544]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 10:27:20 volumio volumio[1145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 10:27:20 volumio sudo[5544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:20 volumio sudo[5544]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:20 volumio volumio[1145]: info: Output device has changed, restarting MPD Dec 26 10:27:20 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 26 10:27:20 volumio volumio[1145]: info: Output device has changed, restarting Shairport Sync Dec 26 10:27:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:27:20 volumio sudo[5552]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 10:27:20 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 26 10:27:20 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 26 10:27:20 volumio systemd[1]: mpd.service: Consumed 1.876s CPU time. Dec 26 10:27:20 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 26 10:27:20 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 26 10:27:20 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 26 10:27:20 volumio sudo[5552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:20 volumio sudo[5552]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:20 volumio sudo[5554]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 10:27:20 volumio sudo[5554]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:20 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 10:27:20 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 10:27:20 volumio volumio[1145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 10:27:20 volumio volumio[1145]: info: MPD Permissions set Dec 26 10:27:20 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 26 10:27:20 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 26 10:27:20 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 26 10:27:20 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 26 10:27:20 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 26 10:27:20 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 10:27:20 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 10:27:20 volumio volumio[1145]: info: MPD Permissions set Dec 26 10:27:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:20 volumio sudo[5564]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 10:27:20 volumio sudo[5564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 10:27:20 volumio sudo[5564]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:27:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:21 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:27:21 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:21 volumio volumio[1145]: info: Starting Shairport Sync Dec 26 10:27:21 volumio volumio[1145]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Dec 26 10:27:21 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 26 10:27:21 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:21 volumio volumio[1145]: info: CoreCommandRouter::volumioPause Dec 26 10:27:21 volumio volumio[1145]: info: CoreStateMachine::pause Dec 26 10:27:21 volumio volumio[1145]: info: mpdhttpout --- Volumio set on pause Dec 26 10:27:21 volumio volumio[1145]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Dec 26 10:27:21 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 26 10:27:21 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:21 volumio volumio[1145]: info: CoreCommandRouter::volumioPause Dec 26 10:27:21 volumio volumio[1145]: info: CoreStateMachine::pause Dec 26 10:27:21 volumio volumio[1145]: info: mpdhttpout --- Volumio set on pause Dec 26 10:27:21 volumio volumio[1145]: info: Starting Shairport Sync Dec 26 10:27:21 volumio sudo[5594]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 10:27:21 volumio sudo[5594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:21 volumio sudo[5597]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 10:27:21 volumio sudo[5597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:21 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 26 10:27:21 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 26 10:27:21 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 10:27:21 volumio systemd[1]: shairport-sync.service: Consumed 1.538s CPU time. Dec 26 10:27:21 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 10:27:21 volumio sudo[5594]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:21 volumio volumio[1145]: info: Shairport-Sync Started Dec 26 10:27:21 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 26 10:27:21 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 26 10:27:21 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 10:27:21 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 10:27:21 volumio sudo[5597]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:21 volumio volumio[1145]: info: Shairport-Sync Started Dec 26 10:27:22 volumio volumio[1145]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 26 10:27:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 26 10:27:22 volumio volumio[1145]: info: Preparing to save Alsa Options, stopping services first Dec 26 10:27:22 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 26 10:27:22 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:22 volumio volumio[1145]: info: CoreCommandRouter::volumioStop Dec 26 10:27:22 volumio volumio[1145]: info: CoreStateMachine::stop Dec 26 10:27:22 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 10:27:22 volumio volumio[1145]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"SMSL USB AUDIO"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Dec 26 10:27:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 10:27:22 volumio volumio[1145]: warn: Unable to locate the device SMSL USB AUDIO a reboot may be required. Dec 26 10:27:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 10:27:22 volumio volumio[1145]: Invalid card number '5'. Dec 26 10:27:22 volumio mpd[5579]: 2025-12-26T10:27:22 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 26 10:27:22 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 26 10:27:22 volumio sudo[5543]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:22 volumio sudo[5554]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:22 volumio volumio[1145]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 26 10:27:22 volumio volumio[1145]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 26 10:27:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 26 10:27:22 volumio volumio[1145]: info: Disabling external Volume Control Dec 26 10:27:22 volumio volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 10:27:22 volumio volumio[1145]: info: Enable softmixer device for audio device undefined Dec 26 10:27:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:22 volumio volumio[1145]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 26 10:27:22 volumio volumio[1145]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 26 10:27:22 volumio volumio[1145]: info: CoreStateMachine::getcurrentVolume Dec 26 10:27:22 volumio volumio[1145]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 10:27:22 volumio volumio[1145]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 26 10:27:23 volumio volumio[1145]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 26 10:27:23 volumio volumio[1145]: info: VolumeController:: Volume=undefined Mute =false Dec 26 10:27:23 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:27:23 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:23 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:27:23 volumio volumio[1145]: info: VolumeController:: Volume=undefined Mute =false Dec 26 10:27:23 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:27:23 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:23 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:27:23 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock Dec 26 10:27:23 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock Dec 26 10:27:23 volumio volumio[1145]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 10:27:23 volumio volumio[1145]: warn: [squeezelite_mc] No supported ALSA formats found for card 5 Dec 26 10:27:23 volumio volumio[1145]: warn: [squeezelite_mc] No ALSA formats returned for card 5 Dec 26 10:27:23 volumio volumio[1145]: info: [squeezelite_mc] Restarting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"5","mixerType":"undefined","mixer":""} Dec 26 10:27:23 volumio volumio[1145]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Dec 26 10:27:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 26 10:27:23 volumio sudo[5702]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 10:27:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 26 10:27:23 volumio sudo[5702]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:23 volumio sudo[5698]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Dec 26 10:27:23 volumio sudo[5698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:23 volumio sudo[5698]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:23 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 26 10:27:23 volumio sudo[5712]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 10:27:23 volumio volumio[1145]: info: Software Volume ALSA configuration written Dec 26 10:27:23 volumio volumio[1145]: info: Preparing to generate the ALSA configuration file Dec 26 10:27:23 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 26 10:27:23 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 26 10:27:23 volumio systemd[1]: mpd.service: Consumed 1.501s CPU time. Dec 26 10:27:23 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 26 10:27:23 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 26 10:27:23 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 26 10:27:23 volumio sudo[5712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:23 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 10:27:23 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 10:27:23 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 26 10:27:23 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 26 10:27:23 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 26 10:27:23 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 26 10:27:23 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 26 10:27:23 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 10:27:23 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 10:27:23 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 26 10:27:23 volumio sudo[5717]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 10:27:23 volumio sudo[5717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 10:27:23 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 26 10:27:23 volumio sudo[5717]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:23 volumio sudo[5719]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 26 10:27:23 volumio sudo[5719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:23 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:27:23 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:27:23 volumio volumio[1145]: error: MPD error: The expression evaluated to a falsy value: Dec 26 10:27:23 volumio volumio[1145]: assert.ok(self.idling) Dec 26 10:27:23 volumio volumio[1145]: error: The expression evaluated to a falsy value: Dec 26 10:27:23 volumio volumio[1145]: assert.ok(self.idling) Dec 26 10:27:23 volumio volumio[1145]: error: MPD error: Error: write EPIPE Dec 26 10:27:23 volumio sudo[5723]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 26 10:27:23 volumio sudo[5723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:23 volumio sudo[5719]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:23 volumio volumio[1145]: error: write EPIPE {"code":"EPIPE","errno":-32,"syscall":"write"} Dec 26 10:27:23 volumio volumio[1145]: error: MPD error: Error: write EPIPE Dec 26 10:27:23 volumio volumio[1145]: error: write EPIPE {"code":"EPIPE","errno":-32,"syscall":"write"} Dec 26 10:27:23 volumio volumio[1145]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 26 10:27:23 volumio volumio[1145]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 26 10:27:23 volumio volumio[1145]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 26 10:27:23 volumio volumio[1145]: info: Reading ALSA contributions from plugins. Dec 26 10:27:23 volumio volumio[1145]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Dec 26 10:27:24 volumio sudo[5723]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:24 volumio volumio[1145]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 26 10:27:24 volumio sudo[5728]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Dec 26 10:27:24 volumio sudo[5728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:24 volumio sudo[5728]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:24 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 26 10:27:24 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:27:24 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 10:27:24 volumio volumio[1145]: Invalid card number '5'. Dec 26 10:27:24 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:24 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:24 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:24 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:24 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:24 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:24 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:24 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 26 10:27:24 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 26 10:27:24 volumio volumio[1145]: info: VolumeController:: Volume=undefined Mute =false Dec 26 10:27:24 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:27:24 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:24 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:27:24 volumio volumio[1145]: info: peppymeterbasic Daemon Stop Dec 26 10:27:24 volumio volumio[1145]: info: peppymeterbasic Daemon Stop Dec 26 10:27:24 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 26 10:27:24 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:27:24 volumio volumio[1145]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Dec 26 10:27:24 volumio sudo[5746]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 26 10:27:24 volumio sudo[5746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:25 volumio sudo[5751]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Dec 26 10:27:25 volumio sudo[5751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:25 volumio sudo[5746]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:25 volumio volumio[1145]: info: peppymeterbasic Daemon Stop Dec 26 10:27:25 volumio sudo[5751]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:25 volumio volumio[1145]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Dec 26 10:27:25 volumio volumio[1145]: info: Asound.conf file unchanged, so no further update is needed Dec 26 10:27:25 volumio volumio[1145]: info: Output device has changed, restarting MPD Dec 26 10:27:25 volumio sudo[5758]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 26 10:27:25 volumio sudo[5758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:25 volumio volumio[1145]: info: Output device has changed, restarting Shairport Sync Dec 26 10:27:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:27:25 volumio mpd[5722]: 2025-12-26T10:27:25 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 26 10:27:25 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 26 10:27:25 volumio sudo[5702]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:25 volumio volumio[1145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 10:27:25 volumio sudo[5712]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:25 volumio systemd[1]: Reloading. Dec 26 10:27:25 volumio sudo[5763]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 10:27:25 volumio sudo[5763]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:25 volumio sudo[5766]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 10:27:25 volumio sudo[5766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:25 volumio sudo[5763]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:25 volumio volumio[1145]: info: MPD Permissions set Dec 26 10:27:25 volumio volumio[1145]: error: MPD error: The expression evaluated to a falsy value: Dec 26 10:27:25 volumio volumio[1145]: assert.ok(self.idling) Dec 26 10:27:25 volumio volumio[1145]: error: The expression evaluated to a falsy value: Dec 26 10:27:25 volumio volumio[1145]: assert.ok(self.idling) Dec 26 10:27:25 volumio volumio[1145]: error: updateQueue error: null Dec 26 10:27:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:27:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:25 volumio volumio[1145]: info: Starting Shairport Sync Dec 26 10:27:25 volumio volumio[1145]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Dec 26 10:27:25 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 26 10:27:25 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:25 volumio volumio[1145]: info: CoreCommandRouter::volumioPause Dec 26 10:27:25 volumio volumio[1145]: info: CoreStateMachine::pause Dec 26 10:27:25 volumio volumio[1145]: info: mpdhttpout --- Volumio set on pause Dec 26 10:27:25 volumio sudo[5809]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 10:27:25 volumio sudo[5809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:26 volumio sudo[5843]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 10:27:26 volumio sudo[5843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:26 volumio sudo[5841]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 10:27:26 volumio sudo[5841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:26 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 10:27:26 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 10:27:26 volumio volumio[1145]: info: CoreCommandRouter::volumioPlay Dec 26 10:27:26 volumio volumio[1145]: info: CoreStateMachine::play index undefined Dec 26 10:27:26 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 10:27:26 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:26 volumio volumio[1145]: info: CoreStateMachine::startPlaybackTimer Dec 26 10:27:26 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:26 volumio volumio[1145]: info: [1766737646248] ControllerWebradio::clearAddPlayTrack Dec 26 10:27:26 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 10:27:26 volumio systemd[1]: /lib/systemd/system/roonbridge.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 10:27:26 volumio systemd[1]: /lib/systemd/system/roonbridge.service:8: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 10:27:26 volumio volumio[1145]: info: sendMpdCommand stop took 0 milliseconds Dec 26 10:27:26 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand clear Dec 26 10:27:26 volumio volumio[1145]: info: Dec 26 10:27:26 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:27:26 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:27:26 volumio volumio[1145]: info: sendMpdCommand clear took 0 milliseconds Dec 26 10:27:26 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand load "https://cast.megapolis.fm/listen/dnipro/radio.mp3" Dec 26 10:27:26 volumio volumio[1145]: info: Dec 26 10:27:26 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:27:26 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:27:26 volumio volumio[1145]: error: updateQueue error: null Dec 26 10:27:26 volumio volumio[1145]: info: ------------------------------ 3ms Dec 26 10:27:26 volumio sudo[5758]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:26 volumio volumio[1145]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 26 10:27:26 volumio systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... Dec 26 10:27:26 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 26 10:27:26 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 26 10:27:26 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 26 10:27:26 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 26 10:27:26 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 26 10:27:26 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 26 10:27:26 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 10:27:26 volumio systemd[1]: shairport-sync.service: Consumed 1.337s CPU time. Dec 26 10:27:26 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 10:27:26 volumio sudo[5864]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 26 10:27:26 volumio sudo[5864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:26 volumio sudo[5809]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:26 volumio volumio[1145]: info: Shairport-Sync Started Dec 26 10:27:26 volumio sudo[5864]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:26 volumio volumio[1145]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Dec 26 10:27:26 volumio volumio[1145]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 26 10:27:26 volumio volumio[1145]: info: CoreStateMachine::getcurrentVolume Dec 26 10:27:26 volumio volumio[1145]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 10:27:26 volumio volumio[1145]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 26 10:27:26 volumio sudo[5896]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Dec 26 10:27:26 volumio sudo[5896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:26 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 26 10:27:26 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 26 10:27:26 volumio systemd[1]: mpd.service: Consumed 1.766s CPU time. Dec 26 10:27:26 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 26 10:27:26 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 26 10:27:26 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 26 10:27:26 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:26 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:26 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:27:26 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:26 volumio volumio[1145]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 26 10:27:27 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 10:27:27 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 10:27:27 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand add "https://cast.megapolis.fm/listen/dnipro/radio.mp3" Dec 26 10:27:27 volumio volumio[1145]: error: MPD error: Error: write EPIPE Dec 26 10:27:27 volumio volumio[1145]: error: write EPIPE {"code":"EPIPE","errno":-32,"syscall":"write"} Dec 26 10:27:27 volumio volumio[1145]: info: VolumeController:: Volume=undefined Mute =false Dec 26 10:27:27 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:27:27 volumio systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Dec 26 10:27:27 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:27 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:27:27 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:27:27 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock Dec 26 10:27:27 volumio sudo[5924]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 10:27:27 volumio sudo[5924]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 10:27:27 volumio sudo[5924]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:27 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock Dec 26 10:27:27 volumio volumio[1145]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 10:27:27 volumio systemd[1]: squeezelite.service: Deactivated successfully. Dec 26 10:27:27 volumio systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Dec 26 10:27:27 volumio sudo[5896]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:27 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 26 10:27:27 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:27:27 volumio volumio[1145]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 26 10:27:27 volumio volumio[1145]: warn: [squeezelite_mc] No supported ALSA formats found for card 5 Dec 26 10:27:27 volumio volumio[1145]: warn: [squeezelite_mc] No ALSA formats returned for card 5 Dec 26 10:27:27 volumio volumio[1145]: info: VolumeController:: Volume=undefined Mute =false Dec 26 10:27:27 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:27:27 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:27 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:27:27 volumio sudo[5934]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 26 10:27:27 volumio sudo[5934]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:27 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:27:28 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 26 10:27:28 volumio sudo[5934]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:28 volumio sudo[5943]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 10:27:28 volumio sudo[5943]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:28 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 26 10:27:28 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 26 10:27:28 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 26 10:27:28 volumio systemd[1]: mpd.service: Consumed 1.074s CPU time. Dec 26 10:27:28 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 26 10:27:28 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 26 10:27:28 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 26 10:27:28 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:27:28 volumio sudo[5946]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 26 10:27:28 volumio sudo[5946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:28 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 10:27:28 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 10:27:28 volumio volumio[1145]: info: peppymeterbasic Daemon Stop Dec 26 10:27:28 volumio volumio[1145]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 26 10:27:28 volumio sudo[5946]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:28 volumio sudo[5949]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 10:27:28 volumio sudo[5949]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 10:27:28 volumio sudo[5949]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:28 volumio volumio[1145]: info: peppymeterbasic Daemon Stop Dec 26 10:27:28 volumio sudo[5951]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 26 10:27:28 volumio sudo[5955]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 10:27:28 volumio sudo[5955]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:28 volumio sudo[5951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:28 volumio sudo[5957]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 10:27:28 volumio sudo[5957]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:29 volumio sudo[5951]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:29 volumio volumio[1145]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Dec 26 10:27:29 volumio sudo[5982]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Dec 26 10:27:29 volumio sudo[5982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:29 volumio sudo[5982]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:29 volumio volumio[1145]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Dec 26 10:27:29 volumio sudo[6005]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Dec 26 10:27:29 volumio sudo[6005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:29 volumio systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Dec 26 10:27:29 volumio systemd[1]: apt-daily-upgrade.service: Deactivated successfully. Dec 26 10:27:29 volumio systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. Dec 26 10:27:29 volumio systemd[1]: apt-daily-upgrade.service: Consumed 1.464s CPU time. Dec 26 10:27:29 volumio systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Dec 26 10:27:29 volumio sudo[6005]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:30 volumio volumio[1145]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 26 10:27:30 volumio sudo[6066]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 26 10:27:30 volumio sudo[6066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:30 volumio sudo[6066]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:30 volumio mpd[5953]: 2025-12-26T10:27:30 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 26 10:27:30 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 26 10:27:30 volumio sudo[5766]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:30 volumio sudo[5943]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:30 volumio volumio[1145]: error: MPD error: The expression evaluated to a falsy value: Dec 26 10:27:30 volumio volumio[1145]: assert.ok(self.idling) Dec 26 10:27:30 volumio volumio[1145]: error: The expression evaluated to a falsy value: Dec 26 10:27:30 volumio volumio[1145]: assert.ok(self.idling) Dec 26 10:27:30 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 26 10:27:30 volumio mpd_monitor.sh[6071]: MPD Monitor Service: Starting MPD Monitor Service Dec 26 10:27:30 volumio sudo[5957]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:30 volumio sudo[5843]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:30 volumio sudo[5955]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:30 volumio sudo[5841]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:30 volumio volumio[1145]: error: updateQueue error: null Dec 26 10:27:30 volumio volumio[1145]: info: Successfully started MPD Monitor Dec 26 10:27:30 volumio volumio[1145]: info: Successfully started MPD Monitor Dec 26 10:27:30 volumio volumio[1145]: info: Successfully started MPD Monitor Dec 26 10:27:30 volumio volumio[1145]: info: Successfully started MPD Monitor Dec 26 10:27:30 volumio volumio[1145]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 26 10:27:30 volumio sudo[6115]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 26 10:27:30 volumio sudo[6115]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:31 volumio sudo[6115]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:31 volumio volumio[1145]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 26 10:27:31 volumio sudo[6158]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 26 10:27:31 volumio sudo[6158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:31 volumio sudo[6158]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:32 volumio volumio[1145]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 26 10:27:32 volumio sudo[6212]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 26 10:27:32 volumio sudo[6212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:32 volumio sudo[6212]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:32 volumio volumio[1145]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 26 10:27:33 volumio sudo[6253]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 26 10:27:33 volumio sudo[6253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:33 volumio sudo[6253]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:33 volumio volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 10:27:33 volumio volumio[1145]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 26 10:27:33 volumio sudo[6271]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 26 10:27:33 volumio sudo[6271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:33 volumio sudo[6279]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 10:27:33 volumio sudo[6279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:33 volumio sudo[6281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 10:27:33 volumio sudo[6281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:33 volumio sudo[6271]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:33 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 26 10:27:33 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 26 10:27:33 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 26 10:27:33 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 26 10:27:33 volumio mpd_monitor.sh[6293]: MPD Monitor Service: Starting MPD Monitor Service Dec 26 10:27:33 volumio sudo[6279]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:33 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 26 10:27:33 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 26 10:27:33 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 26 10:27:33 volumio volumio[1145]: info: Successfully started MPD Monitor Dec 26 10:27:33 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 26 10:27:33 volumio sudo[6281]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:33 volumio mpd_monitor.sh[6297]: MPD Monitor Service: Starting MPD Monitor Service Dec 26 10:27:33 volumio volumio[1145]: info: Successfully started MPD Monitor Dec 26 10:27:39 volumio go-librespot[1680]: time="2025-12-26T10:27:39+02:00" level=trace msg="received accesspoint ping" Dec 26 10:27:39 volumio go-librespot[1680]: time="2025-12-26T10:27:39+02:00" level=trace msg="received accesspoint pong ack" Dec 26 10:27:39 volumio go-librespot[1680]: time="2025-12-26T10:27:39+02:00" level=trace msg="sent dealer ping" Dec 26 10:27:39 volumio go-librespot[1680]: time="2025-12-26T10:27:39+02:00" level=trace msg="received dealer pong" Dec 26 10:27:39 volumio volumio[1145]: info: CoreCommandRouter::volumioPlay Dec 26 10:27:39 volumio volumio[1145]: info: CoreStateMachine::play index undefined Dec 26 10:27:39 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 10:27:39 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:39 volumio volumio[1145]: info: CoreStateMachine::startPlaybackTimer Dec 26 10:27:39 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:39 volumio volumio[1145]: info: [1766737659961] ControllerWebradio::clearAddPlayTrack Dec 26 10:27:39 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 10:27:39 volumio volumio[1145]: info: sendMpdCommand stop took 19 milliseconds Dec 26 10:27:39 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand clear Dec 26 10:27:39 volumio volumio[1145]: info: Dec 26 10:27:39 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:27:39 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:27:39 volumio volumio[1145]: info: sendMpdCommand clear took 9 milliseconds Dec 26 10:27:39 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand load "https://cast.megapolis.fm/listen/dnipro/radio.mp3" Dec 26 10:27:39 volumio volumio[1145]: info: Dec 26 10:27:39 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:27:39 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:27:39 volumio volumio[1145]: error: updateQueue error: null Dec 26 10:27:39 volumio volumio[1145]: info: ------------------------------ 14ms Dec 26 10:27:40 volumio volumio[1145]: error: updateQueue error: null Dec 26 10:27:40 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand add "https://cast.megapolis.fm/listen/dnipro/radio.mp3" Dec 26 10:27:40 volumio volumio[1145]: info: ------------------------------ 513ms Dec 26 10:27:40 volumio volumio[1145]: info: Dec 26 10:27:40 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:27:40 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:27:40 volumio volumio[1145]: info: sendMpdCommand add "https://cast.megapolis.fm/listen/dnipro/radio.mp3" took 1 milliseconds Dec 26 10:27:40 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 26 10:27:40 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand play Dec 26 10:27:40 volumio volumio[1145]: info: Dec 26 10:27:40 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:27:40 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:27:40 volumio volumio[1145]: info: ------------------------------ 17ms Dec 26 10:27:40 volumio volumio[1145]: info: sendMpdCommand play took 19 milliseconds Dec 26 10:27:40 volumio volumio[1145]: info: ------------------------------ 3ms Dec 26 10:27:40 volumio volumio[1145]: info: Dec 26 10:27:40 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:27:40 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:27:40 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:27:40 volumio volumio[1145]: info: Dec 26 10:27:40 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:27:40 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:27:40 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:27:40 volumio volumio[1145]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 26 10:27:40 volumio volumio[1145]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 26 10:27:40 volumio volumio[1145]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 26 10:27:40 volumio volumio[1145]: info: Dec 26 10:27:40 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:27:40 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:27:40 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:27:40 volumio volumio[1145]: info: Dec 26 10:27:40 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:27:40 volumio volumio[1145]: info: sendMpdCommand status took 46 milliseconds Dec 26 10:27:40 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:27:40 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:27:40 volumio volumio[1145]: info: sendMpdCommand status took 47 milliseconds Dec 26 10:27:40 volumio volumio[1145]: info: sendMpdCommand status took 2 milliseconds Dec 26 10:27:40 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:27:40 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:27:40 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:27:40 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:27:40 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:27:40 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:27:40 volumio volumio[1145]: info: sendMpdCommand status took 1 milliseconds Dec 26 10:27:40 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 26 10:27:40 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 26 10:27:40 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 26 10:27:40 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:27:40 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:27:40 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:27:40 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:27:40 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:27:40 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:27:40 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:27:40 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:40 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio.mp3","artist":null,"album":null,"uri":"https://cast.megapolis.fm/listen/dnipro/radio.mp3","trackType":"mp3"} Dec 26 10:27:40 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:27:40 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:27:40 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Dec 26 10:27:40 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:27:40 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:27:40 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:40 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio.mp3","artist":null,"album":null,"uri":"https://cast.megapolis.fm/listen/dnipro/radio.mp3","trackType":"mp3"} Dec 26 10:27:40 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:27:40 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:27:40 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:27:40 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:27:40 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:27:40 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:40 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:27:40 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:27:40 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:27:40 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:27:40 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:27:40 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:27:40 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:40 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio.mp3","artist":null,"album":null,"uri":"https://cast.megapolis.fm/listen/dnipro/radio.mp3","trackType":"mp3"} Dec 26 10:27:40 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:27:40 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:27:40 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:27:40 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:27:40 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:27:40 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:40 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:27:40 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:27:40 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:27:40 volumio volumio[1145]: info: ------------------------------ 162ms Dec 26 10:27:41 volumio volumio[1145]: info: ------------------------------ 316ms Dec 26 10:27:41 volumio volumio[1145]: info: ------------------------------ 271ms Dec 26 10:27:41 volumio volumio[1145]: info: Dec 26 10:27:41 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:27:41 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:27:41 volumio volumio[1145]: info: Dec 26 10:27:41 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:27:41 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 287 milliseconds Dec 26 10:27:41 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:27:41 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:27:41 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:27:41 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:27:41 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:27:41 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:41 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio.mp3","artist":null,"album":null,"uri":"https://cast.megapolis.fm/listen/dnipro/radio.mp3","trackType":"mp3"} Dec 26 10:27:41 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:27:41 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:27:41 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:27:41 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:27:41 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:27:41 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:41 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:27:41 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:27:41 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:27:41 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:27:41 volumio volumio[1145]: info: ------------------------------ 346ms Dec 26 10:27:41 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:27:41 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:27:41 volumio sudo[6739]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:27:41 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:27:41 volumio sudo[6739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:41 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:27:41 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:27:41 volumio sudo[6744]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:27:41 volumio sudo[6744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:41 volumio sudo[6748]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:27:41 volumio sudo[6748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:41 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:27:41 volumio sudo[6741]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:27:41 volumio sudo[6741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:41 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Dec 26 10:27:41 volumio volumio[1145]: info: Dec 26 10:27:41 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:27:41 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:27:41 volumio volumio[1145]: info: Dec 26 10:27:41 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:27:41 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:27:41 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:27:41 volumio sudo[6739]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:41 volumio sudo[6748]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:41 volumio sudo[6741]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:41 volumio sudo[6749]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:27:41 volumio sudo[6749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:41 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:27:41 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:27:41 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:27:41 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:27:41 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:27:41 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:27:41 volumio sudo[6756]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:27:41 volumio sudo[6756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:41 volumio sudo[6744]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:41 volumio sudo[6749]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:41 volumio volumio[1145]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 26 10:27:41 volumio sudo[6756]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:41 volumio volumio[1145]: info: ------------------------------ 662ms Dec 26 10:27:41 volumio volumio[1145]: info: sendMpdCommand status took 663 milliseconds Dec 26 10:27:41 volumio volumio[1145]: info: ------------------------------ 319ms Dec 26 10:27:41 volumio volumio[1145]: info: sendMpdCommand status took 324 milliseconds Dec 26 10:27:41 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:27:41 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:27:41 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:27:41 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:27:41 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:27:41 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:27:41 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:27:41 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:27:41 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:27:41 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:27:41 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 45 milliseconds Dec 26 10:27:41 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 42 milliseconds Dec 26 10:27:41 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:27:41 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:27:41 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:27:41 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:27:41 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:41 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":463,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lomiy&Дядя Вадя & Ugo - Очі Порше (Club Version)","artist":"Megapolis Dnipro FM 90.9","album":null,"uri":"https://cast.megapolis.fm/listen/dnipro/radio.mp3","trackType":"mp3"} Dec 26 10:27:41 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:27:41 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:27:41 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:27:41 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:27:41 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 26 10:27:41 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:27:41 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:27:41 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:27:41 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:27:41 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:27:41 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:27:41 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:27:41 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:27:41 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":463,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lomiy&Дядя Вадя & Ugo - Очі Порше (Club Version)","artist":"Megapolis Dnipro FM 90.9","album":null,"uri":"https://cast.megapolis.fm/listen/dnipro/radio.mp3","trackType":"mp3"} Dec 26 10:27:41 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:27:41 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:27:41 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:27:41 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:27:41 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 26 10:27:41 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:27:41 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:27:41 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:27:41 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:27:42 volumio volumio[6755]: error: XDG_RUNTIME_DIR is invalid or not set in the environment. Dec 26 10:27:42 volumio volumio[1145]: info: ------------------------------ 1257ms Dec 26 10:27:42 volumio volumio[1145]: info: ------------------------------ 911ms Dec 26 10:27:42 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:27:42 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:27:42 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:27:42 volumio sudo[6794]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:27:42 volumio sudo[6794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:42 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:27:42 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:27:42 volumio sudo[6798]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:27:42 volumio sudo[6796]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:27:42 volumio sudo[6796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:42 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:27:42 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:27:42 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:27:42 volumio sudo[6794]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:42 volumio sudo[6801]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:27:42 volumio volumio[6755]: libpng warning: iCCP: known incorrect sRGB profile Dec 26 10:27:42 volumio volumio[6755]: libpng warning: iCCP: known incorrect sRGB profile Dec 26 10:27:42 volumio sudo[6798]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:42 volumio sudo[6801]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:27:42 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:27:42 volumio sudo[6796]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:42 volumio volumio[6755]: libpng warning: iCCP: known incorrect sRGB profile Dec 26 10:27:42 volumio sudo[6798]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:42 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:27:42 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:27:42 volumio sudo[6801]: pam_unix(sudo:session): session closed for user root Dec 26 10:27:42 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:27:58 volumio volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 10:27:58 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 26 10:27:58 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:27:58 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 10:27:59 volumio volumio[1145]: Invalid card number '5'. Dec 26 10:27:59 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:59 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:59 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:59 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:59 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:59 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:59 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:27:59 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 26 10:27:59 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 26 10:28:02 volumio volumio[6755]: libpng warning: iCCP: known incorrect sRGB profile Dec 26 10:28:02 volumio volumio[6755]: libpng warning: iCCP: known incorrect sRGB profile Dec 26 10:28:03 volumio volumio[6755]: libpng warning: iCCP: known incorrect sRGB profile Dec 26 10:28:09 volumio volumio[1145]: info: CoreCommandRouter::volumioStop Dec 26 10:28:09 volumio volumio[1145]: info: CoreStateMachine::stop Dec 26 10:28:09 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 10:28:09 volumio volumio[1145]: info: CoreStateMachine::stPlaybackTimer Dec 26 10:28:09 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock Dec 26 10:28:09 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock Dec 26 10:28:09 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:09 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:09 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:28:09 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:09 volumio volumio[1145]: info: CoreStateMachine::serviceStop Dec 26 10:28:09 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:09 volumio volumio[1145]: info: CoreCommandRouter::serviceStop Dec 26 10:28:09 volumio volumio[1145]: info: [1766737689216] ControllerWebradio::stop Dec 26 10:28:09 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 10:28:09 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 26 10:28:09 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:09 volumio volumio[1145]: info: Dec 26 10:28:09 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:09 volumio volumio[1145]: info: sendMpdCommand stop took 176 milliseconds Dec 26 10:28:09 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:09 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:09 volumio volumio[1145]: info: Dec 26 10:28:09 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:09 volumio sudo[8289]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 26 10:28:09 volumio sudo[8289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:09 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:09 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:09 volumio volumio[1145]: info: sendMpdCommand status took 69 milliseconds Dec 26 10:28:09 volumio volumio[1145]: info: sendMpdCommand status took 55 milliseconds Dec 26 10:28:09 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:09 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:09 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:09 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:09 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 26 10:28:09 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 32 milliseconds Dec 26 10:28:09 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:09 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:09 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:09 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:09 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:09 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:09 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:28:09 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:09 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Dec 26 10:28:09 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:09 volumio volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 26 10:28:09 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:09 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:09 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:09 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:09 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:09 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:09 volumio volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 26 10:28:09 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Dec 26 10:28:09 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Dec 26 10:28:09 volumio systemd[1]: peppymeterbasic.service: Consumed 10.285s CPU time. Dec 26 10:28:09 volumio sudo[8289]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:09 volumio volumio[1145]: info: ------------------------------ 288ms Dec 26 10:28:09 volumio volumio[1145]: info: ------------------------------ 346ms Dec 26 10:28:09 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 26 10:28:09 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 26 10:28:09 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:09 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:09 volumio sudo[8300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 26 10:28:09 volumio sudo[8300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:09 volumio go-librespot[1680]: time="2025-12-26T10:28:09+02:00" level=trace msg="sent dealer ping" Dec 26 10:28:09 volumio volumio[1145]: info: peppymeterbasic Daemon Stop Dec 26 10:28:09 volumio sudo[8302]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 26 10:28:09 volumio go-librespot[1680]: time="2025-12-26T10:28:09+02:00" level=trace msg="received dealer pong" Dec 26 10:28:09 volumio sudo[8302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:10 volumio sudo[8300]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:10 volumio volumio[1145]: info: peppymeterbasic Daemon Stop Dec 26 10:28:10 volumio sudo[8302]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:10 volumio volumio[1145]: info: peppymeterbasic Daemon Stop Dec 26 10:28:14 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 26 10:28:14 volumio volumio[1145]: info: Preload queue cleared Dec 26 10:28:15 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 26 10:28:15 volumio volumio[1145]: info: Preload queue cleared Dec 26 10:28:16 volumio volumio[1145]: info: Preload queue cleared Dec 26 10:28:16 volumio volumio[1145]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 26 10:28:16 volumio volumio[1145]: info: CoreStateMachine::ClearQueue Dec 26 10:28:16 volumio volumio[1145]: info: CoreStateMachine::stop Dec 26 10:28:16 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 10:28:16 volumio volumio[1145]: info: CorePlayQueue::clearPlayQueue Dec 26 10:28:16 volumio volumio[1145]: info: CorePlayQueue::saveQueue Dec 26 10:28:16 volumio volumio[1145]: info: CoreCommandRouter::volumioPushQueue Dec 26 10:28:16 volumio volumio[1145]: info: CoreStateMachine::addQueueItems Dec 26 10:28:16 volumio volumio[1145]: info: CorePlayQueue::addQueueItems Dec 26 10:28:16 volumio volumio[1145]: info: Preload queue cleared Dec 26 10:28:16 volumio volumio[1145]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1796827 Dec 26 10:28:16 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 26 10:28:16 volumio volumio[1145]: info: CoreCommandRouter::volumioPushQueue Dec 26 10:28:16 volumio volumio[1145]: info: CorePlayQueue::saveQueue Dec 26 10:28:16 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock Dec 26 10:28:16 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock Dec 26 10:28:16 volumio volumio[1145]: info: CoreCommandRouter::volumioPlay Dec 26 10:28:16 volumio volumio[1145]: info: CoreStateMachine::play index 0 Dec 26 10:28:16 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 10:28:16 volumio volumio[1145]: info: CoreStateMachine::stop Dec 26 10:28:16 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 10:28:16 volumio volumio[1145]: info: CoreStateMachine::play index undefined Dec 26 10:28:16 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 10:28:16 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:16 volumio volumio[1145]: info: CoreStateMachine::startPlaybackTimer Dec 26 10:28:16 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:16 volumio volumio[1145]: info: [1766737696965] ControllerWebradio::clearAddPlayTrack Dec 26 10:28:16 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 10:28:17 volumio volumio[1145]: info: sendMpdCommand stop took 52 milliseconds Dec 26 10:28:17 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand clear Dec 26 10:28:17 volumio volumio[1145]: info: Dec 26 10:28:17 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:17 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:17 volumio volumio[1145]: info: sendMpdCommand clear took 1 milliseconds Dec 26 10:28:17 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1796827" Dec 26 10:28:17 volumio volumio[1145]: info: Dec 26 10:28:17 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:17 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:17 volumio volumio[1145]: error: updateQueue error: null Dec 26 10:28:17 volumio volumio[1145]: info: ------------------------------ 33ms Dec 26 10:28:17 volumio volumio[1145]: info: Dec 26 10:28:17 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:17 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:17 volumio volumio[1145]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1796827" took 363 milliseconds Dec 26 10:28:17 volumio volumio[1145]: info: ------------------------------ 362ms Dec 26 10:28:17 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 26 10:28:17 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand play Dec 26 10:28:17 volumio volumio[1145]: info: Dec 26 10:28:17 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:17 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:17 volumio volumio[1145]: info: ------------------------------ 12ms Dec 26 10:28:17 volumio volumio[1145]: info: sendMpdCommand play took 11 milliseconds Dec 26 10:28:17 volumio volumio[1145]: info: ------------------------------ 1ms Dec 26 10:28:18 volumio volumio[1145]: info: Dec 26 10:28:18 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:18 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:18 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:18 volumio volumio[1145]: info: Dec 26 10:28:18 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:18 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:18 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:18 volumio volumio[1145]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 26 10:28:18 volumio volumio[1145]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 26 10:28:18 volumio volumio[1145]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 26 10:28:18 volumio volumio[1145]: info: Dec 26 10:28:18 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:18 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:18 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:18 volumio volumio[1145]: info: Dec 26 10:28:18 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:18 volumio volumio[1145]: info: sendMpdCommand status took 42 milliseconds Dec 26 10:28:18 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:18 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:18 volumio volumio[1145]: info: sendMpdCommand status took 38 milliseconds Dec 26 10:28:18 volumio volumio[1145]: info: sendMpdCommand status took 1 milliseconds Dec 26 10:28:18 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:18 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:18 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:18 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:18 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:18 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:18 volumio volumio[1145]: info: Dec 26 10:28:18 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:18 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:18 volumio volumio[1145]: info: Dec 26 10:28:18 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:18 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:18 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:18 volumio volumio[1145]: info: Dec 26 10:28:18 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:18 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:18 volumio volumio[1145]: info: Dec 26 10:28:18 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:18 volumio volumio[1145]: info: Dec 26 10:28:18 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:18 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:18 volumio volumio[1145]: info: Dec 26 10:28:18 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:18 volumio volumio[1145]: info: sendMpdCommand status took 44 milliseconds Dec 26 10:28:18 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:18 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:18 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 50 milliseconds Dec 26 10:28:18 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:18 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:18 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 40 milliseconds Dec 26 10:28:18 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 35 milliseconds Dec 26 10:28:18 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:18 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:18 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:18 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:18 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:18 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:18 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:18 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:18 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Conan Gray - Holidays","artist":"1.FM - Always-Christmas Radio","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:18 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:18 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:18 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Dec 26 10:28:18 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:18 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:18 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:18 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Conan Gray - Holidays","artist":"1.FM - Always-Christmas Radio","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:18 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:18 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:18 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:28:18 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:28:18 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:18 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:28:18 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:19 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:19 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:19 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:19 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:19 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:19 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Conan Gray - Holidays","artist":"1.FM - Always-Christmas Radio","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:19 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:19 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:19 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:28:19 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:28:19 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:19 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:19 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:19 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:19 volumio volumio[1145]: info: ------------------------------ 172ms Dec 26 10:28:19 volumio volumio[1145]: info: ------------------------------ 358ms Dec 26 10:28:19 volumio volumio[1145]: info: ------------------------------ 323ms Dec 26 10:28:19 volumio volumio[1145]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 26 10:28:19 volumio volumio[1145]: info: Dec 26 10:28:19 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:19 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:19 volumio volumio[1145]: info: Dec 26 10:28:19 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:19 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:19 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:19 volumio volumio[1145]: info: ------------------------------ 362ms Dec 26 10:28:19 volumio volumio[1145]: info: sendMpdCommand status took 356 milliseconds Dec 26 10:28:19 volumio volumio[1145]: info: ------------------------------ 348ms Dec 26 10:28:19 volumio volumio[1145]: info: sendMpdCommand status took 347 milliseconds Dec 26 10:28:19 volumio volumio[1145]: info: ------------------------------ 347ms Dec 26 10:28:19 volumio volumio[1145]: info: sendMpdCommand status took 339 milliseconds Dec 26 10:28:19 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 339 milliseconds Dec 26 10:28:19 volumio volumio[1145]: info: ------------------------------ 21ms Dec 26 10:28:19 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:19 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:19 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:19 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:19 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:19 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:19 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:19 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:19 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:19 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:19 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Conan Gray - Holidays","artist":"1.FM - Always-Christmas Radio","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:19 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:19 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:19 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:28:19 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:28:19 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:19 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:28:19 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:19 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:19 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:19 volumio volumio[1145]: info: ------------------------------ 569ms Dec 26 10:28:19 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:19 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:19 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:19 volumio sudo[8871]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:19 volumio sudo[8871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:19 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:19 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:19 volumio sudo[8876]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:19 volumio sudo[8873]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:19 volumio sudo[8873]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:19 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:19 volumio sudo[8876]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:19 volumio sudo[8878]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:19 volumio sudo[8878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:19 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Dec 26 10:28:19 volumio sudo[8871]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:19 volumio sudo[8883]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:19 volumio sudo[8883]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:19 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:19 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:19 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:19 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:19 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:19 volumio sudo[8876]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:19 volumio sudo[8878]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:19 volumio sudo[8886]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:19 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:19 volumio sudo[8873]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:19 volumio sudo[8886]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:20 volumio sudo[8883]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:20 volumio volumio[1145]: info: sendMpdCommand status took 780 milliseconds Dec 26 10:28:20 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 766 milliseconds Dec 26 10:28:20 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 766 milliseconds Dec 26 10:28:20 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 766 milliseconds Dec 26 10:28:20 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:20 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:20 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:20 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:20 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:20 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:20 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:20 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:20 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":478,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Conan Gray - Holidays","artist":"1.FM - Always-Christmas Radio","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:20 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:28:20 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:28:20 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:20 volumio sudo[8886]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:20 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:20 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:20 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:20 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:20 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":478,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Conan Gray - Holidays","artist":"1.FM - Always-Christmas Radio","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:20 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:28:20 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:20 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:20 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:20 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:20 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:20 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:20 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":478,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Conan Gray - Holidays","artist":"1.FM - Always-Christmas Radio","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:20 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:28:20 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:20 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:20 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:20 volumio volumio[1145]: info: ------------------------------ 1704ms Dec 26 10:28:20 volumio volumio[1145]: info: ------------------------------ 1689ms Dec 26 10:28:20 volumio volumio[1145]: info: ------------------------------ 1689ms Dec 26 10:28:20 volumio volumio[1145]: info: Preload queue cleared Dec 26 10:28:20 volumio volumio[1145]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::ClearQueue Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::stop Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 10:28:20 volumio volumio[8888]: error: XDG_RUNTIME_DIR is invalid or not set in the environment. Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::stPlaybackTimer Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock Dec 26 10:28:20 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:20 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:28:20 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::serviceStop Dec 26 10:28:20 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:20 volumio volumio[1145]: info: CoreCommandRouter::serviceStop Dec 26 10:28:20 volumio volumio[1145]: info: [1766737700753] ControllerWebradio::stop Dec 26 10:28:20 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 10:28:20 volumio volumio[1145]: info: CorePlayQueue::clearPlayQueue Dec 26 10:28:20 volumio volumio[1145]: info: CorePlayQueue::saveQueue Dec 26 10:28:20 volumio volumio[1145]: info: CoreCommandRouter::volumioPushQueue Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::addQueueItems Dec 26 10:28:20 volumio volumio[1145]: info: CorePlayQueue::addQueueItems Dec 26 10:28:20 volumio volumio[1145]: info: Preload queue cleared Dec 26 10:28:20 volumio volumio[1145]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1796827 Dec 26 10:28:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 26 10:28:20 volumio volumio[1145]: info: CoreCommandRouter::volumioPushQueue Dec 26 10:28:20 volumio volumio[1145]: info: CorePlayQueue::saveQueue Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock Dec 26 10:28:20 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock Dec 26 10:28:20 volumio volumio[1145]: info: CoreCommandRouter::volumioPlay Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::play index 0 Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::stop Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::play index undefined Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 10:28:20 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:20 volumio volumio[1145]: info: CoreStateMachine::startPlaybackTimer Dec 26 10:28:20 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:20 volumio volumio[1145]: info: [1766737700878] ControllerWebradio::clearAddPlayTrack Dec 26 10:28:20 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 10:28:20 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:20 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:20 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:20 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:20 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:20 volumio volumio[1145]: info: Dec 26 10:28:20 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:20 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 910 milliseconds Dec 26 10:28:20 volumio volumio[1145]: info: sendMpdCommand stop took 220 milliseconds Dec 26 10:28:20 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:20 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:20 volumio volumio[1145]: info: sendMpdCommand stop took 95 milliseconds Dec 26 10:28:20 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:20 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand clear Dec 26 10:28:20 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:20 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:20 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:20 volumio volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 26 10:28:20 volumio volumio[1145]: info: ------------------------------ 1699ms Dec 26 10:28:21 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:21 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:21 volumio volumio[8888]: libpng warning: iCCP: known incorrect sRGB profile Dec 26 10:28:21 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:21 volumio volumio[8888]: libpng warning: iCCP: known incorrect sRGB profile Dec 26 10:28:21 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:21 volumio sudo[8907]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:21 volumio sudo[8907]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:21 volumio sudo[8904]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:21 volumio sudo[8904]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:21 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:21 volumio sudo[8911]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:21 volumio sudo[8911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:21 volumio sudo[8915]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:21 volumio sudo[8915]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:21 volumio sudo[8909]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:21 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:21 volumio sudo[8919]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:21 volumio sudo[8919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:21 volumio sudo[8911]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:21 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 26 10:28:21 volumio sudo[8909]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:21 volumio sudo[8915]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:21 volumio sudo[8919]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:21 volumio sudo[8907]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:21 volumio sudo[8904]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:21 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:21 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:21 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:21 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:21 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:21 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:21 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:21 volumio volumio[1145]: info: Dec 26 10:28:21 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:21 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:21 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:21 volumio sudo[8923]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 26 10:28:21 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:21 volumio sudo[8909]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:21 volumio volumio[1145]: info: Dec 26 10:28:21 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:21 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:21 volumio volumio[1145]: info: sendMpdCommand status took 612 milliseconds Dec 26 10:28:21 volumio volumio[1145]: info: sendMpdCommand clear took 612 milliseconds Dec 26 10:28:21 volumio volumio[1145]: info: sendMpdCommand status took 67 milliseconds Dec 26 10:28:21 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:21 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:21 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1796827" Dec 26 10:28:21 volumio sudo[8923]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:21 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:21 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:21 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:21 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:21 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:21 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:28:21 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:21 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:21 volumio volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 26 10:28:21 volumio volumio[1145]: info: ------------------------------ 131ms Dec 26 10:28:21 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:21 volumio volumio[1145]: info: Dec 26 10:28:21 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:21 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:21 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Dec 26 10:28:21 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:21 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:21 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:21 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:21 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:21 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:21 volumio volumio[1145]: error: updateQueue error: null Dec 26 10:28:21 volumio volumio[1145]: info: ------------------------------ 130ms Dec 26 10:28:21 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 129 milliseconds Dec 26 10:28:21 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:21 volumio volumio[1145]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 26 10:28:21 volumio volumio[1145]: info: ------------------------------ 743ms Dec 26 10:28:21 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 26 10:28:21 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Dec 26 10:28:21 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Dec 26 10:28:21 volumio systemd[1]: peppymeterbasic.service: Consumed 1.532s CPU time. Dec 26 10:28:21 volumio sudo[8923]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:21 volumio volumio[1145]: info: Dec 26 10:28:21 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:21 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:21 volumio volumio[1145]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1796827" took 241 milliseconds Dec 26 10:28:21 volumio volumio[1145]: info: ------------------------------ 163ms Dec 26 10:28:21 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 26 10:28:21 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand play Dec 26 10:28:21 volumio volumio[1145]: info: Dec 26 10:28:21 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:21 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:21 volumio volumio[1145]: info: ------------------------------ 50ms Dec 26 10:28:21 volumio volumio[1145]: info: sendMpdCommand play took 49 milliseconds Dec 26 10:28:21 volumio volumio[1145]: info: ------------------------------ 48ms Dec 26 10:28:21 volumio volumio[1145]: info: peppymeterbasic Daemon Stop Dec 26 10:28:21 volumio sudo[8934]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 26 10:28:21 volumio sudo[8934]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:22 volumio sudo[8934]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:22 volumio volumio[1145]: info: peppymeterbasic Daemon Stop Dec 26 10:28:23 volumio volumio[1145]: info: Dec 26 10:28:23 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:23 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:23 volumio volumio[1145]: info: Dec 26 10:28:23 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:23 volumio volumio[1145]: info: Dec 26 10:28:23 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:23 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:23 volumio volumio[1145]: info: sendMpdCommand status took 9 milliseconds Dec 26 10:28:23 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:23 volumio volumio[1145]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 26 10:28:23 volumio volumio[1145]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 26 10:28:23 volumio volumio[1145]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 26 10:28:23 volumio volumio[1145]: info: Dec 26 10:28:23 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:23 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:23 volumio volumio[1145]: info: sendMpdCommand status took 54 milliseconds Dec 26 10:28:23 volumio volumio[1145]: info: sendMpdCommand status took 53 milliseconds Dec 26 10:28:23 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 39 milliseconds Dec 26 10:28:23 volumio volumio[1145]: info: sendMpdCommand status took 8 milliseconds Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:23 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:23 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:23 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:23 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"christmas_64a","artist":"1.FM - Always Christmas (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:23 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:23 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:23 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Dec 26 10:28:23 volumio volumio[1145]: info: ------------------------------ 83ms Dec 26 10:28:23 volumio volumio[1145]: info: Dec 26 10:28:23 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:23 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:23 volumio volumio[1145]: info: Dec 26 10:28:23 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:23 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:23 volumio volumio[1145]: info: Dec 26 10:28:23 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:23 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:23 volumio volumio[1145]: info: Dec 26 10:28:23 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:23 volumio volumio[1145]: info: Dec 26 10:28:23 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:23 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:23 volumio volumio[1145]: info: Dec 26 10:28:23 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:23 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:23 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 22 milliseconds Dec 26 10:28:23 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:23 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 21 milliseconds Dec 26 10:28:23 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 22 milliseconds Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:23 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:23 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:23 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:23 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Conan Gray - Holidays","artist":"1.FM - Always-Christmas Radio","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:23 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:23 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:23 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:28:23 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:28:23 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:28:23 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:23 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:23 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:23 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:23 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:23 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:23 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Conan Gray - Holidays","artist":"1.FM - Always-Christmas Radio","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:23 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:23 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:23 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:28:23 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:28:23 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:23 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:23 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:23 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:23 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:23 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:23 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:23 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Conan Gray - Holidays","artist":"1.FM - Always-Christmas Radio","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:23 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:23 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:23 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:28:23 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:28:23 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:23 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:23 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:23 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:23 volumio volumio[1145]: info: ------------------------------ 414ms Dec 26 10:28:23 volumio volumio[1145]: info: ------------------------------ 414ms Dec 26 10:28:23 volumio volumio[1145]: info: ------------------------------ 372ms Dec 26 10:28:23 volumio volumio[1145]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 26 10:28:23 volumio volumio[1145]: info: Dec 26 10:28:23 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:23 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:23 volumio volumio[1145]: info: Dec 26 10:28:23 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:23 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:23 volumio volumio[1145]: info: ------------------------------ 382ms Dec 26 10:28:23 volumio volumio[1145]: info: sendMpdCommand status took 374 milliseconds Dec 26 10:28:23 volumio volumio[1145]: info: ------------------------------ 374ms Dec 26 10:28:23 volumio volumio[1145]: info: sendMpdCommand status took 374 milliseconds Dec 26 10:28:23 volumio volumio[1145]: info: ------------------------------ 374ms Dec 26 10:28:23 volumio volumio[1145]: info: sendMpdCommand status took 374 milliseconds Dec 26 10:28:23 volumio volumio[1145]: info: ------------------------------ 52ms Dec 26 10:28:23 volumio volumio[1145]: info: sendMpdCommand status took 52 milliseconds Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:23 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:23 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:23 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:23 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:23 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:23 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:23 volumio sudo[9042]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:23 volumio sudo[9044]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:23 volumio sudo[9044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:23 volumio sudo[9040]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:23 volumio sudo[9040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:23 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:23 volumio sudo[9046]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:23 volumio sudo[9042]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:23 volumio sudo[9050]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:23 volumio sudo[9050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:23 volumio sudo[9046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:24 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:24 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:24 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:24 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:24 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:24 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:24 volumio sudo[9054]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:24 volumio sudo[9054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:24 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Dec 26 10:28:24 volumio sudo[9044]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:24 volumio sudo[9042]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:24 volumio sudo[9046]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:24 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 489 milliseconds Dec 26 10:28:24 volumio sudo[9040]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:24 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 500 milliseconds Dec 26 10:28:24 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 499 milliseconds Dec 26 10:28:24 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 499 milliseconds Dec 26 10:28:24 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:24 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:24 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:24 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:24 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:24 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:24 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:24 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":483,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Conan Gray - Holidays","artist":"1.FM - Always-Christmas Radio","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:24 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:24 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:24 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:28:24 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:28:24 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:24 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:28:24 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:24 volumio sudo[9050]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:24 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:24 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:24 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:24 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:24 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:24 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":483,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Conan Gray - Holidays","artist":"1.FM - Always-Christmas Radio","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:24 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:24 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:24 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:28:24 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:28:24 volumio sudo[9054]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:24 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:24 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:24 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:24 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:24 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:24 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:24 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:24 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":483,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Conan Gray - Holidays","artist":"1.FM - Always-Christmas Radio","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:24 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:24 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:24 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:28:24 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:28:24 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:24 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:24 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:24 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:24 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:24 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:24 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:24 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":483,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Conan Gray - Holidays","artist":"1.FM - Always-Christmas Radio","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:24 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:24 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:24 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:28:24 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:28:24 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:24 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:24 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:24 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:24 volumio volumio[1145]: info: ------------------------------ 1682ms Dec 26 10:28:25 volumio volumio[1145]: info: ------------------------------ 1698ms Dec 26 10:28:25 volumio volumio[1145]: info: ------------------------------ 1697ms Dec 26 10:28:25 volumio volumio[1145]: info: ------------------------------ 1376ms Dec 26 10:28:25 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:25 volumio volumio[9059]: error: XDG_RUNTIME_DIR is invalid or not set in the environment. Dec 26 10:28:25 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:25 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:25 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:25 volumio sudo[9083]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:25 volumio sudo[9083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:25 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:25 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:25 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:25 volumio sudo[9086]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:25 volumio sudo[9086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:25 volumio sudo[9092]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:25 volumio sudo[9089]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:25 volumio sudo[9083]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:25 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:25 volumio sudo[9092]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:25 volumio sudo[9089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:25 volumio sudo[9090]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:25 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:25 volumio sudo[9090]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:25 volumio volumio[9059]: libpng warning: iCCP: known incorrect sRGB profile Dec 26 10:28:25 volumio sudo[9086]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:25 volumio sudo[9090]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:25 volumio sudo[9097]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:25 volumio sudo[9097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:25 volumio sudo[9095]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:25 volumio sudo[9095]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:25 volumio sudo[9092]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:25 volumio sudo[9089]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:25 volumio volumio[9059]: libpng warning: iCCP: known incorrect sRGB profile Dec 26 10:28:25 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:25 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:25 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:25 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:25 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:25 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:25 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:25 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:25 volumio sudo[9103]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:25 volumio sudo[9103]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:25 volumio sudo[9097]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:25 volumio sudo[9103]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:25 volumio sudo[9095]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:25 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:25 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:25 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:25 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:25 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:25 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:25 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:25 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:25 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:25 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:25 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:25 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:25 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:32 volumio kernel: usb 1-1.2: new high-speed USB device number 3 using xhci_hcd Dec 26 10:28:32 volumio kernel: usb 1-1.2: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.53 Dec 26 10:28:32 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Dec 26 10:28:32 volumio kernel: usb 1-1.2: Product: SMSL USB AUDIO Dec 26 10:28:32 volumio kernel: usb 1-1.2: Manufacturer: SMSL Dec 26 10:28:32 volumio kernel: hid-generic 0003:152A:85DD.0001: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-0000:01:00.0-1.2/input3 Dec 26 10:28:32 volumio mtp-probe[9455]: checking bus 1, device 3: "/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.2" Dec 26 10:28:32 volumio mtp-probe[9455]: bus: 1, device: 3 was not an MTP device Dec 26 10:28:32 volumio fsct-plugin[1145]: Failed to initialize device 152a:85dd: BOS descriptor not available, unsupported usb version 2.00 Dec 26 10:28:32 volumio kernel: usbcore: registered new interface driver snd-usb-audio Dec 26 10:28:32 volumio mtp-probe[9479]: checking bus 1, device 3: "/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.2" Dec 26 10:28:32 volumio mtp-probe[9479]: bus: 1, device: 3 was not an MTP device Dec 26 10:28:32 volumio volumio[1145]: info: Dec 26 10:28:32 volumio volumio[1145]: ---------------------------- USB Audio Device Attached Dec 26 10:28:32 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 26 10:28:33 volumio volumio[1145]: info: CoreCommandRouter::Close All Modals sent Dec 26 10:28:33 volumio volumio[1145]: info: Preparing to save Alsa Options, stopping services first Dec 26 10:28:33 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 26 10:28:33 volumio volumio[1145]: info: CoreCommandRouter::volumioStop Dec 26 10:28:33 volumio volumio[1145]: info: CoreStateMachine::stop Dec 26 10:28:33 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 10:28:33 volumio volumio[1145]: info: CoreStateMachine::stPlaybackTimer Dec 26 10:28:33 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock Dec 26 10:28:33 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock Dec 26 10:28:33 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:33 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:33 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:28:33 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:33 volumio volumio[1145]: info: CoreStateMachine::serviceStop Dec 26 10:28:33 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:33 volumio volumio[1145]: info: CoreCommandRouter::serviceStop Dec 26 10:28:33 volumio volumio[1145]: info: [1766737713137] ControllerWebradio::stop Dec 26 10:28:33 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 10:28:33 volumio volumio[1145]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL USB AUDIO","alsacard":"AUDIO"},"i2s":false} Dec 26 10:28:33 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 10:28:33 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 10:28:33 volumio volumio[1145]: info: Setting default mixerSoftMaster for Softvolume device Dec 26 10:28:33 volumio volumio[1145]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 26 10:28:33 volumio volumio[1145]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 26 10:28:33 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 26 10:28:33 volumio volumio[1145]: info: Disabling external Volume Control Dec 26 10:28:33 volumio volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 10:28:33 volumio volumio[1145]: info: Enable softmixer device for audio device undefined Dec 26 10:28:33 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 26 10:28:33 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Dec 26 10:28:33 volumio volumio[1145]: info: Dec 26 10:28:33 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:33 volumio volumio[1145]: info: sendMpdCommand stop took 745 milliseconds Dec 26 10:28:33 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:33 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:33 volumio volumio[1145]: info: Dec 26 10:28:33 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:33 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:33 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:33 volumio volumio[1145]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Dec 26 10:28:33 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:33 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:33 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:28:33 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:33 volumio sudo[9530]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 26 10:28:33 volumio sudo[9530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:34 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Dec 26 10:28:34 volumio volumio[1145]: info: Software Volume ALSA configuration written Dec 26 10:28:34 volumio volumio[1145]: info: Preparing to generate the ALSA configuration file Dec 26 10:28:34 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Dec 26 10:28:34 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Dec 26 10:28:34 volumio systemd[1]: peppymeterbasic.service: Consumed 4.248s CPU time. Dec 26 10:28:34 volumio sudo[9530]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:34 volumio volumio[1145]: info: sendMpdCommand status took 314 milliseconds Dec 26 10:28:34 volumio volumio[1145]: info: sendMpdCommand status took 301 milliseconds Dec 26 10:28:34 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:34 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:34 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:34 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:34 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 26 10:28:34 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 26 10:28:34 volumio volumio[1145]: SPOTIFY: SPOTIFY VOLUME 5 Dec 26 10:28:34 volumio volumio[1145]: SPOTIFY: VOLUMIO VOLUME 1 Dec 26 10:28:34 volumio volumio[1145]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 26 10:28:34 volumio volumio[1145]: info: Setting Spotify Volume from Volumio: 1 Dec 26 10:28:34 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 26 10:28:34 volumio sudo[9541]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 26 10:28:34 volumio sudo[9541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:34 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 26 10:28:34 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:28:34 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 10:28:34 volumio sudo[9541]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:34 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:28:34 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:28:34 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:28:34 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:28:34 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:28:34 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:28:34 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:28:34 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 26 10:28:34 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 26 10:28:34 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 476 milliseconds Dec 26 10:28:34 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 476 milliseconds Dec 26 10:28:34 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:34 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:34 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:34 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:34 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:34 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:34 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:34 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:34 volumio volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 26 10:28:34 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:34 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:34 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:34 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:34 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:34 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:34 volumio volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 26 10:28:34 volumio volumio[1145]: info: ------------------------------ 957ms Dec 26 10:28:34 volumio volumio[1145]: info: ------------------------------ 940ms Dec 26 10:28:34 volumio volumio[1145]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 26 10:28:34 volumio volumio[1145]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 26 10:28:34 volumio volumio[1145]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 26 10:28:34 volumio volumio[1145]: info: Reading ALSA contributions from plugins. Dec 26 10:28:34 volumio volumio[1145]: info: peppymeterbasic Daemon Stop Dec 26 10:28:34 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 26 10:28:34 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 26 10:28:34 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 26 10:28:34 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 26 10:28:34 volumio volumio[1145]: info: peppymeterbasic Daemon Stop Dec 26 10:28:34 volumio sudo[9550]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 26 10:28:35 volumio sudo[9550]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:35 volumio volumio[1145]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Dec 26 10:28:35 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 26 10:28:35 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:35 volumio volumio[1145]: info: CoreCommandRouter::volumioPause Dec 26 10:28:35 volumio volumio[1145]: info: CoreStateMachine::pause Dec 26 10:28:35 volumio volumio[1145]: info: mpdhttpout --- Volumio set on pause Dec 26 10:28:35 volumio sudo[9552]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 26 10:28:35 volumio sudo[9552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:35 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:35 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:35 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:35 volumio volumio[1145]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 26 10:28:35 volumio volumio[1145]: info: [squeezelite_mc] Card 5 supports the following ALSA formats: ["S32_LE","SPECIAL","DSD_U32_BE"] Dec 26 10:28:35 volumio volumio[1145]: info: [squeezelite_mc] Restarting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"5","mixerType":"Software","mixer":"SoftMaster"} Dec 26 10:28:35 volumio volumio[1145]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Dec 26 10:28:35 volumio sudo[9550]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:35 volumio volumio[1145]: info: peppymeterbasic Daemon Stop Dec 26 10:28:35 volumio sudo[9552]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:35 volumio volumio[1145]: info: peppymeterbasic Daemon Stop Dec 26 10:28:35 volumio volumio[1145]: info: Asound.conf file written Dec 26 10:28:35 volumio sudo[9572]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 26 10:28:35 volumio sudo[9572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:35 volumio sudo[9572]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:35 volumio sudo[9569]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Dec 26 10:28:35 volumio sudo[9569]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:35 volumio sudo[9569]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:35 volumio volumio[1145]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 26 10:28:35 volumio volumio[1145]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 26 10:28:35 volumio volumio[1145]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Dec 26 10:28:35 volumio volumio[1145]: info: Output device has changed, restarting MPD Dec 26 10:28:35 volumio volumio[1145]: info: Output device has changed, restarting Shairport Sync Dec 26 10:28:35 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:35 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:28:35 volumio sudo[9585]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 10:28:35 volumio sudo[9585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:35 volumio sudo[9585]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:35 volumio volumio[1145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 10:28:35 volumio sudo[9587]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 10:28:35 volumio sudo[9587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:35 volumio volumio[1145]: info: Output device has changed, restarting MPD Dec 26 10:28:35 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 26 10:28:35 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 26 10:28:35 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 26 10:28:35 volumio systemd[1]: mpd.service: Consumed 2.146s CPU time. Dec 26 10:28:35 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 26 10:28:35 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 26 10:28:35 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 26 10:28:35 volumio volumio[1145]: info: Output device has changed, restarting Shairport Sync Dec 26 10:28:35 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:35 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:28:35 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 10:28:35 volumio sudo[9595]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 10:28:35 volumio sudo[9595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:35 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 10:28:35 volumio sudo[9595]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:36 volumio sudo[9599]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 10:28:36 volumio volumio[1145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 10:28:36 volumio sudo[9599]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:36 volumio sudo[9603]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 10:28:36 volumio sudo[9603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 10:28:36 volumio sudo[9603]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:36 volumio volumio[1145]: SPOTIFY: SETTING SPOTIFY VOLUME 1 Dec 26 10:28:36 volumio volumio[1145]: info: Sending Spotify command with payload to local API: /player/volume Dec 26 10:28:36 volumio volumio[1145]: info: MPD Permissions set Dec 26 10:28:36 volumio volumio[1145]: info: MPD Permissions set Dec 26 10:28:36 volumio volumio[1145]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Dec 26 10:28:36 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 26 10:28:36 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 26 10:28:36 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 26 10:28:36 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 26 10:28:36 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 26 10:28:36 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 10:28:36 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 10:28:36 volumio volumio[1145]: error: Failed to send command to Spotify local API: /player/volume: Error: Bad Request Dec 26 10:28:36 volumio sudo[9611]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 10:28:36 volumio sudo[9611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 10:28:36 volumio sudo[9611]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:36 volumio sudo[9613]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Dec 26 10:28:36 volumio sudo[9613]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:36 volumio sudo[9613]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:36 volumio volumio[1145]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Dec 26 10:28:36 volumio volumio[1145]: info: Starting Shairport Sync Dec 26 10:28:36 volumio volumio[1145]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 26 10:28:36 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::volumioPause Dec 26 10:28:36 volumio volumio[1145]: info: CoreStateMachine::pause Dec 26 10:28:36 volumio volumio[1145]: info: mpdhttpout --- Volumio set on pause Dec 26 10:28:36 volumio volumio[1145]: info: Starting Shairport Sync Dec 26 10:28:36 volumio sudo[9629]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Dec 26 10:28:36 volumio sudo[9629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:36 volumio volumio[1145]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 26 10:28:36 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:36 volumio volumio[1145]: info: CoreCommandRouter::volumioPause Dec 26 10:28:36 volumio volumio[1145]: info: CoreStateMachine::pause Dec 26 10:28:36 volumio volumio[1145]: info: mpdhttpout --- Volumio set on pause Dec 26 10:28:36 volumio sudo[9633]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 10:28:36 volumio sudo[9633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:36 volumio sudo[9629]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:36 volumio sudo[9631]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 10:28:36 volumio volumio[1145]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Dec 26 10:28:36 volumio sudo[9631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:37 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 26 10:28:37 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 26 10:28:37 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 10:28:37 volumio systemd[1]: shairport-sync.service: Consumed 1.576s CPU time. Dec 26 10:28:37 volumio sudo[9638]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 26 10:28:37 volumio sudo[9638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:37 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 10:28:37 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 26 10:28:37 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 26 10:28:37 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 26 10:28:37 volumio sudo[9631]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:37 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 10:28:37 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 10:28:37 volumio sudo[9633]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:37 volumio systemd[1]: Reloading. Dec 26 10:28:37 volumio sudo[9657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 10:28:37 volumio sudo[9657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:37 volumio volumio[1145]: info: Shairport-Sync Started Dec 26 10:28:37 volumio volumio[1145]: info: Shairport-Sync Started Dec 26 10:28:37 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:37 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:37 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:28:37 volumio volumio[1145]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 26 10:28:37 volumio volumio[1145]: info: CoreStateMachine::getcurrentVolume Dec 26 10:28:37 volumio volumio[1145]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 10:28:38 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:38 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:38 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:38 volumio mpd[9615]: 2025-12-26T10:28:38 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 26 10:28:38 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock Dec 26 10:28:38 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock Dec 26 10:28:38 volumio volumio[1145]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 10:28:38 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 10:28:38 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 10:28:38 volumio systemd[1]: /lib/systemd/system/roonbridge.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 10:28:38 volumio systemd[1]: /lib/systemd/system/roonbridge.service:8: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 10:28:38 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 26 10:28:38 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 26 10:28:38 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 26 10:28:38 volumio sudo[9587]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:38 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 26 10:28:38 volumio sudo[9638]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:38 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 26 10:28:38 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 26 10:28:38 volumio systemd[1]: mpd.service: Consumed 1.552s CPU time. Dec 26 10:28:38 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 26 10:28:38 volumio sudo[9599]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:38 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 26 10:28:38 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 26 10:28:38 volumio sudo[9693]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 26 10:28:38 volumio sudo[9693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:38 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 10:28:38 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 10:28:38 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:38 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:38 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:38 volumio sudo[9693]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:38 volumio sudo[9695]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 10:28:38 volumio sudo[9695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 10:28:38 volumio sudo[9695]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:38 volumio volumio[1145]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 26 10:28:38 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 26 10:28:39 volumio sudo[9699]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 26 10:28:39 volumio sudo[9699]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:39 volumio sudo[9701]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 10:28:39 volumio sudo[9701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:39 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 26 10:28:39 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 26 10:28:39 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 26 10:28:39 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 26 10:28:39 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 26 10:28:39 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 26 10:28:39 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 26 10:28:39 volumio volumio[1145]: error: MPD error: Error: connect ECONNRESET /run/mpd/socket Dec 26 10:28:39 volumio volumio[1145]: error: MPD error: Error: connect ECONNRESET /run/mpd/socket Dec 26 10:28:39 volumio volumio[1145]: info: peppymeterbasic Daemon Stop Dec 26 10:28:39 volumio sudo[9699]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:39 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 26 10:28:39 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 10:28:39 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 10:28:39 volumio sudo[9705]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 26 10:28:39 volumio sudo[9705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:39 volumio sudo[9708]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 10:28:39 volumio sudo[9708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:39 volumio volumio[1145]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Dec 26 10:28:39 volumio sudo[9709]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 10:28:39 volumio sudo[9709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 10:28:39 volumio sudo[9709]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:39 volumio sudo[9715]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Dec 26 10:28:39 volumio sudo[9715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:39 volumio systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Dec 26 10:28:39 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 26 10:28:39 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 26 10:28:39 volumio sudo[9705]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:39 volumio volumio[1145]: info: peppymeterbasic Daemon Stop Dec 26 10:28:39 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 26 10:28:39 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 26 10:28:39 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 26 10:28:39 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 10:28:39 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 10:28:39 volumio systemd[1]: squeezelite.service: Deactivated successfully. Dec 26 10:28:39 volumio systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Dec 26 10:28:39 volumio sudo[9715]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:39 volumio go-librespot[1680]: time="2025-12-26T10:28:39+02:00" level=trace msg="sent dealer ping" Dec 26 10:28:39 volumio sudo[9718]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 10:28:39 volumio sudo[9718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 10:28:39 volumio sudo[9718]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:39 volumio go-librespot[1680]: time="2025-12-26T10:28:39+02:00" level=trace msg="received dealer pong" Dec 26 10:28:40 volumio volumio[1145]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 26 10:28:40 volumio sudo[9722]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 26 10:28:40 volumio sudo[9722]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:40 volumio sudo[9722]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:40 volumio volumio[1145]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Dec 26 10:28:40 volumio sudo[9726]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Dec 26 10:28:40 volumio sudo[9726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:40 volumio sudo[9726]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:40 volumio volumio[1145]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Dec 26 10:28:41 volumio sudo[9729]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Dec 26 10:28:41 volumio sudo[9729]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:41 volumio systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Dec 26 10:28:41 volumio mpd[9720]: 2025-12-26T10:28:41 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 26 10:28:41 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 26 10:28:41 volumio systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Dec 26 10:28:41 volumio sudo[9729]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:41 volumio sudo[9657]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:41 volumio sudo[9701]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:41 volumio volumio[1145]: error: updateQueue error: null Dec 26 10:28:41 volumio volumio[1145]: error: updateQueue error: null Dec 26 10:28:41 volumio sudo[9708]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:41 volumio volumio[1145]: error: updateQueue error: null Dec 26 10:28:41 volumio sudo[9741]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 10:28:41 volumio sudo[9741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:41 volumio volumio[1145]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 26 10:28:41 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 26 10:28:41 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 26 10:28:41 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 26 10:28:41 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 26 10:28:41 volumio mpd_monitor.sh[9744]: MPD Monitor Service: Starting MPD Monitor Service Dec 26 10:28:41 volumio sudo[9741]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:42 volumio volumio[1145]: info: Successfully started MPD Monitor Dec 26 10:28:42 volumio sudo[9745]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 26 10:28:42 volumio sudo[9745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:42 volumio sudo[9749]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 10:28:42 volumio sudo[9749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:42 volumio sudo[9745]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:42 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 26 10:28:42 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 26 10:28:42 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 26 10:28:42 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 26 10:28:42 volumio mpd_monitor.sh[9753]: MPD Monitor Service: Starting MPD Monitor Service Dec 26 10:28:42 volumio sudo[9749]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:42 volumio volumio[1145]: info: Successfully started MPD Monitor Dec 26 10:28:42 volumio volumio[1145]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 26 10:28:42 volumio sudo[9758]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 26 10:28:42 volumio sudo[9758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:42 volumio volumio[1145]: info: CoreCommandRouter::volumioPlay Dec 26 10:28:42 volumio volumio[1145]: info: CoreStateMachine::play index undefined Dec 26 10:28:42 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 10:28:42 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:42 volumio volumio[1145]: info: CoreStateMachine::startPlaybackTimer Dec 26 10:28:42 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:42 volumio volumio[1145]: info: [1766737722803] ControllerWebradio::clearAddPlayTrack Dec 26 10:28:42 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 10:28:42 volumio volumio[1145]: info: sendMpdCommand stop took 16 milliseconds Dec 26 10:28:42 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand clear Dec 26 10:28:42 volumio volumio[1145]: info: Dec 26 10:28:42 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:42 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:42 volumio volumio[1145]: info: sendMpdCommand clear took 2 milliseconds Dec 26 10:28:42 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1796827" Dec 26 10:28:42 volumio volumio[1145]: info: Dec 26 10:28:42 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:42 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:42 volumio volumio[1145]: info: Dec 26 10:28:42 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:42 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:42 volumio volumio[1145]: error: updateQueue error: null Dec 26 10:28:42 volumio volumio[1145]: info: ------------------------------ 16ms Dec 26 10:28:42 volumio sudo[9758]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:43 volumio volumio[1145]: info: Dec 26 10:28:43 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:43 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:43 volumio volumio[1145]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1796827" took 265 milliseconds Dec 26 10:28:43 volumio volumio[1145]: info: ------------------------------ 265ms Dec 26 10:28:43 volumio volumio[1145]: info: ------------------------------ 253ms Dec 26 10:28:43 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 26 10:28:43 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand play Dec 26 10:28:43 volumio volumio[1145]: info: Dec 26 10:28:43 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:43 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:43 volumio volumio[1145]: info: Dec 26 10:28:43 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:43 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:43 volumio volumio[1145]: info: ------------------------------ 17ms Dec 26 10:28:43 volumio volumio[1145]: info: sendMpdCommand play took 12 milliseconds Dec 26 10:28:43 volumio volumio[1145]: info: ------------------------------ 12ms Dec 26 10:28:43 volumio volumio[1145]: info: ------------------------------ 2ms Dec 26 10:28:43 volumio volumio[1145]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 26 10:28:43 volumio sudo[9778]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 26 10:28:43 volumio sudo[9778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:43 volumio sudo[9778]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:44 volumio volumio[1145]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 26 10:28:44 volumio sudo[9781]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 26 10:28:44 volumio sudo[9781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:44 volumio sudo[9781]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:44 volumio volumio[1145]: info: Dec 26 10:28:44 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:44 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:44 volumio volumio[1145]: info: Dec 26 10:28:44 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:44 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:44 volumio volumio[1145]: info: Dec 26 10:28:44 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:44 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:44 volumio volumio[1145]: info: Dec 26 10:28:44 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:44 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:44 volumio volumio[1145]: info: Dec 26 10:28:44 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:44 volumio volumio[1145]: info: Dec 26 10:28:44 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:44 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:44 volumio volumio[1145]: info: sendMpdCommand status took 38 milliseconds Dec 26 10:28:44 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:44 volumio volumio[1145]: info: sendMpdCommand status took 33 milliseconds Dec 26 10:28:44 volumio volumio[1145]: info: sendMpdCommand status took 23 milliseconds Dec 26 10:28:44 volumio volumio[1145]: info: sendMpdCommand status took 20 milliseconds Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:44 volumio volumio[1145]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 26 10:28:44 volumio volumio[1145]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 26 10:28:44 volumio volumio[1145]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 26 10:28:44 volumio sudo[9791]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 10:28:44 volumio sudo[9791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:44 volumio sudo[9793]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 10:28:44 volumio sudo[9793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:44 volumio volumio[1145]: info: Dec 26 10:28:44 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:44 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:44 volumio volumio[1145]: info: Dec 26 10:28:44 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:44 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:44 volumio volumio[1145]: info: Dec 26 10:28:44 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:44 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:44 volumio volumio[1145]: info: Dec 26 10:28:44 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:44 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:44 volumio volumio[1145]: info: Dec 26 10:28:44 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:44 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:44 volumio volumio[1145]: info: Dec 26 10:28:44 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:44 volumio volumio[1145]: info: Dec 26 10:28:44 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:44 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:44 volumio volumio[1145]: info: Dec 26 10:28:44 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:44 volumio volumio[1145]: info: sendMpdCommand status took 158 milliseconds Dec 26 10:28:44 volumio volumio[1145]: info: sendMpdCommand status took 158 milliseconds Dec 26 10:28:44 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 155 milliseconds Dec 26 10:28:44 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 163 milliseconds Dec 26 10:28:44 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 164 milliseconds Dec 26 10:28:44 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 164 milliseconds Dec 26 10:28:44 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:44 volumio volumio[1145]: info: sendMpdCommand status took 52 milliseconds Dec 26 10:28:44 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:44 volumio sudo[9795]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:44 volumio volumio[1145]: info: sendMpdCommand status took 45 milliseconds Dec 26 10:28:44 volumio volumio[1145]: info: sendMpdCommand status took 45 milliseconds Dec 26 10:28:44 volumio volumio[1145]: info: sendMpdCommand status took 38 milliseconds Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:44 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 26 10:28:44 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 26 10:28:44 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:44 volumio sudo[9795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:44 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:44 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:44 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:44 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:44 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"christmas_64a","artist":"1.FM - Always Christmas (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:44 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:44 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:44 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Dec 26 10:28:44 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:44 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:44 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:44 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"christmas_64a","artist":"1.FM - Always Christmas (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:44 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:44 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:44 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:28:44 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:28:44 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:44 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:44 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:28:44 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:44 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 26 10:28:44 volumio mpd_monitor.sh[9798]: MPD Monitor Service: Starting MPD Monitor Service Dec 26 10:28:44 volumio sudo[9793]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:44 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 26 10:28:44 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 26 10:28:44 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 26 10:28:44 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:44 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 26 10:28:44 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:44 volumio mpd_monitor.sh[9803]: MPD Monitor Service: Starting MPD Monitor Service Dec 26 10:28:44 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:44 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:44 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:44 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"christmas_64a","artist":"1.FM - Always Christmas (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:44 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:44 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:44 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 26 10:28:44 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 26 10:28:44 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 26 10:28:44 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:28:44 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:28:44 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:44 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:44 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:44 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:44 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 26 10:28:44 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:44 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:44 volumio mpd_monitor.sh[9807]: MPD Monitor Service: Starting MPD Monitor Service Dec 26 10:28:44 volumio sudo[9791]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:44 volumio sudo[9795]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:44 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:44 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:44 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"christmas_64a","artist":"1.FM - Always Christmas (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:44 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:44 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:44 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:28:44 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:28:44 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:44 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:44 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:44 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:44 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:44 volumio volumio[1145]: info: ------------------------------ 440ms Dec 26 10:28:45 volumio volumio[1145]: info: ------------------------------ 683ms Dec 26 10:28:45 volumio volumio[1145]: info: ------------------------------ 671ms Dec 26 10:28:45 volumio volumio[1145]: info: ------------------------------ 670ms Dec 26 10:28:45 volumio volumio[1145]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 26 10:28:45 volumio volumio[1145]: info: Dec 26 10:28:45 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:45 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:45 volumio volumio[1145]: info: Dec 26 10:28:45 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:45 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:45 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:45 volumio volumio[1145]: info: Dec 26 10:28:45 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:45 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:45 volumio volumio[1145]: info: Dec 26 10:28:45 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:45 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:45 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:45 volumio volumio[1145]: info: Dec 26 10:28:45 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:45 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:45 volumio volumio[1145]: info: Dec 26 10:28:45 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:45 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:45 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:45 volumio volumio[1145]: info: Dec 26 10:28:45 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 26 10:28:45 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 26 10:28:45 volumio volumio[1145]: info: Dec 26 10:28:45 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 26 10:28:45 volumio volumio[1145]: info: ControllerMpd::getState Dec 26 10:28:45 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 26 10:28:45 volumio volumio[1145]: info: ------------------------------ 625ms Dec 26 10:28:45 volumio volumio[1145]: info: sendMpdCommand status took 605 milliseconds Dec 26 10:28:45 volumio volumio[1145]: info: ------------------------------ 624ms Dec 26 10:28:45 volumio volumio[1145]: info: sendMpdCommand status took 599 milliseconds Dec 26 10:28:45 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 549 milliseconds Dec 26 10:28:45 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 548 milliseconds Dec 26 10:28:45 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 539 milliseconds Dec 26 10:28:45 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 530 milliseconds Dec 26 10:28:45 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 529 milliseconds Dec 26 10:28:45 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 529 milliseconds Dec 26 10:28:45 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:45 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:45 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:45 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:45 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:45 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:45 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:45 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:45 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:45 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:45 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:45 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:45 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:45 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Conan Gray - Holidays","artist":"1.FM - Always-Christmas Radio","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:45 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:28:45 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:45 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:28:45 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:45 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:45 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:45 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:45 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:45 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Conan Gray - Holidays","artist":"1.FM - Always-Christmas Radio","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:45 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:28:45 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:45 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:45 volumio sudo[9813]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 26 10:28:45 volumio sudo[9813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:45 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:45 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:45 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:45 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:45 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Conan Gray - Holidays","artist":"1.FM - Always-Christmas Radio","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:45 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:28:45 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:45 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:28:45 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:45 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:45 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:45 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:45 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:45 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Conan Gray - Holidays","artist":"1.FM - Always-Christmas Radio","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:45 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:28:45 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:45 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:45 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:45 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:45 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:45 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:45 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Conan Gray - Holidays","artist":"1.FM - Always-Christmas Radio","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:45 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:28:45 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:45 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:45 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:45 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:45 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:45 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:45 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Conan Gray - Holidays","artist":"1.FM - Always-Christmas Radio","album":null,"uri":"http://185.33.21.112:80/christmas_64a","trackType":""} Dec 26 10:28:45 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play Dec 26 10:28:45 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:45 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:45 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:45 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:45 volumio fsct-plugin[1145]: Playback notifications stream lagged 3 events. Dec 26 10:28:45 volumio sudo[9813]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:45 volumio volumio[1145]: info: ------------------------------ 1449ms Dec 26 10:28:45 volumio volumio[1145]: info: ------------------------------ 1451ms Dec 26 10:28:45 volumio volumio[1145]: info: ------------------------------ 1335ms Dec 26 10:28:45 volumio volumio[1145]: info: ------------------------------ 1335ms Dec 26 10:28:45 volumio volumio[1145]: info: ------------------------------ 1323ms Dec 26 10:28:45 volumio volumio[1145]: info: ------------------------------ 1322ms Dec 26 10:28:45 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:45 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:46 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:46 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:46 volumio sudo[9817]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:46 volumio sudo[9817]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:46 volumio sudo[9820]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:46 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:46 volumio sudo[9820]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:46 volumio sudo[9821]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:46 volumio sudo[9821]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:46 volumio sudo[9824]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:46 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:46 volumio sudo[9824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:46 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Dec 26 10:28:46 volumio sudo[9817]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:46 volumio sudo[9827]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:46 volumio sudo[9827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:46 volumio sudo[9820]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:46 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:46 volumio sudo[9821]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:46 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:46 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:46 volumio sudo[9824]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:46 volumio sudo[9834]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:46 volumio sudo[9834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:46 volumio sudo[9836]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:46 volumio sudo[9836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:46 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:46 volumio sudo[9842]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:46 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:46 volumio sudo[9842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:46 volumio sudo[9839]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:46 volumio sudo[9839]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:46 volumio sudo[9827]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:46 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:46 volumio sudo[9844]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:46 volumio sudo[9844]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:46 volumio sudo[9834]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:46 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:46 volumio sudo[9852]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:46 volumio sudo[9852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:46 volumio sudo[9842]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:46 volumio sudo[9849]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:46 volumio sudo[9849]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:46 volumio sudo[9839]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:46 volumio sudo[9836]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:46 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:46 volumio sudo[9844]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:46 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:47 volumio sudo[9852]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:47 volumio sudo[9857]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:47 volumio sudo[9849]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:47 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:47 volumio sudo[9857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:47 volumio sudo[9858]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:47 volumio sudo[9858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:47 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:47 volumio volumio[9833]: error: XDG_RUNTIME_DIR is invalid or not set in the environment. Dec 26 10:28:47 volumio sudo[9865]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:47 volumio sudo[9865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:47 volumio sudo[9860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:47 volumio sudo[9860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:47 volumio volumio[1145]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 26 10:28:47 volumio sudo[9857]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:47 volumio sudo[9860]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:47 volumio sudo[9858]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:47 volumio sudo[9865]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:47 volumio sudo[9880]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:47 volumio sudo[9882]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 26 10:28:47 volumio sudo[9882]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:47 volumio sudo[9880]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:47 volumio sudo[9882]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:47 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 26 10:28:47 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 26 10:28:47 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 26 10:28:47 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 26 10:28:47 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 26 10:28:47 volumio sudo[9880]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:47 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 26 10:28:47 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 26 10:28:47 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 26 10:28:47 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 26 10:28:47 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 26 10:28:47 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 26 10:28:47 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 26 10:28:47 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 26 10:28:47 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 26 10:28:47 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 26 10:28:47 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 26 10:28:47 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 26 10:28:47 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 26 10:28:47 volumio volumio[9833]: libpng warning: iCCP: known incorrect sRGB profile Dec 26 10:28:47 volumio volumio[1145]: info: CoreCommandRouter::volumioPlay Dec 26 10:28:47 volumio volumio[1145]: info: CoreStateMachine::play index undefined Dec 26 10:28:47 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 10:28:47 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:47 volumio volumio[1145]: info: Successfully started MPD Monitor Dec 26 10:28:47 volumio volumio[1145]: info: Successfully started MPD Monitor Dec 26 10:28:47 volumio volumio[1145]: info: Successfully started MPD Monitor Dec 26 10:28:47 volumio volumio[1145]: info: ------------------------------ 2590ms Dec 26 10:28:47 volumio volumio[1145]: info: sendMpdCommand status took 2590 milliseconds Dec 26 10:28:47 volumio volumio[1145]: info: ------------------------------ 2590ms Dec 26 10:28:47 volumio volumio[1145]: info: sendMpdCommand status took 2590 milliseconds Dec 26 10:28:47 volumio volumio[1145]: info: ------------------------------ 2589ms Dec 26 10:28:47 volumio volumio[1145]: info: sendMpdCommand status took 2605 milliseconds Dec 26 10:28:47 volumio volumio[1145]: info: ------------------------------ 2604ms Dec 26 10:28:47 volumio volumio[1145]: info: sendMpdCommand status took 2604 milliseconds Dec 26 10:28:47 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 2602 milliseconds Dec 26 10:28:47 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 2602 milliseconds Dec 26 10:28:47 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:47 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:47 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:47 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:47 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:47 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:47 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 26 10:28:47 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 10:28:47 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:47 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:47 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:47 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:47 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:47 volumio volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 26 10:28:47 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:47 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:47 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:47 volumio volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 26 10:28:47 volumio volumio[1145]: info: ------------------------------ 3230ms Dec 26 10:28:47 volumio volumio[1145]: info: ------------------------------ 3229ms Dec 26 10:28:47 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:47 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:47 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:47 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:47 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:47 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:47 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:47 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:47 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:47 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:47 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:47 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:47 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:47 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:47 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:47 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:47 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:47 volumio volumio[1145]: info: peppymeterbasic Daemon Started Dec 26 10:28:47 volumio volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 10:28:47 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 148 milliseconds Dec 26 10:28:47 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 150 milliseconds Dec 26 10:28:47 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 151 milliseconds Dec 26 10:28:47 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 152 milliseconds Dec 26 10:28:47 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:47 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:47 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:47 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 26 10:28:47 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:47 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:47 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:47 volumio volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 26 10:28:47 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:47 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:47 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:47 volumio volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 26 10:28:47 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:47 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:47 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:47 volumio volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 26 10:28:47 volumio volumio[1145]: info: ControllerMpd::pushState Dec 26 10:28:47 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 26 10:28:47 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:47 volumio volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 26 10:28:47 volumio volumio[1145]: info: ------------------------------ 2770ms Dec 26 10:28:47 volumio volumio[1145]: info: ------------------------------ 2770ms Dec 26 10:28:47 volumio volumio[1145]: info: ------------------------------ 2769ms Dec 26 10:28:47 volumio volumio[1145]: info: ------------------------------ 2768ms Dec 26 10:28:48 volumio volumio[1145]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 26 10:28:48 volumio sudo[9887]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 26 10:28:48 volumio sudo[9887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:28:48 volumio sudo[9887]: pam_unix(sudo:session): session closed for user root Dec 26 10:28:51 volumio volumio[1145]: info: Preload queue cleared Dec 26 10:28:51 volumio volumio[1145]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 26 10:28:51 volumio volumio[1145]: info: CoreStateMachine::ClearQueue Dec 26 10:28:51 volumio volumio[1145]: info: CoreStateMachine::stop Dec 26 10:28:51 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 10:28:51 volumio volumio[1145]: info: CoreStateMachine::stPlaybackTimer Dec 26 10:28:51 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock Dec 26 10:28:51 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock Dec 26 10:28:51 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 26 10:28:51 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 26 10:28:51 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:28:51 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 26 10:28:51 volumio volumio[1145]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 10:28:51 volumio volumio[1145]: Error: channel closed Dec 26 10:28:51 volumio volumio[1145]: at FerrumStreamingControlTechnology.updateStateOnPlayer (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:71:12) Dec 26 10:28:51 volumio volumio[1145]: at FerrumStreamingControlTechnology.pushState (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:182:10) Dec 26 10:28:51 volumio volumio[1145]: at /volumio/app/index.js:326:81 Dec 26 10:28:51 volumio volumio[1145]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Dec 26 10:28:51 volumio volumio[1145]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Dec 26 10:28:51 volumio volumio[1145]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Dec 26 10:28:51 volumio volumio[1145]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:534:22) Dec 26 10:28:51 volumio volumio[1145]: at CoreStateMachine.stop (/volumio/app/statemachine.js:1247:12) Dec 26 10:28:51 volumio volumio[1145]: at CoreStateMachine.clearQueue (/volumio/app/statemachine.js:277:8) Dec 26 10:28:51 volumio volumio[1145]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:494:21) Dec 26 10:28:51 volumio volumio[1145]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Dec 26 10:28:51 volumio volumio[1145]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Dec 26 10:28:51 volumio volumio[1145]: at Socket.emit (node:events:514:28) Dec 26 10:28:51 volumio volumio[1145]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 26 10:28:51 volumio volumio[1145]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Dec 26 10:28:51 volumio volumio[1145]: code: 'GenericFailure' Dec 26 10:28:51 volumio volumio[1145]: } Dec 26 10:28:51 volumio volumio[1145]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 10:28:55 volumio sudo[9920]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-26 10:27' Dec 26 10:28:55 volumio sudo[9920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"