Jun 01 14:43:01 volumio volumio[1247]: error: updateQueue error: null Jun 01 14:43:07 volumio volumio[1247]: info: CoreCommandRouter::volumioPlay Jun 01 14:43:07 volumio volumio[1247]: info: CoreStateMachine::play index undefined Jun 01 14:43:07 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 14:43:07 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 01 14:43:07 volumio volumio[1247]: info: CoreStateMachine::startPlaybackTimer Jun 01 14:43:07 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/music/影子/一年一年把工打.mp3 Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 14:43:07 volumio volumio[1247]: info: sendMpdCommand stop took 2 milliseconds Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 14:43:07 volumio volumio[1247]: info: Jun 01 14:43:07 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 01 14:43:07 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 01 14:43:07 volumio volumio[1247]: info: sendMpdCommand clear took 0 milliseconds Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/music/影子/一年一年把工打.mp3" Jun 01 14:43:07 volumio volumio[1247]: error: updateQueue error: null Jun 01 14:43:07 volumio volumio[1247]: info: ------------------------------ 1ms Jun 01 14:43:07 volumio volumio[1247]: info: Jun 01 14:43:07 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 01 14:43:07 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 01 14:43:07 volumio volumio[1247]: info: Jun 01 14:43:07 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 01 14:43:07 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 01 14:43:07 volumio volumio[1247]: info: Jun 01 14:43:07 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 01 14:43:07 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 01 14:43:07 volumio volumio[1247]: info: sendMpdCommand add "INTERNAL/music/影子/一年一年把工打.mp3" took 2 milliseconds Jun 01 14:43:07 volumio volumio[1247]: info: ------------------------------ 1ms Jun 01 14:43:07 volumio volumio[1247]: info: ------------------------------ 1ms Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand play Jun 01 14:43:07 volumio volumio[1247]: info: Jun 01 14:43:07 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 01 14:43:07 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 01 14:43:07 volumio volumio[1247]: info: Jun 01 14:43:07 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 01 14:43:07 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 01 14:43:07 volumio volumio[1247]: info: ------------------------------ 2ms Jun 01 14:43:07 volumio volumio[1247]: info: sendMpdCommand play took 2 milliseconds Jun 01 14:43:07 volumio volumio[1247]: info: ------------------------------ 1ms Jun 01 14:43:07 volumio volumio[1247]: info: ------------------------------ 1ms Jun 01 14:43:07 volumio volumio[1247]: info: Jun 01 14:43:07 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 01 14:43:07 volumio volumio[1247]: info: ControllerMpd::getState Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 01 14:43:07 volumio volumio[1247]: info: Jun 01 14:43:07 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 01 14:43:07 volumio volumio[1247]: info: ControllerMpd::getState Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 01 14:43:07 volumio volumio[1247]: info: Jun 01 14:43:07 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 01 14:43:07 volumio volumio[1247]: info: ControllerMpd::getState Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 01 14:43:07 volumio volumio[1247]: info: sendMpdCommand status took 200 milliseconds Jun 01 14:43:07 volumio volumio[1247]: info: sendMpdCommand status took 200 milliseconds Jun 01 14:43:07 volumio volumio[1247]: info: sendMpdCommand status took 200 milliseconds Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 14:43:07 volumio volumio[1247]: info: Jun 01 14:43:07 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 01 14:43:07 volumio volumio[1247]: info: ControllerMpd::getState Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 01 14:43:07 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 01 14:43:07 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 01 14:43:07 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 01 14:43:07 volumio volumio[1247]: info: ControllerMpd::pushState Jun 01 14:43:07 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 01 14:43:07 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 01 14:43:07 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"一年一年把工打","artist":"影子","album":"一年一年把工打","uri":"INTERNAL/music/影子/一年一年把工打.mp3","trackType":"mp3"} Jun 01 14:43:07 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 01 14:43:07 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 01 14:43:07 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus stop Jun 01 14:43:07 volumio volumio[1247]: info: ControllerMpd::pushState Jun 01 14:43:07 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 01 14:43:07 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 01 14:43:07 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"一年一年把工打","artist":"影子","album":"一年一年把工打","uri":"INTERNAL/music/影子/一年一年把工打.mp3","trackType":"mp3"} Jun 01 14:43:07 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 01 14:43:07 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 01 14:43:07 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus play Jun 01 14:43:07 volumio volumio[1247]: info: Received an update from plugin. extracting info from payload Jun 01 14:43:07 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 01 14:43:07 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 01 14:43:07 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 14:43:07 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 01 14:43:07 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 01 14:43:07 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 01 14:43:07 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 01 14:43:07 volumio volumio[1247]: info: ControllerMpd::pushState Jun 01 14:43:07 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 01 14:43:07 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 01 14:43:07 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"一年一年把工打","artist":"影子","album":"一年一年把工打","uri":"INTERNAL/music/影子/一年一年把工打.mp3","trackType":"mp3"} Jun 01 14:43:07 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 01 14:43:07 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 01 14:43:07 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus play Jun 01 14:43:07 volumio volumio[1247]: info: Received an update from plugin. extracting info from payload Jun 01 14:43:07 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 01 14:43:07 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 01 14:43:07 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 01 14:43:07 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 01 14:43:07 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 01 14:43:07 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 01 14:43:07 volumio volumio[1247]: info: ------------------------------ 213ms Jun 01 14:43:07 volumio volumio[1247]: info: ------------------------------ 230ms Jun 01 14:43:07 volumio volumio[1247]: info: ------------------------------ 230ms Jun 01 14:43:07 volumio volumio[1247]: info: Jun 01 14:43:07 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 01 14:43:07 volumio volumio[1247]: info: ControllerMpd::getState Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 01 14:43:07 volumio volumio[1247]: info: Jun 01 14:43:07 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 01 14:43:07 volumio volumio[1247]: info: ControllerMpd::getState Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 01 14:43:07 volumio volumio[1247]: info: sendMpdCommand status took 31 milliseconds Jun 01 14:43:07 volumio volumio[1247]: info: sendMpdCommand status took 1 milliseconds Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 01 14:43:07 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 14:43:07 volumio volumio[1247]: info: PeppySpectrum ---peppyspectrum status play Jun 01 14:43:07 volumio volumio[1247]: info: PeppySpectrum ---peppyspectrum status play Jun 01 14:43:08 volumio volumio[1247]: info: PeppySpectrum ---peppyspectrum status play Jun 01 14:43:08 volumio volumio[1247]: info: PeppySpectrum ---peppyspectrum status play Jun 01 14:43:08 volumio sudo[7056]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jun 01 14:43:08 volumio sudo[7056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:43:08 volumio volumio[1247]: info: sendMpdCommand status took 62 milliseconds Jun 01 14:43:08 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 57 milliseconds Jun 01 14:43:08 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 55 milliseconds Jun 01 14:43:08 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 01 14:43:08 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 14:43:08 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 01 14:43:08 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 01 14:43:08 volumio sudo[7057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jun 01 14:43:08 volumio volumio[1247]: info: ControllerMpd::pushState Jun 01 14:43:08 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 01 14:43:08 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 01 14:43:08 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"一年一年把工打","artist":"影子","album":"一年一年把工打","uri":"INTERNAL/music/影子/一年一年把工打.mp3","trackType":"mp3"} Jun 01 14:43:08 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 01 14:43:08 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 01 14:43:08 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus play Jun 01 14:43:08 volumio volumio[1247]: info: Received an update from plugin. extracting info from payload Jun 01 14:43:08 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 01 14:43:08 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 01 14:43:08 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 14:43:08 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 01 14:43:08 volumio sudo[7057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:43:08 volumio sudo[7062]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jun 01 14:43:08 volumio sudo[7062]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:43:08 volumio sudo[7060]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jun 01 14:43:08 volumio sudo[7060]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:43:08 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 01 14:43:08 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 01 14:43:08 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 01 14:43:08 volumio volumio[1247]: info: ControllerMpd::pushState Jun 01 14:43:08 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 01 14:43:08 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 01 14:43:08 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"一年一年把工打","artist":"影子","album":"一年一年把工打","uri":"INTERNAL/music/影子/一年一年把工打.mp3","trackType":"mp3"} Jun 01 14:43:08 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 01 14:43:08 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 01 14:43:08 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus play Jun 01 14:43:08 volumio volumio[1247]: info: Received an update from plugin. extracting info from payload Jun 01 14:43:08 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 01 14:43:08 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 01 14:43:08 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 01 14:43:08 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 01 14:43:08 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 01 14:43:08 volumio systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Jun 01 14:43:08 volumio sudo[7056]: pam_unix(sudo:session): session closed for user root Jun 01 14:43:08 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 01 14:43:08 volumio sudo[7062]: pam_unix(sudo:session): session closed for user root Jun 01 14:43:08 volumio sudo[7057]: pam_unix(sudo:session): session closed for user root Jun 01 14:43:08 volumio sudo[7060]: pam_unix(sudo:session): session closed for user root Jun 01 14:43:08 volumio volumio[1247]: info: ------------------------------ 196ms Jun 01 14:43:08 volumio volumio[1247]: info: ------------------------------ 169ms Jun 01 14:43:08 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 109 milliseconds Jun 01 14:43:08 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 01 14:43:08 volumio volumio[1247]: info: ControllerMpd::pushState Jun 01 14:43:08 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 01 14:43:08 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 01 14:43:08 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"一年一年把工打","artist":"影子","album":"一年一年把工打","uri":"INTERNAL/music/影子/一年一年把工打.mp3","trackType":"mp3"} Jun 01 14:43:08 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 01 14:43:08 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 01 14:43:08 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus play Jun 01 14:43:08 volumio volumio[1247]: info: Received an update from plugin. extracting info from payload Jun 01 14:43:08 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 01 14:43:08 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 01 14:43:08 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 01 14:43:08 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 01 14:43:08 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 01 14:43:08 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 01 14:43:08 volumio volumio[1247]: info: ------------------------------ 209ms Jun 01 14:43:08 volumio startpeppyspectrum.sh[7067]: Traceback (most recent call last): Jun 01 14:43:08 volumio startpeppyspectrum.sh[7067]: File "/data/plugins/user_interface/peppyspectrum/PeppySpectrum/spectrum.py", line 18, in Jun 01 14:43:08 volumio startpeppyspectrum.sh[7067]: import pygame Jun 01 14:43:08 volumio startpeppyspectrum.sh[7067]: ModuleNotFoundError: No module named 'pygame' Jun 01 14:43:08 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Jun 01 14:43:08 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jun 01 14:43:08 volumio volumio[1247]: info: PeppySpectrum ---peppyspectrum status play Jun 01 14:43:08 volumio volumio[1247]: info: PeppySpectrum ---peppyspectrum status play Jun 01 14:43:08 volumio volumio[1247]: info: PeppySpectrum ---peppyspectrum status play Jun 01 14:43:08 volumio volumio[1247]: info: PeppySpectrum ---peppyspectrum status play Jun 01 14:43:08 volumio sudo[7071]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jun 01 14:43:08 volumio sudo[7071]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:43:08 volumio sudo[7072]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jun 01 14:43:08 volumio sudo[7072]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:43:08 volumio volumio[1247]: info: PeppySpectrum ---peppyspectrum status play Jun 01 14:43:08 volumio sudo[7074]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jun 01 14:43:08 volumio sudo[7074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:43:08 volumio volumio[1247]: info: PeppySpectrum ---peppyspectrum status play Jun 01 14:43:08 volumio systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Jun 01 14:43:08 volumio sudo[7081]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jun 01 14:43:08 volumio sudo[7077]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jun 01 14:43:08 volumio sudo[7077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:43:08 volumio volumio[1247]: info: peppyspectrum Daemon Started Jun 01 14:43:08 volumio volumio[1247]: info: peppyspectrum Daemon Started Jun 01 14:43:08 volumio volumio[1247]: info: peppyspectrum Daemon Started Jun 01 14:43:08 volumio volumio[1247]: info: peppyspectrum Daemon Started Jun 01 14:43:08 volumio sudo[7072]: pam_unix(sudo:session): session closed for user root Jun 01 14:43:08 volumio sudo[7081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:43:08 volumio sudo[7074]: pam_unix(sudo:session): session closed for user root Jun 01 14:43:08 volumio sudo[7071]: pam_unix(sudo:session): session closed for user root Jun 01 14:43:08 volumio volumio[1247]: info: peppyspectrum Daemon Started Jun 01 14:43:08 volumio volumio[1247]: info: peppyspectrum Daemon Started Jun 01 14:43:08 volumio volumio[1247]: info: peppyspectrum Daemon Started Jun 01 14:43:08 volumio sudo[7081]: pam_unix(sudo:session): session closed for user root Jun 01 14:43:08 volumio volumio[1247]: info: peppyspectrum Daemon Started Jun 01 14:43:08 volumio sudo[7077]: pam_unix(sudo:session): session closed for user root Jun 01 14:43:08 volumio sudo[7084]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jun 01 14:43:08 volumio sudo[7084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:43:08 volumio volumio[1247]: info: peppyspectrum Daemon Started Jun 01 14:43:08 volumio startpeppyspectrum.sh[7085]: Traceback (most recent call last): Jun 01 14:43:08 volumio startpeppyspectrum.sh[7085]: File "/data/plugins/user_interface/peppyspectrum/PeppySpectrum/spectrum.py", line 18, in Jun 01 14:43:08 volumio startpeppyspectrum.sh[7085]: import pygame Jun 01 14:43:08 volumio startpeppyspectrum.sh[7085]: ModuleNotFoundError: No module named 'pygame' Jun 01 14:43:08 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Jun 01 14:43:08 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jun 01 14:43:08 volumio systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Jun 01 14:43:08 volumio sudo[7084]: pam_unix(sudo:session): session closed for user root Jun 01 14:43:08 volumio volumio[1247]: info: peppyspectrum Daemon Started Jun 01 14:43:08 volumio startpeppyspectrum.sh[7090]: Traceback (most recent call last): Jun 01 14:43:08 volumio startpeppyspectrum.sh[7090]: File "/data/plugins/user_interface/peppyspectrum/PeppySpectrum/spectrum.py", line 18, in Jun 01 14:43:08 volumio startpeppyspectrum.sh[7090]: import pygame Jun 01 14:43:08 volumio startpeppyspectrum.sh[7090]: ModuleNotFoundError: No module named 'pygame' Jun 01 14:43:08 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Jun 01 14:43:08 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jun 01 14:43:14 volumio volumio[1247]: info: CoreCommandRouter::volumioGetState Jun 01 14:43:14 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 01 14:43:18 volumio volumio[1247]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 14:43:18 volumio volumio[1247]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 14:43:18 volumio volumio[1247]: info: Retrieving Cloud Streaming UI Jun 01 14:43:18 volumio volumio[1247]: info: Getting Tidal Cloud Configuration Jun 01 14:43:18 volumio volumio[1247]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 14:43:18 volumio volumio[1247]: info: Getting Qobuz Cloud Configuration Jun 01 14:43:18 volumio volumio[1247]: info: Asking plugin for UI Config Jun 01 14:43:18 volumio volumio[1247]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 14:43:18 volumio volumio[1247]: info: Getting Spotify Cloud Configuration Jun 01 14:43:18 volumio volumio[1247]: info: Asking plugin for UI Config Jun 01 14:43:18 volumio volumio[1247]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 14:43:18 volumio volumio[1247]: info: Saving Spotify Acccount Jun 01 14:43:18 volumio volumio[1247]: info: Got Tidal Cloud Configuration Jun 01 14:43:18 volumio volumio[1247]: info: Got it Jun 01 14:43:18 volumio volumio[1247]: info: Got it Jun 01 14:43:18 volumio volumio[1247]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 01 14:43:18 volumio volumio[1247]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 14:43:18 volumio volumio[1247]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jun 01 14:43:18 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 01 14:43:18 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 14:43:18 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 14:43:18 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 14:43:18 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 14:43:18 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 14:43:18 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 14:43:18 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 14:43:18 volumio volumio[1247]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 14:43:18 volumio volumio[1247]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 14:43:18 volumio volumio[1247]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 14:43:18 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 14:43:19 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 01 14:43:22 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 14:43:24 volumio volumio[1247]: info: CoreCommandRouter::volumioGetState Jun 01 14:43:24 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 01 14:43:25 volumio volumio[1247]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 14:43:25 volumio volumio[1247]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 14:43:25 volumio volumio[1247]: info: Retrieving Cloud Streaming UI Jun 01 14:43:25 volumio volumio[1247]: info: Getting Tidal Cloud Configuration Jun 01 14:43:25 volumio volumio[1247]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 14:43:25 volumio volumio[1247]: info: Getting Qobuz Cloud Configuration Jun 01 14:43:25 volumio volumio[1247]: info: Asking plugin for UI Config Jun 01 14:43:25 volumio volumio[1247]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 14:43:25 volumio volumio[1247]: info: Getting Spotify Cloud Configuration Jun 01 14:43:25 volumio volumio[1247]: info: Asking plugin for UI Config Jun 01 14:43:25 volumio volumio[1247]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 14:43:25 volumio volumio[1247]: info: Saving Spotify Acccount Jun 01 14:43:25 volumio volumio[1247]: info: Got Tidal Cloud Configuration Jun 01 14:43:25 volumio volumio[1247]: info: Got it Jun 01 14:43:25 volumio volumio[1247]: info: Got it Jun 01 14:43:25 volumio volumio[1247]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 01 14:43:25 volumio volumio[1247]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 14:43:25 volumio volumio[1247]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jun 01 14:43:25 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 01 14:43:25 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 14:43:25 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 14:43:25 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 14:43:25 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 14:43:25 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 14:43:25 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 14:43:25 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 14:43:25 volumio volumio[1247]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 14:43:25 volumio volumio[1247]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 14:43:25 volumio volumio[1247]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 14:43:25 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 14:43:25 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 01 14:43:29 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 14:43:32 volumio volumio[1247]: info: Get:13 http://deb.debian.org/debian bookworm/main amd64 python3-olefile all 0.46-3 [36.1 kB] Jun 01 14:43:32 volumio volumio5-onboarding[2210]: time=2026-06-01T14:43:32.792+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:43:32 volumio volumio[1247]: info: Get:13 http://deb.debian.org/debian bookworm/main amd64 python3-olefile all 0.46-3 [36.1 kB] Jun 01 14:43:32 volumio volumio5-onboarding[2210]: time=2026-06-01T14:43:32.806+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:43:33 volumio volumio[1247]: info: Get:14 http://deb.debian.org/debian bookworm/main amd64 python3-pil amd64 9.4.0-1.1+deb12u1 [472 kB] Jun 01 14:43:33 volumio volumio5-onboarding[2210]: time=2026-06-01T14:43:33.712+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:43:33 volumio volumio[1247]: info: Get:14 http://deb.debian.org/debian bookworm/main amd64 python3-pil amd64 9.4.0-1.1+deb12u1 [472 kB] Jun 01 14:43:33 volumio volumio5-onboarding[2210]: time=2026-06-01T14:43:33.732+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:43:34 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 14:43:38 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 14:43:41 volumio volumio[1247]: info: Get:15 http://deb.debian.org/debian bookworm/main amd64 python3-pygame amd64 2.1.2+dfsg-5+b1 [1013 kB] Jun 01 14:43:41 volumio volumio5-onboarding[2210]: time=2026-06-01T14:43:41.973+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:43:41 volumio volumio[1247]: info: Get:15 http://deb.debian.org/debian bookworm/main amd64 python3-pygame amd64 2.1.2+dfsg-5+b1 [1013 kB] Jun 01 14:43:41 volumio volumio5-onboarding[2210]: time=2026-06-01T14:43:41.988+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:43:42 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 14:43:46 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 14:43:50 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 14:43:54 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 14:43:58 volumio volumio[1247]: info: Enabling plugin peppyspectrum Jun 01 14:43:58 volumio volumio[1247]: info: Loading plugin "peppyspectrum"... Jun 01 14:43:58 volumio volumio[1247]: info: Preparing to generate the ALSA configuration file Jun 01 14:43:58 volumio volumio[1247]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 01 14:43:58 volumio volumio[1247]: info: Reading ALSA contributions from plugins. Jun 01 14:43:58 volumio volumio[1247]: info: Asound.conf file unchanged, so no further update is needed Jun 01 14:43:58 volumio volumio[1247]: info: Output device has changed, restarting MPD Jun 01 14:43:58 volumio volumio[1247]: info: Output device has changed, restarting Shairport Sync Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 14:43:58 volumio sudo[7233]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 14:43:58 volumio sudo[7233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:43:58 volumio sudo[7233]: pam_unix(sudo:session): session closed for user root Jun 01 14:43:58 volumio sudo[7235]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 14:43:58 volumio volumio[1247]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 14:43:58 volumio sudo[7235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:43:58 volumio volumio[1247]: info: PLUGIN START: peppyspectrum Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 01 14:43:58 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 01 14:43:58 volumio sudo[7243]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-aloop index=7 pcm_substreams=1 Jun 01 14:43:58 volumio sudo[7243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:43:58 volumio volumio[1247]: info: Preparing to generate the ALSA configuration file Jun 01 14:43:58 volumio sudo[7243]: pam_unix(sudo:session): session closed for user root Jun 01 14:43:58 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 01 14:43:58 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 01 14:43:58 volumio systemd[1]: mpd.service: Consumed 1.309s CPU time. Jun 01 14:43:58 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 01 14:43:58 volumio volumio[1247]: info: Done. Jun 01 14:43:58 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 01 14:43:58 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 14:43:58 volumio volumio[1247]: error: MPD error: Error: write EPIPE Jun 01 14:43:58 volumio volumio[1247]: error: write EPIPE {"code":"EPIPE","errno":-32,"syscall":"write"} Jun 01 14:43:58 volumio volumio[1247]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 01 14:43:58 volumio volumio[1247]: info: Reading ALSA contributions from plugins. Jun 01 14:43:58 volumio volumio[1247]: info: snd_dummy loaded Jun 01 14:43:58 volumio volumio[1247]: info: MPD Permissions set Jun 01 14:43:58 volumio volumio[1247]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jun 01 14:43:58 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 01 14:43:58 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:58 volumio volumio[1247]: info: Starting Shairport Sync Jun 01 14:43:58 volumio sudo[7246]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 01 14:43:58 volumio sudo[7246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 14:43:58 volumio sudo[7246]: pam_unix(sudo:session): session closed for user root Jun 01 14:43:58 volumio volumio[1247]: info: Asound.conf file unchanged, so no further update is needed Jun 01 14:43:58 volumio volumio[1247]: info: Output device has changed, restarting MPD Jun 01 14:43:58 volumio sudo[7258]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 14:43:58 volumio sudo[7258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:43:58 volumio volumio[1247]: info: Output device has changed, restarting Shairport Sync Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 14:43:58 volumio sudo[7260]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 14:43:58 volumio sudo[7260]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:43:58 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 01 14:43:58 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 01 14:43:58 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 14:43:58 volumio systemd[1]: shairport-sync.service: Consumed 1.711s CPU time. Jun 01 14:43:58 volumio sudo[7260]: pam_unix(sudo:session): session closed for user root Jun 01 14:43:58 volumio volumio[1247]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 14:43:58 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 14:43:58 volumio sudo[7263]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 14:43:58 volumio sudo[7263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:43:58 volumio sudo[7258]: pam_unix(sudo:session): session closed for user root Jun 01 14:43:58 volumio volumio[1247]: info: MPD Permissions set Jun 01 14:43:58 volumio volumio[1247]: info: Shairport-Sync Started Jun 01 14:43:58 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 01 14:43:58 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 01 14:43:58 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 01 14:43:58 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 01 14:43:58 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:58 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 01 14:43:58 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:58 volumio volumio[1247]: info: Starting Shairport Sync Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:58 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:58 volumio sudo[7293]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 01 14:43:58 volumio sudo[7293]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 14:43:58 volumio sudo[7293]: pam_unix(sudo:session): session closed for user root Jun 01 14:43:58 volumio sudo[7300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 14:43:58 volumio sudo[7300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:43:58 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 01 14:43:58 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 01 14:43:58 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 14:43:58 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 14:43:58 volumio sudo[7300]: pam_unix(sudo:session): session closed for user root Jun 01 14:43:58 volumio volumio[1247]: info: Shairport-Sync Started Jun 01 14:43:59 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:59 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:59 volumio volumio[1247]: info: Enabling plugin peppy_screensaver Jun 01 14:43:59 volumio volumio[1247]: info: Loading plugin "peppy_screensaver"... Jun 01 14:43:59 volumio volumio[1247]: info: Applying required configuration parameters for plugin peppy_screensaver Jun 01 14:43:59 volumio volumio[1247]: info: Preparing to generate the ALSA configuration file Jun 01 14:43:59 volumio volumio[1247]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jun 01 14:43:59 volumio volumio[1247]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 01 14:43:59 volumio volumio[1247]: info: Reading ALSA contributions from plugins. Jun 01 14:43:59 volumio volumio[1247]: info: Asound.conf file written Jun 01 14:43:59 volumio sudo[7322]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 01 14:43:59 volumio sudo[7322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:43:59 volumio sudo[7322]: pam_unix(sudo:session): session closed for user root Jun 01 14:43:59 volumio volumio[1247]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:6 use case configuration -2 Jun 01 14:43:59 volumio volumio[1247]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Jun 01 14:43:59 volumio volumio[1247]: info: Output device has changed, restarting MPD Jun 01 14:43:59 volumio volumio[1247]: info: Output device has changed, restarting Shairport Sync Jun 01 14:43:59 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:43:59 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 14:43:59 volumio mpd[7303]: 2026-06-01T14:43:59 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 01 14:43:59 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jun 01 14:43:59 volumio sudo[7329]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 14:43:59 volumio sudo[7263]: pam_unix(sudo:session): session closed for user root Jun 01 14:43:59 volumio sudo[7235]: pam_unix(sudo:session): session closed for user root Jun 01 14:43:59 volumio sudo[7330]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 14:43:59 volumio sudo[7329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:43:59 volumio sudo[7330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:43:59 volumio volumio[1247]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 14:43:59 volumio volumio[1247]: info: PLUGIN START: peppy_screensaver Jun 01 14:43:59 volumio volumio[1247]: info: Loading i18n strings for locale zh Jun 01 14:43:59 volumio sudo[7330]: pam_unix(sudo:session): session closed for user root Jun 01 14:43:59 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 01 14:43:59 volumio volumio[1247]: info: /tmp/myfifo created Jun 01 14:43:59 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 01 14:43:59 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 01 14:43:59 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 01 14:43:59 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 01 14:43:59 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 01 14:43:59 volumio volumio[1247]: info: /tmp/myfifosa created Jun 01 14:43:59 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 01 14:43:59 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 01 14:43:59 volumio sudo[7352]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Jun 01 14:43:59 volumio sudo[7352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:43:59 volumio sudo[7352]: pam_unix(sudo:session): session closed for user root Jun 01 14:43:59 volumio volumio[1247]: info: snd-dummy loaded Jun 01 14:43:59 volumio sudo[7351]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 01 14:43:59 volumio sudo[7351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 14:43:59 volumio sudo[7356]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-aloop index=6 pcm_substreams=2 Jun 01 14:43:59 volumio sudo[7356]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:43:59 volumio sudo[7356]: pam_unix(sudo:session): session closed for user root Jun 01 14:43:59 volumio volumio[1247]: info: snd-aloop loaded for x64 Spotify Jun 01 14:43:59 volumio sudo[7351]: pam_unix(sudo:session): session closed for user root Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 14:44:00 volumio volumio[1247]: info: Adding peppy_screensaver REST Endpoint for plugin: user_interface/peppy_screensaver Jun 01 14:44:00 volumio volumio[1247]: info: peppy_screensaver: REST endpoint registered: peppy_screensaver Jun 01 14:44:00 volumio volumio[1247]: info: Updating peppy_screensaver_font REST Endpoint for plugin: user_interface/peppy_screensaver Jun 01 14:44:00 volumio volumio[1247]: info: peppy_screensaver: REST endpoint registered: peppy_screensaver_font Jun 01 14:44:00 volumio volumio[1247]: info: Updating peppy_screensaver_vinyl REST Endpoint for plugin: user_interface/peppy_screensaver Jun 01 14:44:00 volumio volumio[1247]: info: peppy_screensaver: REST endpoint registered: peppy_screensaver_vinyl Jun 01 14:44:00 volumio volumio[1247]: info: Done. Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio sudo[7392]: pam_unix(sudo:auth): conversation failed Jun 01 14:44:00 volumio sudo[7392]: pam_unix(sudo:auth): auth could not identify password for [volumio] Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::volumioGetState Jun 01 14:44:00 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 01 14:44:00 volumio sudo[7394]: pam_unix(sudo:auth): conversation failed Jun 01 14:44:00 volumio sudo[7394]: pam_unix(sudo:auth): auth could not identify password for [volumio] Jun 01 14:44:00 volumio volumio[1247]: info: MPD Permissions set Jun 01 14:44:00 volumio volumio[1247]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Jun 01 14:44:00 volumio volumio[1247]: error: peppy_screensaver: normalizeTemplatePermissions: error on /data/INTERNAL/peppy_screensaver/templates_spectrum: Error: Command failed: /usr/bin/sudo /bin/chown -R volumio:volumio /data/INTERNAL/peppy_screensaver/templates_spectrum && /usr/bin/sudo /bin/chmod -R 777 /data/INTERNAL/peppy_screensaver/templates_spectrum && /usr/bin/sudo /usr/bin/find /data/INTERNAL/peppy_screensaver/templates_spectrum -type f -exec /bin/chmod 666 {} + Jun 01 14:44:00 volumio volumio[1247]: sudo: a terminal is required to read the password; either use the -S option to read from standard input or configure an askpass helper Jun 01 14:44:00 volumio volumio[1247]: sudo: a password is required Jun 01 14:44:00 volumio volumio[1247]: error: peppy_screensaver: normalizeTemplatePermissions: error on /data/INTERNAL/peppy_screensaver/templates: Error: Command failed: /usr/bin/sudo /bin/chown -R volumio:volumio /data/INTERNAL/peppy_screensaver/templates && /usr/bin/sudo /bin/chmod -R 777 /data/INTERNAL/peppy_screensaver/templates && /usr/bin/sudo /usr/bin/find /data/INTERNAL/peppy_screensaver/templates -type f -exec /bin/chmod 666 {} + Jun 01 14:44:00 volumio volumio[1247]: sudo: a terminal is required to read the password; either use the -S option to read from standard input or configure an askpass helper Jun 01 14:44:00 volumio volumio[1247]: sudo: a password is required Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: peppy_screensaver: ALSA template: /data/plugins/user_interface/peppy_screensaver/Peppyalsa.postPeppyalsa.5.x64.conf.tmpl (isX64=true) Jun 01 14:44:00 volumio volumio[1247]: info: Starting Shairport Sync Jun 01 14:44:00 volumio sudo[7413]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Jun 01 14:44:00 volumio volumio[1247]: info: Starting Shairport Sync Jun 01 14:44:00 volumio sudo[7413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 01 14:44:00 volumio volumio[1247]: info: Preparing to generate the ALSA configuration file Jun 01 14:44:00 volumio sudo[7415]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 14:44:00 volumio sudo[7415]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:44:00 volumio sudo[7413]: pam_unix(sudo:session): session closed for user root Jun 01 14:44:00 volumio volumio[1247]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jun 01 14:44:00 volumio volumio[1247]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 01 14:44:00 volumio volumio[1247]: info: Reading ALSA contributions from plugins. Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Jun 01 14:44:00 volumio sudo[7418]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 14:44:00 volumio sudo[7418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:44:00 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 01 14:44:00 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 01 14:44:00 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 14:44:00 volumio sudo[7422]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 14:44:00 volumio sudo[7415]: pam_unix(sudo:session): session closed for user root Jun 01 14:44:00 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 01 14:44:00 volumio sudo[7422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:44:00 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 01 14:44:00 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 14:44:00 volumio sudo[7422]: pam_unix(sudo:session): session closed for user root Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 01 14:44:00 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 14:44:00 volumio sudo[7418]: pam_unix(sudo:session): session closed for user root Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: Shairport-Sync Started Jun 01 14:44:00 volumio volumio[1247]: (node:1247) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 01 14:44:00 volumio volumio[1247]: (Use `node --trace-warnings ...` to show where the warning was created) Jun 01 14:44:00 volumio volumio[1247]: (node:1247) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 01 14:44:00 volumio volumio[1247]: (node:1247) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 01 14:44:00 volumio volumio[1247]: (node:1247) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 01 14:44:00 volumio volumio[1247]: (node:1247) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 01 14:44:00 volumio volumio[1247]: info: MPD Permissions set Jun 01 14:44:00 volumio volumio[1247]: info: Shairport-Sync Started Jun 01 14:44:00 volumio volumio[1247]: info: Asound.conf file unchanged, so no further update is needed Jun 01 14:44:00 volumio volumio[1247]: info: Output device has changed, restarting MPD Jun 01 14:44:00 volumio mpd[7364]: 2026-06-01T14:44:00 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 01 14:44:00 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jun 01 14:44:00 volumio sudo[7329]: pam_unix(sudo:session): session closed for user root Jun 01 14:44:00 volumio volumio[1247]: info: Output device has changed, restarting Shairport Sync Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 14:44:00 volumio sudo[7451]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 14:44:00 volumio sudo[7451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:44:01 volumio sudo[7453]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 14:44:01 volumio sudo[7453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:44:01 volumio sudo[7451]: pam_unix(sudo:session): session closed for user root Jun 01 14:44:01 volumio volumio[1247]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 14:44:01 volumio volumio[1247]: error: MPD error: The expression evaluated to a falsy value: Jun 01 14:44:01 volumio volumio[1247]: assert.ok(self.idling) Jun 01 14:44:01 volumio volumio[1247]: error: The expression evaluated to a falsy value: Jun 01 14:44:01 volumio volumio[1247]: assert.ok(self.idling) Jun 01 14:44:01 volumio volumio[1247]: info: MPD Permissions set Jun 01 14:44:01 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 01 14:44:01 volumio volumio[1247]: error: updateQueue error: null Jun 01 14:44:01 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 01 14:44:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 01 14:44:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 01 14:44:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 01 14:44:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 01 14:44:01 volumio volumio[1247]: error: MPD error: Error: write EPIPE Jun 01 14:44:01 volumio volumio[1247]: error: write EPIPE {"code":"EPIPE","errno":-32,"syscall":"write"} Jun 01 14:44:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 01 14:44:01 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:01 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:01 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:01 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:01 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:01 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:01 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:01 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 01 14:44:01 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 14:44:01 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 01 14:44:01 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:01 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jun 01 14:44:01 volumio sudo[7461]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 01 14:44:01 volumio sudo[7461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 14:44:01 volumio sudo[7461]: pam_unix(sudo:session): session closed for user root Jun 01 14:44:01 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jun 01 14:44:01 volumio volumio[1247]: info: Starting Shairport Sync Jun 01 14:44:01 volumio sudo[7474]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 14:44:01 volumio sudo[7474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:44:01 volumio sudo[7476]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 14:44:01 volumio sudo[7476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:44:01 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 01 14:44:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 01 14:44:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 01 14:44:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 01 14:44:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 01 14:44:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 01 14:44:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 01 14:44:01 volumio sudo[7480]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 14:44:01 volumio sudo[7480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:44:01 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 01 14:44:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 01 14:44:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 01 14:44:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 01 14:44:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 01 14:44:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 01 14:44:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 01 14:44:01 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:01 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:01 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:01 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:01 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 01 14:44:01 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 01 14:44:01 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 14:44:01 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 14:44:01 volumio sudo[7480]: pam_unix(sudo:session): session closed for user root Jun 01 14:44:01 volumio volumio[1247]: info: Shairport-Sync Started Jun 01 14:44:01 volumio sudo[7485]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 01 14:44:01 volumio sudo[7485]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 14:44:01 volumio sudo[7485]: pam_unix(sudo:session): session closed for user root Jun 01 14:44:02 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 14:44:02 volumio mpd[7499]: 2026-06-01T14:44:02 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 01 14:44:02 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jun 01 14:44:02 volumio sudo[7474]: pam_unix(sudo:session): session closed for user root Jun 01 14:44:02 volumio sudo[7453]: pam_unix(sudo:session): session closed for user root Jun 01 14:44:02 volumio sudo[7476]: pam_unix(sudo:session): session closed for user root Jun 01 14:44:02 volumio volumio[1247]: error: MPD error: The expression evaluated to a falsy value: Jun 01 14:44:02 volumio volumio[1247]: assert.ok(self.idling) Jun 01 14:44:02 volumio volumio[1247]: error: The expression evaluated to a falsy value: Jun 01 14:44:02 volumio volumio[1247]: assert.ok(self.idling) Jun 01 14:44:02 volumio volumio[1247]: info: CoreStateMachine::stop Jun 01 14:44:02 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 14:44:02 volumio volumio[1247]: info: CoreStateMachine::stPlaybackTimer Jun 01 14:44:02 volumio volumio[1247]: info: CoreStateMachine::updateTrackBlock Jun 01 14:44:02 volumio volumio[1247]: info: CorePlayQueue::getTrackBlock Jun 01 14:44:02 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 01 14:44:02 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 01 14:44:02 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 14:44:02 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 01 14:44:02 volumio volumio[1247]: info: CoreStateMachine::serviceStop Jun 01 14:44:02 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 01 14:44:02 volumio volumio[1247]: info: CoreCommandRouter::serviceStop Jun 01 14:44:02 volumio volumio[1247]: info: ControllerMpd::stop Jun 01 14:44:02 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 14:44:02 volumio volumio[1247]: info: Disabling plugin peppy_screensaver Jun 01 14:44:02 volumio volumio[1247]: info: Preparing to generate the ALSA configuration file Jun 01 14:44:02 volumio volumio[1247]: info: PeppySpectrum ---peppyspectrum status stop Jun 01 14:44:02 volumio volumio[1247]: info: PeppySpectrum ---peppyspectrum status stop Jun 01 14:44:02 volumio volumio[1247]: info: peppy_screensaver: MPD output 1 enabled Jun 01 14:44:02 volumio volumio[1247]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Jun 01 14:44:02 volumio volumio[1247]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms Jun 01 14:44:02 volumio volumio[1247]: error: MPD error: The expression evaluated to a falsy value: Jun 01 14:44:02 volumio volumio[1247]: assert.ok(self.idling) Jun 01 14:44:02 volumio volumio[1247]: error: The expression evaluated to a falsy value: Jun 01 14:44:02 volumio volumio[1247]: assert.ok(self.idling) Jun 01 14:44:02 volumio volumio[1247]: error: MPD error: The expression evaluated to a falsy value: Jun 01 14:44:02 volumio volumio[1247]: assert.ok(self.idling) Jun 01 14:44:02 volumio volumio[1247]: error: The expression evaluated to a falsy value: Jun 01 14:44:02 volumio volumio[1247]: assert.ok(self.idling) Jun 01 14:44:02 volumio volumio[1247]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 01 14:44:02 volumio volumio[1247]: info: Reading ALSA contributions from plugins. Jun 01 14:44:02 volumio volumio[1247]: error: updateQueue error: null Jun 01 14:44:02 volumio sudo[7512]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 01 14:44:02 volumio sudo[7512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:44:02 volumio sudo[7514]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 01 14:44:02 volumio sudo[7514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:44:02 volumio volumio[1247]: info: Asound.conf file written Jun 01 14:44:02 volumio sudo[7512]: pam_unix(sudo:session): session closed for user root Jun 01 14:44:02 volumio sudo[7514]: pam_unix(sudo:session): session closed for user root Jun 01 14:44:02 volumio sudo[7521]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 01 14:44:02 volumio sudo[7521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:44:02 volumio sudo[7521]: pam_unix(sudo:session): session closed for user root Jun 01 14:44:03 volumio volumio[1247]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:6 use case configuration -2 Jun 01 14:44:03 volumio volumio[1247]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Jun 01 14:44:03 volumio volumio[1247]: info: Output device has changed, restarting MPD Jun 01 14:44:03 volumio volumio[1247]: info: Output device has changed, restarting Shairport Sync Jun 01 14:44:03 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:03 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 14:44:03 volumio sudo[7533]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 14:44:03 volumio sudo[7533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:44:03 volumio sudo[7537]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 14:44:03 volumio sudo[7537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:44:03 volumio sudo[7533]: pam_unix(sudo:session): session closed for user root Jun 01 14:44:03 volumio volumio[1247]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 14:44:03 volumio volumio[1247]: info: Done. Jun 01 14:44:03 volumio volumio[1247]: info: MPD Permissions set Jun 01 14:44:03 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 01 14:44:03 volumio volumio[1247]: info: peppyspectrum Daemon Stop Jun 01 14:44:03 volumio volumio[1247]: info: peppyspectrum Daemon Stop Jun 01 14:44:03 volumio volumio[1247]: warn: peppy_screensaver: Startup - Failed to set MPD output: Error: Command failed: mpc enable 1 Jun 01 14:44:03 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:03 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:03 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:03 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:03 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:03 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:03 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:03 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:03 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 01 14:44:03 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 01 14:44:03 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 01 14:44:03 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 01 14:44:03 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 01 14:44:03 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 01 14:44:03 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 01 14:44:03 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 14:44:03 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 01 14:44:03 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:03 volumio volumio[1247]: info: Starting Shairport Sync Jun 01 14:44:03 volumio sudo[7552]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 01 14:44:03 volumio sudo[7552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 14:44:03 volumio sudo[7552]: pam_unix(sudo:session): session closed for user root Jun 01 14:44:03 volumio sudo[7564]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 14:44:03 volumio sudo[7564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:44:03 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 01 14:44:03 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 01 14:44:03 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 14:44:03 volumio systemd[1]: shairport-sync.service: Start request repeated too quickly. Jun 01 14:44:03 volumio systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Jun 01 14:44:03 volumio systemd[1]: Failed to start shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 14:44:03 volumio sudo[7564]: pam_unix(sudo:session): session closed for user root Jun 01 14:44:03 volumio volumio[1247]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Jun 01 14:44:03 volumio volumio[1247]: Job for shairport-sync.service failed because start of the service was attempted too often. Jun 01 14:44:03 volumio volumio[1247]: See "systemctl status shairport-sync.service" and "journalctl -xeu shairport-sync.service" for details. Jun 01 14:44:03 volumio volumio[1247]: To force a start use "systemctl reset-failed shairport-sync.service" Jun 01 14:44:03 volumio volumio[1247]: followed by "systemctl start shairport-sync.service" again. Jun 01 14:44:03 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:03 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:03 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:03 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 14:44:03 volumio volumio[1247]: info: Fetched 12.3 MB in 4min 19s (47.4 kB/s) Jun 01 14:44:03 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:03.877+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:03 volumio volumio[1247]: info: Fetched 12.3 MB in 4min 19s (47.4 kB/s) Jun 01 14:44:03 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:03.913+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:04 volumio volumio[1247]: info: Selecting previously unselected package file. Jun 01 14:44:04 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:04.041+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:04 volumio volumio[1247]: info: Selecting previously unselected package file. Jun 01 14:44:04 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:04.076+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:04 volumio mpd[7573]: 2026-06-01T14:44:04 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 01 14:44:04 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jun 01 14:44:04 volumio sudo[7537]: pam_unix(sudo:session): session closed for user root Jun 01 14:44:04 volumio volumio[1247]: error: updateQueue error: null Jun 01 14:44:05 volumio volumio[1247]: [620B blob data] Jun 01 14:44:05 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:05.361+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:05 volumio volumio[1247]: [620B blob data] Jun 01 14:44:05 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:05.370+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:05 volumio volumio[1247]: info: Preparing to unpack .../00-file_1%3a5.44-3_amd64.deb ... Jun 01 14:44:05 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:05.404+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:05 volumio volumio[1247]: info: Unpacking file (1:5.44-3) ... Jun 01 14:44:05 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:05.423+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:05 volumio volumio[1247]: info: Preparing to unpack .../00-file_1%3a5.44-3_amd64.deb ... Jun 01 14:44:05 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:05.462+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:05 volumio volumio[1247]: info: Unpacking file (1:5.44-3) ... Jun 01 14:44:05 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:05.477+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:05 volumio volumio[1247]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 14:44:05 volumio volumio[1247]: info: Selecting previously unselected package mailcap. Jun 01 14:44:05 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:05.719+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:05 volumio volumio[1247]: info: Selecting previously unselected package mailcap. Jun 01 14:44:05 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:05.749+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:05 volumio volumio[1247]: info: Preparing to unpack .../01-mailcap_3.70+nmu1_all.deb ... Jun 01 14:44:05 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:05.786+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:05 volumio volumio[1247]: info: Unpacking mailcap (3.70+nmu1) ... Jun 01 14:44:05 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:05.810+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:05 volumio volumio[1247]: info: Preparing to unpack .../01-mailcap_3.70+nmu1_all.deb ... Jun 01 14:44:05 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:05.828+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:05 volumio volumio[1247]: info: Unpacking mailcap (3.70+nmu1) ... Jun 01 14:44:05 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:05.840+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:06 volumio volumio[1247]: info: Selecting previously unselected package mime-support. Jun 01 14:44:06 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:06.106+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:06 volumio volumio[1247]: info: Selecting previously unselected package mime-support. Jun 01 14:44:06 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:06.118+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:06 volumio volumio[1247]: info: Preparing to unpack .../02-mime-support_3.66_all.deb ... Jun 01 14:44:06 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:06.165+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:06 volumio volumio[1247]: info: Unpacking mime-support (3.66) ... Jun 01 14:44:06 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:06.174+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:06 volumio volumio[1247]: info: Preparing to unpack .../02-mime-support_3.66_all.deb ... Jun 01 14:44:06 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:06.197+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:06 volumio volumio[1247]: info: Unpacking mime-support (3.66) ... Jun 01 14:44:06 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:06.219+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:06 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 14:44:06 volumio volumio[1247]: info: Selecting previously unselected package fonts-freefont-ttf. Jun 01 14:44:06 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:06.420+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:06 volumio volumio[1247]: info: Selecting previously unselected package fonts-freefont-ttf. Jun 01 14:44:06 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:06.464+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:06 volumio volumio[1247]: info: Preparing to unpack .../03-fonts-freefont-ttf_20120503-10_all.deb ... Jun 01 14:44:06 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:06.557+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:06 volumio volumio[1247]: info: Unpacking fonts-freefont-ttf (20120503-10) ... Jun 01 14:44:06 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:06.578+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:06 volumio volumio[1247]: info: Preparing to unpack .../03-fonts-freefont-ttf_20120503-10_all.deb ... Jun 01 14:44:06 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:06.595+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:06 volumio volumio[1247]: info: Unpacking fonts-freefont-ttf (20120503-10) ... Jun 01 14:44:06 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:06.607+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:07 volumio ntpd[1072]: PROTO: 198.18.0.16 unlink local addr 192.168.3.13 -> Jun 01 14:44:07 volumio volumio[1247]: info: peppy_screensaver: Grace timer expired — treating as genuine stop Jun 01 14:44:07 volumio volumio[1247]: info: Selecting previously unselected package libfftw3-long3:amd64. Jun 01 14:44:07 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:07.925+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:07 volumio volumio[1247]: info: Selecting previously unselected package libfftw3-long3:amd64. Jun 01 14:44:07 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:07.943+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:07 volumio volumio[1247]: info: Preparing to unpack .../04-libfftw3-long3_3.3.10-1_amd64.deb ... Jun 01 14:44:07 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:07.970+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:07 volumio volumio[1247]: info: Preparing to unpack .../04-libfftw3-long3_3.3.10-1_amd64.deb ... Jun 01 14:44:07 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:07.991+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:08 volumio volumio[1247]: info: Unpacking libfftw3-long3:amd64 (3.3.10-1) ... Jun 01 14:44:08 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:08.034+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:08 volumio volumio[1247]: info: Unpacking libfftw3-long3:amd64 (3.3.10-1) ... Jun 01 14:44:08 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:08.049+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:08 volumio volumio[1247]: info: Selecting previously unselected package libfftw3-quad3:amd64. Jun 01 14:44:08 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:08.426+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:08 volumio volumio[1247]: info: Selecting previously unselected package libfftw3-quad3:amd64. Jun 01 14:44:08 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:08.437+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:08 volumio volumio[1247]: info: Preparing to unpack .../05-libfftw3-quad3_3.3.10-1_amd64.deb ... Jun 01 14:44:08 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:08.478+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:08 volumio volumio[1247]: info: Unpacking libfftw3-quad3:amd64 (3.3.10-1) ... Jun 01 14:44:08 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:08.496+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:08 volumio volumio[1247]: info: Preparing to unpack .../05-libfftw3-quad3_3.3.10-1_amd64.deb ... Jun 01 14:44:08 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:08.517+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:08 volumio volumio[1247]: info: Unpacking libfftw3-quad3:amd64 (3.3.10-1) ... Jun 01 14:44:08 volumio volumio5-onboarding[2210]: time=2026-06-01T14:44:08.528+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 14:44:08 volumio volumio[1247]: info: CALLMETHOD: user_interface peppyspectrum dlspectrum [object Object] Jun 01 14:44:08 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , dlspectrum Jun 01 14:44:10 volumio ntpd[1072]: PROTO: 198.18.0.14 unlink local addr 192.168.3.13 -> Jun 01 14:44:11 volumio ntpd[1072]: PROTO: 198.18.0.15 unlink local addr 192.168.3.13 -> Jun 01 14:44:19 volumio ntpd[1072]: PROTO: 198.18.0.17 unlink local addr 192.168.3.13 -> Jun 01 14:44:20 volumio volumio[1247]: --2026-06-01 14:44:08-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/Zipped-folders/Choose Jun 01 14:44:20 volumio volumio[1247]: Resolving github.com (github.com)... 198.18.0.7 Jun 01 14:44:20 volumio volumio[1247]: Connecting to github.com (github.com)|198.18.0.7|:443... connected. Jun 01 14:44:20 volumio volumio[1247]: HTTP request sent, awaiting response... 404 Not Found Jun 01 14:44:20 volumio volumio[1247]: 2026-06-01 14:44:17 ERROR 404: Not Found. Jun 01 14:44:20 volumio volumio[1247]: --2026-06-01 14:44:17-- http://a/ Jun 01 14:44:20 volumio volumio[1247]: Resolving a (a)... failed: Device or resource busy. Jun 01 14:44:20 volumio volumio[1247]: wget: unable to resolve host address ‘a’ Jun 01 14:44:20 volumio volumio[1247]: --2026-06-01 14:44:17-- http://packet.zip/ Jun 01 14:44:20 volumio volumio[1247]: Resolving packet.zip (packet.zip)... 198.18.0.25 Jun 01 14:44:20 volumio volumio[1247]: Connecting to packet.zip (packet.zip)|198.18.0.25|:80... connected. Jun 01 14:44:20 volumio volumio[1247]: HTTP request sent, awaiting response... 302 Found Jun 01 14:44:20 volumio volumio[1247]: Location: http://www.packet.zip/ [following] Jun 01 14:44:20 volumio volumio[1247]: --2026-06-01 14:44:18-- http://www.packet.zip/ Jun 01 14:44:20 volumio volumio[1247]: Resolving www.packet.zip (www.packet.zip)... 198.18.0.26 Jun 01 14:44:20 volumio volumio[1247]: Connecting to www.packet.zip (www.packet.zip)|198.18.0.26|:80... connected. Jun 01 14:44:20 volumio volumio[1247]: HTTP request sent, awaiting response... 403 Forbidden Jun 01 14:44:20 volumio volumio[1247]: 2026-06-01 14:44:20 ERROR 403: Forbidden. Jun 01 14:44:20 volumio volumio[1247]: error: PeppySpectrum --- An error occurs while downloading or installing Spectrums Jun 01 14:44:20 volumio volumio[1247]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Jun 01 14:44:20 volumio volumio[1247]: /bin/rm: cannot remove 'a': No such file or directory Jun 01 14:44:20 volumio volumio[1247]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Jun 01 14:44:20 volumio volumio[1247]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 14:44:20 volumio volumio[1247]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* Jun 01 14:44:20 volumio volumio[1247]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Jun 01 14:44:20 volumio volumio[1247]: /bin/rm: cannot remove 'a': No such file or directory Jun 01 14:44:20 volumio volumio[1247]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Jun 01 14:44:20 volumio volumio[1247]: at checkExecSyncError (node:child_process:887:11) Jun 01 14:44:20 volumio volumio[1247]: at execSync (node:child_process:959:15) Jun 01 14:44:20 volumio volumio[1247]: at /data/plugins/user_interface/peppyspectrum/index.js:770:19 Jun 01 14:44:20 volumio volumio[1247]: at new Promise () Jun 01 14:44:20 volumio volumio[1247]: at peppyspectrum.dlspectrum (/data/plugins/user_interface/peppyspectrum/index.js:745:12) Jun 01 14:44:20 volumio volumio[1247]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Jun 01 14:44:20 volumio volumio[1247]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) Jun 01 14:44:20 volumio volumio[1247]: at Socket.emit (node:events:514:28) Jun 01 14:44:20 volumio volumio[1247]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jun 01 14:44:20 volumio volumio[1247]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Jun 01 14:44:20 volumio volumio[1247]: status: 1, Jun 01 14:44:20 volumio volumio[1247]: signal: null, Jun 01 14:44:20 volumio volumio[1247]: output: [ Jun 01 14:44:20 volumio volumio[1247]: null, Jun 01 14:44:20 volumio volumio[1247]: , Jun 01 14:44:20 volumio volumio[1247]: Jun 01 14:44:20 volumio volumio[1247]: ], Jun 01 14:44:20 volumio volumio[1247]: pid: 7754, Jun 01 14:44:20 volumio volumio[1247]: stdout: , Jun 01 14:44:20 volumio volumio[1247]: stderr: Jun 01 14:44:20 volumio volumio[1247]: } Jun 01 14:44:20 volumio volumio[1247]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 14:44:20 volumio sudo[7771]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-01 14:43' Jun 01 14:44:20 volumio sudo[7771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"