-- Logs begin at Thu 2019-02-14 12:11:59 EET, end at Wed 2024-05-01 22:31:32 EEST. -- May 01 22:30:06 volumio volumio[845]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] May 01 22:30:06 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions May 01 22:30:06 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:30:06 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:06 volumio volumio[845]: info: Restoring Previous Volume level: 100 false true May 01 22:30:06 volumio volumio[845]: info: VolumeController::SetAlsaVolume100 May 01 22:30:06 volumio volumio[845]: info: Enable softmixer device for audio device number 5 May 01 22:30:06 volumio volumio[845]: info: CoreCommandRouter::volumioStop May 01 22:30:06 volumio volumio[845]: info: CoreStateMachine::stop May 01 22:30:06 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 22:30:06 volumio volumio[845]: info: CoreStateMachine::stPlaybackTimer May 01 22:30:06 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock May 01 22:30:06 volumio volumio[845]: info: CorePlayQueue::getTrackBlock May 01 22:30:06 volumio volumio[845]: info: CoreStateMachine::pushState May 01 22:30:06 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:06 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 22:30:06 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 01 22:30:06 volumio volumio[845]: info: CoreStateMachine::serviceStop May 01 22:30:06 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:06 volumio volumio[845]: info: CoreCommandRouter::serviceStop May 01 22:30:06 volumio volumio[845]: info: ControllerMpd::stop May 01 22:30:06 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop May 01 22:30:06 volumio volumio[845]: info: Enable softmixer device for audio device undefined May 01 22:30:06 volumio volumio[845]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 01 22:30:06 volumio volumio[845]: info: Updating Volume Controller Parameters: Device: 5 Name: FiiO BTA30 PRO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 01 22:30:06 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 01 22:30:06 volumio volumio[845]: info: Disabling external Volume Control May 01 22:30:06 volumio volumio[845]: info: Output device has changed, restarting MPD May 01 22:30:06 volumio sudo[2008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 01 22:30:06 volumio sudo[2008]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:06 volumio volumio[845]: info: Output device has changed, restarting Shairport Sync May 01 22:30:06 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:06 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 22:30:06 volumio sudo[2008]: pam_unix(sudo:session): session closed for user root May 01 22:30:06 volumio sudo[2011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 01 22:30:06 volumio sudo[2011]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:06 volumio systemd[1]: Stopping Music Player Daemon... May 01 22:30:06 volumio systemd[1]: mpd.service: Succeeded. May 01 22:30:06 volumio systemd[1]: Stopped Music Player Daemon. May 01 22:30:06 volumio volumio[845]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 01 22:30:06 volumio volumio[845]: info: Volume configurations have been set May 01 22:30:06 volumio systemd[1]: Starting Music Player Daemon... May 01 22:30:06 volumio volumio[845]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 01 22:30:06 volumio volumio[845]: info: Updating Volume Controller Parameters: Device: 5 Name: FiiO BTA30 PRO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 01 22:30:06 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 01 22:30:06 volumio volumio[845]: info: Disabling external Volume Control May 01 22:30:06 volumio volumio[845]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 22:30:06 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:30:06 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:06 volumio volumio[845]: info: CoreStateMachine::pushState May 01 22:30:06 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:06 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 01 22:30:06 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:30:06 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:06 volumio volumio[845]: info: CoreStateMachine::pushState May 01 22:30:06 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:06 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 01 22:30:06 volumio volumio[845]: error: MPD error: Error: write EPIPE May 01 22:30:06 volumio volumio[845]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} May 01 22:30:06 volumio volumio[845]: info: peppyspectrum status stop May 01 22:30:06 volumio volumio[845]: info: peppyspectrum status stop May 01 22:30:06 volumio volumio[845]: info: peppyspectrum status stop May 01 22:30:06 volumio sudo[2026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 01 22:30:06 volumio sudo[2026]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:06 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:30:06 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:06 volumio volumio[845]: info: CoreStateMachine::pushState May 01 22:30:06 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:06 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 22:30:06 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 01 22:30:06 volumio sudo[2029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 01 22:30:07 volumio sudo[2029]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:07 volumio volumio[845]: info: MPD Permissions set May 01 22:30:07 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:30:07 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:07 volumio sudo[2026]: pam_unix(sudo:session): session closed for user root May 01 22:30:07 volumio sudo[2029]: pam_unix(sudo:session): session closed for user root May 01 22:30:07 volumio sudo[2032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 01 22:30:07 volumio sudo[2032]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:07 volumio volumio[845]: info: peppyspectrum status stop May 01 22:30:07 volumio sudo[2032]: pam_unix(sudo:session): session closed for user root May 01 22:30:07 volumio volumio[845]: info: peppyspectrum Daemon Stop May 01 22:30:07 volumio volumio[845]: info: Software Volume ALSA configuration written May 01 22:30:07 volumio volumio[845]: info: Preparing to generate the ALSA configuration file May 01 22:30:07 volumio sudo[2037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 01 22:30:07 volumio volumio[845]: info: peppyspectrum Daemon Stop May 01 22:30:07 volumio volumio[845]: info: peppyspectrum Daemon Stop May 01 22:30:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:07 volumio sudo[2037]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:07 volumio sudo[2037]: pam_unix(sudo:session): session closed for user root May 01 22:30:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 22:30:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:07 volumio volumio[845]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 01 22:30:07 volumio volumio[845]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf May 01 22:30:07 volumio volumio[845]: info: Reading ALSA contributions from plugins. May 01 22:30:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 01 22:30:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 22:30:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 01 22:30:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 22:30:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 22:30:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 22:30:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 22:30:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 22:30:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 22:30:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 22:30:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 01 22:30:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 01 22:30:07 volumio volumio[845]: info: touch_display: Setting screensaver timeout to 0 seconds. May 01 22:30:07 volumio volumio[845]: info: peppyspectrum Daemon Stop May 01 22:30:07 volumio volumio[845]: info: Starting Shairport Sync May 01 22:30:07 volumio sudo[2048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 01 22:30:07 volumio sudo[2048]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:07 volumio volumio[845]: info: Asound.conf file written May 01 22:30:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 01 22:30:07 volumio systemd[1]: shairport-sync.service: Succeeded. May 01 22:30:07 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 01 22:30:07 volumio sudo[2052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 01 22:30:07 volumio sudo[2052]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:07 volumio sudo[2052]: pam_unix(sudo:session): session closed for user root May 01 22:30:07 volumio mpd[2016]: May 01 22:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 01 22:30:07 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 01 22:30:07 volumio sudo[2048]: pam_unix(sudo:session): session closed for user root May 01 22:30:07 volumio systemd[1]: Started Music Player Daemon. May 01 22:30:07 volumio sudo[2011]: pam_unix(sudo:session): session closed for user root May 01 22:30:07 volumio volumio[845]: info: Output device has changed, restarting MPD May 01 22:30:07 volumio sudo[2061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 01 22:30:07 volumio sudo[2061]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:07 volumio sudo[2061]: pam_unix(sudo:session): session closed for user root May 01 22:30:07 volumio volumio[845]: info: Output device has changed, restarting Shairport Sync May 01 22:30:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 22:30:07 volumio sudo[2064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 01 22:30:07 volumio sudo[2064]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:07 volumio systemd[1]: Stopping Music Player Daemon... May 01 22:30:07 volumio systemd[1]: mpd.service: Succeeded. May 01 22:30:07 volumio systemd[1]: Stopped Music Player Daemon. May 01 22:30:07 volumio systemd[1]: Starting Music Player Daemon... May 01 22:30:07 volumio volumio[845]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 01 22:30:08 volumio mpd[2069]: May 01 22:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 01 22:30:08 volumio systemd[1]: Started Music Player Daemon. May 01 22:30:08 volumio sudo[2064]: pam_unix(sudo:session): session closed for user root May 01 22:30:09 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 1 May 01 22:30:09 volumio kernel: xhci_hcd 0000:01:00.0: @000000042a119de0 00000000 00000000 0e000000 02028000 May 01 22:30:09 volumio volumio[845]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo May 01 22:30:09 volumio volumio[845]: Found hardware: "USB-Audio" "USB Mixer" "USB2972:0047" "" "" May 01 22:30:09 volumio volumio[845]: Hardware is initialized using a generic method May 01 22:30:09 volumio volumio[845]: info: Output device has changed, restarting MPD May 01 22:30:09 volumio sudo[2078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 01 22:30:09 volumio sudo[2078]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:09 volumio sudo[2078]: pam_unix(sudo:session): session closed for user root May 01 22:30:09 volumio volumio[845]: info: Output device has changed, restarting Shairport Sync May 01 22:30:09 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:09 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 22:30:09 volumio sudo[2081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 01 22:30:09 volumio sudo[2081]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:09 volumio systemd[1]: Stopping Music Player Daemon... May 01 22:30:09 volumio volumio[845]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 01 22:30:09 volumio systemd[1]: mpd.service: Succeeded. May 01 22:30:09 volumio systemd[1]: Stopped Music Player Daemon. May 01 22:30:09 volumio volumio[845]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 01 22:30:09 volumio volumio[845]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 01 22:30:09 volumio systemd[1]: Starting Music Player Daemon... May 01 22:30:09 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 01 22:30:09 volumio volumio[845]: info: Disabling external Volume Control May 01 22:30:09 volumio volumio[845]: error: [ytcr] Error connecting MPD: May 01 22:30:09 volumio volumio[845]: (Error) write EPIPE May 01 22:30:09 volumio volumio[845]: Error: write EPIPE May 01 22:30:09 volumio volumio[845]: at afterWriteDispatched (internal/stream_base_commons.js:156:25) May 01 22:30:09 volumio volumio[845]: at writeGeneric (internal/stream_base_commons.js:147:3) May 01 22:30:09 volumio volumio[845]: at Socket._writeGeneric (net.js:785:11) May 01 22:30:09 volumio volumio[845]: at Socket._write (net.js:797:8) May 01 22:30:09 volumio volumio[845]: at writeOrBuffer (internal/streams/writable.js:358:12) May 01 22:30:09 volumio volumio[845]: at Socket.Writable.write (internal/streams/writable.js:303:10) May 01 22:30:09 volumio volumio[845]: at Socket.onData (/data/plugins/music_service/ytcr/node_modules/mpd2/lib/index.js:271:16) May 01 22:30:09 volumio volumio[845]: at Socket.emit (events.js:315:20) May 01 22:30:09 volumio volumio[845]: at addChunk (internal/streams/readable.js:309:12) May 01 22:30:09 volumio volumio[845]: at readableAddChunk (internal/streams/readable.js:280:11) May 01 22:30:09 volumio volumio[845]: at Socket.Readable.push (internal/streams/readable.js:223:10) May 01 22:30:09 volumio volumio[845]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) May 01 22:30:09 volumio volumio[845]: Retrying in 5 seconds... May 01 22:30:09 volumio volumio[845]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 May 01 22:30:09 volumio volumio[845]: info: MPD Permissions set May 01 22:30:09 volumio volumio[845]: info: MPD Permissions set May 01 22:30:09 volumio volumio[845]: info: Shairport-Sync Started May 01 22:30:09 volumio mpd[2087]: May 01 22:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 01 22:30:09 volumio systemd[1]: Started Music Player Daemon. May 01 22:30:09 volumio sudo[2081]: pam_unix(sudo:session): session closed for user root May 01 22:30:11 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:30:11 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:11 volumio volumio[845]: info: CoreStateMachine::pushState May 01 22:30:11 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:11 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 22:30:11 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 01 22:30:11 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 01 22:30:11 volumio volumio[845]: info: VolumeController::SetAlsaVolume100 May 01 22:30:11 volumio sudo[2099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 01 22:30:11 volumio sudo[2099]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:11 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:30:11 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:11 volumio volumio[845]: info: CoreStateMachine::pushState May 01 22:30:11 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:11 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 22:30:11 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 01 22:30:11 volumio systemd[1]: Stopping Music Player Daemon... May 01 22:30:11 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:11 volumio systemd[1]: mpd.service: Succeeded. May 01 22:30:11 volumio systemd[1]: Stopped Music Player Daemon. May 01 22:30:11 volumio volumio[845]: info: peppyspectrum status stop May 01 22:30:11 volumio volumio[845]: info: peppyspectrum status stop May 01 22:30:11 volumio sudo[2104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 01 22:30:11 volumio sudo[2104]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:11 volumio systemd[1]: Starting Music Player Daemon... May 01 22:30:12 volumio volumio[845]: error: MPD error: Error: write EPIPE May 01 22:30:12 volumio volumio[845]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} May 01 22:30:12 volumio volumio[845]: error: MPD error: The expression evaluated to a falsy value: May 01 22:30:12 volumio volumio[845]: assert.ok(self.idling) May 01 22:30:12 volumio volumio[845]: error: The expression evaluated to a falsy value: May 01 22:30:12 volumio volumio[845]: assert.ok(self.idling) May 01 22:30:12 volumio volumio[845]: error: MPD error: Error: write EPIPE May 01 22:30:12 volumio volumio[845]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} May 01 22:30:12 volumio sudo[2104]: pam_unix(sudo:session): session closed for user root May 01 22:30:12 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:12 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:12 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:12 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:12 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:12 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:12 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:12 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:12 volumio sudo[2107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 01 22:30:12 volumio sudo[2107]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:12 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 22:30:12 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:12 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:12 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:12 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:12 volumio sudo[2107]: pam_unix(sudo:session): session closed for user root May 01 22:30:12 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:12 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:12 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:12 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:12 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:12 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 22:30:12 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:12 volumio volumio[845]: info: peppyspectrum Daemon Stop May 01 22:30:12 volumio volumio[845]: info: peppyspectrum Daemon Stop May 01 22:30:12 volumio volumio[845]: info: Starting Shairport Sync May 01 22:30:12 volumio volumio[845]: info: Starting Shairport Sync May 01 22:30:12 volumio sudo[2118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 01 22:30:12 volumio sudo[2118]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:12 volumio sudo[2120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 01 22:30:12 volumio sudo[2120]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:12 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 01 22:30:12 volumio systemd[1]: shairport-sync.service: Succeeded. May 01 22:30:12 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 01 22:30:12 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 01 22:30:12 volumio sudo[2118]: pam_unix(sudo:session): session closed for user root May 01 22:30:12 volumio volumio[845]: info: Shairport-Sync Started May 01 22:30:12 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 01 22:30:12 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 01 22:30:12 volumio systemd[1]: shairport-sync.service: Succeeded. May 01 22:30:12 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 01 22:30:12 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 01 22:30:12 volumio sudo[2120]: pam_unix(sudo:session): session closed for user root May 01 22:30:12 volumio volumio[845]: info: Shairport-Sync Started May 01 22:30:12 volumio mpd[2102]: May 01 22:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 01 22:30:12 volumio systemd[1]: Started Music Player Daemon. May 01 22:30:12 volumio sudo[2099]: pam_unix(sudo:session): session closed for user root May 01 22:30:12 volumio volumio[845]: error: updateQueue error: null May 01 22:30:12 volumio volumio[845]: error: updateQueue error: null May 01 22:30:16 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:30:16 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:20 volumio volumio[845]: info: Preload queue cleared May 01 22:30:20 volumio volumio[845]: info: CoreCommandRouter::volumioReplaceandPlayItems May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::ClearQueue May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::stop May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 22:30:20 volumio volumio[845]: info: CorePlayQueue::clearPlayQueue May 01 22:30:20 volumio volumio[845]: info: CorePlayQueue::saveQueue May 01 22:30:20 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::addQueueItems May 01 22:30:20 volumio volumio[845]: info: CorePlayQueue::addQueueItems May 01 22:30:20 volumio volumio[845]: info: Preload queue cleared May 01 22:30:20 volumio volumio[845]: info: Adding Item to queue: albums://Milli%20Vanilli/'80%20Dance%20Classics May 01 22:30:20 volumio volumio[845]: info: Exploding uri albums://Milli%20Vanilli/'80%20Dance%20Classics in service mpd May 01 22:30:20 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 01 22:30:20 volumio volumio[845]: info: CorePlayQueue::saveQueue May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock May 01 22:30:20 volumio volumio[845]: info: CorePlayQueue::getTrackBlock May 01 22:30:20 volumio volumio[845]: info: CoreCommandRouter::volumioPlay May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::play index 0 May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::stop May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::play index undefined May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 22:30:20 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::startPlaybackTimer May 01 22:30:20 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:20 volumio volumio[845]: verbose: ControllerMpd::clearAddPlayTracks NAS/PC/CD Rip to dsd/CD Rip/'80 Dance Classics/14 - Girl You Know It's True.wav May 01 22:30:20 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop May 01 22:30:20 volumio volumio[845]: info: sendMpdCommand stop took 3 milliseconds May 01 22:30:20 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand clear May 01 22:30:20 volumio volumio[845]: info: May 01 22:30:20 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 01 22:30:20 volumio volumio[845]: info: Ignoring MPD Status Update May 01 22:30:20 volumio volumio[845]: info: sendMpdCommand clear took 1 milliseconds May 01 22:30:20 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand add "NAS/PC/CD Rip to dsd/CD Rip/'80 Dance Classics/14 - Girl You Know It's True.wav" May 01 22:30:20 volumio volumio[845]: info: May 01 22:30:20 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 01 22:30:20 volumio volumio[845]: info: Ignoring MPD Status Update May 01 22:30:20 volumio volumio[845]: error: updateQueue error: null May 01 22:30:20 volumio volumio[845]: info: May 01 22:30:20 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 01 22:30:20 volumio volumio[845]: info: Ignoring MPD Status Update May 01 22:30:20 volumio volumio[845]: info: ------------------------------ 4ms May 01 22:30:20 volumio volumio[845]: info: sendMpdCommand add "NAS/PC/CD Rip to dsd/CD Rip/'80 Dance Classics/14 - Girl You Know It's True.wav" took 4 milliseconds May 01 22:30:20 volumio volumio[845]: info: ------------------------------ 3ms May 01 22:30:20 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play May 01 22:30:20 volumio volumio[845]: info: May 01 22:30:20 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 01 22:30:20 volumio volumio[845]: info: Ignoring MPD Status Update May 01 22:30:20 volumio volumio[845]: info: ------------------------------ 5ms May 01 22:30:20 volumio volumio[845]: info: sendMpdCommand play took 3 milliseconds May 01 22:30:20 volumio volumio[845]: info: ------------------------------ 2ms May 01 22:30:20 volumio volumio[845]: info: May 01 22:30:20 volumio volumio[845]: ---------------------------- MPD announces state update: player May 01 22:30:20 volumio volumio[845]: info: ControllerMpd::getState May 01 22:30:20 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 01 22:30:20 volumio volumio[845]: info: May 01 22:30:20 volumio volumio[845]: ---------------------------- MPD announces state update: player May 01 22:30:20 volumio volumio[845]: info: ControllerMpd::getState May 01 22:30:20 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 01 22:30:20 volumio volumio[845]: info: sendMpdCommand status took 47 milliseconds May 01 22:30:20 volumio volumio[845]: verbose: ControllerMpd::parseState May 01 22:30:20 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 22:30:20 volumio volumio[845]: info: May 01 22:30:20 volumio volumio[845]: ---------------------------- MPD announces state update: player May 01 22:30:20 volumio volumio[845]: info: ControllerMpd::getState May 01 22:30:20 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 01 22:30:20 volumio volumio[845]: info: sendMpdCommand status took 48 milliseconds May 01 22:30:20 volumio volumio[845]: info: sendMpdCommand playlistinfo took 2 milliseconds May 01 22:30:20 volumio volumio[845]: verbose: ControllerMpd::parseState May 01 22:30:20 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 22:30:20 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 01 22:30:20 volumio volumio[845]: info: ControllerMpd::pushState May 01 22:30:20 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 01 22:30:20 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:20 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Girl You Know It's True","artist":"Milli Vanilli","album":"'80 Dance Classics","uri":"NAS/PC/CD Rip to dsd/CD Rip/'80 Dance Classics/14 - Girl You Know It's True.wav","trackType":"wav"} May 01 22:30:20 volumio volumio[845]: verbose: CURRENT POSITION 0 May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus stop May 01 22:30:20 volumio volumio[845]: info: ------------------------------ 54ms May 01 22:30:20 volumio volumio[845]: info: May 01 22:30:20 volumio volumio[845]: ---------------------------- MPD announces state update: player May 01 22:30:20 volumio volumio[845]: info: ControllerMpd::getState May 01 22:30:20 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 01 22:30:20 volumio volumio[845]: info: sendMpdCommand status took 4 milliseconds May 01 22:30:20 volumio volumio[845]: info: sendMpdCommand playlistinfo took 4 milliseconds May 01 22:30:20 volumio volumio[845]: info: sendMpdCommand status took 1 milliseconds May 01 22:30:20 volumio volumio[845]: verbose: ControllerMpd::parseState May 01 22:30:20 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 22:30:20 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 01 22:30:20 volumio volumio[845]: verbose: ControllerMpd::parseState May 01 22:30:20 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 22:30:20 volumio volumio[845]: info: ControllerMpd::pushState May 01 22:30:20 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 01 22:30:20 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:20 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Girl You Know It's True","artist":"Milli Vanilli","album":"'80 Dance Classics","uri":"NAS/PC/CD Rip to dsd/CD Rip/'80 Dance Classics/14 - Girl You Know It's True.wav","trackType":"wav"} May 01 22:30:20 volumio volumio[845]: verbose: CURRENT POSITION 0 May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 01 22:30:20 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::pushState May 01 22:30:20 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:20 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 22:30:20 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::pushState May 01 22:30:20 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:20 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 01 22:30:20 volumio volumio[845]: info: ------------------------------ 83ms May 01 22:30:20 volumio volumio[845]: info: sendMpdCommand playlistinfo took 30 milliseconds May 01 22:30:20 volumio volumio[845]: info: sendMpdCommand playlistinfo took 30 milliseconds May 01 22:30:20 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 01 22:30:20 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 01 22:30:20 volumio volumio[845]: info: ControllerMpd::pushState May 01 22:30:20 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 01 22:30:20 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:20 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Girl You Know It's True","artist":"Milli Vanilli","album":"'80 Dance Classics","uri":"NAS/PC/CD Rip to dsd/CD Rip/'80 Dance Classics/14 - Girl You Know It's True.wav","trackType":"wav"} May 01 22:30:20 volumio volumio[845]: verbose: CURRENT POSITION 0 May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 01 22:30:20 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::pushState May 01 22:30:20 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:20 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::pushState May 01 22:30:20 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:20 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 01 22:30:20 volumio volumio[845]: info: ControllerMpd::pushState May 01 22:30:20 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 01 22:30:20 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:20 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Girl You Know It's True","artist":"Milli Vanilli","album":"'80 Dance Classics","uri":"NAS/PC/CD Rip to dsd/CD Rip/'80 Dance Classics/14 - Girl You Know It's True.wav","trackType":"wav"} May 01 22:30:20 volumio volumio[845]: verbose: CURRENT POSITION 0 May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 01 22:30:20 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::pushState May 01 22:30:20 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:20 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 01 22:30:20 volumio volumio[845]: info: CoreStateMachine::pushState May 01 22:30:20 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:20 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 01 22:30:20 volumio volumio[845]: info: ------------------------------ 96ms May 01 22:30:20 volumio volumio[845]: info: ------------------------------ 93ms May 01 22:30:20 volumio volumio[845]: info: peppyspectrum status play May 01 22:30:20 volumio volumio[845]: info: peppyspectrum status play May 01 22:30:20 volumio sudo[2136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 01 22:30:20 volumio sudo[2136]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:20 volumio volumio[845]: info: peppyspectrum status play May 01 22:30:20 volumio sudo[2140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 01 22:30:20 volumio sudo[2140]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:20 volumio volumio[845]: info: peppyspectrum status play May 01 22:30:20 volumio sudo[2143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 01 22:30:20 volumio sudo[2143]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:20 volumio systemd[1]: Started peppyspectrum Daemon. May 01 22:30:20 volumio sudo[2146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 01 22:30:20 volumio sudo[2136]: pam_unix(sudo:session): session closed for user root May 01 22:30:20 volumio volumio[845]: info: peppyspectrum status play May 01 22:30:21 volumio sudo[2140]: pam_unix(sudo:session): session closed for user root May 01 22:30:21 volumio sudo[2146]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:21 volumio sudo[2143]: pam_unix(sudo:session): session closed for user root May 01 22:30:21 volumio volumio[845]: info: peppyspectrum status play May 01 22:30:21 volumio volumio[845]: info: peppyspectrum Daemon Started May 01 22:30:21 volumio volumio[845]: info: peppyspectrum Daemon Started May 01 22:30:21 volumio volumio[845]: info: peppyspectrum Daemon Started May 01 22:30:21 volumio sudo[2151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 01 22:30:21 volumio sudo[2146]: pam_unix(sudo:session): session closed for user root May 01 22:30:21 volumio sudo[2151]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:21 volumio sudo[2152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 01 22:30:21 volumio sudo[2152]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:21 volumio sudo[2151]: pam_unix(sudo:session): session closed for user root May 01 22:30:21 volumio sudo[2152]: pam_unix(sudo:session): session closed for user root May 01 22:30:21 volumio volumio[2139]: pygame 1.9.4.post1 May 01 22:30:21 volumio volumio[2139]: Hello from the pygame community. https://www.pygame.org/contribute.html May 01 22:30:21 volumio volumio[2139]: Traceback (most recent call last): May 01 22:30:21 volumio volumio[2139]: File "spectrum.py", line 30, in May 01 22:30:21 volumio volumio[2139]: from spectrumutil import SpectrumUtil May 01 22:30:21 volumio volumio[2139]: File "/data/plugins/user_interface/peppyspectrum/PeppySpectrum/spectrumutil.py", line 19, in May 01 22:30:21 volumio volumio[2139]: from PIL import Image May 01 22:30:21 volumio volumio[2139]: ModuleNotFoundError: No module named 'PIL' May 01 22:30:21 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE May 01 22:30:21 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. May 01 22:30:24 volumio volumio[845]: info: peppyspectrum Daemon Started May 01 22:30:24 volumio volumio[845]: info: peppyspectrum Daemon Started May 01 22:30:24 volumio volumio[845]: info: peppyspectrum Daemon Started May 01 22:30:24 volumio volumio[845]: info: touch_display: Setting screensaver timeout to 0 seconds. May 01 22:30:37 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:30:37 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:39 volumio kernel: CIFS: VFS: \\192.168.1.100 Invalid mid allocation time May 01 22:30:39 volumio kernel: CIFS: VFS: \\192.168.1.100 Invalid mid allocation time May 01 22:30:40 volumio volumio[845]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 22:30:40 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 01 22:30:40 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 01 22:30:40 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 01 22:30:42 volumio volumio[845]: info: Received Get System Version May 01 22:30:42 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 22:30:42 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard May 01 22:30:42 volumio volumio[845]: info: CoreCommandRouter::Reload Ui May 01 22:30:43 volumio sudo[2178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 01 22:30:43 volumio sudo[2180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 22:30:43 volumio sudo[2180]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:44 volumio sudo[2178]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:44 volumio sudo[2180]: pam_unix(sudo:session): session closed for user root May 01 22:30:44 volumio sudo[2178]: pam_unix(sudo:session): session closed for user root May 01 22:30:44 volumio volumio[845]: verbose: New Socket.io Connection to 192.168.1.174 from 192.168.1.100 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 8 May 01 22:30:44 volumio volumio[845]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 9 May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::volumioGetVisibleSources May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones May 01 22:30:44 volumio volumio[845]: info: Received Get System Info May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 22:30:44 volumio volumio[845]: info: Discovery: Getting this device information May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:30:44 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 22:30:44 volumio volumio-remote-updater[491]: No test mode May 01 22:30:44 volumio volumio-remote-updater[491]: No alpha test mode May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::volumioGetVisibleSources May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones May 01 22:30:44 volumio volumio[845]: info: Received Get System Info May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 22:30:44 volumio volumio[845]: info: Discovery: Getting this device information May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:30:44 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 01 22:30:44 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 01 22:30:45 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 01 22:30:45 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 01 22:30:45 volumio volumio[845]: info: Loading i18n strings for locale en May 01 22:30:45 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone May 01 22:30:45 volumio volumio[845]: info: Setting timezone to Europe/Sofia May 01 22:30:46 volumio sudo[2186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 01 22:30:46 volumio sudo[2186]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:46 volumio sudo[2186]: pam_unix(sudo:session): session closed for user root May 01 22:30:46 volumio sudo[2189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Sofia /etc/localtime May 01 22:30:46 volumio sudo[2189]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:46 volumio sudo[2189]: pam_unix(sudo:session): session closed for user root May 01 22:30:46 volumio sudo[2192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 01 22:30:46 volumio sudo[2192]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:46 volumio sudo[2192]: pam_unix(sudo:session): session closed for user root May 01 22:30:46 volumio sudo[2195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Sofia May 01 22:30:46 volumio sudo[2195]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:46 volumio dbus-daemon[518]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.22' (uid=0 pid=2196 comm="/usr/bin/timedatectl set-timezone Europe/Sofia ") May 01 22:30:46 volumio systemd[1]: Starting Time & Date Service... May 01 22:30:46 volumio dbus-daemon[518]: [system] Successfully activated service 'org.freedesktop.timedate1' May 01 22:30:46 volumio systemd[1]: Started Time & Date Service. May 01 22:30:46 volumio sudo[2195]: pam_unix(sudo:session): session closed for user root May 01 22:30:46 volumio volumio[845]: Updating browse sources language May 01 22:30:46 volumio volumio[845]: Cannot find translation for source YouTube2 May 01 22:30:46 volumio volumio[845]: Cannot find translation for source 80s80s Radio May 01 22:30:46 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 22:30:46 volumio volumio[845]: Cannot find translation for source YouTube2 May 01 22:30:46 volumio volumio[845]: Cannot find translation for source 80s80s Radio May 01 22:30:46 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 01 22:30:46 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 01 22:30:46 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 01 22:30:46 volumio sudo[2199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 01 22:30:46 volumio sudo[2199]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:46 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 01 22:30:46 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 01 22:30:47 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 01 22:30:50 volumio volumio-remote-updater[491]: No test mode May 01 22:30:50 volumio volumio-remote-updater[491]: No alpha test mode May 01 22:30:50 volumio volumio[845]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 01 22:30:50 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 01 22:30:50 volumio sudo[2199]: pam_unix(sudo:session): session closed for user root May 01 22:30:50 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings May 01 22:30:50 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices May 01 22:30:50 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 01 22:30:50 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 22:30:50 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 01 22:30:54 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 01 22:30:54 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 01 22:30:54 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 01 22:30:54 volumio volumio[845]: info: Setting default mixerSoftMaster for Softvolume device May 01 22:30:54 volumio volumio[845]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 01 22:30:54 volumio volumio[845]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 01 22:30:54 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 01 22:30:54 volumio volumio[845]: info: Disabling external Volume Control May 01 22:30:54 volumio volumio[845]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 22:30:54 volumio volumio[845]: info: Enable softmixer device for audio device undefined May 01 22:30:54 volumio volumio[845]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 22:30:54 volumio volumio[845]: info: Software Volume ALSA configuration written May 01 22:30:54 volumio volumio[845]: info: Preparing to generate the ALSA configuration file May 01 22:30:54 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 01 22:30:54 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 22:30:54 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 01 22:30:54 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 22:30:54 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 22:30:54 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 22:30:54 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 22:30:54 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 22:30:54 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 22:30:54 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 22:30:54 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 01 22:30:54 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 01 22:30:54 volumio volumio[845]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 01 22:30:54 volumio volumio[845]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf May 01 22:30:54 volumio volumio[845]: info: Reading ALSA contributions from plugins. May 01 22:30:54 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:30:54 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:54 volumio volumio[845]: info: CoreStateMachine::pushState May 01 22:30:54 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:54 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 22:30:54 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 01 22:30:54 volumio volumio[845]: info: peppyspectrum status play May 01 22:30:54 volumio volumio[845]: info: Asound.conf file unchanged, so no further update is needed May 01 22:30:54 volumio volumio[845]: info: Output device has changed, restarting MPD May 01 22:30:54 volumio sudo[2226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 01 22:30:54 volumio sudo[2226]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:55 volumio volumio[845]: info: Output device has changed, restarting Shairport Sync May 01 22:30:55 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:55 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 22:30:55 volumio sudo[2231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 01 22:30:55 volumio sudo[2231]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:55 volumio systemd[1]: Started peppyspectrum Daemon. May 01 22:30:55 volumio sudo[2231]: pam_unix(sudo:session): session closed for user root May 01 22:30:55 volumio sudo[2232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 01 22:30:55 volumio sudo[2232]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:55 volumio sudo[2226]: pam_unix(sudo:session): session closed for user root May 01 22:30:55 volumio systemd[1]: Stopping Music Player Daemon... May 01 22:30:55 volumio volumio[845]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 01 22:30:55 volumio volumio[845]: info: MPD Permissions set May 01 22:30:55 volumio volumio[845]: info: peppyspectrum Daemon Started May 01 22:30:55 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:55 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:55 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:55 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:55 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:55 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:55 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:55 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:55 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 22:30:55 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:55 volumio volumio[845]: info: Starting Shairport Sync May 01 22:30:55 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:30:55 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:55 volumio sudo[2245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 01 22:30:55 volumio sudo[2245]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:55 volumio systemd[1]: mpd.service: Succeeded. May 01 22:30:55 volumio systemd[1]: Stopped Music Player Daemon. May 01 22:30:55 volumio systemd[1]: Starting Music Player Daemon... May 01 22:30:55 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 01 22:30:55 volumio systemd[1]: shairport-sync.service: Succeeded. May 01 22:30:55 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 01 22:30:55 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 01 22:30:55 volumio sudo[2245]: pam_unix(sudo:session): session closed for user root May 01 22:30:55 volumio volumio[845]: info: Shairport-Sync Started May 01 22:30:55 volumio volumio[2229]: pygame 1.9.4.post1 May 01 22:30:55 volumio volumio[2229]: Hello from the pygame community. https://www.pygame.org/contribute.html May 01 22:30:55 volumio volumio[2229]: Traceback (most recent call last): May 01 22:30:55 volumio volumio[2229]: File "spectrum.py", line 30, in May 01 22:30:55 volumio volumio[2229]: from spectrumutil import SpectrumUtil May 01 22:30:55 volumio volumio[2229]: File "/data/plugins/user_interface/peppyspectrum/PeppySpectrum/spectrumutil.py", line 19, in May 01 22:30:55 volumio volumio[2229]: from PIL import Image May 01 22:30:55 volumio volumio[2229]: ModuleNotFoundError: No module named 'PIL' May 01 22:30:55 volumio volumio[845]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 01 22:30:55 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 01 22:30:55 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE May 01 22:30:55 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. May 01 22:30:55 volumio mpd[2247]: May 01 22:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 01 22:30:55 volumio systemd[1]: Started Music Player Daemon. May 01 22:30:55 volumio sudo[2232]: pam_unix(sudo:session): session closed for user root May 01 22:30:55 volumio volumio[845]: error: updateQueue error: null May 01 22:30:56 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 01 22:30:56 volumio volumio[845]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 22:30:57 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 01 22:30:57 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation May 01 22:30:57 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware May 01 22:30:57 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray May 01 22:30:57 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage May 01 22:30:57 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware May 01 22:30:58 volumio volumio[845]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 22:30:58 volumio volumio[845]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 01 22:30:58 volumio volumio[845]: info: CoreStateMachine::getcurrentVolume May 01 22:30:58 volumio volumio[845]: info: CoreCommandRouter::volumioRetrievevolume May 01 22:30:58 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:30:58 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:58 volumio volumio[845]: info: CoreStateMachine::pushState May 01 22:30:58 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:58 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 22:30:58 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 01 22:30:58 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock May 01 22:30:58 volumio volumio[845]: info: CorePlayQueue::getTrackBlock May 01 22:30:58 volumio volumio[845]: info: CoreCommandRouter::volumioRetrievevolume May 01 22:30:58 volumio volumio[845]: info: peppyspectrum status play May 01 22:30:58 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:30:58 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:58 volumio volumio[845]: info: CoreStateMachine::pushState May 01 22:30:58 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:30:58 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 01 22:30:58 volumio sudo[2310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 01 22:30:58 volumio sudo[2310]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:58 volumio volumio[845]: info: peppyspectrum status play May 01 22:30:58 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction May 01 22:30:58 volumio sudo[2314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 01 22:30:58 volumio sudo[2314]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:58 volumio volumio[845]: info: CoreCommandRouter::Reload Ui May 01 22:30:58 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard May 01 22:30:58 volumio volumio[845]: info: Wizard terminated Successfully May 01 22:30:58 volumio volumio[845]: info: CoreCommandRouter::volumiosetStartupVolume May 01 22:30:58 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 22:30:58 volumio systemd[1]: Started peppyspectrum Daemon. May 01 22:30:58 volumio sudo[2310]: pam_unix(sudo:session): session closed for user root May 01 22:30:58 volumio volumio[845]: info: peppyspectrum Daemon Started May 01 22:30:58 volumio sudo[2314]: pam_unix(sudo:session): session closed for user root May 01 22:30:58 volumio volumio[845]: info: peppyspectrum Daemon Started May 01 22:30:59 volumio volumio[2313]: pygame 1.9.4.post1 May 01 22:30:59 volumio volumio[2313]: Hello from the pygame community. https://www.pygame.org/contribute.html May 01 22:30:59 volumio volumio[2313]: Traceback (most recent call last): May 01 22:30:59 volumio volumio[2313]: File "spectrum.py", line 30, in May 01 22:30:59 volumio volumio[2313]: from spectrumutil import SpectrumUtil May 01 22:30:59 volumio volumio[2313]: File "/data/plugins/user_interface/peppyspectrum/PeppySpectrum/spectrumutil.py", line 19, in May 01 22:30:59 volumio volumio[2313]: from PIL import Image May 01 22:30:59 volumio volumio[2313]: ModuleNotFoundError: No module named 'PIL' May 01 22:30:59 volumio sudo[2320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 01 22:30:59 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE May 01 22:30:59 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. May 01 22:30:59 volumio sudo[2320]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:59 volumio sudo[2320]: pam_unix(sudo:session): session closed for user root May 01 22:30:59 volumio sudo[2323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 22:30:59 volumio sudo[2323]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:59 volumio sudo[2323]: pam_unix(sudo:session): session closed for user root May 01 22:30:59 volumio volumio[845]: verbose: New Socket.io Connection to 192.168.1.174 from 192.168.1.100 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 8 May 01 22:30:59 volumio sudo[2326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 01 22:30:59 volumio sudo[2326]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:59 volumio sudo[2326]: pam_unix(sudo:session): session closed for user root May 01 22:30:59 volumio sudo[2329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 22:30:59 volumio sudo[2329]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:30:59 volumio sudo[2329]: pam_unix(sudo:session): session closed for user root May 01 22:30:59 volumio volumio[845]: verbose: New Socket.io Connection to 192.168.1.174 from 192.168.1.100 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 9 May 01 22:31:00 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 22:31:00 volumio volumio[845]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 10 May 01 22:31:00 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 01 22:31:00 volumio volumio[845]: info: CoreCommandRouter::volumioGetVisibleSources May 01 22:31:00 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 22:31:00 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:31:00 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:31:00 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 01 22:31:00 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 01 22:31:00 volumio volumio[845]: info: Received Get System Info May 01 22:31:00 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 22:31:00 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 22:31:00 volumio volumio[845]: info: Discovery: Getting this device information May 01 22:31:00 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:31:00 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:31:00 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 22:31:00 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:31:00 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:31:00 volumio volumio[845]: info: Listing playlists May 01 22:31:00 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 01 22:31:00 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 01 22:31:01 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:31:01 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:31:01 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 01 22:31:01 volumio volumio[845]: info: Received Get System Info May 01 22:31:01 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 22:31:01 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 22:31:01 volumio volumio[845]: info: Discovery: Getting this device information May 01 22:31:01 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:31:01 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:31:01 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 22:31:02 volumio volumio[845]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 11 May 01 22:31:02 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 01 22:31:02 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 22:31:02 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 01 22:31:02 volumio volumio[845]: info: CoreCommandRouter::volumioGetVisibleSources May 01 22:31:02 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 22:31:02 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:31:02 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:31:02 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 01 22:31:02 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 01 22:31:02 volumio volumio[845]: info: Received Get System Info May 01 22:31:02 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 22:31:02 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 22:31:02 volumio volumio[845]: info: Discovery: Getting this device information May 01 22:31:02 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:31:02 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:31:02 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 22:31:02 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:31:02 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:31:02 volumio volumio[845]: info: Listing playlists May 01 22:31:02 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 01 22:31:02 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 01 22:31:02 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 01 22:31:02 volumio volumio[845]: info: Received Get System Info May 01 22:31:02 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 22:31:02 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 22:31:02 volumio volumio[845]: info: Discovery: Getting this device information May 01 22:31:02 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:31:02 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:31:02 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 22:31:03 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:31:03 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:31:05 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 01 22:31:05 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 01 22:31:05 volumio volumio[845]: info: Received Get System Info May 01 22:31:05 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 22:31:05 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 22:31:05 volumio volumio[845]: info: Discovery: Getting this device information May 01 22:31:05 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:31:05 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:31:05 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 22:31:05 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 01 22:31:05 volumio volumio[845]: info: Received Get System Info May 01 22:31:05 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 22:31:05 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 22:31:05 volumio volumio[845]: info: Discovery: Getting this device information May 01 22:31:05 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:31:05 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:31:05 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 22:31:06 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 01 22:31:06 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 01 22:31:08 volumio volumio[845]: info: CoreCommandRouter::volumioPause May 01 22:31:08 volumio volumio[845]: info: CoreStateMachine::pause May 01 22:31:08 volumio volumio[845]: info: CoreStateMachine::stPlaybackTimer May 01 22:31:08 volumio volumio[845]: info: CoreStateMachine::servicePause May 01 22:31:08 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:31:08 volumio volumio[845]: info: CoreCommandRouter::servicePause May 01 22:31:08 volumio volumio[845]: info: ControllerMpd::pause May 01 22:31:08 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand pause May 01 22:31:08 volumio volumio[845]: info: sendMpdCommand pause took 1 milliseconds May 01 22:31:10 volumio volumio[845]: info: CoreCommandRouter::volumioGetQueue May 01 22:31:10 volumio volumio[845]: info: CoreStateMachine::getQueue May 01 22:31:10 volumio volumio[845]: info: CorePlayQueue::getQueue May 01 22:31:13 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 01 22:31:13 volumio volumio[845]: info: CURURI: music-library May 01 22:31:13 volumio volumio[845]: info: Preload queue cleared May 01 22:31:15 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 01 22:31:15 volumio volumio[845]: info: CURURI: music-library/NAS May 01 22:31:15 volumio volumio[845]: info: Preload queue cleared May 01 22:31:16 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 01 22:31:16 volumio volumio[845]: info: CURURI: music-library/NAS/PC May 01 22:31:16 volumio volumio[845]: info: Preload queue cleared May 01 22:31:16 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 01 22:31:16 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 01 22:31:16 volumio systemd[1]: systemd-timedated.service: Succeeded. May 01 22:31:17 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 01 22:31:17 volumio volumio[845]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 01 22:31:17 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 01 22:31:17 volumio volumio[845]: info: Received Get System Version May 01 22:31:17 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 22:31:17 volumio volumio[845]: info: Received Get System Info May 01 22:31:17 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 22:31:17 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 22:31:17 volumio volumio[845]: info: Discovery: Getting this device information May 01 22:31:17 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:31:17 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:31:17 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 22:31:20 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 01 22:31:20 volumio volumio[845]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 01 22:31:20 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 01 22:31:20 volumio volumio[845]: info: Received Get System Version May 01 22:31:20 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 22:31:20 volumio volumio[845]: info: Received Get System Info May 01 22:31:20 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 22:31:20 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 22:31:20 volumio volumio[845]: info: Discovery: Getting this device information May 01 22:31:20 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 01 22:31:20 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:31:20 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 22:31:24 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 01 22:31:24 volumio volumio[845]: info: CURURI: music-library/NAS/PC/DSD May 01 22:31:24 volumio volumio[845]: info: Preload queue cleared May 01 22:31:24 volumio volumio[845]: info: Preloading song: music-library/NAS/PC/DSD/01 - David Elias - The Window - Vision of Her (DSD64).dsf May 01 22:31:24 volumio volumio[845]: info: Preloading song: music-library/NAS/PC/DSD/07 - David Elias - Acoustic Trio - Rodeo On A Ridge (DSD64).dsf May 01 22:31:24 volumio volumio[845]: info: Preloading song: music-library/NAS/PC/DSD/08 - David Elias - Crossing - Morning Light Western Town (DSD64 2.0).dsf May 01 22:31:24 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/01 - David Elias - The Window - Vision of Her (DSD64).dsf in service mpd May 01 22:31:24 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/07 - David Elias - Acoustic Trio - Rodeo On A Ridge (DSD64).dsf in service mpd May 01 22:31:24 volumio volumio[845]: info: ALBUMART /albumart?cacheid=379&web=David%20Elias//extralarge&path=%2Fmnt%2FNAS%2FPC%2FDSD%2F01%20-%20David%20Elias%20-%20The%20Window%20-%20Vision%20of%20Her%20(DSD64).dsf&metadata=false May 01 22:31:24 volumio volumio[845]: info: URI /mnt/NAS/PC/DSD/01 - David Elias - The Window - Vision of Her (DSD64).dsf May 01 22:31:24 volumio volumio[845]: info: ALBUMART /albumart?cacheid=379&web=David%20Elias/Acoustic%20Trio%20-%20DSD%20Sessions/extralarge&path=%2Fmnt%2FNAS%2FPC%2FDSD%2F07%20-%20David%20Elias%20-%20Acoustic%20Trio%20-%20Rodeo%20On%20A%20Ridge%20(DSD64).dsf&metadata=false May 01 22:31:24 volumio volumio[845]: info: URI /mnt/NAS/PC/DSD/07 - David Elias - Acoustic Trio - Rodeo On A Ridge (DSD64).dsf May 01 22:31:24 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/08 - David Elias - Crossing - Morning Light Western Town (DSD64 2.0).dsf in service mpd May 01 22:31:24 volumio volumio[845]: info: ALBUMART /albumart?cacheid=379&web=David%20Elias/Crossing/extralarge&path=%2Fmnt%2FNAS%2FPC%2FDSD%2F08%20-%20David%20Elias%20-%20Crossing%20-%20Morning%20Light%20Western%20Town%20(DSD64%202.0).dsf&metadata=false May 01 22:31:24 volumio volumio[845]: info: URI /mnt/NAS/PC/DSD/08 - David Elias - Crossing - Morning Light Western Town (DSD64 2.0).dsf May 01 22:31:28 volumio volumio[845]: info: Preload queue cleared May 01 22:31:28 volumio volumio[845]: info: CoreCommandRouter::volumioReplaceandPlayItems May 01 22:31:28 volumio volumio[845]: info: CoreStateMachine::ClearQueue May 01 22:31:28 volumio volumio[845]: info: CoreStateMachine::stop May 01 22:31:28 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 22:31:28 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock May 01 22:31:28 volumio volumio[845]: info: CorePlayQueue::getTrackBlock May 01 22:31:28 volumio volumio[845]: info: CoreStateMachine::stPlaybackTimer May 01 22:31:28 volumio volumio[845]: info: CoreStateMachine::pushState May 01 22:31:28 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:31:28 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 22:31:28 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 01 22:31:28 volumio volumio[845]: info: CoreStateMachine::serviceStop May 01 22:31:28 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 01 22:31:28 volumio volumio[845]: info: CoreCommandRouter::serviceStop May 01 22:31:28 volumio volumio[845]: info: ControllerMpd::stop May 01 22:31:28 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop May 01 22:31:28 volumio volumio[845]: info: CorePlayQueue::clearPlayQueue May 01 22:31:28 volumio volumio[845]: info: CorePlayQueue::saveQueue May 01 22:31:28 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 01 22:31:28 volumio volumio[845]: info: CoreStateMachine::addQueueItems May 01 22:31:28 volumio volumio[845]: info: CorePlayQueue::addQueueItems May 01 22:31:28 volumio volumio[845]: info: Preload queue cleared May 01 22:31:28 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/01 - David Elias - The Window - Vision of Her (DSD64).dsf May 01 22:31:28 volumio volumio[845]: info: Using cached record of: music-library/NAS/PC/DSD/01 - David Elias - The Window - Vision of Her (DSD64).dsf May 01 22:31:28 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/07 - David Elias - Acoustic Trio - Rodeo On A Ridge (DSD64).dsf May 01 22:31:28 volumio volumio[845]: info: Using cached record of: music-library/NAS/PC/DSD/07 - David Elias - Acoustic Trio - Rodeo On A Ridge (DSD64).dsf May 01 22:31:28 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/08 - David Elias - Crossing - Morning Light Western Town (DSD64 2.0).dsf May 01 22:31:28 volumio volumio[845]: info: Using cached record of: music-library/NAS/PC/DSD/08 - David Elias - Crossing - Morning Light Western Town (DSD64 2.0).dsf May 01 22:31:28 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/ABBA - Super Trouper (1980, Sweden 1st, DSD_Stig) May 01 22:31:28 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/ABBA - Super Trouper (1980, Sweden 1st, DSD_Stig) in service mpd May 01 22:31:28 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/Anming Piano Songs - In A Dream While Listening [Mora DSF DSD 1bit2.8MHz] May 01 22:31:28 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/Anming Piano Songs - In A Dream While Listening [Mora DSF DSD 1bit2.8MHz] in service mpd May 01 22:31:28 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/Arne Domnerus - Jazz At The Pawnshop Late Night - New Unreleased Tapes (2015) [DSD256] May 01 22:31:28 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/Arne Domnerus - Jazz At The Pawnshop Late Night - New Unreleased Tapes (2015) [DSD256] in service mpd May 01 22:31:28 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/Audiophile Hi-Res System Test [DSD 128] May 01 22:31:28 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/Audiophile Hi-Res System Test [DSD 128] in service mpd May 01 22:31:28 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/Audiophile Speaker Set-Up - Great Sampling Tracks Included [DSD128] May 01 22:31:28 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/Audiophile Speaker Set-Up - Great Sampling Tracks Included [DSD128] in service mpd May 01 22:31:28 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/Bad Boys Blue - Heartbeat - 1986,(Germany),DSF(tracks),(ART-9XA+KIV+SAEC-308+TT-81+K-10R) May 01 22:31:28 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/Bad Boys Blue - Heartbeat - 1986,(Germany),DSF(tracks),(ART-9XA+KIV+SAEC-308+TT-81+K-10R) in service mpd May 01 22:31:29 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/Bad Boys Blue - Hot Girls, Bad Boys - 1985,(Germany),DSF(tracks),(ART-9XA+KIV+SAEC-308+TT-81+K-10R) May 01 22:31:29 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/Bad Boys Blue - Hot Girls, Bad Boys - 1985,(Germany),DSF(tracks),(ART-9XA+KIV+SAEC-308+TT-81+K-10R) in service mpd May 01 22:31:29 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/Bad Boys Blue - Love Is No Crime - 1987,(Germany),DSF(tracks),(ART-9XA+KIV+SAEC-308+TT-81+K-10R) May 01 22:31:29 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/Bad Boys Blue - Love Is No Crime - 1987,(Germany),DSF(tracks),(ART-9XA+KIV+SAEC-308+TT-81+K-10R) in service mpd May 01 22:31:29 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/C.C. Catch - Like A Hurricane - 1987,(Germany),DSF(tracks),(ART-9XA+KIV+SAEC-308+TT-81+K-10R) May 01 22:31:29 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/C.C. Catch - Like A Hurricane - 1987,(Germany),DSF(tracks),(ART-9XA+KIV+SAEC-308+TT-81+K-10R) in service mpd May 01 22:31:29 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/Chris Rea - The Road To Hell - 1989,(Germany),DSF(tracks),(SPU+Verto+MF(34)+UA-7082+TT-81+K-MR-2000S) May 01 22:31:29 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/Chris Rea - The Road To Hell - 1989,(Germany),DSF(tracks),(SPU+Verto+MF(34)+UA-7082+TT-81+K-MR-2000S) in service mpd May 01 22:31:29 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/Dan Wilson - Vessels of Wood and Earth (2021) [DSD256] May 01 22:31:29 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/Dan Wilson - Vessels of Wood and Earth (2021) [DSD256] in service mpd May 01 22:31:29 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/Dr Alban Hello Afrika The Album May 01 22:31:29 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/Dr Alban Hello Afrika The Album in service mpd May 01 22:31:30 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/Enigma 2018 DSD May 01 22:31:30 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/Enigma 2018 DSD in service mpd May 01 22:31:30 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/Flac to dsd May 01 22:31:30 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/Flac to dsd in service mpd May 01 22:31:30 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/Hollywood Movie Works - Best Soundtrack (All Time Best) [DSD] (2017) May 01 22:31:30 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/Hollywood Movie Works - Best Soundtrack (All Time Best) [DSD] (2017) in service mpd May 01 22:31:30 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/Ice MC May 01 22:31:30 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/Ice MC in service mpd May 01 22:31:30 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/Jazz At The Pawnshop - 812864019711 May 01 22:31:30 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/Jazz At The Pawnshop - 812864019711 in service mpd May 01 22:31:31 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/Jazz-at-the-Pawnshop May 01 22:31:31 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/Jazz-at-the-Pawnshop in service mpd May 01 22:31:31 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/Jean Michel Jarre May 01 22:31:31 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/Jean Michel Jarre in service mpd May 01 22:31:31 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/Jean Michel Jarre - Equinoxe Stig DSD May 01 22:31:31 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/Jean Michel Jarre - Equinoxe Stig DSD in service mpd May 01 22:31:31 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/Jean-Michel Jarre - Oxygene DSD May 01 22:31:31 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/Jean-Michel Jarre - Oxygene DSD in service mpd May 01 22:31:31 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/Jean-Michel Jarre-Magnetic Fields May 01 22:31:31 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/Jean-Michel Jarre-Magnetic Fields in service mpd May 01 22:31:32 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/Joe Dassin - Et Si Tu N'existais Pas (1975) May 01 22:31:32 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/Joe Dassin - Et Si Tu N'existais Pas (1975) in service mpd May 01 22:31:32 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/Lenny Kravitz - Lenny Kravitz - Greatest Hits May 01 22:31:32 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/Lenny Kravitz - Lenny Kravitz - Greatest Hits in service mpd May 01 22:31:32 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/LP Francesco Napoli - Balla..Balla! - 1987 May 01 22:31:32 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/LP Francesco Napoli - Balla..Balla! - 1987 in service mpd May 01 22:31:32 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/PC/DSD/Manowar Kings Of Metal May 01 22:31:32 volumio volumio[845]: info: Exploding uri music-library/NAS/PC/DSD/Manowar Kings Of Metal in service mpd May 01 22:31:32 volumio volumio[845]: info: peppyspectrum status stop May 01 22:31:32 volumio volumio[845]: info: ALBUMART /albumart?cacheid=379&web=ABBA/Super%20Trouper/extralarge&path=%2Fmnt%2FNAS%2FPC%2FDSD%2FABBA%20-%20Super%20Trouper%20(1980%2C%20Sweden%201st%2C%20DSD_Stig)%2FA1%20-%20Super%20Trouper.dsf&metadata=false May 01 22:31:32 volumio volumio[845]: info: URI /mnt/NAS/PC/DSD/ABBA - Super Trouper (1980, Sweden 1st, DSD_Stig)/A1 - Super Trouper.dsf May 01 22:31:32 volumio volumio[845]: info: ALBUMART /albumart?cacheid=379&web=ABBA/Super%20Trouper/extralarge&path=%2Fmnt%2FNAS%2FPC%2FDSD%2FABBA%20-%20Super%20Trouper%20(1980%2C%20Sweden%201st%2C%20DSD_Stig)%2FA2%20-%20The%20Winner%20Takes%20It%20All.dsf&metadata=false May 01 22:31:32 volumio volumio[845]: info: URI /mnt/NAS/PC/DSD/ABBA - Super Trouper (1980, Sweden 1st, DSD_Stig)/A2 - The Winner Takes It All.dsf May 01 22:31:32 volumio volumio[845]: info: ALBUMART /albumart?cacheid=379&web=ABBA/Super%20Trouper/extralarge&path=%2Fmnt%2FNAS%2FPC%2FDSD%2FABBA%20-%20Super%20Trouper%20(1980%2C%20Sweden%201st%2C%20DSD_Stig)%2FA3%20-%20On%20And%20On%20And%20On.dsf&metadata=false May 01 22:31:32 volumio volumio[845]: info: URI /mnt/NAS/PC/DSD/ABBA - Super Trouper (1980, Sweden 1st, DSD_Stig)/A3 - On And On And On.dsf May 01 22:31:32 volumio volumio[845]: info: ALBUMART /albumart?cacheid=379&web=ABBA/Super%20Trouper/extralarge&path=%2Fmnt%2FNAS%2FPC%2FDSD%2FABBA%20-%20Super%20Trouper%20(1980%2C%20Sweden%201st%2C%20DSD_Stig)%2FA4%20-%20Andante%2C%20Andante.dsf&metadata=false May 01 22:31:32 volumio volumio[845]: info: URI /mnt/NAS/PC/DSD/ABBA - Super Trouper (1980, Sweden 1st, DSD_Stig)/A4 - Andante, Andante.dsf May 01 22:31:32 volumio volumio[845]: info: ALBUMART /albumart?cacheid=379&web=ABBA/Super%20Trouper/extralarge&path=%2Fmnt%2FNAS%2FPC%2FDSD%2FABBA%20-%20Super%20Trouper%20(1980%2C%20Sweden%201st%2C%20DSD_Stig)%2FA5%20-%20Me%20And%20I.dsf&metadata=false May 01 22:31:32 volumio volumio[845]: info: URI /mnt/NAS/PC/DSD/ABBA - Super Trouper (1980, Sweden 1st, DSD_Stig)/A5 - Me And I.dsf May 01 22:31:32 volumio volumio[845]: info: ALBUMART /albumart?cacheid=379&web=ABBA/Super%20Trouper/extralarge&path=%2Fmnt%2FNAS%2FPC%2FDSD%2FABBA%20-%20Super%20Trouper%20(1980%2C%20Sweden%201st%2C%20DSD_Stig)%2FB1%20-%20Happy%20New%20Year.dsf&metadata=false May 01 22:31:32 volumio volumio[845]: info: URI /mnt/NAS/PC/DSD/ABBA - Super Trouper (1980, Sweden 1st, DSD_Stig)/B1 - Happy New Year.dsf May 01 22:31:32 volumio volumio[845]: info: ALBUMART /albumart?cacheid=379&web=ABBA/Super%20Trouper/extralarge&path=%2Fmnt%2FNAS%2FPC%2FDSD%2FABBA%20-%20Super%20Trouper%20(1980%2C%20Sweden%201st%2C%20DSD_Stig)%2FB2%20-%20Our%20Last%20Summer.dsf&metadata=false May 01 22:31:32 volumio volumio[845]: info: URI /mnt/NAS/PC/DSD/ABBA - Super Trouper (1980, Sweden 1st, DSD_Stig)/B2 - Our Last Summer.dsf May 01 22:31:32 volumio volumio[845]: info: ALBUMART /albumart?cacheid=379&web=ABBA/Super%20Trouper/extralarge&path=%2Fmnt%2FNAS%2FPC%2FDSD%2FABBA%20-%20Super%20Trouper%20(1980%2C%20Sweden%201st%2C%20DSD_Stig)%2FB3%20-%20The%20Piper.dsf&metadata=false May 01 22:31:32 volumio volumio[845]: info: URI /mnt/NAS/PC/DSD/ABBA - Super Trouper (1980, Sweden 1st, DSD_Stig)/B3 - The Piper.dsf May 01 22:31:32 volumio volumio[845]: info: ALBUMART /albumart?cacheid=379&web=ABBA/Super%20Trouper/extralarge&path=%2Fmnt%2FNAS%2FPC%2FDSD%2FABBA%20-%20Super%20Trouper%20(1980%2C%20Sweden%201st%2C%20DSD_Stig)%2FB4%20-%20Lay%20All%20Your%20Love%20On%20Me.dsf&metadata=false May 01 22:31:32 volumio volumio[845]: info: URI /mnt/NAS/PC/DSD/ABBA - Super Trouper (1980, Sweden 1st, DSD_Stig)/B4 - Lay All Your Love On Me.dsf May 01 22:31:32 volumio volumio[845]: info: ALBUMART /albumart?cacheid=379&web=ABBA/Super%20Trouper/extralarge&path=%2Fmnt%2FNAS%2FPC%2FDSD%2FABBA%20-%20Super%20Trouper%20(1980%2C%20Sweden%201st%2C%20DSD_Stig)%2FB5%20-%20The%20Way%20Old%20Friends%20Do.dsf&metadata=false May 01 22:31:32 volumio volumio[845]: info: URI /mnt/NAS/PC/DSD/ABBA - Super Trouper (1980, Sweden 1st, DSD_Stig)/B5 - The Way Old Friends Do.dsf May 01 22:31:32 volumio volumio[845]: info: ALBUMART /albumart?cacheid=379&web=ABBA/Super%20Trouper/extralarge&path=%2Fmnt%2FNAS%2FPC%2FDSD%2FABBA%20-%20Super%20Trouper%20(1980%2C%20Sweden%201st%2C%20DSD_Stig)%2FSuper%20Trouper.cue&metadata=false May 01 22:31:32 volumio volumio[845]: info: URI /mnt/NAS/PC/DSD/ABBA - Super Trouper (1980, Sweden 1st, DSD_Stig)/Super Trouper.cue May 01 22:31:32 volumio volumio[845]: info: ALBUMART /albumart?cacheid=379&web=ABBA/Super%20Trouper/extralarge&path=%2Fmnt%2FNAS%2FPC%2FDSD%2FABBA%20-%20Super%20Trouper%20(1980%2C%20Sweden%201st%2C%20DSD_Stig)%2FSuper%20Trouper.cue&metadata=false May 01 22:31:32 volumio volumio[845]: info: URI /mnt/NAS/PC/DSD/ABBA - Super Trouper (1980, Sweden 1st, DSD_Stig)/Super Trouper.cue May 01 22:31:32 volumio volumio[845]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 22:31:32 volumio sudo[2338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 01 22:31:32 volumio sudo[2338]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 22:31:32 volumio volumio[845]: Error: Unable to resolve or reject the same promise twice May 01 22:31:32 volumio volumio[845]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 01 22:31:32 volumio volumio[845]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 May 01 22:31:32 volumio volumio[845]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) May 01 22:31:32 volumio volumio[845]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) May 01 22:31:32 volumio volumio[845]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) May 01 22:31:32 volumio volumio[845]: at Socket.emit (events.js:315:20) May 01 22:31:32 volumio volumio[845]: at addChunk (internal/streams/readable.js:309:12) May 01 22:31:32 volumio volumio[845]: at readableAddChunk (internal/streams/readable.js:280:11) May 01 22:31:32 volumio volumio[845]: at Socket.Readable.push (internal/streams/readable.js:223:10) May 01 22:31:32 volumio volumio[845]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) May 01 22:31:32 volumio volumio[845]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 22:31:32 volumio sudo[2338]: pam_unix(sudo:session): session closed for user root May 01 22:31:32 volumio sudo[2349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-01 22:30 May 01 22:31:32 volumio sudo[2349]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"