Jan 13 17:43:10 slaudio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 75. Jan 13 17:43:10 slaudio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 17:43:10 slaudio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 17:43:10 slaudio upmpdcli[5158]: Could not open config: /tmp/upmpdcli.conf Jan 13 17:43:10 slaudio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:43:10 slaudio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 13 17:43:25 slaudio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 76. Jan 13 17:43:25 slaudio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 17:43:25 slaudio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 17:43:25 slaudio upmpdcli[5187]: Could not open config: /tmp/upmpdcli.conf Jan 13 17:43:25 slaudio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:43:25 slaudio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 13 17:43:31 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 13 17:43:31 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 13 17:43:40 slaudio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 77. Jan 13 17:43:40 slaudio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 17:43:40 slaudio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 17:43:40 slaudio upmpdcli[5206]: Could not open config: /tmp/upmpdcli.conf Jan 13 17:43:40 slaudio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:43:40 slaudio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 13 17:43:55 slaudio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 78. Jan 13 17:43:55 slaudio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 17:43:55 slaudio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 17:43:55 slaudio upmpdcli[5239]: Could not open config: /tmp/upmpdcli.conf Jan 13 17:43:55 slaudio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:43:55 slaudio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 13 17:44:11 slaudio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 79. Jan 13 17:44:11 slaudio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 17:44:11 slaudio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 17:44:11 slaudio upmpdcli[5258]: Could not open config: /tmp/upmpdcli.conf Jan 13 17:44:11 slaudio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:44:11 slaudio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 13 17:44:16 slaudio volumio[1514]: verbose: New Socket.io Connection to 192.168.0.107:3000 from 192.168.0.106 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jan 13 17:44:17 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 17:44:17 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 17:44:17 slaudio volumio[1514]: info: Discovery: Getting this device information Jan 13 17:44:17 slaudio volumio[1514]: info: CoreCommandRouter::volumioGetState Jan 13 17:44:17 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4459 Jan 13 17:44:17 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 17:44:26 slaudio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 80. Jan 13 17:44:26 slaudio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 17:44:26 slaudio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 17:44:26 slaudio upmpdcli[5288]: Could not open config: /tmp/upmpdcli.conf Jan 13 17:44:26 slaudio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:44:26 slaudio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 13 17:44:30 slaudio volumio[1514]: info: Stopping peppymeterbasic service Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::stop Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::stPlaybackTimer Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::updateTrackBlock Jan 13 17:44:30 slaudio volumio[1514]: info: CorePlayQueue::getTrackBlock Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::pushState Jan 13 17:44:30 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4459 Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::volumioPushState Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::serviceStop Jan 13 17:44:30 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4459 Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::serviceStop Jan 13 17:44:30 slaudio volumio[1514]: info: ControllerMpd::stop Jan 13 17:44:30 slaudio volumio[1514]: verbose: ControllerMpd::sendMpdCommand stop Jan 13 17:44:30 slaudio volumio[1514]: info: Disabling plugin peppymeterbasic Jan 13 17:44:30 slaudio volumio[1514]: info: Preparing to generate the ALSA configuration file Jan 13 17:44:30 slaudio volumio[1514]: info: PeppyMeterBasic ---peppymeterbasic status stop Jan 13 17:44:30 slaudio volumio[1514]: info: Jan 13 17:44:30 slaudio volumio[1514]: ---------------------------- MPD announces state update: player Jan 13 17:44:30 slaudio volumio[1514]: info: sendMpdCommand stop took 30 milliseconds Jan 13 17:44:30 slaudio volumio[1514]: info: ControllerMpd::getState Jan 13 17:44:30 slaudio volumio[1514]: verbose: ControllerMpd::sendMpdCommand status Jan 13 17:44:30 slaudio sudo[5294]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jan 13 17:44:30 slaudio sudo[5294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 17:44:30 slaudio volumio[1514]: info: Jan 13 17:44:30 slaudio volumio[1514]: ---------------------------- MPD announces state update: player Jan 13 17:44:30 slaudio volumio[1514]: info: ControllerMpd::getState Jan 13 17:44:30 slaudio volumio[1514]: verbose: ControllerMpd::sendMpdCommand status Jan 13 17:44:30 slaudio volumio[1514]: info: Jan 13 17:44:30 slaudio volumio[1514]: ---------------------------- MPD announces state update: player Jan 13 17:44:30 slaudio volumio[1514]: info: ControllerMpd::getState Jan 13 17:44:30 slaudio volumio[1514]: verbose: ControllerMpd::sendMpdCommand status Jan 13 17:44:30 slaudio volumio[1514]: info: Jan 13 17:44:30 slaudio volumio[1514]: ---------------------------- MPD announces state update: player Jan 13 17:44:30 slaudio volumio[1514]: info: ControllerMpd::getState Jan 13 17:44:30 slaudio volumio[1514]: verbose: ControllerMpd::sendMpdCommand status Jan 13 17:44:30 slaudio sudo[5302]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jan 13 17:44:30 slaudio sudo[5302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 17:44:30 slaudio volumio[1514]: info: sendMpdCommand status took 24 milliseconds Jan 13 17:44:30 slaudio volumio[1514]: info: sendMpdCommand status took 12 milliseconds Jan 13 17:44:30 slaudio volumio[1514]: info: sendMpdCommand status took 12 milliseconds Jan 13 17:44:30 slaudio volumio[1514]: info: sendMpdCommand status took 12 milliseconds Jan 13 17:44:30 slaudio volumio[1514]: verbose: ControllerMpd::parseState Jan 13 17:44:30 slaudio volumio[1514]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 17:44:30 slaudio volumio[1514]: verbose: ControllerMpd::parseState Jan 13 17:44:30 slaudio volumio[1514]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 17:44:30 slaudio volumio[1514]: verbose: ControllerMpd::parseState Jan 13 17:44:30 slaudio volumio[1514]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 17:44:30 slaudio volumio[1514]: verbose: ControllerMpd::parseState Jan 13 17:44:30 slaudio volumio[1514]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 17:44:30 slaudio volumio[1514]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 13 17:44:30 slaudio volumio[1514]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 13 17:44:30 slaudio volumio[1514]: verbose: ControllerMpd::parseTrackInfo Jan 13 17:44:30 slaudio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Jan 13 17:44:30 slaudio volumio[1514]: verbose: ControllerMpd::parseTrackInfo Jan 13 17:44:30 slaudio volumio[1514]: info: ControllerMpd::pushState Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::servicePushState Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::pushState Jan 13 17:44:30 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4459 Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::volumioPushState Jan 13 17:44:30 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4459 Jan 13 17:44:30 slaudio volumio[1514]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hendrik Freischlader - Cry Again","artist":"Stereoplay","album":"Perfectes Timing","uri":"INTERNAL/muzic/Stereoplay/Perfectes Timing/Stereoplay - Hendrik Freischlader - Cry Again.ape","trackType":"ape"} Jan 13 17:44:30 slaudio volumio[1514]: verbose: CURRENT POSITION 4459 Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::syncState stateService stop Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::syncState currentStatus stop Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::pushState Jan 13 17:44:30 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4459 Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::volumioPushState Jan 13 17:44:30 slaudio volumio[1514]: info: No code Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::pushState Jan 13 17:44:30 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4459 Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::volumioPushState Jan 13 17:44:30 slaudio volumio[1514]: info: ControllerMpd::pushState Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::servicePushState Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::pushState Jan 13 17:44:30 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4459 Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::volumioPushState Jan 13 17:44:30 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4459 Jan 13 17:44:30 slaudio volumio[1514]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hendrik Freischlader - Cry Again","artist":"Stereoplay","album":"Perfectes Timing","uri":"INTERNAL/muzic/Stereoplay/Perfectes Timing/Stereoplay - Hendrik Freischlader - Cry Again.ape","trackType":"ape"} Jan 13 17:44:30 slaudio volumio[1514]: verbose: CURRENT POSITION 4459 Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::syncState stateService stop Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::syncState currentStatus stop Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::pushState Jan 13 17:44:30 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4459 Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::volumioPushState Jan 13 17:44:30 slaudio volumio[1514]: info: No code Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::pushState Jan 13 17:44:30 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4459 Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::volumioPushState Jan 13 17:44:30 slaudio volumio[1514]: info: ------------------------------ 63ms Jan 13 17:44:30 slaudio volumio[1514]: info: ------------------------------ 46ms Jan 13 17:44:30 slaudio volumio[1514]: info: touch_display: Setting screensaver timeout to 10 seconds. Jan 13 17:44:30 slaudio volumio[1514]: info: sendMpdCommand playlistinfo took 36 milliseconds Jan 13 17:44:30 slaudio volumio[1514]: info: sendMpdCommand playlistinfo took 35 milliseconds Jan 13 17:44:30 slaudio volumio[1514]: verbose: ControllerMpd::parseTrackInfo Jan 13 17:44:30 slaudio volumio[1514]: verbose: ControllerMpd::parseTrackInfo Jan 13 17:44:30 slaudio volumio[1514]: info: ControllerMpd::pushState Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::servicePushState Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::pushState Jan 13 17:44:30 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4459 Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::volumioPushState Jan 13 17:44:30 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4459 Jan 13 17:44:30 slaudio volumio[1514]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hendrik Freischlader - Cry Again","artist":"Stereoplay","album":"Perfectes Timing","uri":"INTERNAL/muzic/Stereoplay/Perfectes Timing/Stereoplay - Hendrik Freischlader - Cry Again.ape","trackType":"ape"} Jan 13 17:44:30 slaudio volumio[1514]: verbose: CURRENT POSITION 4459 Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::syncState stateService stop Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::syncState currentStatus stop Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::pushState Jan 13 17:44:30 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4459 Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::volumioPushState Jan 13 17:44:30 slaudio volumio[1514]: info: No code Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::pushState Jan 13 17:44:30 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4459 Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::volumioPushState Jan 13 17:44:30 slaudio volumio[1514]: info: ControllerMpd::pushState Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::servicePushState Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::pushState Jan 13 17:44:30 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4459 Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::volumioPushState Jan 13 17:44:30 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4459 Jan 13 17:44:30 slaudio volumio[1514]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hendrik Freischlader - Cry Again","artist":"Stereoplay","album":"Perfectes Timing","uri":"INTERNAL/muzic/Stereoplay/Perfectes Timing/Stereoplay - Hendrik Freischlader - Cry Again.ape","trackType":"ape"} Jan 13 17:44:30 slaudio volumio[1514]: verbose: CURRENT POSITION 4459 Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::syncState stateService stop Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::syncState currentStatus stop Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::pushState Jan 13 17:44:30 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4459 Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::volumioPushState Jan 13 17:44:30 slaudio volumio[1514]: info: No code Jan 13 17:44:30 slaudio volumio[1514]: info: CoreStateMachine::pushState Jan 13 17:44:30 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4459 Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::volumioPushState Jan 13 17:44:30 slaudio volumio[1514]: info: ------------------------------ 66ms Jan 13 17:44:30 slaudio volumio[1514]: info: ------------------------------ 66ms Jan 13 17:44:30 slaudio volumio[1514]: info: Asound.conf file written Jan 13 17:44:30 slaudio sudo[5312]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 13 17:44:30 slaudio sudo[5312]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 17:44:30 slaudio sudo[5312]: pam_unix(sudo:session): session closed for user root Jan 13 17:44:30 slaudio volumio[1514]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jan 13 17:44:30 slaudio volumio[1514]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jan 13 17:44:30 slaudio volumio[1514]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Jan 13 17:44:30 slaudio volumio[1514]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Jan 13 17:44:30 slaudio volumio[1514]: info: Output device has changed, restarting MPD Jan 13 17:44:30 slaudio volumio[1514]: info: Done. Jan 13 17:44:30 slaudio sudo[5319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:44:30 slaudio sudo[5319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 17:44:30 slaudio sudo[5320]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:44:30 slaudio sudo[5320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 17:44:30 slaudio sudo[5319]: pam_unix(sudo:session): session closed for user root Jan 13 17:44:30 slaudio volumio[1514]: info: MPD Permissions set Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:30 slaudio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 13 17:44:30 slaudio systemd[1]: peppymeterbasic.service: Deactivated successfully. Jan 13 17:44:30 slaudio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Jan 13 17:44:30 slaudio systemd[1]: peppymeterbasic.service: Consumed 38.049s CPU time. Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:44:30 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:30 slaudio sudo[5294]: pam_unix(sudo:session): session closed for user root Jan 13 17:44:30 slaudio volumio[1514]: info: peppymeterbasic Daemon Stop Jan 13 17:44:30 slaudio sudo[5302]: pam_unix(sudo:session): session closed for user root Jan 13 17:44:30 slaudio systemd[1]: mpd.service: Deactivated successfully. Jan 13 17:44:30 slaudio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 13 17:44:30 slaudio systemd[1]: mpd.service: Consumed 1min 5.317s CPU time. Jan 13 17:44:30 slaudio systemd[1]: mpd.socket: Deactivated successfully. Jan 13 17:44:30 slaudio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 13 17:44:30 slaudio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 13 17:44:30 slaudio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 13 17:44:30 slaudio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 13 17:44:30 slaudio sudo[5329]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 17:44:30 slaudio sudo[5329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 13 17:44:30 slaudio sudo[5329]: pam_unix(sudo:session): session closed for user root Jan 13 17:44:31 slaudio mpd[5331]: 2026-01-13T17:44:31 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 17:44:31 slaudio systemd[1]: Started mpd.service - Music Player Daemon. Jan 13 17:44:31 slaudio sudo[5320]: pam_unix(sudo:session): session closed for user root Jan 13 17:44:31 slaudio volumio[1514]: error: updateQueue error: null Jan 13 17:44:32 slaudio volumio[1514]: info: Enabling plugin peppyspectrum Jan 13 17:44:32 slaudio volumio[1514]: info: Loading plugin "peppyspectrum"... Jan 13 17:44:32 slaudio volumio[1514]: info: Preparing to generate the ALSA configuration file Jan 13 17:44:32 slaudio volumio[1514]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jan 13 17:44:32 slaudio volumio[1514]: info: Reading ALSA contributions from plugins. Jan 13 17:44:32 slaudio volumio[1514]: info: Asound.conf file written Jan 13 17:44:32 slaudio sudo[5336]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 13 17:44:32 slaudio sudo[5336]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 17:44:32 slaudio sudo[5336]: pam_unix(sudo:session): session closed for user root Jan 13 17:44:32 slaudio volumio[1514]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jan 13 17:44:32 slaudio volumio[1514]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jan 13 17:44:32 slaudio volumio[1514]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Jan 13 17:44:32 slaudio volumio[1514]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Jan 13 17:44:32 slaudio volumio[1514]: info: Output device has changed, restarting MPD Jan 13 17:44:32 slaudio sudo[5342]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:44:32 slaudio volumio[1514]: info: PLUGIN START: peppyspectrum Jan 13 17:44:32 slaudio sudo[5342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 17:44:32 slaudio sudo[5342]: pam_unix(sudo:session): session closed for user root Jan 13 17:44:32 slaudio sudo[5344]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:44:32 slaudio sudo[5344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 17:44:32 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 13 17:44:32 slaudio volumio[1514]: info: Preparing to generate the ALSA configuration file Jan 13 17:44:32 slaudio volumio[1514]: info: Done. Jan 13 17:44:32 slaudio sudo[5348]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-aloop index=7 pcm_substreams=1 Jan 13 17:44:32 slaudio sudo[5348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 17:44:32 slaudio volumio[1514]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jan 13 17:44:32 slaudio volumio[1514]: info: Reading ALSA contributions from plugins. Jan 13 17:44:32 slaudio volumio[1514]: info: MPD Permissions set Jan 13 17:44:32 slaudio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 13 17:44:32 slaudio sudo[5348]: pam_unix(sudo:session): session closed for user root Jan 13 17:44:32 slaudio volumio[1514]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jan 13 17:44:32 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:32 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:32 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:32 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:32 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:32 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:32 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:32 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:32 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:44:32 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:32 slaudio volumio[1514]: info: snd_dummy loaded Jan 13 17:44:32 slaudio systemd[1]: mpd.service: Deactivated successfully. Jan 13 17:44:32 slaudio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 13 17:44:32 slaudio systemd[1]: mpd.socket: Deactivated successfully. Jan 13 17:44:32 slaudio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 13 17:44:32 slaudio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 13 17:44:32 slaudio volumio[1514]: info: Asound.conf file unchanged, so no further update is needed Jan 13 17:44:32 slaudio volumio[1514]: info: Output device has changed, restarting MPD Jan 13 17:44:32 slaudio sudo[5358]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:44:32 slaudio sudo[5358]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 17:44:32 slaudio sudo[5358]: pam_unix(sudo:session): session closed for user root Jan 13 17:44:32 slaudio sudo[5360]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:44:32 slaudio sudo[5360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 17:44:32 slaudio volumio[1514]: info: MPD Permissions set Jan 13 17:44:32 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:32 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:32 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:32 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:32 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:32 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:32 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:32 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:32 slaudio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 13 17:44:32 slaudio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 13 17:44:32 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:44:32 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:44:32 slaudio systemd[1]: mpd.service: Deactivated successfully. Jan 13 17:44:32 slaudio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 13 17:44:32 slaudio systemd[1]: mpd.socket: Deactivated successfully. Jan 13 17:44:32 slaudio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 13 17:44:32 slaudio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 13 17:44:32 slaudio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 13 17:44:32 slaudio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 13 17:44:32 slaudio sudo[5374]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 17:44:32 slaudio sudo[5374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 13 17:44:32 slaudio sudo[5374]: pam_unix(sudo:session): session closed for user root Jan 13 17:44:33 slaudio mpd[5376]: 2026-01-13T17:44:33 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 17:44:33 slaudio systemd[1]: Started mpd.service - Music Player Daemon. Jan 13 17:44:33 slaudio sudo[5360]: pam_unix(sudo:session): session closed for user root Jan 13 17:44:33 slaudio sudo[5344]: pam_unix(sudo:session): session closed for user root Jan 13 17:44:33 slaudio volumio[1514]: error: MPD error: The expression evaluated to a falsy value: Jan 13 17:44:33 slaudio volumio[1514]: assert.ok(self.idling) Jan 13 17:44:33 slaudio volumio[1514]: error: The expression evaluated to a falsy value: Jan 13 17:44:33 slaudio volumio[1514]: assert.ok(self.idling) Jan 13 17:44:33 slaudio volumio[1514]: error: updateQueue error: null Jan 13 17:44:33 slaudio volumio[1514]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:44:34 slaudio sudo[5383]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 13 17:44:34 slaudio sudo[5383]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 17:44:34 slaudio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 13 17:44:34 slaudio systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 13 17:44:34 slaudio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 13 17:44:34 slaudio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 13 17:44:34 slaudio sudo[5383]: pam_unix(sudo:session): session closed for user root Jan 13 17:44:34 slaudio mpd_monitor.sh[5385]: MPD Monitor Service: Starting MPD Monitor Service Jan 13 17:44:34 slaudio volumio[1514]: info: Successfully started MPD Monitor Jan 13 17:44:35 slaudio volumio[1514]: info: CoreCommandRouter::volumioNext Jan 13 17:44:35 slaudio volumio[1514]: info: CoreStateMachine::next Jan 13 17:44:35 slaudio volumio[1514]: info: CoreStateMachine::stop Jan 13 17:44:35 slaudio volumio[1514]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 17:44:35 slaudio volumio[1514]: info: CoreStateMachine::play index undefined Jan 13 17:44:35 slaudio volumio[1514]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 17:44:35 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4460 Jan 13 17:44:35 slaudio volumio[1514]: info: CoreStateMachine::startPlaybackTimer Jan 13 17:44:35 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4460 Jan 13 17:44:35 slaudio volumio[1514]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/muzic/Stereoplay/Perfectes Timing/Stereoplay - Friedeman - Saitensprung.ape Jan 13 17:44:35 slaudio volumio[1514]: verbose: ControllerMpd::sendMpdCommand stop Jan 13 17:44:35 slaudio volumio[1514]: info: CoreStateMachine::updateTrackBlock Jan 13 17:44:35 slaudio volumio[1514]: info: CorePlayQueue::getTrackBlock Jan 13 17:44:35 slaudio volumio[1514]: info: sendMpdCommand stop took 2 milliseconds Jan 13 17:44:35 slaudio volumio[1514]: verbose: ControllerMpd::sendMpdCommand clear Jan 13 17:44:35 slaudio volumio[1514]: info: Jan 13 17:44:35 slaudio volumio[1514]: ---------------------------- MPD announces system playlist update Jan 13 17:44:35 slaudio volumio[1514]: info: Ignoring MPD Status Update Jan 13 17:44:35 slaudio volumio[1514]: info: sendMpdCommand clear took 1 milliseconds Jan 13 17:44:35 slaudio volumio[1514]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/muzic/Stereoplay/Perfectes Timing/Stereoplay - Friedeman - Saitensprung.ape" Jan 13 17:44:35 slaudio volumio[1514]: info: Jan 13 17:44:35 slaudio volumio[1514]: ---------------------------- MPD announces system playlist update Jan 13 17:44:35 slaudio volumio[1514]: info: Ignoring MPD Status Update Jan 13 17:44:35 slaudio volumio[1514]: error: updateQueue error: null Jan 13 17:44:35 slaudio volumio[1514]: info: Jan 13 17:44:35 slaudio volumio[1514]: ---------------------------- MPD announces system playlist update Jan 13 17:44:35 slaudio volumio[1514]: info: Ignoring MPD Status Update Jan 13 17:44:35 slaudio volumio[1514]: info: ------------------------------ 1ms Jan 13 17:44:35 slaudio volumio[1514]: info: sendMpdCommand add "INTERNAL/muzic/Stereoplay/Perfectes Timing/Stereoplay - Friedeman - Saitensprung.ape" took 1 milliseconds Jan 13 17:44:35 slaudio volumio[1514]: info: ------------------------------ 1ms Jan 13 17:44:35 slaudio volumio[1514]: verbose: ControllerMpd::sendMpdCommand play Jan 13 17:44:35 slaudio volumio[1514]: info: Jan 13 17:44:35 slaudio volumio[1514]: ---------------------------- MPD announces system playlist update Jan 13 17:44:35 slaudio volumio[1514]: info: Ignoring MPD Status Update Jan 13 17:44:35 slaudio volumio[1514]: info: ------------------------------ 1ms Jan 13 17:44:35 slaudio volumio[1514]: info: sendMpdCommand play took 1 milliseconds Jan 13 17:44:35 slaudio volumio[1514]: info: ------------------------------ 1ms Jan 13 17:44:35 slaudio volumio[1514]: info: Jan 13 17:44:35 slaudio volumio[1514]: ---------------------------- MPD announces state update: player Jan 13 17:44:35 slaudio volumio[1514]: info: ControllerMpd::getState Jan 13 17:44:35 slaudio volumio[1514]: verbose: ControllerMpd::sendMpdCommand status Jan 13 17:44:35 slaudio volumio[1514]: info: Jan 13 17:44:35 slaudio volumio[1514]: ---------------------------- MPD announces state update: player Jan 13 17:44:35 slaudio volumio[1514]: info: ControllerMpd::getState Jan 13 17:44:35 slaudio volumio[1514]: verbose: ControllerMpd::sendMpdCommand status Jan 13 17:44:35 slaudio volumio[1514]: info: sendMpdCommand status took 21 milliseconds Jan 13 17:44:35 slaudio volumio[1514]: verbose: ControllerMpd::parseState Jan 13 17:44:35 slaudio volumio[1514]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 17:44:35 slaudio volumio[1514]: info: Jan 13 17:44:35 slaudio volumio[1514]: ---------------------------- MPD announces state update: player Jan 13 17:44:35 slaudio volumio[1514]: info: ControllerMpd::getState Jan 13 17:44:35 slaudio volumio[1514]: verbose: ControllerMpd::sendMpdCommand status Jan 13 17:44:35 slaudio volumio[1514]: info: Jan 13 17:44:35 slaudio volumio[1514]: ---------------------------- MPD announces state update: player Jan 13 17:44:35 slaudio volumio[1514]: info: ControllerMpd::getState Jan 13 17:44:35 slaudio volumio[1514]: verbose: ControllerMpd::sendMpdCommand status Jan 13 17:44:35 slaudio volumio[1514]: info: sendMpdCommand status took 23 milliseconds Jan 13 17:44:35 slaudio volumio[1514]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 13 17:44:35 slaudio volumio[1514]: verbose: ControllerMpd::parseState Jan 13 17:44:35 slaudio volumio[1514]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 17:44:35 slaudio volumio[1514]: verbose: ControllerMpd::parseTrackInfo Jan 13 17:44:35 slaudio volumio[1514]: info: ControllerMpd::pushState Jan 13 17:44:35 slaudio volumio[1514]: info: CoreCommandRouter::servicePushState Jan 13 17:44:35 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4460 Jan 13 17:44:35 slaudio volumio[1514]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Friedeman - Saitensprung","artist":"Stereoplay","album":"Perfectes Timing","uri":"INTERNAL/muzic/Stereoplay/Perfectes Timing/Stereoplay - Friedeman - Saitensprung.ape","trackType":"ape"} Jan 13 17:44:35 slaudio volumio[1514]: verbose: CURRENT POSITION 4460 Jan 13 17:44:35 slaudio volumio[1514]: info: CoreStateMachine::syncState stateService play Jan 13 17:44:35 slaudio volumio[1514]: info: CoreStateMachine::syncState currentStatus stop Jan 13 17:44:35 slaudio volumio[1514]: info: ------------------------------ 24ms Jan 13 17:44:35 slaudio volumio[1514]: info: sendMpdCommand status took 2 milliseconds Jan 13 17:44:35 slaudio volumio[1514]: verbose: ControllerMpd::parseState Jan 13 17:44:35 slaudio volumio[1514]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 17:44:35 slaudio volumio[1514]: info: sendMpdCommand status took 1 milliseconds Jan 13 17:44:35 slaudio volumio[1514]: verbose: ControllerMpd::parseState Jan 13 17:44:35 slaudio volumio[1514]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 17:44:35 slaudio volumio[1514]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 13 17:44:35 slaudio volumio[1514]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 13 17:44:35 slaudio volumio[1514]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 13 17:44:35 slaudio volumio[1514]: verbose: ControllerMpd::parseTrackInfo Jan 13 17:44:35 slaudio volumio[1514]: verbose: ControllerMpd::parseTrackInfo Jan 13 17:44:35 slaudio volumio[1514]: verbose: ControllerMpd::parseTrackInfo Jan 13 17:44:35 slaudio volumio[1514]: info: ControllerMpd::pushState Jan 13 17:44:35 slaudio volumio[1514]: info: CoreCommandRouter::servicePushState Jan 13 17:44:35 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4460 Jan 13 17:44:35 slaudio volumio[1514]: verbose: STATE SERVICE {"status":"play","position":0,"seek":618,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Friedeman - Saitensprung","artist":"Stereoplay","album":"Perfectes Timing","uri":"INTERNAL/muzic/Stereoplay/Perfectes Timing/Stereoplay - Friedeman - Saitensprung.ape","trackType":"ape"} Jan 13 17:44:35 slaudio volumio[1514]: verbose: CURRENT POSITION 4460 Jan 13 17:44:35 slaudio volumio[1514]: info: CoreStateMachine::syncState stateService play Jan 13 17:44:35 slaudio volumio[1514]: info: CoreStateMachine::syncState currentStatus play Jan 13 17:44:35 slaudio volumio[1514]: info: Received an update from plugin. extracting info from payload Jan 13 17:44:35 slaudio volumio[1514]: info: CoreStateMachine::pushState Jan 13 17:44:35 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4460 Jan 13 17:44:35 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 17:44:35 slaudio volumio[1514]: info: CoreCommandRouter::volumioPushState Jan 13 17:44:35 slaudio volumio[1514]: info: CoreStateMachine::pushState Jan 13 17:44:35 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4460 Jan 13 17:44:35 slaudio volumio[1514]: info: CoreCommandRouter::volumioPushState Jan 13 17:44:35 slaudio volumio[1514]: info: ControllerMpd::pushState Jan 13 17:44:35 slaudio volumio[1514]: info: CoreCommandRouter::servicePushState Jan 13 17:44:35 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4460 Jan 13 17:44:35 slaudio volumio[1514]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Friedeman - Saitensprung","artist":"Stereoplay","album":"Perfectes Timing","uri":"INTERNAL/muzic/Stereoplay/Perfectes Timing/Stereoplay - Friedeman - Saitensprung.ape","trackType":"ape"} Jan 13 17:44:35 slaudio volumio[1514]: verbose: CURRENT POSITION 4460 Jan 13 17:44:35 slaudio volumio[1514]: info: CoreStateMachine::syncState stateService play Jan 13 17:44:35 slaudio volumio[1514]: info: CoreStateMachine::syncState currentStatus play Jan 13 17:44:35 slaudio volumio[1514]: info: Received an update from plugin. extracting info from payload Jan 13 17:44:35 slaudio volumio[1514]: info: CoreStateMachine::pushState Jan 13 17:44:35 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4460 Jan 13 17:44:35 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 17:44:35 slaudio volumio[1514]: info: CoreCommandRouter::volumioPushState Jan 13 17:44:35 slaudio volumio[1514]: info: CoreStateMachine::pushState Jan 13 17:44:35 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4460 Jan 13 17:44:35 slaudio volumio[1514]: info: CoreCommandRouter::volumioPushState Jan 13 17:44:35 slaudio volumio[1514]: info: ControllerMpd::pushState Jan 13 17:44:35 slaudio volumio[1514]: info: CoreCommandRouter::servicePushState Jan 13 17:44:35 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4460 Jan 13 17:44:35 slaudio volumio[1514]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Friedeman - Saitensprung","artist":"Stereoplay","album":"Perfectes Timing","uri":"INTERNAL/muzic/Stereoplay/Perfectes Timing/Stereoplay - Friedeman - Saitensprung.ape","trackType":"ape"} Jan 13 17:44:35 slaudio volumio[1514]: verbose: CURRENT POSITION 4460 Jan 13 17:44:35 slaudio volumio[1514]: info: CoreStateMachine::syncState stateService play Jan 13 17:44:35 slaudio volumio[1514]: info: CoreStateMachine::syncState currentStatus play Jan 13 17:44:35 slaudio volumio[1514]: info: Received an update from plugin. extracting info from payload Jan 13 17:44:35 slaudio volumio[1514]: info: CoreStateMachine::pushState Jan 13 17:44:35 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4460 Jan 13 17:44:35 slaudio volumio[1514]: info: CoreCommandRouter::volumioPushState Jan 13 17:44:35 slaudio volumio[1514]: info: CoreStateMachine::pushState Jan 13 17:44:35 slaudio volumio[1514]: info: CorePlayQueue::getTrack 4460 Jan 13 17:44:35 slaudio volumio[1514]: info: CoreCommandRouter::volumioPushState Jan 13 17:44:35 slaudio volumio[1514]: info: ------------------------------ 34ms Jan 13 17:44:35 slaudio volumio[1514]: info: ------------------------------ 13ms Jan 13 17:44:35 slaudio volumio[1514]: info: ------------------------------ 13ms Jan 13 17:44:35 slaudio volumio[1514]: info: PeppySpectrum ---peppyspectrum status play Jan 13 17:44:35 slaudio volumio[1514]: info: PeppySpectrum ---peppyspectrum status play Jan 13 17:44:35 slaudio volumio[1514]: info: PeppySpectrum ---peppyspectrum status play Jan 13 17:44:35 slaudio sudo[5411]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 13 17:44:35 slaudio sudo[5411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 17:44:35 slaudio volumio[1514]: info: PeppySpectrum ---peppyspectrum status play Jan 13 17:44:35 slaudio sudo[5413]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 13 17:44:35 slaudio volumio[1514]: info: PeppySpectrum ---peppyspectrum status play Jan 13 17:44:35 slaudio sudo[5416]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 13 17:44:35 slaudio sudo[5413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 17:44:35 slaudio sudo[5416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 17:44:35 slaudio sudo[5418]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 13 17:44:35 slaudio sudo[5418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 17:44:35 slaudio volumio[1514]: info: PeppySpectrum ---peppyspectrum status play Jan 13 17:44:35 slaudio sudo[5423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 13 17:44:35 slaudio sudo[5423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 17:44:35 slaudio volumio[1514]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 13 17:44:35 slaudio sudo[5425]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 13 17:44:35 slaudio sudo[5425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 17:44:35 slaudio systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Jan 13 17:44:35 slaudio sudo[5411]: pam_unix(sudo:session): session closed for user root Jan 13 17:44:35 slaudio volumio[1514]: info: peppyspectrum Daemon Started Jan 13 17:44:35 slaudio sudo[5416]: pam_unix(sudo:session): session closed for user root Jan 13 17:44:35 slaudio sudo[5413]: pam_unix(sudo:session): session closed for user root Jan 13 17:44:35 slaudio sudo[5423]: pam_unix(sudo:session): session closed for user root Jan 13 17:44:35 slaudio sudo[5425]: pam_unix(sudo:session): session closed for user root Jan 13 17:44:35 slaudio volumio[1514]: info: peppyspectrum Daemon Started Jan 13 17:44:35 slaudio volumio[1514]: info: peppyspectrum Daemon Started Jan 13 17:44:35 slaudio sudo[5418]: pam_unix(sudo:session): session closed for user root Jan 13 17:44:35 slaudio volumio[1514]: info: peppyspectrum Daemon Started Jan 13 17:44:35 slaudio volumio[1514]: info: peppyspectrum Daemon Started Jan 13 17:44:35 slaudio volumio[1514]: info: peppyspectrum Daemon Started Jan 13 17:44:36 slaudio startpeppyspectrum.sh[5431]: libpng warning: iCCP: known incorrect sRGB profile Jan 13 17:44:36 slaudio startpeppyspectrum.sh[5431]: libpng warning: iCCP: known incorrect sRGB profile Jan 13 17:44:36 slaudio startpeppyspectrum.sh[5431]: libpng warning: iCCP: known incorrect sRGB profile Jan 13 17:44:36 slaudio sudo[5438]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 13 17:44:36 slaudio sudo[5438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 17:44:36 slaudio sudo[5440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 13 17:44:36 slaudio sudo[5440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 17:44:36 slaudio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 13 17:44:36 slaudio systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 13 17:44:36 slaudio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 13 17:44:36 slaudio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 13 17:44:36 slaudio sudo[5440]: pam_unix(sudo:session): session closed for user root Jan 13 17:44:36 slaudio mpd_monitor.sh[5443]: MPD Monitor Service: Starting MPD Monitor Service Jan 13 17:44:36 slaudio sudo[5438]: pam_unix(sudo:session): session closed for user root Jan 13 17:44:36 slaudio volumio[1514]: info: Successfully started MPD Monitor Jan 13 17:44:36 slaudio volumio[1514]: info: Successfully started MPD Monitor Jan 13 17:44:41 slaudio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 81. Jan 13 17:44:41 slaudio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 17:44:41 slaudio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 17:44:41 slaudio upmpdcli[5447]: Could not open config: /tmp/upmpdcli.conf Jan 13 17:44:41 slaudio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:44:41 slaudio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 13 17:44:46 slaudio volumio[1514]: info: CALLMETHOD: user_interface peppyspectrum dlspectrum [object Object] Jan 13 17:44:46 slaudio volumio[1514]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , dlspectrum Jan 13 17:44:48 slaudio volumio[1514]: --2026-01-13 17:44:46-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/Zipped-folders/Choose Jan 13 17:44:48 slaudio volumio[1514]: Resolving github.com (github.com)... 140.82.121.4 Jan 13 17:44:48 slaudio volumio[1514]: Connecting to github.com (github.com)|140.82.121.4|:443... connected. Jan 13 17:44:48 slaudio volumio[1514]: HTTP request sent, awaiting response... 404 Not Found Jan 13 17:44:48 slaudio volumio[1514]: 2026-01-13 17:44:47 ERROR 404: Not Found. Jan 13 17:44:48 slaudio volumio[1514]: --2026-01-13 17:44:47-- http://a/ Jan 13 17:44:48 slaudio volumio[1514]: Resolving a (a)... failed: Device or resource busy. Jan 13 17:44:48 slaudio volumio[1514]: wget: unable to resolve host address ‘a’ Jan 13 17:44:48 slaudio volumio[1514]: --2026-01-13 17:44:47-- http://packet.zip/ Jan 13 17:44:48 slaudio volumio[1514]: Resolving packet.zip (packet.zip)... 162.255.119.167 Jan 13 17:44:48 slaudio volumio[1514]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected. Jan 13 17:44:48 slaudio volumio[1514]: HTTP request sent, awaiting response... 302 Found Jan 13 17:44:48 slaudio volumio[1514]: Location: http://www.packet.zip/ [following] Jan 13 17:44:48 slaudio volumio[1514]: --2026-01-13 17:44:47-- http://www.packet.zip/ Jan 13 17:44:48 slaudio volumio[1514]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 Jan 13 17:44:48 slaudio volumio[1514]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Jan 13 17:44:48 slaudio volumio[1514]: HTTP request sent, awaiting response... 403 Forbidden Jan 13 17:44:48 slaudio volumio[1514]: 2026-01-13 17:44:48 ERROR 403: Forbidden. Jan 13 17:44:48 slaudio volumio[1514]: error: PeppySpectrum --- An error occurs while downloading or installing Spectrums Jan 13 17:44:48 slaudio volumio[1514]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Jan 13 17:44:48 slaudio volumio[1514]: /bin/rm: cannot remove 'a': No such file or directory Jan 13 17:44:48 slaudio volumio[1514]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Jan 13 17:44:48 slaudio volumio[1514]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:44:48 slaudio volumio[1514]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* Jan 13 17:44:48 slaudio volumio[1514]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Jan 13 17:44:48 slaudio volumio[1514]: /bin/rm: cannot remove 'a': No such file or directory Jan 13 17:44:48 slaudio volumio[1514]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Jan 13 17:44:48 slaudio volumio[1514]: at checkExecSyncError (node:child_process:887:11) Jan 13 17:44:48 slaudio volumio[1514]: at execSync (node:child_process:959:15) Jan 13 17:44:48 slaudio volumio[1514]: at /data/plugins/user_interface/peppyspectrum/index.js:770:19 Jan 13 17:44:48 slaudio volumio[1514]: at new Promise () Jan 13 17:44:48 slaudio volumio[1514]: at peppyspectrum.dlspectrum (/data/plugins/user_interface/peppyspectrum/index.js:745:12) Jan 13 17:44:48 slaudio volumio[1514]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Jan 13 17:44:48 slaudio volumio[1514]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) Jan 13 17:44:48 slaudio volumio[1514]: at Socket.emit (node:events:514:28) Jan 13 17:44:48 slaudio volumio[1514]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 { Jan 13 17:44:48 slaudio volumio[1514]: status: 1, Jan 13 17:44:48 slaudio volumio[1514]: signal: null, Jan 13 17:44:48 slaudio volumio[1514]: output: [ Jan 13 17:44:48 slaudio volumio[1514]: null, Jan 13 17:44:48 slaudio volumio[1514]: , Jan 13 17:44:48 slaudio volumio[1514]: Jan 13 17:44:48 slaudio volumio[1514]: ], Jan 13 17:44:48 slaudio volumio[1514]: pid: 5466, Jan 13 17:44:48 slaudio volumio[1514]: stdout: , Jan 13 17:44:48 slaudio volumio[1514]: stderr: Jan 13 17:44:48 slaudio volumio[1514]: } Jan 13 17:44:48 slaudio volumio[1514]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:44:48 slaudio sudo[5484]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-13 17:43' Jan 13 17:44:48 slaudio sudo[5484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"