-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Thu 2024-08-29 14:30:33 CST. -- Aug 29 14:29:09 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Aug 29 14:29:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:29:09 volumio sudo[2043]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Aug 29 14:29:09 volumio sudo[2043]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:29:13 volumio volumio[1069]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 6 Aug 29 14:29:13 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Aug 29 14:29:13 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:29:14 volumio screensaver.sh[705]: libpng warning: iCCP: known incorrect sRGB profile Aug 29 14:29:14 volumio screensaver.sh[705]: libpng warning: Interlace handling should be turned on when using png_read_image Aug 29 14:29:17 volumio screensaver.sh[705]: libpng warning: iCCP: known incorrect sRGB profile Aug 29 14:29:17 volumio screensaver.sh[705]: libpng warning: Interlace handling should be turned on when using png_read_image Aug 29 14:29:19 volumio volumio[1069]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 Aug 29 14:29:19 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Aug 29 14:29:19 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:29:38 volumio startx[1071]: (EE) client bug: timer event3 debounce: offset negative (-11ms) Aug 29 14:29:38 volumio startx[1071]: (EE) client bug: timer event3 debounce short: offset negative (-25ms) Aug 29 14:29:38 volumio sudo[2043]: pam_unix(sudo:session): session closed for user root Aug 29 14:29:42 volumio volumio[1069]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:29:42 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 29 14:29:42 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:29:42 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 14:29:42 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:29:42 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:29:42 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:29:42 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 29 14:29:42 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 29 14:29:49 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Aug 29 14:29:49 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:29:50 volumio sudo[2211]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Aug 29 14:29:50 volumio sudo[2211]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:29:50 volumio volumio[1069]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 29 14:29:50 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 29 14:29:50 volumio volumio[1069]: info: Preparing to save Alsa Options, stopping services first Aug 29 14:29:50 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Aug 29 14:29:50 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:29:50 volumio volumio[1069]: info: CoreCommandRouter::volumioPause Aug 29 14:29:50 volumio volumio[1069]: info: CoreStateMachine::pause Aug 29 14:29:50 volumio volumio[1069]: info: CoreStateMachine::stPlaybackTimer Aug 29 14:29:50 volumio volumio[1069]: info: CoreStateMachine::servicePause Aug 29 14:29:50 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:29:50 volumio volumio[1069]: info: CoreCommandRouter::servicePause Aug 29 14:29:50 volumio volumio[1069]: info: ControllerMpd::pause Aug 29 14:29:50 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand pause Aug 29 14:29:50 volumio volumio[1069]: info: Saving Audio Output to: {"output_device":{"value":"1,0","label":"Analog Out"}} Aug 29 14:29:50 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 14:29:51 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 14:29:51 volumio volumio[1069]: info: Found match in Cards Database: setting mixer for card Analog Out Aug 29 14:29:51 volumio volumio[1069]: info: Setting mixer Master for card Analog Out Aug 29 14:29:51 volumio volumio[1069]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 29 14:29:51 volumio volumio[1069]: info: Updating Volume Controller Parameters: Device: 1,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 29 14:29:51 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 29 14:29:51 volumio volumio[1069]: info: Disabling external Volume Control Aug 29 14:29:51 volumio volumio[1069]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:29:51 volumio volumio[1069]: info: Preparing to generate the ALSA configuration file Aug 29 14:29:51 volumio volumio[1069]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 29 14:29:51 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 29 14:29:51 volumio volumio[1069]: info: Preparing to save Alsa Options, stopping services first Aug 29 14:29:51 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Aug 29 14:29:51 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:29:51 volumio volumio[1069]: info: CoreCommandRouter::volumioPause Aug 29 14:29:51 volumio volumio[1069]: info: CoreStateMachine::pause Aug 29 14:29:51 volumio volumio[1069]: info: Saving Audio Output to: {"output_device":{"value":"1,0","label":"Analog Out"}} Aug 29 14:29:51 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 14:29:51 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 14:29:51 volumio volumio[1069]: info: Found match in Cards Database: setting mixer for card Analog Out Aug 29 14:29:51 volumio volumio[1069]: info: Setting mixer Master for card Analog Out Aug 29 14:29:51 volumio volumio[1069]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 29 14:29:51 volumio volumio[1069]: info: Updating Volume Controller Parameters: Device: 1,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 29 14:29:51 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 29 14:29:51 volumio volumio[1069]: info: Disabling external Volume Control Aug 29 14:29:51 volumio volumio[1069]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:29:51 volumio volumio[1069]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Aug 29 14:29:51 volumio volumio[1069]: info: Reading ALSA contributions from plugins. Aug 29 14:29:51 volumio volumio[1069]: info: Aug 29 14:29:51 volumio volumio[1069]: ---------------------------- MPD announces state update: player Aug 29 14:29:51 volumio volumio[1069]: info: sendMpdCommand pause took 1031 milliseconds Aug 29 14:29:51 volumio volumio[1069]: info: ControllerMpd::getState Aug 29 14:29:51 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Aug 29 14:29:51 volumio volumio[1069]: info: Aug 29 14:29:51 volumio volumio[1069]: ---------------------------- MPD announces state update: player Aug 29 14:29:51 volumio volumio[1069]: info: ControllerMpd::getState Aug 29 14:29:51 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Aug 29 14:29:51 volumio volumio[1069]: info: Aug 29 14:29:51 volumio volumio[1069]: ---------------------------- MPD announces state update: player Aug 29 14:29:51 volumio volumio[1069]: info: ControllerMpd::getState Aug 29 14:29:51 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Aug 29 14:29:51 volumio volumio[1069]: info: Aug 29 14:29:51 volumio volumio[1069]: ---------------------------- MPD announces state update: player Aug 29 14:29:51 volumio volumio[1069]: info: ControllerMpd::getState Aug 29 14:29:51 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Aug 29 14:29:51 volumio volumio[1069]: info: sendMpdCommand status took 87 milliseconds Aug 29 14:29:51 volumio volumio[1069]: info: sendMpdCommand status took 81 milliseconds Aug 29 14:29:51 volumio volumio[1069]: info: sendMpdCommand status took 71 milliseconds Aug 29 14:29:51 volumio volumio[1069]: info: sendMpdCommand status took 63 milliseconds Aug 29 14:29:51 volumio volumio[1069]: verbose: ControllerMpd::parseState Aug 29 14:29:51 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 14:29:51 volumio volumio[1069]: verbose: ControllerMpd::parseState Aug 29 14:29:51 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 14:29:51 volumio volumio[1069]: verbose: ControllerMpd::parseState Aug 29 14:29:51 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 14:29:51 volumio volumio[1069]: verbose: ControllerMpd::parseState Aug 29 14:29:51 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 14:29:52 volumio volumio[1069]: info: sendMpdCommand playlistinfo took 67 milliseconds Aug 29 14:29:52 volumio volumio[1069]: verbose: ControllerMpd::parseTrackInfo Aug 29 14:29:52 volumio volumio[1069]: verbose: ControllerMpd::parseTrackInfo Aug 29 14:29:52 volumio volumio[1069]: verbose: ControllerMpd::parseTrackInfo Aug 29 14:29:52 volumio volumio[1069]: info: ControllerMpd::pushState Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::servicePushState Aug 29 14:29:52 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:29:52 volumio volumio[1069]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":74782,"duration":325,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4839 Kbps","isStreaming":false,"title":"未来へ","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 未来へ.flac","trackType":"flac"} Aug 29 14:29:52 volumio volumio[1069]: verbose: CURRENT POSITION 1 Aug 29 14:29:52 volumio volumio[1069]: info: CoreStateMachine::syncState stateService pause Aug 29 14:29:52 volumio volumio[1069]: info: CoreStateMachine::syncState currentStatus pause Aug 29 14:29:52 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:29:52 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:29:52 volumio volumio[1069]: info: CoreStateMachine::stPlaybackTimer Aug 29 14:29:52 volumio volumio[1069]: info: ControllerMpd::pushState Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::servicePushState Aug 29 14:29:52 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:29:52 volumio volumio[1069]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":74782,"duration":325,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4839 Kbps","isStreaming":false,"title":"未来へ","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 未来へ.flac","trackType":"flac"} Aug 29 14:29:52 volumio volumio[1069]: verbose: CURRENT POSITION 1 Aug 29 14:29:52 volumio volumio[1069]: info: CoreStateMachine::syncState stateService pause Aug 29 14:29:52 volumio volumio[1069]: info: CoreStateMachine::syncState currentStatus pause Aug 29 14:29:52 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:29:52 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:29:52 volumio volumio[1069]: info: CoreStateMachine::stPlaybackTimer Aug 29 14:29:52 volumio volumio[1069]: info: ControllerMpd::pushState Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::servicePushState Aug 29 14:29:52 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:29:52 volumio volumio[1069]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":74782,"duration":325,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4839 Kbps","isStreaming":false,"title":"未来へ","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 未来へ.flac","trackType":"flac"} Aug 29 14:29:52 volumio volumio[1069]: verbose: CURRENT POSITION 1 Aug 29 14:29:52 volumio volumio[1069]: info: CoreStateMachine::syncState stateService pause Aug 29 14:29:52 volumio volumio[1069]: info: CoreStateMachine::syncState currentStatus pause Aug 29 14:29:52 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:29:52 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:29:52 volumio volumio[1069]: info: CoreStateMachine::stPlaybackTimer Aug 29 14:29:52 volumio volumio[1069]: info: ControllerMpd::pushState Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::servicePushState Aug 29 14:29:52 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:29:52 volumio volumio[1069]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":74782,"duration":325,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4839 Kbps","isStreaming":false,"title":"未来へ","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 未来へ.flac","trackType":"flac"} Aug 29 14:29:52 volumio volumio[1069]: verbose: CURRENT POSITION 1 Aug 29 14:29:52 volumio volumio[1069]: info: CoreStateMachine::syncState stateService pause Aug 29 14:29:52 volumio volumio[1069]: info: CoreStateMachine::syncState currentStatus pause Aug 29 14:29:52 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:29:52 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:29:52 volumio volumio[1069]: info: CoreStateMachine::stPlaybackTimer Aug 29 14:29:52 volumio volumio[1069]: info: ------------------------------ 337ms Aug 29 14:29:52 volumio volumio[1069]: info: ------------------------------ 332ms Aug 29 14:29:52 volumio volumio[1069]: info: ------------------------------ 331ms Aug 29 14:29:52 volumio volumio[1069]: info: ------------------------------ 322ms Aug 29 14:29:52 volumio volumio[1069]: info: VolumeController:: Volume=41 Mute =false Aug 29 14:29:52 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:29:52 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 29 14:29:52 volumio volumio[1069]: info: VolumeController:: Volume=41 Mute =false Aug 29 14:29:52 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:29:52 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:29:52 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:29:52 volumio volumio[1069]: info: Asound.conf file written Aug 29 14:29:53 volumio sudo[2248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 29 14:29:53 volumio sudo[2248]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:29:53 volumio sudo[2248]: pam_unix(sudo:session): session closed for user root Aug 29 14:29:53 volumio volumio[1069]: info: Output device has changed, restarting MPD Aug 29 14:29:53 volumio volumio[1069]: info: Output device has changed, restarting Shairport Sync Aug 29 14:29:53 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:29:53 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:29:53 volumio sudo[2254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:29:53 volumio sudo[2256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:29:53 volumio sudo[2256]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:29:53 volumio sudo[2254]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:29:53 volumio sudo[2254]: pam_unix(sudo:session): session closed for user root Aug 29 14:29:53 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 14:29:53 volumio volumio[1069]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:29:53 volumio volumio[1069]: info: Preparing to generate the ALSA configuration file Aug 29 14:29:53 volumio volumio[1069]: info: Output device has changed, restarting MPD Aug 29 14:29:53 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:29:53 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:29:53 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:29:53 volumio sudo[2264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:29:53 volumio volumio[1069]: info: Output device has changed, restarting Shairport Sync Aug 29 14:29:53 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:29:53 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:29:53 volumio sudo[2264]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:29:53 volumio sudo[2264]: pam_unix(sudo:session): session closed for user root Aug 29 14:29:53 volumio sudo[2266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:29:53 volumio sudo[2266]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:29:53 volumio sudo[2263]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:29:53 volumio sudo[2263]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:29:53 volumio sudo[2263]: pam_unix(sudo:session): session closed for user root Aug 29 14:29:53 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 29 14:29:53 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:29:53 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:29:53 volumio volumio[1069]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:29:53 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:29:53 volumio volumio[1069]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Aug 29 14:29:53 volumio volumio[1069]: info: Reading ALSA contributions from plugins. Aug 29 14:29:53 volumio volumio[1069]: info: MPD Permissions set Aug 29 14:29:53 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:29:53 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:29:53 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:29:53 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:29:53 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:29:54 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:29:54 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 29 14:29:54 volumio sudo[2272]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:29:54 volumio sudo[2272]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:29:54 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:29:54 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:29:54 volumio sudo[2272]: pam_unix(sudo:session): session closed for user root Aug 29 14:29:54 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:29:54 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:29:54 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 29 14:29:54 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:29:54 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:29:54 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:29:54 volumio volumio[1069]: info: Starting Shairport Sync Aug 29 14:29:54 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:29:54 volumio volumio[1069]: info: Starting Shairport Sync Aug 29 14:29:54 volumio sudo[2292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:29:54 volumio volumio[1069]: info: Asound.conf file unchanged, so no further update is needed Aug 29 14:29:54 volumio volumio[1069]: info: Output device has changed, restarting MPD Aug 29 14:29:54 volumio sudo[2292]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:29:54 volumio sudo[2295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:29:54 volumio sudo[2295]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:29:54 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 14:29:54 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 14:29:54 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 14:29:54 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 14:29:54 volumio sudo[2292]: pam_unix(sudo:session): session closed for user root Aug 29 14:29:54 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 14:29:54 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 29 14:29:54 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 14:29:54 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 14:29:54 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 14:29:54 volumio sudo[2304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:29:54 volumio sudo[2295]: pam_unix(sudo:session): session closed for user root Aug 29 14:29:54 volumio sudo[2304]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:29:54 volumio volumio[1069]: info: Output device has changed, restarting Shairport Sync Aug 29 14:29:54 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:29:54 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:29:54 volumio sudo[2304]: pam_unix(sudo:session): session closed for user root Aug 29 14:29:55 volumio sudo[2309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:29:55 volumio sudo[2309]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:29:55 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 29 14:29:55 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:29:55 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:29:55 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:29:55 volumio volumio[1069]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:29:55 volumio volumio[1069]: info: MPD Permissions set Aug 29 14:29:55 volumio volumio[1069]: info: Shairport-Sync Started Aug 29 14:29:55 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:29:55 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:29:55 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:29:55 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:29:55 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:29:55 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 29 14:29:55 volumio sudo[2317]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:29:55 volumio sudo[2317]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:29:55 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:29:55 volumio volumio[1069]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 29 14:29:55 volumio volumio[1069]: info: CoreStateMachine::getcurrentVolume Aug 29 14:29:55 volumio volumio[1069]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 14:29:55 volumio sudo[2317]: pam_unix(sudo:session): session closed for user root Aug 29 14:29:55 volumio volumio[1069]: info: Starting Shairport Sync Aug 29 14:29:55 volumio sudo[2333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:29:55 volumio sudo[2333]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:29:55 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:29:55 volumio volumio[1069]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 29 14:29:55 volumio volumio[1069]: info: CoreStateMachine::getcurrentVolume Aug 29 14:29:55 volumio volumio[1069]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 14:29:55 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 14:29:55 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 14:29:55 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 14:29:55 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 14:29:55 volumio sudo[2333]: pam_unix(sudo:session): session closed for user root Aug 29 14:29:55 volumio volumio[1069]: info: Shairport-Sync Started Aug 29 14:29:55 volumio volumio[1069]: info: VolumeController:: Volume=41 Mute =false Aug 29 14:29:55 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:29:55 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:29:55 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:29:56 volumio volumio[1069]: info: CoreStateMachine::updateTrackBlock Aug 29 14:29:56 volumio volumio[1069]: info: CorePlayQueue::getTrackBlock Aug 29 14:29:56 volumio volumio[1069]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 14:29:56 volumio volumio[1069]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 6 Aug 29 14:29:56 volumio volumio[1069]: info: VolumeController:: Volume=41 Mute =false Aug 29 14:29:56 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:29:56 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:29:56 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:29:56 volumio volumio[1069]: info: CoreStateMachine::updateTrackBlock Aug 29 14:29:56 volumio volumio[1069]: info: CorePlayQueue::getTrackBlock Aug 29 14:29:56 volumio volumio[1069]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 14:29:56 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Aug 29 14:29:56 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:29:56 volumio volumio[1069]: info: VolumeController:: Volume=41 Mute =false Aug 29 14:29:56 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:29:56 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:29:56 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:29:57 volumio volumio[1069]: info: VolumeController:: Volume=41 Mute =false Aug 29 14:29:57 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:29:57 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:29:57 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:29:58 volumio mpd[2328]: Aug 29 14:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 14:29:58 volumio systemd[1]: Started Music Player Daemon. Aug 29 14:29:58 volumio volumio[1069]: Upnp client error: Error: This socket has been ended by the other party Aug 29 14:29:58 volumio sudo[2256]: pam_unix(sudo:session): session closed for user root Aug 29 14:29:58 volumio sudo[2309]: pam_unix(sudo:session): session closed for user root Aug 29 14:29:58 volumio sudo[2266]: pam_unix(sudo:session): session closed for user root Aug 29 14:29:58 volumio volumio[1069]: error: MPD error: The expression evaluated to a falsy value: Aug 29 14:29:58 volumio volumio[1069]: assert.ok(self.idling) Aug 29 14:29:58 volumio volumio[1069]: error: The expression evaluated to a falsy value: Aug 29 14:29:58 volumio volumio[1069]: assert.ok(self.idling) Aug 29 14:29:58 volumio volumio[1069]: error: MPD error: The expression evaluated to a falsy value: Aug 29 14:29:58 volumio volumio[1069]: assert.ok(self.idling) Aug 29 14:29:58 volumio volumio[1069]: error: The expression evaluated to a falsy value: Aug 29 14:29:58 volumio volumio[1069]: assert.ok(self.idling) Aug 29 14:29:58 volumio volumio[1069]: error: updateQueue error: null Aug 29 14:29:59 volumio volumio[1069]: error: Upnp client error: Error: This socket has been ended by the other party Aug 29 14:29:59 volumio volumio[1069]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 Aug 29 14:29:59 volumio sudo[2211]: pam_unix(sudo:session): session closed for user root Aug 29 14:30:06 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Aug 29 14:30:06 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:30:09 volumio volumio[1069]: info: CoreCommandRouter::volumioNext Aug 29 14:30:09 volumio volumio[1069]: info: CoreStateMachine::next Aug 29 14:30:09 volumio volumio[1069]: info: CoreStateMachine::stop Aug 29 14:30:09 volumio volumio[1069]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 14:30:09 volumio volumio[1069]: info: CoreStateMachine::updateTrackBlock Aug 29 14:30:09 volumio volumio[1069]: info: CorePlayQueue::getTrackBlock Aug 29 14:30:09 volumio volumio[1069]: info: CoreStateMachine::stPlaybackTimer Aug 29 14:30:09 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:30:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:30:09 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 14:30:09 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:30:09 volumio volumio[1069]: info: CoreStateMachine::serviceStop Aug 29 14:30:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 1 Aug 29 14:30:09 volumio volumio[1069]: info: CoreCommandRouter::serviceStop Aug 29 14:30:09 volumio volumio[1069]: info: ControllerMpd::stop Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 14:30:09 volumio volumio[1069]: info: sendMpdCommand stop took 16 milliseconds Aug 29 14:30:09 volumio volumio[1069]: info: CoreStateMachine::play index undefined Aug 29 14:30:09 volumio volumio[1069]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 14:30:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:09 volumio volumio[1069]: info: CoreStateMachine::startPlaybackTimer Aug 29 14:30:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::clearAddPlayTracks USB/Richard Sanderson - Reality.flac Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 14:30:09 volumio volumio[1069]: info: CoreStateMachine::updateTrackBlock Aug 29 14:30:09 volumio volumio[1069]: info: CorePlayQueue::getTrackBlock Aug 29 14:30:09 volumio volumio[1069]: info: sendMpdCommand stop took 3 milliseconds Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 14:30:09 volumio volumio[1069]: info: Aug 29 14:30:09 volumio volumio[1069]: ---------------------------- MPD announces system playlist update Aug 29 14:30:09 volumio volumio[1069]: info: Ignoring MPD Status Update Aug 29 14:30:09 volumio volumio[1069]: info: sendMpdCommand clear took 2 milliseconds Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand add "USB/Richard Sanderson - Reality.flac" Aug 29 14:30:09 volumio volumio[1069]: info: Aug 29 14:30:09 volumio volumio[1069]: ---------------------------- MPD announces system playlist update Aug 29 14:30:09 volumio volumio[1069]: info: Ignoring MPD Status Update Aug 29 14:30:09 volumio volumio[1069]: info: Aug 29 14:30:09 volumio volumio[1069]: ---------------------------- MPD announces system playlist update Aug 29 14:30:09 volumio volumio[1069]: info: Ignoring MPD Status Update Aug 29 14:30:09 volumio volumio[1069]: error: updateQueue error: null Aug 29 14:30:09 volumio volumio[1069]: info: Aug 29 14:30:09 volumio volumio[1069]: ---------------------------- MPD announces system playlist update Aug 29 14:30:09 volumio volumio[1069]: info: Ignoring MPD Status Update Aug 29 14:30:09 volumio volumio[1069]: info: ------------------------------ 12ms Aug 29 14:30:09 volumio volumio[1069]: info: sendMpdCommand add "USB/Richard Sanderson - Reality.flac" took 10 milliseconds Aug 29 14:30:09 volumio volumio[1069]: info: ------------------------------ 8ms Aug 29 14:30:09 volumio volumio[1069]: info: ------------------------------ 7ms Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand play Aug 29 14:30:09 volumio volumio[1069]: info: Aug 29 14:30:09 volumio volumio[1069]: ---------------------------- MPD announces system playlist update Aug 29 14:30:09 volumio volumio[1069]: info: Ignoring MPD Status Update Aug 29 14:30:09 volumio volumio[1069]: info: Aug 29 14:30:09 volumio volumio[1069]: ---------------------------- MPD announces system playlist update Aug 29 14:30:09 volumio volumio[1069]: info: Ignoring MPD Status Update Aug 29 14:30:09 volumio volumio[1069]: info: Aug 29 14:30:09 volumio volumio[1069]: ---------------------------- MPD announces state update: player Aug 29 14:30:09 volumio volumio[1069]: info: ------------------------------ 29ms Aug 29 14:30:09 volumio volumio[1069]: info: sendMpdCommand play took 26 milliseconds Aug 29 14:30:09 volumio volumio[1069]: info: ------------------------------ 24ms Aug 29 14:30:09 volumio volumio[1069]: info: ControllerMpd::getState Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Aug 29 14:30:09 volumio volumio[1069]: info: Aug 29 14:30:09 volumio volumio[1069]: ---------------------------- MPD announces state update: player Aug 29 14:30:09 volumio volumio[1069]: info: ControllerMpd::getState Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Aug 29 14:30:09 volumio volumio[1069]: info: Aug 29 14:30:09 volumio volumio[1069]: ---------------------------- MPD announces state update: player Aug 29 14:30:09 volumio volumio[1069]: info: ControllerMpd::getState Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Aug 29 14:30:09 volumio volumio[1069]: info: Aug 29 14:30:09 volumio volumio[1069]: ---------------------------- MPD announces state update: player Aug 29 14:30:09 volumio volumio[1069]: info: ------------------------------ 94ms Aug 29 14:30:09 volumio volumio[1069]: info: sendMpdCommand status took 69 milliseconds Aug 29 14:30:09 volumio volumio[1069]: info: sendMpdCommand status took 68 milliseconds Aug 29 14:30:09 volumio volumio[1069]: info: sendMpdCommand status took 62 milliseconds Aug 29 14:30:09 volumio volumio[1069]: info: ControllerMpd::getState Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::parseState Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::parseState Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::parseState Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 14:30:09 volumio volumio[1069]: info: Aug 29 14:30:09 volumio volumio[1069]: ---------------------------- MPD announces state update: player Aug 29 14:30:09 volumio volumio[1069]: info: ControllerMpd::getState Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Aug 29 14:30:09 volumio volumio[1069]: info: Aug 29 14:30:09 volumio volumio[1069]: ---------------------------- MPD announces state update: player Aug 29 14:30:09 volumio volumio[1069]: info: ControllerMpd::getState Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Aug 29 14:30:09 volumio volumio[1069]: info: sendMpdCommand status took 21 milliseconds Aug 29 14:30:09 volumio volumio[1069]: info: sendMpdCommand playlistinfo took 21 milliseconds Aug 29 14:30:09 volumio volumio[1069]: info: sendMpdCommand playlistinfo took 20 milliseconds Aug 29 14:30:09 volumio volumio[1069]: info: sendMpdCommand status took 13 milliseconds Aug 29 14:30:09 volumio volumio[1069]: info: sendMpdCommand status took 14 milliseconds Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::parseState Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::parseTrackInfo Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::parseState Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::parseState Aug 29 14:30:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 14:30:09 volumio volumio[1069]: info: ControllerMpd::pushState Aug 29 14:30:09 volumio volumio[1069]: info: CoreCommandRouter::servicePushState Aug 29 14:30:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:09 volumio volumio[1069]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Reality","artist":"Richard Sanderson","album":"Reality","uri":"USB/Richard Sanderson - Reality.flac","trackType":"flac"} Aug 29 14:30:09 volumio volumio[1069]: verbose: CURRENT POSITION 2 Aug 29 14:30:09 volumio volumio[1069]: info: CoreStateMachine::syncState stateService play Aug 29 14:30:09 volumio volumio[1069]: info: CoreStateMachine::syncState currentStatus stop Aug 29 14:30:09 volumio volumio[1069]: info: ControllerMpd::pushState Aug 29 14:30:09 volumio volumio[1069]: info: CoreCommandRouter::servicePushState Aug 29 14:30:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:09 volumio volumio[1069]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"839 Kbps","isStreaming":false,"title":"Reality","artist":"Richard Sanderson","album":"Reality","uri":"USB/Richard Sanderson - Reality.flac","trackType":"flac"} Aug 29 14:30:09 volumio volumio[1069]: verbose: CURRENT POSITION 2 Aug 29 14:30:09 volumio volumio[1069]: info: CoreStateMachine::syncState stateService play Aug 29 14:30:09 volumio volumio[1069]: info: CoreStateMachine::syncState currentStatus play Aug 29 14:30:09 volumio volumio[1069]: info: Received an update from plugin. extracting info from payload Aug 29 14:30:09 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:30:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:09 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 14:30:09 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:30:09 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:30:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:09 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:30:09 volumio volumio[1069]: info: ControllerMpd::pushState Aug 29 14:30:09 volumio volumio[1069]: info: CoreCommandRouter::servicePushState Aug 29 14:30:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:09 volumio volumio[1069]: verbose: STATE SERVICE {"status":"play","position":0,"seek":387,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"828 Kbps","isStreaming":false,"title":"Reality","artist":"Richard Sanderson","album":"Reality","uri":"USB/Richard Sanderson - Reality.flac","trackType":"flac"} Aug 29 14:30:09 volumio volumio[1069]: verbose: CURRENT POSITION 2 Aug 29 14:30:10 volumio volumio[1069]: info: CoreStateMachine::syncState stateService play Aug 29 14:30:10 volumio volumio[1069]: info: CoreStateMachine::syncState currentStatus play Aug 29 14:30:10 volumio volumio[1069]: info: Received an update from plugin. extracting info from payload Aug 29 14:30:10 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:30:10 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:10 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 14:30:10 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:30:10 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:30:10 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:10 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:30:10 volumio volumio[1069]: info: ------------------------------ 173ms Aug 29 14:30:10 volumio volumio[1069]: info: ------------------------------ 213ms Aug 29 14:30:10 volumio volumio[1069]: info: ------------------------------ 214ms Aug 29 14:30:10 volumio volumio[1069]: info: sendMpdCommand playlistinfo took 130 milliseconds Aug 29 14:30:10 volumio volumio[1069]: info: sendMpdCommand playlistinfo took 132 milliseconds Aug 29 14:30:10 volumio volumio[1069]: info: sendMpdCommand playlistinfo took 128 milliseconds Aug 29 14:30:10 volumio volumio[1069]: verbose: ControllerMpd::parseTrackInfo Aug 29 14:30:10 volumio volumio[1069]: info: ControllerMpd::pushState Aug 29 14:30:10 volumio volumio[1069]: info: CoreCommandRouter::servicePushState Aug 29 14:30:10 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:10 volumio volumio[1069]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"847 Kbps","isStreaming":false,"title":"Reality","artist":"Richard Sanderson","album":"Reality","uri":"USB/Richard Sanderson - Reality.flac","trackType":"flac"} Aug 29 14:30:10 volumio volumio[1069]: verbose: CURRENT POSITION 2 Aug 29 14:30:10 volumio volumio[1069]: info: CoreStateMachine::syncState stateService play Aug 29 14:30:10 volumio volumio[1069]: info: CoreStateMachine::syncState currentStatus play Aug 29 14:30:10 volumio volumio[1069]: info: Received an update from plugin. extracting info from payload Aug 29 14:30:10 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:30:10 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:10 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 14:30:10 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:30:10 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:30:10 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:10 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:30:10 volumio volumio[1069]: info: ControllerMpd::pushState Aug 29 14:30:10 volumio volumio[1069]: info: CoreCommandRouter::servicePushState Aug 29 14:30:10 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:10 volumio volumio[1069]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"847 Kbps","isStreaming":false,"title":"Reality","artist":"Richard Sanderson","album":"Reality","uri":"USB/Richard Sanderson - Reality.flac","trackType":"flac"} Aug 29 14:30:10 volumio volumio[1069]: verbose: CURRENT POSITION 2 Aug 29 14:30:10 volumio volumio[1069]: info: CoreStateMachine::syncState stateService play Aug 29 14:30:10 volumio volumio[1069]: info: CoreStateMachine::syncState currentStatus play Aug 29 14:30:10 volumio volumio[1069]: info: Received an update from plugin. extracting info from payload Aug 29 14:30:10 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:30:10 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:10 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:30:10 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:30:10 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:10 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:30:10 volumio volumio[1069]: info: ControllerMpd::pushState Aug 29 14:30:10 volumio volumio[1069]: info: CoreCommandRouter::servicePushState Aug 29 14:30:10 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:10 volumio volumio[1069]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"847 Kbps","isStreaming":false,"title":"Reality","artist":"Richard Sanderson","album":"Reality","uri":"USB/Richard Sanderson - Reality.flac","trackType":"flac"} Aug 29 14:30:10 volumio volumio[1069]: verbose: CURRENT POSITION 2 Aug 29 14:30:10 volumio volumio[1069]: info: CoreStateMachine::syncState stateService play Aug 29 14:30:10 volumio volumio[1069]: info: CoreStateMachine::syncState currentStatus play Aug 29 14:30:10 volumio volumio[1069]: info: Received an update from plugin. extracting info from payload Aug 29 14:30:10 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:30:10 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:10 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:30:10 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:30:10 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:10 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:30:10 volumio volumio[1069]: info: ------------------------------ 325ms Aug 29 14:30:10 volumio volumio[1069]: info: ------------------------------ 306ms Aug 29 14:30:10 volumio volumio[1069]: info: ------------------------------ 300ms Aug 29 14:30:11 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Aug 29 14:30:11 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:11 volumio sudo[2378]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Aug 29 14:30:11 volumio sudo[2378]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:30:14 volumio volumio[1069]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 Aug 29 14:30:15 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Aug 29 14:30:15 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:16 volumio volumio[1069]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8 Aug 29 14:30:16 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Aug 29 14:30:16 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:17 volumio sudo[2378]: pam_unix(sudo:session): session closed for user root Aug 29 14:30:23 volumio volumio[1069]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:30:23 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 29 14:30:23 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:30:23 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 14:30:23 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:30:23 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:30:23 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:30:23 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 29 14:30:23 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 29 14:30:28 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Aug 29 14:30:28 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:28 volumio sudo[2466]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Aug 29 14:30:28 volumio sudo[2466]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:30:31 volumio volumio[1069]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 29 14:30:31 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 29 14:30:31 volumio volumio[1069]: info: Preparing to save Alsa Options, stopping services first Aug 29 14:30:31 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Aug 29 14:30:31 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:31 volumio volumio[1069]: info: CoreCommandRouter::volumioPause Aug 29 14:30:31 volumio volumio[1069]: info: CoreStateMachine::pause Aug 29 14:30:31 volumio volumio[1069]: info: CoreStateMachine::stPlaybackTimer Aug 29 14:30:31 volumio volumio[1069]: info: CoreStateMachine::servicePause Aug 29 14:30:31 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:31 volumio volumio[1069]: info: CoreCommandRouter::servicePause Aug 29 14:30:31 volumio volumio[1069]: info: ControllerMpd::pause Aug 29 14:30:31 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand pause Aug 29 14:30:31 volumio volumio[1069]: info: Saving Audio Output to: {"output_device":{"value":"7","label":"Dummy"}} Aug 29 14:30:31 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 14:30:31 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 14:30:31 volumio volumio[1069]: info: Setting mixer Master for card Dummy Aug 29 14:30:31 volumio volumio[1069]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 29 14:30:31 volumio volumio[1069]: info: Updating Volume Controller Parameters: Device: 7 Name: Dummy Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 29 14:30:31 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 29 14:30:31 volumio volumio[1069]: info: Disabling external Volume Control Aug 29 14:30:31 volumio volumio[1069]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:30:31 volumio volumio[1069]: info: Preparing to generate the ALSA configuration file Aug 29 14:30:31 volumio volumio[1069]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 29 14:30:31 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 29 14:30:31 volumio volumio[1069]: info: Preparing to save Alsa Options, stopping services first Aug 29 14:30:31 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Aug 29 14:30:31 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:31 volumio volumio[1069]: info: CoreCommandRouter::volumioPause Aug 29 14:30:31 volumio volumio[1069]: info: CoreStateMachine::pause Aug 29 14:30:31 volumio volumio[1069]: info: Saving Audio Output to: {"output_device":{"value":"7","label":"Dummy"}} Aug 29 14:30:31 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 14:30:31 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 14:30:32 volumio volumio[1069]: info: Setting mixer Master for card Dummy Aug 29 14:30:32 volumio volumio[1069]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 29 14:30:32 volumio volumio[1069]: info: Updating Volume Controller Parameters: Device: 7 Name: Dummy Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 29 14:30:32 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 29 14:30:32 volumio volumio[1069]: info: Disabling external Volume Control Aug 29 14:30:32 volumio volumio[1069]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:30:32 volumio volumio[1069]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Aug 29 14:30:32 volumio volumio[1069]: info: Reading ALSA contributions from plugins. Aug 29 14:30:32 volumio volumio[1069]: info: Aug 29 14:30:32 volumio volumio[1069]: ---------------------------- MPD announces state update: player Aug 29 14:30:32 volumio volumio[1069]: info: sendMpdCommand pause took 1082 milliseconds Aug 29 14:30:32 volumio volumio[1069]: info: ControllerMpd::getState Aug 29 14:30:32 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Aug 29 14:30:32 volumio volumio[1069]: info: Aug 29 14:30:32 volumio volumio[1069]: ---------------------------- MPD announces state update: player Aug 29 14:30:32 volumio volumio[1069]: info: ControllerMpd::getState Aug 29 14:30:32 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Aug 29 14:30:32 volumio volumio[1069]: info: Aug 29 14:30:32 volumio volumio[1069]: ---------------------------- MPD announces state update: player Aug 29 14:30:32 volumio volumio[1069]: info: ControllerMpd::getState Aug 29 14:30:32 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Aug 29 14:30:32 volumio volumio[1069]: info: sendMpdCommand status took 29 milliseconds Aug 29 14:30:32 volumio volumio[1069]: info: sendMpdCommand status took 21 milliseconds Aug 29 14:30:32 volumio volumio[1069]: info: sendMpdCommand status took 16 milliseconds Aug 29 14:30:32 volumio volumio[1069]: verbose: ControllerMpd::parseState Aug 29 14:30:32 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 14:30:32 volumio volumio[1069]: verbose: ControllerMpd::parseState Aug 29 14:30:32 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 14:30:32 volumio volumio[1069]: verbose: ControllerMpd::parseState Aug 29 14:30:32 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 14:30:32 volumio volumio[1069]: Unable to parse: Aug 29 14:30:32 volumio volumio[1069]: Simple mixer control 'Master',0 Aug 29 14:30:32 volumio volumio[1069]: Capabilities: volume cswitch Aug 29 14:30:32 volumio volumio[1069]: Playback channels: Front Left - Front Right Aug 29 14:30:32 volumio volumio[1069]: Capture channels: Front Left - Front Right Aug 29 14:30:32 volumio volumio[1069]: Limits: -50 - 100 Aug 29 14:30:32 volumio volumio[1069]: Front Left: 0 [17%] [-30.00dB] Capture [off] Aug 29 14:30:32 volumio volumio[1069]: Front Right: 0 [17%] [-30.00dB] Capture [off] Aug 29 14:30:32 volumio volumio[1069]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Aug 29 14:30:32 volumio volumio[1069]: Unable to parse: Aug 29 14:30:32 volumio volumio[1069]: Simple mixer control 'Master',0 Aug 29 14:30:32 volumio volumio[1069]: Capabilities: volume cswitch Aug 29 14:30:32 volumio volumio[1069]: Playback channels: Front Left - Front Right Aug 29 14:30:32 volumio volumio[1069]: Capture channels: Front Left - Front Right Aug 29 14:30:32 volumio volumio[1069]: Limits: -50 - 100 Aug 29 14:30:32 volumio volumio[1069]: Front Left: 0 [17%] [-30.00dB] Capture [off] Aug 29 14:30:32 volumio volumio[1069]: Front Right: 0 [17%] [-30.00dB] Capture [off] Aug 29 14:30:32 volumio volumio[1069]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Aug 29 14:30:32 volumio volumio[1069]: info: sendMpdCommand playlistinfo took 127 milliseconds Aug 29 14:30:32 volumio volumio[1069]: info: sendMpdCommand playlistinfo took 128 milliseconds Aug 29 14:30:32 volumio volumio[1069]: info: sendMpdCommand playlistinfo took 128 milliseconds Aug 29 14:30:32 volumio volumio[1069]: verbose: ControllerMpd::parseTrackInfo Aug 29 14:30:32 volumio volumio[1069]: verbose: ControllerMpd::parseTrackInfo Aug 29 14:30:32 volumio volumio[1069]: verbose: ControllerMpd::parseTrackInfo Aug 29 14:30:32 volumio volumio[1069]: info: ControllerMpd::pushState Aug 29 14:30:32 volumio volumio[1069]: info: CoreCommandRouter::servicePushState Aug 29 14:30:32 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:32 volumio volumio[1069]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22538,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"849 Kbps","isStreaming":false,"title":"Reality","artist":"Richard Sanderson","album":"Reality","uri":"USB/Richard Sanderson - Reality.flac","trackType":"flac"} Aug 29 14:30:32 volumio volumio[1069]: verbose: CURRENT POSITION 2 Aug 29 14:30:32 volumio volumio[1069]: info: CoreStateMachine::syncState stateService pause Aug 29 14:30:32 volumio volumio[1069]: info: CoreStateMachine::syncState currentStatus pause Aug 29 14:30:32 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:30:32 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:32 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 14:30:32 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:30:32 volumio volumio[1069]: info: CoreStateMachine::stPlaybackTimer Aug 29 14:30:32 volumio volumio[1069]: info: ControllerMpd::pushState Aug 29 14:30:32 volumio volumio[1069]: info: CoreCommandRouter::servicePushState Aug 29 14:30:32 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:32 volumio volumio[1069]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22538,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"849 Kbps","isStreaming":false,"title":"Reality","artist":"Richard Sanderson","album":"Reality","uri":"USB/Richard Sanderson - Reality.flac","trackType":"flac"} Aug 29 14:30:32 volumio volumio[1069]: verbose: CURRENT POSITION 2 Aug 29 14:30:32 volumio volumio[1069]: info: CoreStateMachine::syncState stateService pause Aug 29 14:30:32 volumio volumio[1069]: info: CoreStateMachine::syncState currentStatus pause Aug 29 14:30:32 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:30:32 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:32 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:30:32 volumio volumio[1069]: info: CoreStateMachine::stPlaybackTimer Aug 29 14:30:32 volumio volumio[1069]: info: ControllerMpd::pushState Aug 29 14:30:32 volumio volumio[1069]: info: CoreCommandRouter::servicePushState Aug 29 14:30:32 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:32 volumio volumio[1069]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22538,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"849 Kbps","isStreaming":false,"title":"Reality","artist":"Richard Sanderson","album":"Reality","uri":"USB/Richard Sanderson - Reality.flac","trackType":"flac"} Aug 29 14:30:32 volumio volumio[1069]: verbose: CURRENT POSITION 2 Aug 29 14:30:32 volumio volumio[1069]: info: CoreStateMachine::syncState stateService pause Aug 29 14:30:32 volumio volumio[1069]: info: CoreStateMachine::syncState currentStatus pause Aug 29 14:30:32 volumio volumio[1069]: info: CoreStateMachine::pushState Aug 29 14:30:32 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:32 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Aug 29 14:30:32 volumio volumio[1069]: info: CoreStateMachine::stPlaybackTimer Aug 29 14:30:32 volumio volumio[1069]: info: ------------------------------ 301ms Aug 29 14:30:32 volumio volumio[1069]: info: ------------------------------ 289ms Aug 29 14:30:32 volumio volumio[1069]: info: ------------------------------ 287ms Aug 29 14:30:32 volumio volumio[1069]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 29 14:30:32 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 29 14:30:32 volumio volumio[1069]: info: Preparing to save Alsa Options, stopping services first Aug 29 14:30:32 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Aug 29 14:30:32 volumio volumio[1069]: info: CorePlayQueue::getTrack 2 Aug 29 14:30:32 volumio volumio[1069]: info: CoreCommandRouter::volumioPause Aug 29 14:30:32 volumio volumio[1069]: info: CoreStateMachine::pause Aug 29 14:30:32 volumio volumio[1069]: info: Saving Audio Output to: {"output_device":{"value":"7","label":"Dummy"}} Aug 29 14:30:32 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 14:30:32 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 14:30:32 volumio volumio[1069]: info: Setting mixer Master for card Dummy Aug 29 14:30:32 volumio volumio[1069]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 29 14:30:32 volumio volumio[1069]: info: Updating Volume Controller Parameters: Device: 7 Name: Dummy Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 29 14:30:32 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 29 14:30:32 volumio volumio[1069]: info: Disabling external Volume Control Aug 29 14:30:32 volumio volumio[1069]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:30:33 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 29 14:30:33 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:30:33 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 14:30:33 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:30:33 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 29 14:30:33 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 29 14:30:33 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 29 14:30:33 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:30:33 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 14:30:33 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:30:33 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:30:33 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:30:33 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:30:33 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:30:33 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:30:33 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:30:33 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 29 14:30:33 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 29 14:30:33 volumio volumio[1069]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 14:30:33 volumio volumio[1069]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Aug 29 14:30:33 volumio volumio[1069]: at Object.readdirSync (fs.js:1048:3) Aug 29 14:30:33 volumio volumio[1069]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Aug 29 14:30:33 volumio volumio[1069]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Aug 29 14:30:33 volumio volumio[1069]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Aug 29 14:30:33 volumio volumio[1069]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Aug 29 14:30:33 volumio volumio[1069]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Aug 29 14:30:33 volumio volumio[1069]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Aug 29 14:30:33 volumio volumio[1069]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Aug 29 14:30:33 volumio volumio[1069]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Aug 29 14:30:33 volumio volumio[1069]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Aug 29 14:30:33 volumio volumio[1069]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Aug 29 14:30:33 volumio volumio[1069]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Aug 29 14:30:33 volumio volumio[1069]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Aug 29 14:30:33 volumio volumio[1069]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Aug 29 14:30:33 volumio volumio[1069]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Aug 29 14:30:33 volumio volumio[1069]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Aug 29 14:30:33 volumio volumio[1069]: errno: -20, Aug 29 14:30:33 volumio volumio[1069]: syscall: 'scandir', Aug 29 14:30:33 volumio volumio[1069]: code: 'ENOTDIR', Aug 29 14:30:33 volumio volumio[1069]: path: '/tmp/peppyrunning' Aug 29 14:30:33 volumio volumio[1069]: } Aug 29 14:30:33 volumio volumio[1069]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 14:30:33 volumio sudo[2530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 14:29 Aug 29 14:30:33 volumio sudo[2530]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"