-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Tue 2025-06-17 19:20:03 CEST. -- Jun 17 19:19:06 volumio volumio[1217]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 17 19:19:06 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 17 19:19:06 volumio volumio[1217]: info: Preparing to save Alsa Options, stopping services first Jun 17 19:19:06 volumio volumio[1217]: info: CoreCommandRouter::volumioGetState Jun 17 19:19:06 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:06 volumio volumio[1217]: info: CoreCommandRouter::volumioPause Jun 17 19:19:06 volumio volumio[1217]: info: CoreStateMachine::pause Jun 17 19:19:06 volumio volumio[1217]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"I-Sabre Q2M DAC"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jun 17 19:19:06 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 17 19:19:06 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 17 19:19:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 17 19:19:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 17 19:19:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 17 19:19:06 volumio volumio[1217]: info: Setting mixer Digital for card I-Sabre Q2M DAC Jun 17 19:19:06 volumio volumio[1217]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 17 19:19:06 volumio volumio[1217]: info: Updating Volume Controller Parameters: Device: 1 Name: I-Sabre Q2M DAC Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 17 19:19:06 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 17 19:19:06 volumio volumio[1217]: info: Disabling external Volume Control Jun 17 19:19:06 volumio volumio[1217]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 17 19:19:06 volumio volumio[1217]: info: Preparing to generate the ALSA configuration file Jun 17 19:19:06 volumio volumio[1217]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 17 19:19:06 volumio volumio[1217]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 17 19:19:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 17 19:19:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 17 19:19:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 17 19:19:06 volumio volumio[1217]: info: Reading ALSA contributions from plugins. Jun 17 19:19:07 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 17 19:19:07 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 17 19:19:07 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 17 19:19:07 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 17 19:19:07 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 17 19:19:07 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 17 19:19:07 volumio volumio[1217]: info: VolumeController:: Volume=76 Mute =true Jun 17 19:19:07 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:07 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:07 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:07 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:07 volumio sudo[2898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:07 volumio sudo[2898]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:07 volumio sudo[2898]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:07 volumio sudo[2902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:07 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:07 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:07 volumio sudo[2902]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:07 volumio volumio[1217]: info: Asound.conf file written Jun 17 19:19:07 volumio sudo[2902]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:07 volumio sudo[2905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 17 19:19:07 volumio sudo[2905]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:07 volumio sudo[2905]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:07 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 17 19:19:07 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 17 19:19:07 volumio volumio[1217]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Volume:0' : Remote I/O error Jun 17 19:19:07 volumio volumio[1217]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Switch:0' : Remote I/O error Jun 17 19:19:07 volumio volumio[1217]: info: Output device has changed, restarting MPD Jun 17 19:19:07 volumio sudo[2912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 17 19:19:07 volumio sudo[2912]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:07 volumio sudo[2912]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:07 volumio volumio[1217]: info: Output device has changed, restarting Shairport Sync Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 19:19:07 volumio sudo[2915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 17 19:19:07 volumio sudo[2915]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:07 volumio systemd[1]: Stopping Music Player Daemon... Jun 17 19:19:07 volumio volumio[1217]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 19:19:07 volumio volumio[1217]: info: Output device has changed, restarting MPD Jun 17 19:19:07 volumio sudo[2921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 17 19:19:07 volumio sudo[2921]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:07 volumio sudo[2921]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:07 volumio volumio[1217]: info: Output device has changed, restarting Shairport Sync Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 19:19:07 volumio sudo[2924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 17 19:19:07 volumio sudo[2924]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:07 volumio volumio[1217]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 19:19:07 volumio volumio[1217]: info: MPD Permissions set Jun 17 19:19:07 volumio volumio[1217]: info: MPD Permissions set Jun 17 19:19:07 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:07 volumio volumio[1217]: info: Starting Shairport Sync Jun 17 19:19:07 volumio volumio[1217]: info: Starting Shairport Sync Jun 17 19:19:07 volumio sudo[2938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 17 19:19:07 volumio sudo[2938]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:07 volumio systemd[1]: mpd.service: Succeeded. Jun 17 19:19:07 volumio systemd[1]: Stopped Music Player Daemon. Jun 17 19:19:07 volumio sudo[2942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 17 19:19:07 volumio sudo[2942]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:08 volumio systemd[1]: Starting Music Player Daemon... Jun 17 19:19:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 17 19:19:08 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 17 19:19:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 17 19:19:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 17 19:19:08 volumio sudo[2938]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:08 volumio volumio[1217]: info: Shairport-Sync Started Jun 17 19:19:08 volumio sudo[2942]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:08 volumio volumio[1217]: info: Shairport-Sync Started Jun 17 19:19:08 volumio sudo[2941]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 17 19:19:08 volumio sudo[2941]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:08 volumio sudo[2941]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:08 volumio mpd[2948]: Jun 17 19:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 17 19:19:10 volumio volumio[1217]: info: CoreCommandRouter::volumioGetState Jun 17 19:19:10 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:10 volumio systemd[1]: Started Music Player Daemon. Jun 17 19:19:10 volumio sudo[2915]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:10 volumio sudo[2924]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:10 volumio volumio[1217]: error: MPD error: The expression evaluated to a falsy value: Jun 17 19:19:10 volumio volumio[1217]: assert.ok(self.idling) Jun 17 19:19:10 volumio volumio[1217]: error: The expression evaluated to a falsy value: Jun 17 19:19:10 volumio volumio[1217]: assert.ok(self.idling) Jun 17 19:19:10 volumio volumio[1217]: error: updateQueue error: null Jun 17 19:19:10 volumio volumio[1217]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 17 19:19:10 volumio volumio[1217]: info: CoreStateMachine::getcurrentVolume Jun 17 19:19:10 volumio volumio[1217]: info: CoreCommandRouter::volumioRetrievevolume Jun 17 19:19:11 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 17 19:19:11 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 17 19:19:11 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 17 19:19:11 volumio volumio[1217]: info: CoreCommandRouter::volumioPlay Jun 17 19:19:11 volumio volumio[1217]: info: CoreStateMachine::play index undefined Jun 17 19:19:11 volumio volumio[1217]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 19:19:11 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:11 volumio volumio[1217]: info: CoreStateMachine::startPlaybackTimer Jun 17 19:19:11 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:11 volumio volumio[1217]: verbose: ControllerMpd::clearAddPlayTracks NAS/Synology/Testy/01 - Lily Was Here.flac Jun 17 19:19:11 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 19:19:11 volumio volumio[1217]: info: sendMpdCommand stop took 1 milliseconds Jun 17 19:19:11 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clear Jun 17 19:19:11 volumio volumio[1217]: info: Jun 17 19:19:11 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:11 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:11 volumio volumio[1217]: info: sendMpdCommand clear took 1 milliseconds Jun 17 19:19:11 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand add "NAS/Synology/Testy/01 - Lily Was Here.flac" Jun 17 19:19:11 volumio volumio[1217]: info: Jun 17 19:19:11 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:11 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:11 volumio volumio[1217]: error: updateQueue error: null Jun 17 19:19:11 volumio volumio[1217]: info: Jun 17 19:19:11 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:11 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:11 volumio volumio[1217]: info: ------------------------------ 4ms Jun 17 19:19:11 volumio volumio[1217]: info: sendMpdCommand add "NAS/Synology/Testy/01 - Lily Was Here.flac" took 3 milliseconds Jun 17 19:19:11 volumio volumio[1217]: info: ------------------------------ 3ms Jun 17 19:19:11 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand play Jun 17 19:19:11 volumio volumio[1217]: info: Jun 17 19:19:11 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:11 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:11 volumio volumio[1217]: info: ------------------------------ 5ms Jun 17 19:19:11 volumio volumio[1217]: info: sendMpdCommand play took 5 milliseconds Jun 17 19:19:11 volumio volumio[1217]: info: ------------------------------ 4ms Jun 17 19:19:11 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 17 19:19:11 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 17 19:19:11 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 17 19:19:11 volumio volumio[1217]: info: Jun 17 19:19:11 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:11 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:11 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:11 volumio volumio[1217]: info: Jun 17 19:19:11 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:11 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:11 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:11 volumio volumio[1217]: info: Jun 17 19:19:11 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:11 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:11 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:11 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 17 19:19:11 volumio volumio[1217]: info: sendMpdCommand status took 11 milliseconds Jun 17 19:19:11 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:11 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:11 volumio volumio[1217]: info: Jun 17 19:19:11 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:11 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:11 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:11 volumio volumio[1217]: info: VolumeController:: Volume=76 Mute =true Jun 17 19:19:11 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:11 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:11 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:19:11 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:11 volumio volumio[1217]: info: CoreStateMachine::updateTrackBlock Jun 17 19:19:11 volumio volumio[1217]: info: CorePlayQueue::getTrackBlock Jun 17 19:19:11 volumio volumio[1217]: info: CoreCommandRouter::volumioRetrievevolume Jun 17 19:19:11 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 17 19:19:11 volumio volumio[1217]: info: sendMpdCommand status took 91 milliseconds Jun 17 19:19:11 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 17 19:19:11 volumio volumio[1217]: info: sendMpdCommand status took 89 milliseconds Jun 17 19:19:11 volumio volumio[1217]: info: sendMpdCommand clearerror took 83 milliseconds Jun 17 19:19:11 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 81 milliseconds Jun 17 19:19:11 volumio volumio[1217]: info: sendMpdCommand status took 80 milliseconds Jun 17 19:19:11 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 17 19:19:11 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 17 19:19:11 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 17 19:19:11 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:11 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:11 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:11 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:11 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:19:11 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:11 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:11 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:11 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:11 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:11 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Lily Was Here.flac","artist":"Candy Dulfer","album":"The Essential Candy Dulfer","uri":"NAS/Synology/Testy/01 - Lily Was Here.flac","trackType":"flac"} Jun 17 19:19:11 volumio volumio[1217]: verbose: CURRENT POSITION 0 Jun 17 19:19:11 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:11 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:11 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:11 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:11 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:11 volumio volumio[1217]: info: ------------------------------ 137ms Jun 17 19:19:11 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:11 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:11 volumio sudo[2961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:11 volumio sudo[2961]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:11 volumio sudo[2961]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:11 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:11 volumio sudo[2964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:11 volumio sudo[2964]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:11 volumio sudo[2964]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:11 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:11 volumio sudo[2967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:11 volumio sudo[2967]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:11 volumio sudo[2970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:11 volumio sudo[2970]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:11 volumio sudo[2967]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:11 volumio volumio[1217]: info: sendMpdCommand clearerror took 230 milliseconds Jun 17 19:19:11 volumio volumio[1217]: info: sendMpdCommand clearerror took 230 milliseconds Jun 17 19:19:11 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 217 milliseconds Jun 17 19:19:11 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 215 milliseconds Jun 17 19:19:11 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:19:11 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:19:11 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:11 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:11 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:11 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Lily Was Here.flac","artist":"Candy Dulfer","album":"The Essential Candy Dulfer","uri":"NAS/Synology/Testy/01 - Lily Was Here.flac","trackType":"flac"} Jun 17 19:19:11 volumio volumio[1217]: verbose: CURRENT POSITION 0 Jun 17 19:19:11 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:11 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:11 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:11 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:11 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:19:11 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:11 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:11 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:11 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:11 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Lily Was Here.flac","artist":"Candy Dulfer","album":"The Essential Candy Dulfer","uri":"NAS/Synology/Testy/01 - Lily Was Here.flac","trackType":"flac"} Jun 17 19:19:11 volumio volumio[1217]: verbose: CURRENT POSITION 0 Jun 17 19:19:11 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:11 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:11 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:11 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:11 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:11 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:11 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:11 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:11 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Lily Was Here.flac","artist":"Candy Dulfer","album":"The Essential Candy Dulfer","uri":"NAS/Synology/Testy/01 - Lily Was Here.flac","trackType":"flac"} Jun 17 19:19:11 volumio volumio[1217]: verbose: CURRENT POSITION 0 Jun 17 19:19:11 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:11 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:11 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:11 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:11 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:11 volumio sudo[2970]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:11 volumio volumio[1217]: info: ------------------------------ 375ms Jun 17 19:19:11 volumio volumio[1217]: info: ------------------------------ 372ms Jun 17 19:19:11 volumio volumio[1217]: info: ------------------------------ 362ms Jun 17 19:19:11 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:11 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:11 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:11 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:11 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:11 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:11 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 17 19:19:11 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 17 19:19:11 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 17 19:19:11 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:11 volumio sudo[2974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:11 volumio sudo[2974]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:11 volumio sudo[2974]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:11 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:11 volumio sudo[2977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:11 volumio sudo[2977]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:11 volumio sudo[2977]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:11 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:11 volumio sudo[2980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:11 volumio sudo[2980]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:11 volumio sudo[2980]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:11 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:11 volumio sudo[2983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:11 volumio sudo[2983]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:11 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:11 volumio sudo[2983]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:11 volumio sudo[2986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:11 volumio sudo[2986]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:11 volumio sudo[2986]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:11 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:11 volumio sudo[2989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:11 volumio sudo[2989]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:11 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:11 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:11 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:11 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:11 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:11 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:11 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:11 volumio volumio[1217]: info: VolumeController:: Volume=76 Mute =true Jun 17 19:19:11 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:11 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:11 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:19:11 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:11 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:11 volumio sudo[2989]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:11 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:11 volumio sudo[2992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:11 volumio sudo[2992]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:11 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:11 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:11 volumio sudo[2995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:11 volumio sudo[2995]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:11 volumio sudo[2992]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:11 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:11 volumio sudo[2995]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:11 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:11 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:12 volumio volumio[1217]: info: CoreCommandRouter::volumioPlay Jun 17 19:19:12 volumio volumio[1217]: info: CoreStateMachine::play index undefined Jun 17 19:19:12 volumio volumio[1217]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 19:19:12 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:12 volumio volumio[1217]: info: CoreStateMachine::startPlaybackTimer Jun 17 19:19:12 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::clearAddPlayTracks NAS/Synology/Testy/01 - Lily Was Here.flac Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 19:19:12 volumio volumio[1217]: info: Jun 17 19:19:12 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:12 volumio volumio[1217]: info: sendMpdCommand stop took 4 milliseconds Jun 17 19:19:12 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clear Jun 17 19:19:12 volumio volumio[1217]: info: Jun 17 19:19:12 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:12 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:12 volumio volumio[1217]: info: sendMpdCommand status took 3 milliseconds Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:12 volumio volumio[1217]: info: Jun 17 19:19:12 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:12 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:12 volumio volumio[1217]: info: sendMpdCommand clear took 3 milliseconds Jun 17 19:19:12 volumio volumio[1217]: info: sendMpdCommand status took 2 milliseconds Jun 17 19:19:12 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand add "NAS/Synology/Testy/01 - Lily Was Here.flac" Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:19:12 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:12 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:12 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:12 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:12 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:19:12 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:12 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:12 volumio volumio[1217]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 17 19:19:12 volumio volumio[1217]: verbose: CURRENT POSITION 0 Jun 17 19:19:12 volumio volumio[1217]: info: CoreStateMachine::syncState stateService stop Jun 17 19:19:12 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:12 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:12 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:12 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:12 volumio volumio[1217]: info: No code Jun 17 19:19:12 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:12 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:12 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:12 volumio volumio[1217]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 17 19:19:12 volumio volumio[1217]: info: ------------------------------ 40ms Jun 17 19:19:12 volumio volumio[1217]: info: ------------------------------ 41ms Jun 17 19:19:12 volumio volumio[1217]: info: Jun 17 19:19:12 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:12 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:12 volumio volumio[1217]: error: updateQueue error: null Jun 17 19:19:12 volumio volumio[1217]: info: Jun 17 19:19:12 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:12 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:12 volumio volumio[1217]: info: ------------------------------ 43ms Jun 17 19:19:12 volumio volumio[1217]: info: sendMpdCommand add "NAS/Synology/Testy/01 - Lily Was Here.flac" took 43 milliseconds Jun 17 19:19:12 volumio volumio[1217]: info: ------------------------------ 6ms Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand play Jun 17 19:19:12 volumio volumio[1217]: info: Jun 17 19:19:12 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:12 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:12 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:12 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:12 volumio sudo[2998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:12 volumio sudo[2998]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:12 volumio sudo[2998]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:12 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:12 volumio sudo[3001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:12 volumio sudo[3001]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:12 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:12 volumio sudo[3001]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:12 volumio sudo[3006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:12 volumio sudo[3006]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:12 volumio sudo[3006]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:12 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:12 volumio sudo[3010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:12 volumio sudo[3010]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:12 volumio sudo[3010]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:12 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:12 volumio sudo[3013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:12 volumio sudo[3013]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:12 volumio volumio[1217]: info: Jun 17 19:19:12 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:12 volumio volumio[1217]: info: Jun 17 19:19:12 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:12 volumio volumio[1217]: info: ------------------------------ 261ms Jun 17 19:19:12 volumio volumio[1217]: info: sendMpdCommand play took 258 milliseconds Jun 17 19:19:12 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:12 volumio volumio[1217]: info: ------------------------------ 257ms Jun 17 19:19:12 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:12 volumio sudo[3013]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:12 volumio volumio[1217]: info: Jun 17 19:19:12 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:12 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:12 volumio sudo[3016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:12 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:12 volumio sudo[3016]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:12 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:12 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:12 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:12 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:12 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:12 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 17 19:19:12 volumio volumio[1217]: info: sendMpdCommand status took 24 milliseconds Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 17 19:19:12 volumio volumio[1217]: info: sendMpdCommand status took 23 milliseconds Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 17 19:19:12 volumio volumio[1217]: info: sendMpdCommand status took 23 milliseconds Jun 17 19:19:12 volumio sudo[3016]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:12 volumio volumio[1217]: info: Jun 17 19:19:12 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:12 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:12 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:12 volumio volumio[1217]: info: sendMpdCommand clearerror took 24 milliseconds Jun 17 19:19:12 volumio volumio[1217]: info: sendMpdCommand clearerror took 24 milliseconds Jun 17 19:19:12 volumio volumio[1217]: info: sendMpdCommand clearerror took 22 milliseconds Jun 17 19:19:12 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 17 19:19:12 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 17 19:19:12 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 17 19:19:12 volumio volumio[1217]: info: sendMpdCommand status took 5 milliseconds Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:12 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:12 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:12 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:12 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Lily Was Here.flac","artist":"Candy Dulfer","album":"The Essential Candy Dulfer","uri":"NAS/Synology/Testy/01 - Lily Was Here.flac","trackType":"flac"} Jun 17 19:19:12 volumio volumio[1217]: verbose: CURRENT POSITION 0 Jun 17 19:19:12 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:12 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:12 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:12 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:12 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:19:12 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:12 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:12 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:12 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:12 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Lily Was Here.flac","artist":"Candy Dulfer","album":"The Essential Candy Dulfer","uri":"NAS/Synology/Testy/01 - Lily Was Here.flac","trackType":"flac"} Jun 17 19:19:12 volumio volumio[1217]: verbose: CURRENT POSITION 0 Jun 17 19:19:12 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:12 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:12 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:12 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:12 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:12 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:12 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:12 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:12 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Lily Was Here.flac","artist":"Candy Dulfer","album":"The Essential Candy Dulfer","uri":"NAS/Synology/Testy/01 - Lily Was Here.flac","trackType":"flac"} Jun 17 19:19:12 volumio volumio[1217]: verbose: CURRENT POSITION 0 Jun 17 19:19:12 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:12 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:12 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:12 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:12 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:12 volumio volumio[1217]: info: ------------------------------ 91ms Jun 17 19:19:12 volumio volumio[1217]: info: ------------------------------ 91ms Jun 17 19:19:12 volumio volumio[1217]: info: ------------------------------ 84ms Jun 17 19:19:12 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:12 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:12 volumio sudo[3019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:12 volumio sudo[3019]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:12 volumio sudo[3019]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:12 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:12 volumio sudo[3022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:12 volumio sudo[3022]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:12 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:12 volumio sudo[3022]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:12 volumio sudo[3025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:12 volumio sudo[3025]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:12 volumio sudo[3025]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:12 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:12 volumio sudo[3028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:12 volumio sudo[3028]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:12 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:12 volumio sudo[3028]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:12 volumio sudo[3031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:12 volumio sudo[3031]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:12 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:12 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 272 milliseconds Jun 17 19:19:12 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:19:12 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:12 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:12 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:12 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Lily Was Here.flac","artist":"Candy Dulfer","album":"The Essential Candy Dulfer","uri":"NAS/Synology/Testy/01 - Lily Was Here.flac","trackType":"flac"} Jun 17 19:19:12 volumio volumio[1217]: verbose: CURRENT POSITION 0 Jun 17 19:19:12 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:12 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:12 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:12 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:12 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:19:12 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:12 volumio sudo[3031]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:12 volumio sudo[3034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:12 volumio sudo[3034]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:12 volumio volumio[1217]: info: ------------------------------ 299ms Jun 17 19:19:12 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:12 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:12 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:12 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:12 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:12 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:12 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:12 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:12 volumio sudo[3034]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:12 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:12 volumio sudo[3037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:12 volumio sudo[3037]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:12 volumio sudo[3037]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:12 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:12 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:12 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:12 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:12 volumio sudo[3040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:12 volumio sudo[3040]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:12 volumio sudo[3040]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:12 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:19 volumio volumio[1217]: info: CoreCommandRouter::volumioPlay Jun 17 19:19:19 volumio volumio[1217]: info: CoreStateMachine::play index undefined Jun 17 19:19:19 volumio volumio[1217]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 19:19:19 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:19 volumio volumio[1217]: info: CoreStateMachine::startPlaybackTimer Jun 17 19:19:19 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:19 volumio volumio[1217]: verbose: ControllerMpd::clearAddPlayTracks NAS/Synology/Testy/01 - Lily Was Here.flac Jun 17 19:19:19 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 19:19:19 volumio volumio[1217]: info: Jun 17 19:19:19 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:19 volumio volumio[1217]: info: sendMpdCommand stop took 2 milliseconds Jun 17 19:19:19 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:19 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:19 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clear Jun 17 19:19:19 volumio volumio[1217]: info: Jun 17 19:19:19 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:19 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:19 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:19 volumio volumio[1217]: info: Jun 17 19:19:19 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:19 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:19 volumio volumio[1217]: info: sendMpdCommand status took 4 milliseconds Jun 17 19:19:19 volumio volumio[1217]: info: sendMpdCommand clear took 5 milliseconds Jun 17 19:19:19 volumio volumio[1217]: info: sendMpdCommand status took 3 milliseconds Jun 17 19:19:19 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:19 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:19 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand add "NAS/Synology/Testy/01 - Lily Was Here.flac" Jun 17 19:19:19 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:19 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:19 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:19 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:19 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:19 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:19:19 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:19 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:19 volumio volumio[1217]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 17 19:19:19 volumio volumio[1217]: verbose: CURRENT POSITION 0 Jun 17 19:19:19 volumio volumio[1217]: info: CoreStateMachine::syncState stateService stop Jun 17 19:19:19 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:19 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:19 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:19 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:19 volumio volumio[1217]: info: No code Jun 17 19:19:19 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:19 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:19 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:19 volumio volumio[1217]: info: ------------------------------ 42ms Jun 17 19:19:19 volumio volumio[1217]: info: Jun 17 19:19:19 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:19 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:19 volumio volumio[1217]: error: updateQueue error: null Jun 17 19:19:19 volumio volumio[1217]: info: Jun 17 19:19:19 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:19 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:19 volumio volumio[1217]: info: ------------------------------ 43ms Jun 17 19:19:19 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 43 milliseconds Jun 17 19:19:19 volumio volumio[1217]: info: sendMpdCommand add "NAS/Synology/Testy/01 - Lily Was Here.flac" took 43 milliseconds Jun 17 19:19:19 volumio volumio[1217]: info: ------------------------------ 4ms Jun 17 19:19:19 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:19:19 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand play Jun 17 19:19:19 volumio volumio[1217]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 17 19:19:19 volumio volumio[1217]: info: ------------------------------ 54ms Jun 17 19:19:19 volumio volumio[1217]: info: Jun 17 19:19:19 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:19 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:19 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:19 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:19 volumio sudo[3057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:19 volumio sudo[3057]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:19 volumio sudo[3057]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:19 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:19 volumio sudo[3060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:19 volumio sudo[3060]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:19 volumio sudo[3060]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:19 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:19 volumio sudo[3063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:19 volumio sudo[3063]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:19 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:19 volumio sudo[3063]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:19 volumio sudo[3066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:19 volumio sudo[3066]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:19 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:19 volumio sudo[3069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:19 volumio sudo[3066]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:19 volumio sudo[3069]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:19 volumio volumio[1217]: info: Jun 17 19:19:19 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:19 volumio volumio[1217]: info: Jun 17 19:19:19 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:19 volumio volumio[1217]: info: ------------------------------ 243ms Jun 17 19:19:19 volumio volumio[1217]: info: sendMpdCommand play took 241 milliseconds Jun 17 19:19:19 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:19 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:19 volumio volumio[1217]: info: ------------------------------ 237ms Jun 17 19:19:19 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:19 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:19 volumio volumio[1217]: info: Jun 17 19:19:19 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:19 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:19 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:19 volumio sudo[3069]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:19 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:19 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:19 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:19 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:19 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:19 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:19 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:19 volumio sudo[3072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:19 volumio sudo[3072]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:19 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 17 19:19:19 volumio volumio[1217]: info: sendMpdCommand status took 20 milliseconds Jun 17 19:19:19 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 17 19:19:19 volumio volumio[1217]: info: sendMpdCommand status took 20 milliseconds Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 17 19:19:20 volumio volumio[1217]: info: sendMpdCommand status took 19 milliseconds Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:20 volumio volumio[1217]: info: Jun 17 19:19:20 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:20 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:20 volumio volumio[1217]: info: sendMpdCommand clearerror took 24 milliseconds Jun 17 19:19:20 volumio volumio[1217]: info: sendMpdCommand clearerror took 22 milliseconds Jun 17 19:19:20 volumio volumio[1217]: info: sendMpdCommand clearerror took 21 milliseconds Jun 17 19:19:20 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 17 19:19:20 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 17 19:19:20 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:19:20 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:20 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:20 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:20 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Lily Was Here.flac","artist":"Candy Dulfer","album":"The Essential Candy Dulfer","uri":"NAS/Synology/Testy/01 - Lily Was Here.flac","trackType":"flac"} Jun 17 19:19:20 volumio volumio[1217]: verbose: CURRENT POSITION 0 Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:20 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:20 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:19:20 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:20 volumio sudo[3072]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:20 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:20 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:20 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:20 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Lily Was Here.flac","artist":"Candy Dulfer","album":"The Essential Candy Dulfer","uri":"NAS/Synology/Testy/01 - Lily Was Here.flac","trackType":"flac"} Jun 17 19:19:20 volumio volumio[1217]: verbose: CURRENT POSITION 0 Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:20 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:20 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:20 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:20 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:20 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:20 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Lily Was Here.flac","artist":"Candy Dulfer","album":"The Essential Candy Dulfer","uri":"NAS/Synology/Testy/01 - Lily Was Here.flac","trackType":"flac"} Jun 17 19:19:20 volumio volumio[1217]: verbose: CURRENT POSITION 0 Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:20 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:20 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:20 volumio volumio[1217]: info: ------------------------------ 108ms Jun 17 19:19:20 volumio volumio[1217]: info: ------------------------------ 107ms Jun 17 19:19:20 volumio volumio[1217]: info: ------------------------------ 103ms Jun 17 19:19:20 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:20 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:20 volumio sudo[3075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:20 volumio sudo[3075]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:20 volumio sudo[3075]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:20 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:20 volumio sudo[3078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:20 volumio sudo[3078]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:20 volumio sudo[3078]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:20 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:20 volumio sudo[3081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:20 volumio sudo[3081]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:20 volumio sudo[3081]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:20 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:20 volumio sudo[3084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:20 volumio sudo[3084]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:20 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:20 volumio sudo[3084]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:20 volumio sudo[3087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:20 volumio sudo[3087]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:20 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:20 volumio volumio[1217]: info: sendMpdCommand status took 301 milliseconds Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:20 volumio sudo[3087]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:20 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:20 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:20 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:20 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:20 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:20 volumio sudo[3090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:20 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:20 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:20 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:20 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 17 19:19:20 volumio sudo[3090]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:19:20 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:20 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:20 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:20 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Lily Was Here.flac","artist":"Candy Dulfer","album":"The Essential Candy Dulfer","uri":"NAS/Synology/Testy/01 - Lily Was Here.flac","trackType":"flac"} Jun 17 19:19:20 volumio volumio[1217]: verbose: CURRENT POSITION 0 Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:20 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 17 19:19:20 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:19:20 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:20 volumio volumio[1217]: info: ------------------------------ 326ms Jun 17 19:19:20 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:20 volumio sudo[3090]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:20 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:20 volumio sudo[3093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:20 volumio sudo[3093]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:20 volumio sudo[3093]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:20 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:20 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:20 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:20 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:20 volumio sudo[3096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:20 volumio sudo[3096]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:20 volumio sudo[3096]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:20 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:20 volumio volumio[1217]: info: CoreCommandRouter::volumioNext Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::next Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::stop Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::play index undefined Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 19:19:20 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::startPlaybackTimer Jun 17 19:19:20 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::clearAddPlayTracks NAS/Synology/Testy/01 - Slow Down.flac Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::updateTrackBlock Jun 17 19:19:20 volumio volumio[1217]: info: CorePlayQueue::getTrackBlock Jun 17 19:19:20 volumio volumio[1217]: info: Jun 17 19:19:20 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:20 volumio volumio[1217]: info: sendMpdCommand stop took 3 milliseconds Jun 17 19:19:20 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clear Jun 17 19:19:20 volumio volumio[1217]: info: Jun 17 19:19:20 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:20 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:20 volumio volumio[1217]: info: Jun 17 19:19:20 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:20 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:20 volumio volumio[1217]: info: sendMpdCommand status took 3 milliseconds Jun 17 19:19:20 volumio volumio[1217]: info: sendMpdCommand clear took 3 milliseconds Jun 17 19:19:20 volumio volumio[1217]: info: sendMpdCommand status took 2 milliseconds Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand add "NAS/Synology/Testy/01 - Slow Down.flac" Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:20 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:20 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:20 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:20 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:19:20 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:20 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:20 volumio volumio[1217]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 17 19:19:20 volumio volumio[1217]: verbose: CURRENT POSITION 1 Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::syncState stateService stop Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:20 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:20 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:20 volumio volumio[1217]: info: No code Jun 17 19:19:20 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:20 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:20 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:20 volumio volumio[1217]: info: ------------------------------ 47ms Jun 17 19:19:20 volumio volumio[1217]: info: Jun 17 19:19:20 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:20 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:20 volumio volumio[1217]: error: updateQueue error: null Jun 17 19:19:20 volumio volumio[1217]: info: Jun 17 19:19:20 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:20 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:20 volumio volumio[1217]: info: ------------------------------ 50ms Jun 17 19:19:20 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 49 milliseconds Jun 17 19:19:20 volumio volumio[1217]: info: sendMpdCommand add "NAS/Synology/Testy/01 - Slow Down.flac" took 49 milliseconds Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:19:20 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand play Jun 17 19:19:20 volumio volumio[1217]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 17 19:19:20 volumio volumio[1217]: info: ------------------------------ 59ms Jun 17 19:19:20 volumio volumio[1217]: info: Jun 17 19:19:20 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:20 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:20 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:20 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:20 volumio sudo[3099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:20 volumio sudo[3099]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:21 volumio sudo[3099]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:21 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:21 volumio sudo[3102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:21 volumio sudo[3102]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:21 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:21 volumio sudo[3102]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:21 volumio sudo[3105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:21 volumio sudo[3105]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:21 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:21 volumio sudo[3108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:21 volumio sudo[3105]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:21 volumio sudo[3108]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:21 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:21 volumio sudo[3108]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:21 volumio sudo[3111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:21 volumio sudo[3111]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:21 volumio volumio[1217]: info: Jun 17 19:19:21 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:21 volumio volumio[1217]: info: ------------------------------ 315ms Jun 17 19:19:21 volumio volumio[1217]: info: ------------------------------ 312ms Jun 17 19:19:21 volumio volumio[1217]: info: sendMpdCommand play took 309 milliseconds Jun 17 19:19:21 volumio volumio[1217]: info: ------------------------------ 306ms Jun 17 19:19:21 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:21 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:21 volumio volumio[1217]: info: Jun 17 19:19:21 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:21 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:21 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:21 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:21 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:21 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:21 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:21 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:21 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:21 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:21 volumio volumio[1217]: info: Jun 17 19:19:21 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:21 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:21 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:21 volumio sudo[3114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:21 volumio sudo[3111]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:21 volumio sudo[3114]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:21 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 17 19:19:21 volumio volumio[1217]: info: sendMpdCommand status took 16 milliseconds Jun 17 19:19:21 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 17 19:19:21 volumio volumio[1217]: info: sendMpdCommand status took 15 milliseconds Jun 17 19:19:21 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:21 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:21 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:21 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:21 volumio volumio[1217]: info: Jun 17 19:19:21 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:21 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:21 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:21 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 17 19:19:21 volumio volumio[1217]: info: sendMpdCommand status took 32 milliseconds Jun 17 19:19:21 volumio volumio[1217]: info: sendMpdCommand clearerror took 25 milliseconds Jun 17 19:19:21 volumio volumio[1217]: info: sendMpdCommand clearerror took 25 milliseconds Jun 17 19:19:21 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 17 19:19:21 volumio volumio[1217]: info: sendMpdCommand status took 14 milliseconds Jun 17 19:19:21 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:21 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:21 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:19:21 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:21 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:21 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:21 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:21 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:21 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Slow Down","artist":"Imany","album":"The Shape Of A Borken Heart","uri":"NAS/Synology/Testy/01 - Slow Down.flac","trackType":"flac"} Jun 17 19:19:21 volumio volumio[1217]: verbose: CURRENT POSITION 1 Jun 17 19:19:21 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:21 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:21 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:21 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:21 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:19:21 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:21 volumio sudo[3114]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:21 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:21 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:21 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:21 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Slow Down","artist":"Imany","album":"The Shape Of A Borken Heart","uri":"NAS/Synology/Testy/01 - Slow Down.flac","trackType":"flac"} Jun 17 19:19:21 volumio volumio[1217]: verbose: CURRENT POSITION 1 Jun 17 19:19:21 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:21 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:21 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:21 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:21 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:21 volumio volumio[1217]: info: ------------------------------ 76ms Jun 17 19:19:21 volumio volumio[1217]: info: ------------------------------ 73ms Jun 17 19:19:21 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:21 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:21 volumio sudo[3117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:21 volumio sudo[3117]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:21 volumio sudo[3117]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:21 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:21 volumio sudo[3120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:21 volumio sudo[3120]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:21 volumio sudo[3120]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:21 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:21 volumio sudo[3123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:21 volumio sudo[3123]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:21 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:21 volumio sudo[3123]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:21 volumio volumio[1217]: info: sendMpdCommand clearerror took 196 milliseconds Jun 17 19:19:21 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 193 milliseconds Jun 17 19:19:21 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 192 milliseconds Jun 17 19:19:21 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:19:21 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:19:21 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:21 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:21 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:21 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Slow Down","artist":"Imany","album":"The Shape Of A Borken Heart","uri":"NAS/Synology/Testy/01 - Slow Down.flac","trackType":"flac"} Jun 17 19:19:21 volumio volumio[1217]: verbose: CURRENT POSITION 1 Jun 17 19:19:21 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:21 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:21 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:21 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:21 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:21 volumio sudo[3126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:21 volumio sudo[3126]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:21 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:21 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:21 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:21 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Slow Down","artist":"Imany","album":"The Shape Of A Borken Heart","uri":"NAS/Synology/Testy/01 - Slow Down.flac","trackType":"flac"} Jun 17 19:19:21 volumio volumio[1217]: verbose: CURRENT POSITION 1 Jun 17 19:19:21 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:21 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:21 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:21 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:21 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:21 volumio sudo[3126]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:21 volumio volumio[1217]: info: ------------------------------ 272ms Jun 17 19:19:21 volumio volumio[1217]: info: ------------------------------ 253ms Jun 17 19:19:21 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:21 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:21 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:21 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:21 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:21 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:21 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:21 volumio sudo[3129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:21 volumio sudo[3129]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:21 volumio sudo[3129]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:21 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:21 volumio sudo[3132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:21 volumio sudo[3132]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:21 volumio sudo[3132]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:21 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:21 volumio sudo[3135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:21 volumio sudo[3135]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:21 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:21 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:21 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:21 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:21 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:21 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:21 volumio sudo[3135]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:21 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:21 volumio sudo[3138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:21 volumio sudo[3138]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:21 volumio sudo[3138]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:21 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:22 volumio volumio[1217]: info: CoreCommandRouter::volumioPlay Jun 17 19:19:22 volumio volumio[1217]: info: CoreStateMachine::play index undefined Jun 17 19:19:22 volumio volumio[1217]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 19:19:22 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:22 volumio volumio[1217]: info: CoreStateMachine::startPlaybackTimer Jun 17 19:19:22 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::clearAddPlayTracks NAS/Synology/Testy/01 - Slow Down.flac Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 19:19:22 volumio volumio[1217]: info: sendMpdCommand stop took 2 milliseconds Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clear Jun 17 19:19:22 volumio volumio[1217]: info: Jun 17 19:19:22 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:22 volumio volumio[1217]: info: Jun 17 19:19:22 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:22 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:22 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:22 volumio volumio[1217]: info: sendMpdCommand clear took 2 milliseconds Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand add "NAS/Synology/Testy/01 - Slow Down.flac" Jun 17 19:19:22 volumio volumio[1217]: info: Jun 17 19:19:22 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:22 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:22 volumio volumio[1217]: error: updateQueue error: null Jun 17 19:19:22 volumio volumio[1217]: info: Jun 17 19:19:22 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:22 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:22 volumio volumio[1217]: info: sendMpdCommand status took 3 milliseconds Jun 17 19:19:22 volumio volumio[1217]: info: ------------------------------ 4ms Jun 17 19:19:22 volumio volumio[1217]: info: sendMpdCommand add "NAS/Synology/Testy/01 - Slow Down.flac" took 3 milliseconds Jun 17 19:19:22 volumio volumio[1217]: info: sendMpdCommand status took 2 milliseconds Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand play Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:22 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:22 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:22 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:22 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:22 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:19:22 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:22 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:22 volumio volumio[1217]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 17 19:19:22 volumio volumio[1217]: verbose: CURRENT POSITION 1 Jun 17 19:19:22 volumio volumio[1217]: info: CoreStateMachine::syncState stateService stop Jun 17 19:19:22 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:22 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:22 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:22 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:22 volumio volumio[1217]: info: No code Jun 17 19:19:22 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:22 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:22 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:22 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:22 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:22 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:22 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:22 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:22 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:22 volumio volumio[1217]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 17 19:19:22 volumio volumio[1217]: verbose: CURRENT POSITION 1 Jun 17 19:19:22 volumio volumio[1217]: info: CoreStateMachine::syncState stateService stop Jun 17 19:19:22 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:22 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:22 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:22 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:22 volumio volumio[1217]: info: No code Jun 17 19:19:22 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:22 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:22 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:22 volumio volumio[1217]: info: ------------------------------ 83ms Jun 17 19:19:22 volumio volumio[1217]: info: ------------------------------ 82ms Jun 17 19:19:22 volumio volumio[1217]: info: Jun 17 19:19:22 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:22 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:22 volumio volumio[1217]: info: Jun 17 19:19:22 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:22 volumio volumio[1217]: info: Jun 17 19:19:22 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:22 volumio volumio[1217]: info: ------------------------------ 90ms Jun 17 19:19:22 volumio volumio[1217]: info: sendMpdCommand play took 89 milliseconds Jun 17 19:19:22 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:22 volumio volumio[1217]: info: ------------------------------ 9ms Jun 17 19:19:22 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:22 volumio volumio[1217]: info: Jun 17 19:19:22 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:22 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:22 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:22 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:22 volumio sudo[3141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:22 volumio sudo[3141]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:22 volumio sudo[3141]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:22 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:22 volumio sudo[3144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:22 volumio sudo[3144]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:22 volumio sudo[3144]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:22 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:22 volumio sudo[3147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:22 volumio sudo[3147]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:22 volumio sudo[3147]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:22 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:22 volumio sudo[3150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:22 volumio sudo[3150]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:22 volumio sudo[3150]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:22 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:22 volumio sudo[3153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:22 volumio sudo[3153]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:22 volumio sudo[3153]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:22 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:22 volumio sudo[3156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:22 volumio sudo[3156]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:22 volumio sudo[3156]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:22 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:22 volumio sudo[3159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:22 volumio sudo[3159]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:22 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:22 volumio sudo[3159]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:22 volumio sudo[3162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:22 volumio sudo[3162]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:22 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:22 volumio sudo[3162]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:22 volumio sudo[3165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:22 volumio sudo[3165]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:22 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:22 volumio sudo[3168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:22 volumio sudo[3165]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:22 volumio sudo[3168]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:22 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:22 volumio sudo[3168]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:22 volumio sudo[3171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:22 volumio sudo[3171]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:22 volumio sudo[3171]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:22 volumio sudo[3174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:22 volumio sudo[3174]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 17 19:19:22 volumio volumio[1217]: info: sendMpdCommand status took 475 milliseconds Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 17 19:19:22 volumio volumio[1217]: info: sendMpdCommand status took 478 milliseconds Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 17 19:19:22 volumio volumio[1217]: info: sendMpdCommand status took 478 milliseconds Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:22 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:22 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:22 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:22 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:22 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:22 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:22 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:22 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:22 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:22 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:22 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:22 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:22 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:22 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:22 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:22 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:22 volumio sudo[3174]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:22 volumio volumio[1217]: info: sendMpdCommand clearerror took 31 milliseconds Jun 17 19:19:22 volumio volumio[1217]: info: sendMpdCommand clearerror took 28 milliseconds Jun 17 19:19:22 volumio volumio[1217]: info: sendMpdCommand clearerror took 25 milliseconds Jun 17 19:19:22 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 17 19:19:22 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:19:22 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:19:22 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:22 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:22 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:22 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Slow Down","artist":"Imany","album":"The Shape Of A Borken Heart","uri":"NAS/Synology/Testy/01 - Slow Down.flac","trackType":"flac"} Jun 17 19:19:22 volumio volumio[1217]: verbose: CURRENT POSITION 1 Jun 17 19:19:22 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:22 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:22 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:22 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:22 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:19:22 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:22 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:22 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:22 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:22 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Slow Down","artist":"Imany","album":"The Shape Of A Borken Heart","uri":"NAS/Synology/Testy/01 - Slow Down.flac","trackType":"flac"} Jun 17 19:19:22 volumio volumio[1217]: verbose: CURRENT POSITION 1 Jun 17 19:19:22 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:22 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:22 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:22 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:22 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:22 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:22 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:22 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:22 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Slow Down","artist":"Imany","album":"The Shape Of A Borken Heart","uri":"NAS/Synology/Testy/01 - Slow Down.flac","trackType":"flac"} Jun 17 19:19:22 volumio volumio[1217]: verbose: CURRENT POSITION 1 Jun 17 19:19:22 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:22 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:22 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:22 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:22 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:23 volumio volumio[1217]: info: ------------------------------ 551ms Jun 17 19:19:23 volumio volumio[1217]: info: ------------------------------ 550ms Jun 17 19:19:23 volumio volumio[1217]: info: ------------------------------ 547ms Jun 17 19:19:23 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:23 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:23 volumio sudo[3177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:23 volumio sudo[3177]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:23 volumio sudo[3177]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:23 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:23 volumio sudo[3180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:23 volumio sudo[3180]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:23 volumio sudo[3180]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:23 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:23 volumio sudo[3183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:23 volumio sudo[3183]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:23 volumio sudo[3183]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:23 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:23 volumio sudo[3186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:23 volumio sudo[3186]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:23 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:23 volumio sudo[3186]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:23 volumio sudo[3189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:23 volumio sudo[3189]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:23 volumio sudo[3189]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:23 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:23 volumio sudo[3192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:23 volumio sudo[3192]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:23 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:23 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:23 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:23 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:23 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:23 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:23 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:23 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:23 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:23 volumio sudo[3192]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:23 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:26 volumio volumio[1217]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 17 19:19:26 volumio volumio[1217]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 17 19:19:26 volumio volumio[1217]: info: Retrieving Cloud Streaming UI Jun 17 19:19:26 volumio volumio[1217]: info: Getting Tidal Cloud Configuration Jun 17 19:19:26 volumio volumio[1217]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 17 19:19:26 volumio volumio[1217]: info: Getting Qobuz Cloud Configuration Jun 17 19:19:26 volumio volumio[1217]: info: Asking plugin for UI Config Jun 17 19:19:26 volumio volumio[1217]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 17 19:19:26 volumio volumio[1217]: info: Getting Spotify Cloud Configuration Jun 17 19:19:26 volumio volumio[1217]: info: Asking plugin for UI Config Jun 17 19:19:26 volumio volumio[1217]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 17 19:19:26 volumio volumio[1217]: info: Saving Spotify Acccount Jun 17 19:19:26 volumio volumio[1217]: info: Got Tidal Cloud Configuration Jun 17 19:19:26 volumio volumio[1217]: info: Got it Jun 17 19:19:26 volumio volumio[1217]: info: Got it Jun 17 19:19:26 volumio volumio[1217]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 17 19:19:26 volumio volumio[1217]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 17 19:19:26 volumio volumio[1217]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jun 17 19:19:26 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 17 19:19:26 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 17 19:19:26 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 17 19:19:26 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 17 19:19:26 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:26 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:26 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:26 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:26 volumio volumio[1217]: info: CoreCommandRouter::volumioGetBrowseSources Jun 17 19:19:26 volumio volumio[1217]: info: CoreCommandRouter::volumioGetBrowseSources Jun 17 19:19:26 volumio volumio[1217]: info: CoreCommandRouter::volumioGetBrowseSources Jun 17 19:19:26 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 17 19:19:26 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 17 19:19:30 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 17 19:19:31 volumio volumio[1217]: info: CoreCommandRouter::volumioGetState Jun 17 19:19:31 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Jun 17 19:19:35 volumio volumio[1217]: error: error Jun 17 19:19:35 volumio volumio[1217]: error: error Jun 17 19:19:37 volumio volumio[1217]: info: CoreCommandRouter::volumioPlay Jun 17 19:19:37 volumio volumio[1217]: info: CoreStateMachine::play index 2 Jun 17 19:19:37 volumio volumio[1217]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 19:19:37 volumio volumio[1217]: info: CoreStateMachine::stop Jun 17 19:19:37 volumio volumio[1217]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 19:19:37 volumio volumio[1217]: info: CoreStateMachine::play index undefined Jun 17 19:19:37 volumio volumio[1217]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 19:19:37 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:37 volumio volumio[1217]: info: CoreStateMachine::startPlaybackTimer Jun 17 19:19:37 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:37 volumio volumio[1217]: verbose: ControllerMpd::clearAddPlayTracks NAS/Synology/Testy/01 Sultans of Swing.mp3 Jun 17 19:19:37 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 19:19:37 volumio volumio[1217]: info: sendMpdCommand stop took 1 milliseconds Jun 17 19:19:37 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clear Jun 17 19:19:37 volumio volumio[1217]: info: Jun 17 19:19:37 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:37 volumio volumio[1217]: info: Jun 17 19:19:37 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:37 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:37 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:37 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:37 volumio volumio[1217]: info: sendMpdCommand clear took 1 milliseconds Jun 17 19:19:37 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand add "NAS/Synology/Testy/01 Sultans of Swing.mp3" Jun 17 19:19:37 volumio volumio[1217]: info: Jun 17 19:19:37 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:37 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:37 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:37 volumio volumio[1217]: error: updateQueue error: null Jun 17 19:19:37 volumio volumio[1217]: info: Jun 17 19:19:37 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:37 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:37 volumio volumio[1217]: info: sendMpdCommand status took 5 milliseconds Jun 17 19:19:37 volumio volumio[1217]: info: ------------------------------ 5ms Jun 17 19:19:37 volumio volumio[1217]: info: sendMpdCommand add "NAS/Synology/Testy/01 Sultans of Swing.mp3" took 4 milliseconds Jun 17 19:19:37 volumio volumio[1217]: info: sendMpdCommand status took 3 milliseconds Jun 17 19:19:37 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:37 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand play Jun 17 19:19:37 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:37 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:37 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:37 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:37 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:37 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:19:37 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:37 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:37 volumio volumio[1217]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 17 19:19:37 volumio volumio[1217]: verbose: CURRENT POSITION 2 Jun 17 19:19:37 volumio volumio[1217]: info: CoreStateMachine::syncState stateService stop Jun 17 19:19:37 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:37 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:37 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:37 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:37 volumio volumio[1217]: info: No code Jun 17 19:19:37 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:37 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:37 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:37 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:37 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:37 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:37 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:37 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:37 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:37 volumio volumio[1217]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 17 19:19:37 volumio volumio[1217]: verbose: CURRENT POSITION 2 Jun 17 19:19:37 volumio volumio[1217]: info: CoreStateMachine::syncState stateService stop Jun 17 19:19:37 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:37 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:37 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:37 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:37 volumio volumio[1217]: info: No code Jun 17 19:19:37 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:37 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:37 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:38 volumio volumio[1217]: info: ------------------------------ 77ms Jun 17 19:19:38 volumio volumio[1217]: info: ------------------------------ 76ms Jun 17 19:19:38 volumio volumio[1217]: info: Jun 17 19:19:38 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:38 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:38 volumio volumio[1217]: info: Jun 17 19:19:38 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:38 volumio volumio[1217]: info: ------------------------------ 76ms Jun 17 19:19:38 volumio volumio[1217]: info: sendMpdCommand play took 73 milliseconds Jun 17 19:19:38 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:38 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:38 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:38 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:38 volumio sudo[3271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:38 volumio sudo[3271]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:38 volumio sudo[3271]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:38 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:38 volumio sudo[3274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:38 volumio sudo[3274]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:38 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:38 volumio sudo[3274]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:38 volumio sudo[3277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:38 volumio sudo[3277]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:38 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:38 volumio sudo[3277]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:38 volumio sudo[3280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:38 volumio sudo[3280]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:38 volumio sudo[3280]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:38 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:38 volumio sudo[3283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:38 volumio sudo[3283]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:38 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:38 volumio sudo[3283]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:38 volumio sudo[3286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:38 volumio sudo[3286]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:38 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:38 volumio sudo[3286]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:38 volumio sudo[3289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:38 volumio sudo[3289]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:38 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:38 volumio sudo[3289]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:38 volumio sudo[3292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:38 volumio sudo[3292]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:38 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:38 volumio sudo[3295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:38 volumio sudo[3295]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:38 volumio sudo[3292]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:38 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:38 volumio sudo[3295]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:38 volumio sudo[3298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:38 volumio sudo[3298]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:38 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:38 volumio sudo[3301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:38 volumio sudo[3301]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:38 volumio sudo[3298]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:38 volumio volumio[1217]: info: Jun 17 19:19:38 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:38 volumio volumio[1217]: info: ------------------------------ 464ms Jun 17 19:19:38 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:38 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:38 volumio sudo[3301]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:38 volumio sudo[3304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:38 volumio sudo[3304]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:38 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 17 19:19:38 volumio volumio[1217]: info: sendMpdCommand status took 467 milliseconds Jun 17 19:19:38 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:38 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:38 volumio volumio[1217]: info: Jun 17 19:19:38 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:38 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:38 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:38 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:38 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:38 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:38 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:38 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:38 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:38 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:38 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:38 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:38 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:38 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:38 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:38 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:38 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:38 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:38 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:38 volumio sudo[3304]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:38 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 17 19:19:38 volumio volumio[1217]: info: sendMpdCommand status took 42 milliseconds Jun 17 19:19:38 volumio volumio[1217]: info: sendMpdCommand clearerror took 33 milliseconds Jun 17 19:19:38 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 31 milliseconds Jun 17 19:19:38 volumio volumio[1217]: info: sendMpdCommand status took 28 milliseconds Jun 17 19:19:38 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:38 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:38 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:19:38 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:38 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:38 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:38 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:38 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:38 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":346,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sultans of Swing","artist":"Dire Straits","album":"Money for Nothing","uri":"NAS/Synology/Testy/01 Sultans of Swing.mp3","trackType":"mp3"} Jun 17 19:19:38 volumio volumio[1217]: verbose: CURRENT POSITION 2 Jun 17 19:19:38 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:38 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:38 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:38 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:38 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:19:38 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:38 volumio volumio[1217]: info: ------------------------------ 531ms Jun 17 19:19:38 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:38 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:38 volumio sudo[3307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:38 volumio sudo[3307]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:38 volumio sudo[3307]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:38 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:38 volumio sudo[3310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:38 volumio sudo[3310]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:38 volumio volumio[1217]: info: sendMpdCommand clearerror took 123 milliseconds Jun 17 19:19:38 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 120 milliseconds Jun 17 19:19:38 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 120 milliseconds Jun 17 19:19:38 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:19:38 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:19:38 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:38 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:38 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:38 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":346,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sultans of Swing","artist":"Dire Straits","album":"Money for Nothing","uri":"NAS/Synology/Testy/01 Sultans of Swing.mp3","trackType":"mp3"} Jun 17 19:19:38 volumio volumio[1217]: verbose: CURRENT POSITION 2 Jun 17 19:19:38 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:38 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:38 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:38 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:38 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:38 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:38 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:38 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:38 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":346,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sultans of Swing","artist":"Dire Straits","album":"Money for Nothing","uri":"NAS/Synology/Testy/01 Sultans of Swing.mp3","trackType":"mp3"} Jun 17 19:19:38 volumio volumio[1217]: verbose: CURRENT POSITION 2 Jun 17 19:19:38 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:38 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:38 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:38 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:38 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:38 volumio sudo[3310]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:38 volumio volumio[1217]: info: ------------------------------ 211ms Jun 17 19:19:38 volumio volumio[1217]: info: ------------------------------ 195ms Jun 17 19:19:38 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:38 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:38 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:38 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:38 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:38 volumio sudo[3313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:38 volumio sudo[3313]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:38 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:38 volumio sudo[3313]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:38 volumio sudo[3316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:38 volumio sudo[3316]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:38 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:38 volumio sudo[3316]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:38 volumio sudo[3319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:38 volumio sudo[3319]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:38 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:38 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:38 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:38 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:38 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:38 volumio sudo[3322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:38 volumio sudo[3322]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:38 volumio sudo[3319]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:38 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:38 volumio sudo[3322]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:38 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:42 volumio volumio[1217]: info: CoreCommandRouter::volumioGetState Jun 17 19:19:42 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:45 volumio volumio[1217]: info: CoreCommandRouter::volumioPlay Jun 17 19:19:45 volumio volumio[1217]: info: CoreStateMachine::play index undefined Jun 17 19:19:45 volumio volumio[1217]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 19:19:45 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:45 volumio volumio[1217]: info: CoreStateMachine::startPlaybackTimer Jun 17 19:19:45 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:45 volumio volumio[1217]: verbose: ControllerMpd::clearAddPlayTracks NAS/Synology/Testy/01 Sultans of Swing.mp3 Jun 17 19:19:45 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 19:19:45 volumio volumio[1217]: info: sendMpdCommand stop took 2 milliseconds Jun 17 19:19:45 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clear Jun 17 19:19:45 volumio volumio[1217]: info: Jun 17 19:19:45 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:45 volumio volumio[1217]: info: Jun 17 19:19:45 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:45 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:45 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:45 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:45 volumio volumio[1217]: info: sendMpdCommand clear took 2 milliseconds Jun 17 19:19:45 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand add "NAS/Synology/Testy/01 Sultans of Swing.mp3" Jun 17 19:19:45 volumio volumio[1217]: info: Jun 17 19:19:45 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:45 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:45 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:45 volumio volumio[1217]: error: updateQueue error: null Jun 17 19:19:45 volumio volumio[1217]: info: Jun 17 19:19:45 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:45 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:45 volumio volumio[1217]: info: sendMpdCommand status took 2 milliseconds Jun 17 19:19:45 volumio volumio[1217]: info: ------------------------------ 3ms Jun 17 19:19:45 volumio volumio[1217]: info: sendMpdCommand add "NAS/Synology/Testy/01 Sultans of Swing.mp3" took 3 milliseconds Jun 17 19:19:45 volumio volumio[1217]: info: sendMpdCommand status took 2 milliseconds Jun 17 19:19:45 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:45 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand play Jun 17 19:19:45 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:45 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:45 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:45 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:45 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:45 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:19:45 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:45 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:45 volumio volumio[1217]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 17 19:19:45 volumio volumio[1217]: verbose: CURRENT POSITION 2 Jun 17 19:19:45 volumio volumio[1217]: info: CoreStateMachine::syncState stateService stop Jun 17 19:19:45 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:45 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:45 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:45 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:45 volumio volumio[1217]: info: No code Jun 17 19:19:45 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:45 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:45 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:45 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:45 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:45 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:45 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:45 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:45 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:45 volumio volumio[1217]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 17 19:19:45 volumio volumio[1217]: verbose: CURRENT POSITION 2 Jun 17 19:19:45 volumio volumio[1217]: info: CoreStateMachine::syncState stateService stop Jun 17 19:19:45 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:45 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:45 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:45 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:45 volumio volumio[1217]: info: No code Jun 17 19:19:45 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:45 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:45 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:45 volumio volumio[1217]: info: ------------------------------ 72ms Jun 17 19:19:45 volumio volumio[1217]: info: ------------------------------ 73ms Jun 17 19:19:45 volumio volumio[1217]: info: Jun 17 19:19:45 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Jun 17 19:19:45 volumio volumio[1217]: info: Ignoring MPD Status Update Jun 17 19:19:45 volumio volumio[1217]: info: Jun 17 19:19:45 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:45 volumio volumio[1217]: info: Jun 17 19:19:45 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:45 volumio volumio[1217]: info: ------------------------------ 79ms Jun 17 19:19:45 volumio volumio[1217]: info: sendMpdCommand play took 78 milliseconds Jun 17 19:19:45 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:45 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:45 volumio volumio[1217]: info: ------------------------------ 7ms Jun 17 19:19:45 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:45 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:45 volumio volumio[1217]: info: Jun 17 19:19:45 volumio volumio[1217]: ---------------------------- MPD announces state update: player Jun 17 19:19:45 volumio volumio[1217]: info: ControllerMpd::getState Jun 17 19:19:45 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:19:45 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:45 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:45 volumio sudo[3339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:45 volumio sudo[3339]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:45 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:45 volumio sudo[3342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:45 volumio sudo[3339]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:45 volumio sudo[3342]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:45 volumio sudo[3342]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:45 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:45 volumio sudo[3345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:45 volumio sudo[3345]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:45 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:45 volumio sudo[3345]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:45 volumio sudo[3348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:45 volumio sudo[3348]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:45 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:45 volumio sudo[3348]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:45 volumio sudo[3351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:45 volumio sudo[3351]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:45 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:45 volumio sudo[3351]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:45 volumio sudo[3354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:45 volumio sudo[3354]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:45 volumio sudo[3354]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:45 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:46 volumio sudo[3357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:46 volumio sudo[3357]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:46 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:46 volumio sudo[3360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:46 volumio sudo[3360]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:46 volumio sudo[3357]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:46 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:46 volumio sudo[3363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:46 volumio sudo[3363]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:46 volumio sudo[3360]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:46 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:46 volumio sudo[3366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:46 volumio sudo[3366]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:46 volumio sudo[3363]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:46 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:46 volumio sudo[3366]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:46 volumio sudo[3369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:46 volumio sudo[3369]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:46 volumio sudo[3369]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:46 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 17 19:19:46 volumio sudo[3372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:46 volumio sudo[3372]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:46 volumio volumio[1217]: info: sendMpdCommand status took 440 milliseconds Jun 17 19:19:46 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 17 19:19:46 volumio volumio[1217]: info: sendMpdCommand status took 443 milliseconds Jun 17 19:19:46 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 17 19:19:46 volumio volumio[1217]: info: sendMpdCommand status took 443 milliseconds Jun 17 19:19:46 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:46 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:46 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:46 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:46 volumio volumio[1217]: verbose: ControllerMpd::parseState Jun 17 19:19:46 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:19:46 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:46 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:46 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:46 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:46 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:46 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:46 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:46 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:46 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:46 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:46 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:46 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:46 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:46 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:46 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:46 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:46 volumio volumio[1217]: info: sendMpdCommand clearerror took 31 milliseconds Jun 17 19:19:46 volumio volumio[1217]: info: sendMpdCommand clearerror took 28 milliseconds Jun 17 19:19:46 volumio volumio[1217]: info: sendMpdCommand clearerror took 26 milliseconds Jun 17 19:19:46 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 17 19:19:46 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 17 19:19:46 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:19:46 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:19:46 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:46 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:46 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:46 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":346,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sultans of Swing","artist":"Dire Straits","album":"Money for Nothing","uri":"NAS/Synology/Testy/01 Sultans of Swing.mp3","trackType":"mp3"} Jun 17 19:19:46 volumio volumio[1217]: verbose: CURRENT POSITION 2 Jun 17 19:19:46 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:46 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:46 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:46 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:46 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:19:46 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:46 volumio sudo[3372]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:46 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:46 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:46 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:46 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":346,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sultans of Swing","artist":"Dire Straits","album":"Money for Nothing","uri":"NAS/Synology/Testy/01 Sultans of Swing.mp3","trackType":"mp3"} Jun 17 19:19:46 volumio volumio[1217]: verbose: CURRENT POSITION 2 Jun 17 19:19:46 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:46 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:46 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:46 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:46 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:46 volumio volumio[1217]: info: ControllerMpd::pushState Jun 17 19:19:46 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Jun 17 19:19:46 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:46 volumio volumio[1217]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":346,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sultans of Swing","artist":"Dire Straits","album":"Money for Nothing","uri":"NAS/Synology/Testy/01 Sultans of Swing.mp3","trackType":"mp3"} Jun 17 19:19:46 volumio volumio[1217]: verbose: CURRENT POSITION 2 Jun 17 19:19:46 volumio volumio[1217]: info: CoreStateMachine::syncState stateService pause Jun 17 19:19:46 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:19:46 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:46 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:46 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:46 volumio volumio[1217]: info: ------------------------------ 530ms Jun 17 19:19:46 volumio volumio[1217]: info: ------------------------------ 529ms Jun 17 19:19:46 volumio volumio[1217]: info: ------------------------------ 528ms Jun 17 19:19:46 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:46 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:46 volumio sudo[3375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:46 volumio sudo[3375]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:46 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:46 volumio sudo[3375]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:46 volumio sudo[3378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:46 volumio sudo[3378]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:46 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:46 volumio sudo[3378]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:46 volumio sudo[3381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:46 volumio sudo[3381]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:46 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:46 volumio sudo[3381]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:46 volumio sudo[3384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:46 volumio sudo[3384]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:46 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:46 volumio sudo[3384]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:46 volumio sudo[3387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:46 volumio sudo[3387]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:46 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:46 volumio sudo[3390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:46 volumio sudo[3390]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:46 volumio sudo[3387]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:46 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:46 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:46 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:46 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:46 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:46 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:46 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:46 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:46 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:46 volumio sudo[3390]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:46 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:53 volumio volumio[1217]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 17 19:19:53 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 17 19:19:53 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 19:19:53 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 17 19:19:53 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 17 19:19:53 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 17 19:19:53 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 17 19:19:53 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:53 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:53 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:53 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:53 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:53 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:53 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 17 19:19:53 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 17 19:19:57 volumio volumio[1217]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 17 19:19:57 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 17 19:19:57 volumio volumio[1217]: info: Preparing to save Alsa Options, stopping services first Jun 17 19:19:57 volumio volumio[1217]: info: CoreCommandRouter::volumioGetState Jun 17 19:19:57 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:57 volumio volumio[1217]: info: CoreCommandRouter::volumioPause Jun 17 19:19:57 volumio volumio[1217]: info: CoreStateMachine::pause Jun 17 19:19:57 volumio volumio[1217]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"I-Sabre Q2M DAC"},"i2s":true,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jun 17 19:19:57 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 17 19:19:57 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jun 17 19:19:57 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 19:19:57 volumio volumio[1217]: info: Enabling I2S DAC: Adafruit MAX98357 Jun 17 19:19:57 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Jun 17 19:19:57 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 19:19:57 volumio sudo[3415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jun 17 19:19:57 volumio sudo[3415]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:57 volumio sudo[3415]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:57 volumio volumio[1217]: info: No Overlays Loaded Jun 17 19:19:57 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 19:19:58 volumio sudo[3418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay max98357a Jun 17 19:19:58 volumio sudo[3418]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:58 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Jun 17 19:19:58 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Jun 17 19:19:58 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/simple-audio-card,format Jun 17 19:19:58 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/simple-audio-card,name Jun 17 19:19:58 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Jun 17 19:19:58 volumio sudo[3418]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:58 volumio kernel: max98357a: probe of max98357a failed with error -16 Jun 17 19:19:58 volumio volumio[1217]: warn: Unable to locate the device I-Sabre Q2M DAC a reboot may be required. Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 19:19:58 volumio volumio[1217]: info: Setting mixer PCM for card Headphones Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 17 19:19:58 volumio volumio[1217]: info: Updating Volume Controller Parameters: Device: 2 Name: Adafruit MAX98357 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 17 19:19:58 volumio volumio[1217]: info: Disabling external Volume Control Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Jun 17 19:19:58 volumio volumio[1217]: info: Preparing to generate the ALSA configuration file Jun 17 19:19:58 volumio volumio[1217]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 17 19:19:58 volumio volumio[1217]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 17 19:19:58 volumio volumio[1217]: info: Reading ALSA contributions from plugins. Jun 17 19:19:58 volumio volumio[1217]: info: I2S Param [object Object] successfully enabled Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 17 19:19:58 volumio volumio[1217]: info: Getting Alsa Cards List without I2S DAC Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 17 19:19:58 volumio volumio[1217]: info: VolumeController:: Volume=0 Mute =false Jun 17 19:19:58 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:19:58 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:19:58 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:19:58 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:19:58 volumio sudo[3447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:19:58 volumio sudo[3447]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:58 volumio sudo[3447]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:58 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jun 17 19:19:58 volumio volumio[1217]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Jun 17 19:19:58 volumio volumio[1217]: info: Asound.conf file written Jun 17 19:19:58 volumio sudo[3451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:19:58 volumio sudo[3451]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:58 volumio sudo[3451]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:58 volumio sudo[3454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 17 19:19:58 volumio sudo[3454]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:58 volumio sudo[3454]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:58 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 17 19:19:58 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 17 19:19:58 volumio volumio[1217]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Volume:0' : Remote I/O error Jun 17 19:19:58 volumio volumio[1217]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Switch:0' : Remote I/O error Jun 17 19:19:58 volumio volumio[1217]: info: Output device has changed, restarting MPD Jun 17 19:19:58 volumio sudo[3459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 17 19:19:58 volumio sudo[3459]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:58 volumio sudo[3459]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:58 volumio volumio[1217]: info: Output device has changed, restarting Shairport Sync Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:58 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 19:19:58 volumio sudo[3463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 17 19:19:58 volumio sudo[3463]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:58 volumio systemd[1]: Stopping Music Player Daemon... Jun 17 19:19:58 volumio volumio[1217]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 19:19:58 volumio volumio[1217]: info: Output device has changed, restarting MPD Jun 17 19:19:59 volumio sudo[3469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 17 19:19:59 volumio sudo[3469]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:59 volumio sudo[3469]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:59 volumio volumio[1217]: info: Output device has changed, restarting Shairport Sync Jun 17 19:19:59 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:59 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 19:19:59 volumio sudo[3472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 17 19:19:59 volumio sudo[3472]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:59 volumio volumio[1217]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 19:19:59 volumio volumio[1217]: info: MPD Permissions set Jun 17 19:19:59 volumio volumio[1217]: info: MPD Permissions set Jun 17 19:19:59 volumio volumio[1217]: info: peppymeterbasic Daemon Stop Jun 17 19:19:59 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:59 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:59 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:59 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 19:19:59 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:59 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:59 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 19:19:59 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 19:19:59 volumio volumio[1217]: info: Starting Shairport Sync Jun 17 19:19:59 volumio volumio[1217]: info: Starting Shairport Sync Jun 17 19:19:59 volumio sudo[3486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 17 19:19:59 volumio sudo[3486]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:59 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 17 19:19:59 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 17 19:19:59 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 17 19:19:59 volumio sudo[3489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 17 19:19:59 volumio sudo[3489]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:59 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 17 19:19:59 volumio sudo[3486]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:59 volumio systemd[1]: mpd.service: Succeeded. Jun 17 19:19:59 volumio volumio[1217]: info: Shairport-Sync Started Jun 17 19:19:59 volumio systemd[1]: Stopped Music Player Daemon. Jun 17 19:19:59 volumio systemd[1]: Starting Music Player Daemon... Jun 17 19:19:59 volumio shairport-sync[3491]: free(): double free detected in tcache 2 Jun 17 19:19:59 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 17 19:19:59 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=6/ABRT Jun 17 19:19:59 volumio systemd[1]: shairport-sync.service: Failed with result 'signal'. Jun 17 19:19:59 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 17 19:19:59 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 17 19:19:59 volumio sudo[3489]: pam_unix(sudo:session): session closed for user root Jun 17 19:19:59 volumio volumio[1217]: info: Shairport-Sync Started Jun 17 19:19:59 volumio sudo[3492]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 17 19:19:59 volumio sudo[3492]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:19:59 volumio sudo[3492]: pam_unix(sudo:session): session closed for user root Jun 17 19:20:00 volumio mpd[3498]: Jun 17 19:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 17 19:20:02 volumio systemd[1]: Started Music Player Daemon. Jun 17 19:20:02 volumio sudo[3472]: pam_unix(sudo:session): session closed for user root Jun 17 19:20:02 volumio sudo[3463]: pam_unix(sudo:session): session closed for user root Jun 17 19:20:02 volumio volumio[1217]: error: MPD error: The expression evaluated to a falsy value: Jun 17 19:20:02 volumio volumio[1217]: assert.ok(self.idling) Jun 17 19:20:02 volumio volumio[1217]: error: The expression evaluated to a falsy value: Jun 17 19:20:02 volumio volumio[1217]: assert.ok(self.idling) Jun 17 19:20:02 volumio volumio[1217]: error: updateQueue error: null Jun 17 19:20:02 volumio volumio[1217]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 17 19:20:02 volumio volumio[1217]: info: CoreStateMachine::getcurrentVolume Jun 17 19:20:02 volumio volumio[1217]: info: CoreCommandRouter::volumioRetrievevolume Jun 17 19:20:02 volumio volumio[1217]: info: VolumeController:: Volume=0 Mute =false Jun 17 19:20:02 volumio volumio[1217]: info: CoreStateMachine::pushState Jun 17 19:20:02 volumio volumio[1217]: info: CorePlayQueue::getTrack 2 Jun 17 19:20:02 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:20:02 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 17 19:20:02 volumio volumio[1217]: info: CoreStateMachine::updateTrackBlock Jun 17 19:20:02 volumio volumio[1217]: info: CorePlayQueue::getTrackBlock Jun 17 19:20:02 volumio volumio[1217]: info: CoreCommandRouter::volumioRetrievevolume Jun 17 19:20:02 volumio volumio[1217]: info: PeppySpectrum ---peppyspectrum status stop Jun 17 19:20:02 volumio volumio[1217]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 17 19:20:02 volumio sudo[3505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 17 19:20:02 volumio sudo[3505]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:20:02 volumio sudo[3505]: pam_unix(sudo:session): session closed for user root Jun 17 19:20:02 volumio volumio[1217]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Jun 17 19:20:02 volumio volumio[1217]: info: PLUGIN onReboot : networkfs Jun 17 19:20:02 volumio sudo[3508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 17 19:20:02 volumio sudo[3508]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:20:02 volumio sudo[3508]: pam_unix(sudo:session): session closed for user root Jun 17 19:20:02 volumio sudo[3511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/Synology Jun 17 19:20:02 volumio sudo[3511]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:20:02 volumio systemd[1]: mnt-NAS-Synology.mount: Succeeded. Jun 17 19:20:02 volumio sudo[3511]: pam_unix(sudo:session): session closed for user root Jun 17 19:20:02 volumio volumio[1217]: info: PLUGIN onReboot : audiophonicsonoff Jun 17 19:20:02 volumio volumio[1217]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 19:20:02 volumio volumio[1217]: TypeError: Cannot read property 'writeSync' of undefined Jun 17 19:20:02 volumio volumio[1217]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25) Jun 17 19:20:02 volumio volumio[1217]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Jun 17 19:20:02 volumio volumio[1217]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Jun 17 19:20:02 volumio volumio[1217]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jun 17 19:20:02 volumio volumio[1217]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jun 17 19:20:02 volumio volumio[1217]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Jun 17 19:20:02 volumio volumio[1217]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) Jun 17 19:20:02 volumio volumio[1217]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33) Jun 17 19:20:02 volumio volumio[1217]: at Socket.emit (events.js:315:20) Jun 17 19:20:02 volumio volumio[1217]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 17 19:20:02 volumio volumio[1217]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jun 17 19:20:02 volumio volumio[1217]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 19:20:03 volumio sudo[3526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-17 19:19 Jun 17 19:20:03 volumio sudo[3526]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"