-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-12-28 12:36:33 CET. -- Dec 28 12:35:09 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 12:35:09 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 28 12:35:15 volumio volumio[848]: info: Stopping peppyspectrum service Dec 28 12:35:15 volumio volumio[848]: info: CoreStateMachine::stop Dec 28 12:35:15 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 12:35:15 volumio volumio[848]: info: CoreStateMachine::stPlaybackTimer Dec 28 12:35:15 volumio volumio[848]: info: CoreStateMachine::updateTrackBlock Dec 28 12:35:15 volumio volumio[848]: info: CorePlayQueue::getTrackBlock Dec 28 12:35:15 volumio volumio[848]: info: CoreStateMachine::pushState Dec 28 12:35:15 volumio volumio[848]: info: CorePlayQueue::getTrack 4 Dec 28 12:35:15 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 12:35:15 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 28 12:35:15 volumio volumio[848]: info: CoreStateMachine::serviceStop Dec 28 12:35:15 volumio volumio[848]: info: CorePlayQueue::getTrack 4 Dec 28 12:35:15 volumio volumio[848]: info: CoreCommandRouter::serviceStop Dec 28 12:35:15 volumio volumio[848]: info: ControllerMpd::stop Dec 28 12:35:15 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 12:35:15 volumio volumio[848]: info: Disabling plugin peppyspectrum Dec 28 12:35:15 volumio volumio[848]: info: Preparing to generate the ALSA configuration file Dec 28 12:35:15 volumio volumio[848]: info: PeppySpectrum ---peppyspectrum status stop Dec 28 12:35:15 volumio volumio[848]: info: Dec 28 12:35:15 volumio volumio[848]: ---------------------------- MPD announces state update: player Dec 28 12:35:15 volumio volumio[848]: info: sendMpdCommand stop took 60 milliseconds Dec 28 12:35:15 volumio volumio[848]: info: ControllerMpd::getState Dec 28 12:35:15 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Dec 28 12:35:15 volumio sudo[3475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 28 12:35:15 volumio sudo[3475]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:15 volumio systemd[1]: Stopping peppyspectrum Daemon... Dec 28 12:35:15 volumio systemd[1]: peppyspectrum.service: Main process exited, code=killed, status=15/TERM Dec 28 12:35:15 volumio sudo[3478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 28 12:35:15 volumio sudo[3478]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:15 volumio volumio[848]: info: Dec 28 12:35:15 volumio volumio[848]: ---------------------------- MPD announces state update: player Dec 28 12:35:15 volumio volumio[848]: info: ControllerMpd::getState Dec 28 12:35:15 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Dec 28 12:35:15 volumio volumio[848]: info: Dec 28 12:35:15 volumio volumio[848]: ---------------------------- MPD announces state update: player Dec 28 12:35:15 volumio volumio[848]: info: ControllerMpd::getState Dec 28 12:35:15 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Dec 28 12:35:15 volumio volumio[848]: info: Dec 28 12:35:15 volumio volumio[848]: ---------------------------- MPD announces state update: player Dec 28 12:35:15 volumio volumio[848]: info: ControllerMpd::getState Dec 28 12:35:15 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status Dec 28 12:35:15 volumio volumio[848]: info: sendMpdCommand status took 70 milliseconds Dec 28 12:35:15 volumio volumio[848]: info: sendMpdCommand status took 5 milliseconds Dec 28 12:35:15 volumio volumio[848]: info: sendMpdCommand status took 4 milliseconds Dec 28 12:35:15 volumio volumio[848]: info: sendMpdCommand status took 2 milliseconds Dec 28 12:35:15 volumio volumio[848]: verbose: ControllerMpd::parseState Dec 28 12:35:15 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 12:35:15 volumio volumio[848]: verbose: ControllerMpd::parseState Dec 28 12:35:15 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 12:35:15 volumio volumio[848]: verbose: ControllerMpd::parseState Dec 28 12:35:15 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 12:35:15 volumio volumio[848]: verbose: ControllerMpd::parseState Dec 28 12:35:15 volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 12:35:15 volumio volumio[848]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 28 12:35:15 volumio volumio[848]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 28 12:35:15 volumio volumio[848]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 28 12:35:15 volumio volumio[848]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 28 12:35:15 volumio volumio[848]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 28 12:35:15 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Dec 28 12:35:15 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Dec 28 12:35:15 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Dec 28 12:35:15 volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo Dec 28 12:35:15 volumio volumio[848]: info: ControllerMpd::pushState Dec 28 12:35:15 volumio volumio[848]: info: CoreCommandRouter::servicePushState Dec 28 12:35:15 volumio volumio[848]: info: CoreStateMachine::pushState Dec 28 12:35:15 volumio volumio[848]: info: CorePlayQueue::getTrack 4 Dec 28 12:35:15 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 28 12:35:15 volumio volumio[848]: info: CorePlayQueue::getTrack 4 Dec 28 12:35:15 volumio volumio[848]: 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":"Crisis","artist":"Art Blakey & The Jazz Messengers","album":"Mosaic","uri":"USB/Seagate_Basic/Música Variada ( Varios Formatos )/Art Blakey & The Jazz Messengers - Mosaic [24-192)/05 - Crisis.flac","trackType":"flac"} Dec 28 12:35:15 volumio volumio[848]: verbose: CURRENT POSITION 4 Dec 28 12:35:15 volumio volumio[848]: info: CoreStateMachine::syncState stateService stop Dec 28 12:35:15 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus stop Dec 28 12:35:15 volumio volumio[848]: info: CoreStateMachine::pushState Dec 28 12:35:15 volumio volumio[848]: info: CorePlayQueue::getTrack 4 Dec 28 12:35:15 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 28 12:35:15 volumio volumio[848]: info: No code Dec 28 12:35:15 volumio volumio[848]: info: CoreStateMachine::pushState Dec 28 12:35:15 volumio volumio[848]: info: CorePlayQueue::getTrack 4 Dec 28 12:35:15 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 28 12:35:15 volumio volumio[848]: info: ControllerMpd::pushState Dec 28 12:35:15 volumio volumio[848]: info: CoreCommandRouter::servicePushState Dec 28 12:35:15 volumio volumio[848]: info: CoreStateMachine::pushState Dec 28 12:35:15 volumio volumio[848]: info: CorePlayQueue::getTrack 4 Dec 28 12:35:15 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 28 12:35:15 volumio volumio[848]: info: CorePlayQueue::getTrack 4 Dec 28 12:35:15 volumio volumio[848]: 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":"Crisis","artist":"Art Blakey & The Jazz Messengers","album":"Mosaic","uri":"USB/Seagate_Basic/Música Variada ( Varios Formatos )/Art Blakey & The Jazz Messengers - Mosaic [24-192)/05 - Crisis.flac","trackType":"flac"} Dec 28 12:35:15 volumio volumio[848]: verbose: CURRENT POSITION 4 Dec 28 12:35:15 volumio volumio[848]: info: CoreStateMachine::syncState stateService stop Dec 28 12:35:15 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus stop Dec 28 12:35:15 volumio volumio[848]: info: CoreStateMachine::pushState Dec 28 12:35:15 volumio volumio[848]: info: CorePlayQueue::getTrack 4 Dec 28 12:35:15 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 28 12:35:15 volumio volumio[848]: info: No code Dec 28 12:35:15 volumio volumio[848]: info: CoreStateMachine::pushState Dec 28 12:35:15 volumio volumio[848]: info: CorePlayQueue::getTrack 4 Dec 28 12:35:15 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 28 12:35:15 volumio volumio[848]: info: ControllerMpd::pushState Dec 28 12:35:15 volumio volumio[848]: info: CoreCommandRouter::servicePushState Dec 28 12:35:15 volumio volumio[848]: info: CoreStateMachine::pushState Dec 28 12:35:16 volumio volumio[848]: info: CorePlayQueue::getTrack 4 Dec 28 12:35:16 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 28 12:35:16 volumio volumio[848]: info: CorePlayQueue::getTrack 4 Dec 28 12:35:16 volumio volumio[848]: 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":"Crisis","artist":"Art Blakey & The Jazz Messengers","album":"Mosaic","uri":"USB/Seagate_Basic/Música Variada ( Varios Formatos )/Art Blakey & The Jazz Messengers - Mosaic [24-192)/05 - Crisis.flac","trackType":"flac"} Dec 28 12:35:16 volumio volumio[848]: verbose: CURRENT POSITION 4 Dec 28 12:35:16 volumio volumio[848]: info: CoreStateMachine::syncState stateService stop Dec 28 12:35:16 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus stop Dec 28 12:35:16 volumio volumio[848]: info: CoreStateMachine::pushState Dec 28 12:35:16 volumio volumio[848]: info: CorePlayQueue::getTrack 4 Dec 28 12:35:16 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 28 12:35:16 volumio volumio[848]: info: No code Dec 28 12:35:16 volumio volumio[848]: info: CoreStateMachine::pushState Dec 28 12:35:16 volumio volumio[848]: info: CorePlayQueue::getTrack 4 Dec 28 12:35:16 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 28 12:35:16 volumio volumio[848]: info: ControllerMpd::pushState Dec 28 12:35:16 volumio volumio[848]: info: CoreCommandRouter::servicePushState Dec 28 12:35:16 volumio volumio[848]: info: CoreStateMachine::pushState Dec 28 12:35:16 volumio volumio[848]: info: CorePlayQueue::getTrack 4 Dec 28 12:35:16 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 28 12:35:16 volumio volumio[848]: info: CorePlayQueue::getTrack 4 Dec 28 12:35:16 volumio volumio[848]: 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":"Crisis","artist":"Art Blakey & The Jazz Messengers","album":"Mosaic","uri":"USB/Seagate_Basic/Música Variada ( Varios Formatos )/Art Blakey & The Jazz Messengers - Mosaic [24-192)/05 - Crisis.flac","trackType":"flac"} Dec 28 12:35:16 volumio volumio[848]: verbose: CURRENT POSITION 4 Dec 28 12:35:16 volumio volumio[848]: info: CoreStateMachine::syncState stateService stop Dec 28 12:35:16 volumio volumio[848]: info: CoreStateMachine::syncState currentStatus stop Dec 28 12:35:16 volumio systemd[1]: peppyspectrum.service: Succeeded. Dec 28 12:35:16 volumio volumio[848]: info: CoreStateMachine::pushState Dec 28 12:35:16 volumio volumio[848]: info: CorePlayQueue::getTrack 4 Dec 28 12:35:16 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 28 12:35:16 volumio systemd[1]: Stopped peppyspectrum Daemon. Dec 28 12:35:16 volumio volumio[848]: info: No code Dec 28 12:35:16 volumio volumio[848]: info: CoreStateMachine::pushState Dec 28 12:35:16 volumio volumio[848]: info: CorePlayQueue::getTrack 4 Dec 28 12:35:16 volumio volumio[848]: info: CoreCommandRouter::volumioPushState Dec 28 12:35:16 volumio sudo[3478]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:16 volumio sudo[3475]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:16 volumio volumio[848]: info: ------------------------------ 260ms Dec 28 12:35:16 volumio volumio[848]: info: ------------------------------ 194ms Dec 28 12:35:16 volumio volumio[848]: info: ------------------------------ 192ms Dec 28 12:35:16 volumio volumio[848]: info: ------------------------------ 190ms Dec 28 12:35:16 volumio volumio[848]: info: peppyspectrum Daemon Stop Dec 28 12:35:16 volumio volumio[848]: info: Asound.conf file written Dec 28 12:35:16 volumio sudo[3485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 28 12:35:16 volumio sudo[3485]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:16 volumio sudo[3485]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:16 volumio volumio[848]: info: Output device has changed, restarting MPD Dec 28 12:35:16 volumio sudo[3492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 12:35:16 volumio sudo[3492]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:16 volumio sudo[3492]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:16 volumio volumio[848]: info: Done. Dec 28 12:35:16 volumio volumio[848]: info: MPD Permissions set Dec 28 12:35:16 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:16 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:16 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:16 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:16 volumio sudo[3495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 12:35:16 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:16 volumio sudo[3495]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:16 volumio systemd[1]: musicservicesshield.service: Succeeded. Dec 28 12:35:16 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Dec 28 12:35:16 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Dec 28 12:35:16 volumio systemd[1]: Stopping Music Player Daemon... Dec 28 12:35:16 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 12:35:16 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:16 volumio systemd[1]: mpd.service: Succeeded. Dec 28 12:35:16 volumio systemd[1]: Stopped Music Player Daemon. Dec 28 12:35:16 volumio systemd[1]: Starting Music Player Daemon... Dec 28 12:35:16 volumio sudo[3501]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 12:35:16 volumio sudo[3501]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:16 volumio sudo[3501]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:16 volumio go-librespot[1152]: time="2025-12-28T12:35:16+01:00" level=trace msg="received accesspoint ping" Dec 28 12:35:16 volumio go-librespot[1152]: time="2025-12-28T12:35:16+01:00" level=trace msg="received accesspoint pong ack" Dec 28 12:35:17 volumio mpd[3503]: Dec 28 12:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 28 12:35:17 volumio volumio[848]: info: Enabling plugin peppyspectrum Dec 28 12:35:17 volumio volumio[848]: info: Loading plugin "peppyspectrum"... Dec 28 12:35:17 volumio volumio[848]: info: Preparing to generate the ALSA configuration file Dec 28 12:35:17 volumio volumio[848]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 28 12:35:17 volumio volumio[848]: info: Reading ALSA contributions from plugins. Dec 28 12:35:17 volumio volumio[848]: info: Asound.conf file written Dec 28 12:35:17 volumio go-librespot[1152]: time="2025-12-28T12:35:17+01:00" level=trace msg="sent dealer ping" Dec 28 12:35:17 volumio sudo[3505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 28 12:35:17 volumio sudo[3505]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:17 volumio sudo[3505]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:17 volumio volumio[848]: info: Output device has changed, restarting MPD Dec 28 12:35:17 volumio sudo[3510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 12:35:17 volumio sudo[3510]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:17 volumio sudo[3510]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:17 volumio volumio[848]: info: PLUGIN START: peppyspectrum Dec 28 12:35:17 volumio sudo[3513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 12:35:17 volumio sudo[3513]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:17 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 28 12:35:17 volumio volumio[848]: info: Preparing to generate the ALSA configuration file Dec 28 12:35:17 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 28 12:35:17 volumio systemd[1]: mpd.service: Succeeded. Dec 28 12:35:17 volumio systemd[1]: Stopped Music Player Daemon. Dec 28 12:35:17 volumio volumio[848]: info: Done. Dec 28 12:35:17 volumio volumio[848]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 28 12:35:17 volumio volumio[848]: info: Reading ALSA contributions from plugins. Dec 28 12:35:17 volumio volumio[848]: error: Upnp client error: Error: This socket has been ended by the other party Dec 28 12:35:17 volumio volumio[848]: info: MPD Permissions set Dec 28 12:35:17 volumio volumio[848]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 28 12:35:17 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:17 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:17 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:17 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:17 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 12:35:17 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:17 volumio systemd[1]: Starting Music Player Daemon... Dec 28 12:35:17 volumio volumio[848]: info: Asound.conf file unchanged, so no further update is needed Dec 28 12:35:17 volumio volumio[848]: info: Output device has changed, restarting MPD Dec 28 12:35:17 volumio sudo[3522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 12:35:17 volumio sudo[3522]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:17 volumio sudo[3515]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 12:35:17 volumio sudo[3515]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:17 volumio sudo[3522]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:17 volumio sudo[3524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 12:35:17 volumio sudo[3524]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:17 volumio sudo[3515]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:17 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 28 12:35:17 volumio systemd[1]: mpd.service: Succeeded. Dec 28 12:35:17 volumio volumio[848]: info: MPD Permissions set Dec 28 12:35:17 volumio systemd[1]: Stopped Music Player Daemon. Dec 28 12:35:17 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:17 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:17 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:17 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:17 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:17 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:17 volumio systemd[1]: Starting Music Player Daemon... Dec 28 12:35:17 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 12:35:17 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:17 volumio go-librespot[1152]: time="2025-12-28T12:35:17+01:00" level=trace msg="received dealer pong" Dec 28 12:35:17 volumio sudo[3532]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 12:35:17 volumio sudo[3532]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:17 volumio sudo[3532]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:18 volumio mpd[3538]: Dec 28 12:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 28 12:35:18 volumio volumio[848]: info: Stopping peppyspectrum service Dec 28 12:35:18 volumio volumio[848]: info: CoreStateMachine::stop Dec 28 12:35:18 volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 12:35:18 volumio volumio[848]: info: Disabling plugin peppyspectrum Dec 28 12:35:18 volumio volumio[848]: info: Preparing to generate the ALSA configuration file Dec 28 12:35:18 volumio sudo[3540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 28 12:35:18 volumio sudo[3540]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:18 volumio volumio[848]: info: Asound.conf file written Dec 28 12:35:18 volumio sudo[3540]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:18 volumio sudo[3543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 28 12:35:18 volumio sudo[3543]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:18 volumio sudo[3543]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:18 volumio volumio[848]: info: Output device has changed, restarting MPD Dec 28 12:35:18 volumio sudo[3548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 12:35:18 volumio sudo[3548]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:18 volumio sudo[3548]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:18 volumio volumio[848]: info: Done. Dec 28 12:35:18 volumio volumio[848]: info: MPD Permissions set Dec 28 12:35:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:18 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:19 volumio sudo[3551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 12:35:19 volumio sudo[3551]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:19 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 12:35:19 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:19 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 28 12:35:19 volumio systemd[1]: mpd.service: Succeeded. Dec 28 12:35:19 volumio systemd[1]: Stopped Music Player Daemon. Dec 28 12:35:19 volumio systemd[1]: Starting Music Player Daemon... Dec 28 12:35:19 volumio sudo[3557]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 12:35:19 volumio sudo[3557]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:19 volumio sudo[3557]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:19 volumio mpd[3559]: Dec 28 12:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 28 12:35:20 volumio systemd[1]: Started Music Player Daemon. Dec 28 12:35:20 volumio sudo[3513]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:20 volumio sudo[3495]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:20 volumio sudo[3524]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:20 volumio sudo[3551]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:20 volumio volumio[848]: error: MPD error: The expression evaluated to a falsy value: Dec 28 12:35:20 volumio volumio[848]: assert.ok(self.idling) Dec 28 12:35:20 volumio volumio[848]: error: The expression evaluated to a falsy value: Dec 28 12:35:20 volumio volumio[848]: assert.ok(self.idling) Dec 28 12:35:20 volumio volumio[848]: error: MPD error: The expression evaluated to a falsy value: Dec 28 12:35:20 volumio volumio[848]: assert.ok(self.idling) Dec 28 12:35:20 volumio volumio[848]: error: The expression evaluated to a falsy value: Dec 28 12:35:20 volumio volumio[848]: assert.ok(self.idling) Dec 28 12:35:20 volumio volumio[848]: error: MPD error: The expression evaluated to a falsy value: Dec 28 12:35:20 volumio volumio[848]: assert.ok(self.idling) Dec 28 12:35:20 volumio volumio[848]: error: The expression evaluated to a falsy value: Dec 28 12:35:20 volumio volumio[848]: assert.ok(self.idling) Dec 28 12:35:20 volumio volumio[848]: error: updateQueue error: null Dec 28 12:35:20 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Dec 28 12:35:20 volumio volumio[3564]: cset: --> shielding system active with Dec 28 12:35:20 volumio volumio[3564]: cset: "system" cpuset of CPUSPEC(1-3) with 231 tasks running Dec 28 12:35:20 volumio volumio[3564]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Dec 28 12:35:20 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Dec 28 12:35:22 volumio volumio[848]: info: Starting Uninstall of plugin user_interface - peppyspectrum Dec 28 12:35:22 volumio volumio[848]: info: Uninstalling plugin peppyspectrum Dec 28 12:35:22 volumio volumio[848]: info: Disabling plugin peppyspectrum Dec 28 12:35:22 volumio volumio[848]: info: Preparing to generate the ALSA configuration file Dec 28 12:35:22 volumio volumio[848]: info: Asound.conf file unchanged, so no further update is needed Dec 28 12:35:22 volumio volumio[848]: info: Output device has changed, restarting MPD Dec 28 12:35:22 volumio sudo[3580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 12:35:22 volumio sudo[3580]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:22 volumio sudo[3580]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:22 volumio volumio[848]: info: Checking if uninstall.sh is present Dec 28 12:35:22 volumio volumio[848]: info: Executing uninstall.sh Dec 28 12:35:22 volumio sudo[3583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 12:35:22 volumio sudo[3583]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:22 volumio systemd[1]: musicservicesshield.service: Succeeded. Dec 28 12:35:22 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Dec 28 12:35:22 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Dec 28 12:35:22 volumio systemd[1]: Stopping Music Player Daemon... Dec 28 12:35:22 volumio volumio[848]: info: MPD Permissions set Dec 28 12:35:22 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:22 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:22 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:22 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:22 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:22 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:22 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:22 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:22 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 12:35:22 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 12:35:22 volumio sudo[3587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/peppyspectrum/uninstall.sh Dec 28 12:35:22 volumio sudo[3587]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:22 volumio systemd[1]: mpd.service: Succeeded. Dec 28 12:35:22 volumio systemd[1]: Stopped Music Player Daemon. Dec 28 12:35:22 volumio sudo[3606]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -Rf /data/plugins/user_interface/peppyspectrum/PeppySpectrum/__pycache__ Dec 28 12:35:22 volumio sudo[3606]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:22 volumio volumio[848]: info: Uninstall script completed Dec 28 12:35:22 volumio volumio[848]: info: Removing plugin peppyspectrum from configuration Dec 28 12:35:22 volumio sudo[3606]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:22 volumio sudo[3610]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /etc/systemd/system/peppyspectrum.service Dec 28 12:35:22 volumio sudo[3610]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:22 volumio sudo[3610]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:22 volumio systemd[1]: Starting Music Player Daemon... Dec 28 12:35:22 volumio sudo[3587]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:22 volumio sudo[3608]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 12:35:22 volumio sudo[3608]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:22 volumio sudo[3608]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:22 volumio volumio[848]: info: Successfully removed peppyspectrum configuration files Dec 28 12:35:22 volumio volumio[848]: info: Plugin folders cleanup Dec 28 12:35:22 volumio volumio[848]: info: Scanning into folder /volumio/app/plugins/ Dec 28 12:35:22 volumio volumio[848]: info: Scanning category audio_interface Dec 28 12:35:22 volumio volumio[848]: info: Scanning category miscellanea Dec 28 12:35:22 volumio volumio[848]: info: Scanning category music_service Dec 28 12:35:22 volumio volumio[848]: info: Scanning category plugins.json Dec 28 12:35:22 volumio volumio[848]: info: Scanning category system_controller Dec 28 12:35:22 volumio volumio[848]: info: Scanning category user_interface Dec 28 12:35:22 volumio volumio[848]: info: Scanning into folder /data/plugins/ Dec 28 12:35:22 volumio volumio[848]: info: Scanning category music_service Dec 28 12:35:22 volumio volumio[848]: info: Scanning category system_hardware Dec 28 12:35:22 volumio volumio[848]: info: Scanning category user_interface Dec 28 12:35:22 volumio volumio[848]: info: Cleaning folder for peppyspectrum Dec 28 12:35:23 volumio volumio[848]: info: Plugin folders cleanup completed Dec 28 12:35:23 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 12:35:23 volumio volumio[848]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.3.0","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Music Services Shield","name":"music_services_shield","category":"system_hardware","version":"1.3.1","icon":"fa-shield","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Now Playing","name":"now_playing","category":"user_interface","version":"0.7.6","icon":"fa-play-circle","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.5.0","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":true,"active":true}] Dec 28 12:35:23 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 28 12:35:23 volumio sudo[3619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 28 12:35:23 volumio sudo[3619]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:23 volumio sudo[3622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 28 12:35:23 volumio systemd[1]: Stopping MPD Monitor Service... Dec 28 12:35:23 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 28 12:35:23 volumio systemd[1]: mpd_monitor.service: Succeeded. Dec 28 12:35:23 volumio systemd[1]: Stopped MPD Monitor Service. Dec 28 12:35:23 volumio sudo[3622]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:23 volumio sudo[3625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 28 12:35:23 volumio sudo[3625]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:23 volumio sudo[3628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 28 12:35:23 volumio sudo[3628]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:23 volumio mpd[3617]: Dec 28 12:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 28 12:35:24 volumio systemd[1]: Started Music Player Daemon. Dec 28 12:35:24 volumio sudo[3583]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:24 volumio volumio[848]: error: updateQueue error: null Dec 28 12:35:24 volumio systemd[1]: Started MPD Monitor Service. Dec 28 12:35:24 volumio sudo[3622]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:24 volumio sudo[3628]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:24 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Dec 28 12:35:24 volumio volumio[848]: info: Successfully started MPD Monitor Dec 28 12:35:24 volumio mpd_monitor.sh[3632]: MPD Monitor Service: Starting MPD Monitor Service Dec 28 12:35:24 volumio sudo[3619]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:24 volumio sudo[3625]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:24 volumio volumio[848]: info: Successfully started MPD Monitor Dec 28 12:35:24 volumio volumio[848]: info: Successfully started MPD Monitor Dec 28 12:35:24 volumio volumio[848]: info: Successfully started MPD Monitor Dec 28 12:35:24 volumio volumio[3633]: cset: --> shielding system active with Dec 28 12:35:24 volumio volumio[3633]: cset: "system" cpuset of CPUSPEC(1-3) with 231 tasks running Dec 28 12:35:24 volumio volumio[3633]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Dec 28 12:35:24 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Dec 28 12:35:27 volumio sudo[3652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 28 12:35:27 volumio sudo[3652]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:27 volumio systemd[1]: Stopping MPD Monitor Service... Dec 28 12:35:27 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 28 12:35:27 volumio systemd[1]: mpd_monitor.service: Succeeded. Dec 28 12:35:27 volumio systemd[1]: Stopped MPD Monitor Service. Dec 28 12:35:27 volumio systemd[1]: Started MPD Monitor Service. Dec 28 12:35:27 volumio sudo[3652]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:27 volumio mpd_monitor.sh[3654]: MPD Monitor Service: Starting MPD Monitor Service Dec 28 12:35:27 volumio volumio[848]: info: Successfully started MPD Monitor Dec 28 12:35:33 volumio volumio[848]: info: Enabling plugin now_playing Dec 28 12:35:33 volumio volumio[848]: info: Loading plugin "now_playing"... Dec 28 12:35:33 volumio volumio[848]: info: PLUGIN START: now_playing Dec 28 12:35:33 volumio volumio[848]: info: [now-playing] ConfigUpdater: config is up to date. Dec 28 12:35:33 volumio volumio[848]: info: [now-playing] App already started Dec 28 12:35:33 volumio volumio[848]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Dec 28 12:35:33 volumio volumio[848]: info: Done. Dec 28 12:35:34 volumio sudo[3675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i s/localhost:4004/localhost:3000/g /opt/volumiokiosk.sh Dec 28 12:35:34 volumio sudo[3675]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:34 volumio sudo[3675]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:34 volumio volumio[848]: [sudo] password for volumio: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk Dec 28 12:35:34 volumio sudo[3678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk Dec 28 12:35:34 volumio sudo[3678]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:34 volumio sudo[3678]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:34 volumio volumio[848]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl restart volumio-kiosk Dec 28 12:35:34 volumio sudo[3681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk Dec 28 12:35:34 volumio sudo[3681]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:34 volumio systemd[1]: Stopping Volumio Kiosk... Dec 28 12:35:34 volumio startx[1150]: xinit: connection to X server lost Dec 28 12:35:34 volumio startx[1150]: [1B blob data] Dec 28 12:35:34 volumio startx[1150]: waiting for X server to shut down (II) Server terminated successfully (0). Closing log file. Dec 28 12:35:35 volumio startx[1150]: xinit: unexpected signal 15 Dec 28 12:35:35 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Dec 28 12:35:35 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Dec 28 12:35:35 volumio systemd[1]: Stopped Volumio Kiosk. Dec 28 12:35:35 volumio systemd[1]: Started Volumio Kiosk. Dec 28 12:35:35 volumio sudo[3681]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:35 volumio volumio[848]: warn: [now-playing] MyBackgroundMonitor stopped Dec 28 12:35:35 volumio volumio[848]: info: Disabling plugin now_playing Dec 28 12:35:35 volumio volumio[848]: info: Done. Dec 28 12:35:35 volumio startx[3688]: X.Org X Server 1.20.4 Dec 28 12:35:35 volumio startx[3688]: X Protocol Version 11, Revision 0 Dec 28 12:35:35 volumio startx[3688]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Dec 28 12:35:35 volumio startx[3688]: Current Operating System: Linux volumio 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l Dec 28 12:35:35 volumio startx[3688]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=2560 bcm2708_fb.fbheight=720 bcm2708_fb.fbswap=1 smsc95xx.macaddr=D8:3A:DD:46:0A:DE vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=db7775c5-9234-4b9a-955e-7341a245d498 imgfile=/volumio_current.sqsh bootpart=UUID=F102-DE29 datapart=UUID=4112086d-38d3-4206-a7af-ef93bf4e4008 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Dec 28 12:35:35 volumio startx[3688]: Build Date: 04 April 2023 07:50:56AM Dec 28 12:35:35 volumio startx[3688]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Dec 28 12:35:35 volumio startx[3688]: Current version of pixman: 0.36.0 Dec 28 12:35:35 volumio startx[3688]: Before reporting problems, check http://wiki.x.org Dec 28 12:35:35 volumio startx[3688]: to make sure that you have the latest version. Dec 28 12:35:35 volumio startx[3688]: Markers: (--) probed, (**) from config file, (==) default setting, Dec 28 12:35:35 volumio startx[3688]: (++) from command line, (!!) notice, (II) informational, Dec 28 12:35:35 volumio startx[3688]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Dec 28 12:35:35 volumio startx[3688]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Dec 28 12:35:35 2025 Dec 28 12:35:35 volumio startx[3688]: (==) Using config directory: "/etc/X11/xorg.conf.d" Dec 28 12:35:35 volumio startx[3688]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Dec 28 12:35:36 volumio volumio[848]: info: Enabling plugin now_playing Dec 28 12:35:36 volumio volumio[848]: info: Loading plugin "now_playing"... Dec 28 12:35:36 volumio volumio[848]: info: PLUGIN START: now_playing Dec 28 12:35:36 volumio volumio[848]: info: [now-playing] ConfigUpdater: config is up to date. Dec 28 12:35:36 volumio volumio[848]: info: [now-playing] App is listening on port 4004. Dec 28 12:35:36 volumio volumio[848]: info: [now-playing] Backing up /opt/volumiokiosk.sh to /home/volumio/.now_playing/volumiokiosk.sh.bak Dec 28 12:35:36 volumio sudo[3817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i s/localhost:3000/localhost:4004/g /opt/volumiokiosk.sh Dec 28 12:35:36 volumio sudo[3817]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:36 volumio sudo[3817]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:36 volumio volumio[848]: [sudo] password for volumio: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk Dec 28 12:35:36 volumio sudo[3820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk Dec 28 12:35:36 volumio sudo[3820]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:36 volumio sudo[3820]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:36 volumio volumio[848]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl restart volumio-kiosk Dec 28 12:35:36 volumio sudo[3823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk Dec 28 12:35:36 volumio sudo[3823]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:35:36 volumio systemd[1]: Stopping Volumio Kiosk... Dec 28 12:35:36 volumio startx[3688]: xinit: connection to X server lost Dec 28 12:35:36 volumio startx[3688]: [1B blob data] Dec 28 12:35:36 volumio startx[3688]: waiting for X server to shut down (II) Server terminated successfully (0). Closing log file. Dec 28 12:35:37 volumio startx[3688]: xinit: unexpected signal 15 Dec 28 12:35:37 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Dec 28 12:35:37 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Dec 28 12:35:37 volumio systemd[1]: Stopped Volumio Kiosk. Dec 28 12:35:37 volumio systemd[1]: Started Volumio Kiosk. Dec 28 12:35:37 volumio sudo[3823]: pam_unix(sudo:session): session closed for user root Dec 28 12:35:37 volumio volumio[848]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Dec 28 12:35:37 volumio volumio[848]: info: Done. Dec 28 12:35:37 volumio startx[3827]: X.Org X Server 1.20.4 Dec 28 12:35:37 volumio startx[3827]: X Protocol Version 11, Revision 0 Dec 28 12:35:37 volumio startx[3827]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Dec 28 12:35:37 volumio startx[3827]: Current Operating System: Linux volumio 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l Dec 28 12:35:37 volumio startx[3827]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=2560 bcm2708_fb.fbheight=720 bcm2708_fb.fbswap=1 smsc95xx.macaddr=D8:3A:DD:46:0A:DE vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=db7775c5-9234-4b9a-955e-7341a245d498 imgfile=/volumio_current.sqsh bootpart=UUID=F102-DE29 datapart=UUID=4112086d-38d3-4206-a7af-ef93bf4e4008 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Dec 28 12:35:37 volumio startx[3827]: Build Date: 04 April 2023 07:50:56AM Dec 28 12:35:37 volumio startx[3827]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Dec 28 12:35:37 volumio startx[3827]: Current version of pixman: 0.36.0 Dec 28 12:35:37 volumio startx[3827]: Before reporting problems, check http://wiki.x.org Dec 28 12:35:37 volumio startx[3827]: to make sure that you have the latest version. Dec 28 12:35:37 volumio startx[3827]: Markers: (--) probed, (**) from config file, (==) default setting, Dec 28 12:35:37 volumio startx[3827]: (++) from command line, (!!) notice, (II) informational, Dec 28 12:35:37 volumio startx[3827]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Dec 28 12:35:37 volumio startx[3827]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Dec 28 12:35:37 2025 Dec 28 12:35:37 volumio startx[3827]: (==) Using config directory: "/etc/X11/xorg.conf.d" Dec 28 12:35:37 volumio startx[3827]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Dec 28 12:35:39 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 12:35:39 volumio volumio[848]: info: Discovery: Getting this device information Dec 28 12:35:39 volumio volumio[848]: info: CoreCommandRouter::volumioGetState Dec 28 12:35:39 volumio volumio[848]: info: CorePlayQueue::getTrack 4 Dec 28 12:35:39 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 12:35:40 volumio volumio[848]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 28 12:35:40 volumio volumio[848]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Dec 28 12:35:40 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Dec 28 12:35:41 volumio volumio[848]: info: Listing playlists Dec 28 12:35:41 volumio volumio[848]: info: CoreCommandRouter::volumioGetQueue Dec 28 12:35:41 volumio volumio[848]: info: CoreStateMachine::getQueue Dec 28 12:35:41 volumio volumio[848]: info: CorePlayQueue::getQueue Dec 28 12:35:41 volumio volumio[848]: info: CoreCommandRouter::volumioGetVisibleSources Dec 28 12:35:41 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 12:35:41 volumio volumio[848]: info: CoreCommandRouter::volumioGetState Dec 28 12:35:41 volumio volumio[848]: info: CorePlayQueue::getTrack 4 Dec 28 12:35:47 volumio go-librespot[1152]: time="2025-12-28T12:35:47+01:00" level=trace msg="sent dealer ping" Dec 28 12:35:47 volumio go-librespot[1152]: time="2025-12-28T12:35:47+01:00" level=trace msg="received dealer pong" Dec 28 12:36:14 volumio volumio[848]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 12:36:17 volumio go-librespot[1152]: time="2025-12-28T12:36:17+01:00" level=trace msg="sent dealer ping" Dec 28 12:36:18 volumio go-librespot[1152]: time="2025-12-28T12:36:18+01:00" level=trace msg="received dealer pong" Dec 28 12:36:19 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 12:36:19 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 28 12:36:26 volumio volumio[848]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 12:36:26 volumio volumio[848]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 12:36:26 volumio volumio[848]: info: Retrieving Cloud Streaming UI Dec 28 12:36:26 volumio volumio[848]: info: Getting Tidal Cloud Configuration Dec 28 12:36:26 volumio volumio[848]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 12:36:26 volumio volumio[848]: info: Getting Qobuz Cloud Configuration Dec 28 12:36:26 volumio volumio[848]: info: Asking plugin for UI Config Dec 28 12:36:26 volumio volumio[848]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 12:36:26 volumio volumio[848]: info: Getting Spotify Cloud Configuration Dec 28 12:36:26 volumio volumio[848]: info: Asking plugin for UI Config Dec 28 12:36:26 volumio volumio[848]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 12:36:26 volumio volumio[848]: info: Saving Spotify Acccount Dec 28 12:36:26 volumio volumio[848]: info: Got Tidal Cloud Configuration Dec 28 12:36:26 volumio volumio[848]: info: Got it Dec 28 12:36:26 volumio volumio[848]: info: Got it Dec 28 12:36:26 volumio volumio[848]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 28 12:36:26 volumio volumio[848]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 12:36:26 volumio volumio[848]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 28 12:36:26 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 28 12:36:26 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 28 12:36:26 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 28 12:36:26 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 28 12:36:26 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 12:36:26 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 12:36:26 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 12:36:26 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 12:36:26 volumio volumio[848]: info: CoreCommandRouter::volumioGetBrowseSources Dec 28 12:36:26 volumio volumio[848]: info: CoreCommandRouter::volumioGetBrowseSources Dec 28 12:36:26 volumio volumio[848]: info: CoreCommandRouter::volumioGetBrowseSources Dec 28 12:36:26 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 12:36:26 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 28 12:36:27 volumio volumio[848]: info: Disabling MyMusic plugin upnp Dec 28 12:36:27 volumio sudo[4352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Dec 28 12:36:27 volumio sudo[4352]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 12:36:27 volumio volumio[848]: error: Upnp client error: Error: This socket has been ended by the other party Dec 28 12:36:27 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Dec 28 12:36:30 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 12:36:32 volumio volumio[848]: info: Enabling MyMusic plugin upnp Dec 28 12:36:32 volumio volumio[848]: info: Enabling plugin upnp Dec 28 12:36:32 volumio volumio[848]: info: Loading plugin "upnp"... Dec 28 12:36:32 volumio volumio[848]: info: [1766921792699] Starting Upmpd Daemon Dec 28 12:36:32 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 12:36:32 volumio volumio[848]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 12:36:32 volumio volumio[848]: Error: listen EADDRINUSE: address already in use :::6599 Dec 28 12:36:32 volumio volumio[848]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Dec 28 12:36:32 volumio volumio[848]: at listenInCluster (net.js:1366:12) Dec 28 12:36:32 volumio volumio[848]: at Server.listen (net.js:1452:7) Dec 28 12:36:32 volumio volumio[848]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Dec 28 12:36:32 volumio volumio[848]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Dec 28 12:36:32 volumio volumio[848]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Dec 28 12:36:32 volumio volumio[848]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 28 12:36:32 volumio volumio[848]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Dec 28 12:36:32 volumio volumio[848]: code: 'EADDRINUSE', Dec 28 12:36:32 volumio volumio[848]: errno: -98, Dec 28 12:36:32 volumio volumio[848]: syscall: 'listen', Dec 28 12:36:32 volumio volumio[848]: address: '::', Dec 28 12:36:32 volumio volumio[848]: port: 6599 Dec 28 12:36:32 volumio volumio[848]: } Dec 28 12:36:32 volumio volumio[848]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 12:36:33 volumio sudo[4377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-28 12:35 Dec 28 12:36:33 volumio sudo[4377]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"