Feb 18 01:53:01 volumio volumio[1388]: info: CoreCommandRouter::volumioPause Feb 18 01:53:01 volumio volumio[1388]: info: CoreStateMachine::pause Feb 18 01:53:01 volumio volumio[1388]: info: CoreStateMachine::stPlaybackTimer Feb 18 01:53:01 volumio volumio[1388]: info: CoreStateMachine::servicePause Feb 18 01:53:01 volumio volumio[1388]: info: CorePlayQueue::getTrack 1 Feb 18 01:53:01 volumio volumio[1388]: info: CoreCommandRouter::servicePause Feb 18 01:53:01 volumio volumio[1388]: info: ControllerMpd::pause Feb 18 01:53:01 volumio volumio[1388]: verbose: ControllerMpd::sendMpdCommand pause Feb 18 01:53:01 volumio volumio[1388]: info: Feb 18 01:53:01 volumio volumio[1388]: ---------------------------- MPD announces state update: player Feb 18 01:53:01 volumio volumio[1388]: info: sendMpdCommand pause took 1 milliseconds Feb 18 01:53:01 volumio volumio[1388]: info: ControllerMpd::getState Feb 18 01:53:01 volumio volumio[1388]: verbose: ControllerMpd::sendMpdCommand status Feb 18 01:53:01 volumio volumio[1388]: info: sendMpdCommand status took 0 milliseconds Feb 18 01:53:01 volumio volumio[1388]: verbose: ControllerMpd::parseState Feb 18 01:53:01 volumio volumio[1388]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 01:53:01 volumio volumio[1388]: info: Feb 18 01:53:01 volumio volumio[1388]: ---------------------------- MPD announces state update: player Feb 18 01:53:01 volumio volumio[1388]: info: ControllerMpd::getState Feb 18 01:53:01 volumio volumio[1388]: verbose: ControllerMpd::sendMpdCommand status Feb 18 01:53:01 volumio volumio[1388]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 18 01:53:01 volumio volumio[1388]: info: sendMpdCommand status took 0 milliseconds Feb 18 01:53:01 volumio volumio[1388]: verbose: ControllerMpd::parseTrackInfo Feb 18 01:53:01 volumio volumio[1388]: verbose: ControllerMpd::parseState Feb 18 01:53:01 volumio volumio[1388]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 01:53:01 volumio volumio[1388]: info: ControllerMpd::pushState Feb 18 01:53:01 volumio volumio[1388]: info: CoreCommandRouter::servicePushState Feb 18 01:53:01 volumio volumio[1388]: info: CorePlayQueue::getTrack 1 Feb 18 01:53:01 volumio volumio[1388]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":39614,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"人间烟火","artist":"程响","album":"人间烟火","uri":"NAS/WTRNAS/人间烟火-程响.mp3","trackType":"mp3"} Feb 18 01:53:01 volumio volumio[1388]: verbose: CURRENT POSITION 1 Feb 18 01:53:01 volumio volumio[1388]: info: CoreStateMachine::syncState stateService pause Feb 18 01:53:01 volumio volumio[1388]: info: CoreStateMachine::syncState currentStatus pause Feb 18 01:53:01 volumio volumio[1388]: info: CoreStateMachine::pushState Feb 18 01:53:01 volumio volumio[1388]: info: CorePlayQueue::getTrack 1 Feb 18 01:53:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 01:53:01 volumio volumio[1388]: info: CoreCommandRouter::volumioPushState Feb 18 01:53:01 volumio volumio[1388]: info: CoreStateMachine::stPlaybackTimer Feb 18 01:53:01 volumio volumio[1388]: info: ------------------------------ 12ms Feb 18 01:53:01 volumio volumio[1388]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 18 01:53:01 volumio volumio[1388]: verbose: ControllerMpd::parseTrackInfo Feb 18 01:53:01 volumio volumio[1388]: info: ControllerMpd::pushState Feb 18 01:53:01 volumio volumio[1388]: info: CoreCommandRouter::servicePushState Feb 18 01:53:01 volumio volumio[1388]: info: CorePlayQueue::getTrack 1 Feb 18 01:53:01 volumio volumio[1388]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":39614,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"人间烟火","artist":"程响","album":"人间烟火","uri":"NAS/WTRNAS/人间烟火-程响.mp3","trackType":"mp3"} Feb 18 01:53:01 volumio volumio[1388]: verbose: CURRENT POSITION 1 Feb 18 01:53:01 volumio volumio[1388]: info: CoreStateMachine::syncState stateService pause Feb 18 01:53:01 volumio volumio[1388]: info: CoreStateMachine::syncState currentStatus pause Feb 18 01:53:01 volumio volumio[1388]: info: CoreStateMachine::pushState Feb 18 01:53:01 volumio volumio[1388]: info: CorePlayQueue::getTrack 1 Feb 18 01:53:01 volumio volumio[1388]: info: CoreCommandRouter::volumioPushState Feb 18 01:53:01 volumio volumio[1388]: info: CoreStateMachine::stPlaybackTimer Feb 18 01:53:01 volumio volumio[1388]: info: ------------------------------ 20ms Feb 18 01:53:01 volumio volumio[1388]: info: peppy_screensaver: pushState - status=pause service=mpd volatile=false Feb 18 01:53:01 volumio volumio[1388]: info: peppy_screensaver: pushState - status=pause service=mpd volatile=false Feb 18 01:53:01 volumio volumio[1388]: info: PeppyMeterBasic ---peppymeterbasic status pause Feb 18 01:53:01 volumio volumio[1388]: info: PeppyMeterBasic ---peppymeterbasic status pause Feb 18 01:53:01 volumio sudo[3338]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 18 01:53:01 volumio sudo[3338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:53:01 volumio sudo[3340]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 18 01:53:01 volumio sudo[3340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:53:01 volumio volumio[1388]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 18 01:53:01 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Feb 18 01:53:01 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Feb 18 01:53:01 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Feb 18 01:53:01 volumio systemd[1]: peppymeterbasic.service: Consumed 1.410s CPU time. Feb 18 01:53:01 volumio sudo[3340]: pam_unix(sudo:session): session closed for user root Feb 18 01:53:01 volumio volumio[1388]: info: peppymeterbasic Daemon Stop Feb 18 01:53:01 volumio sudo[3338]: pam_unix(sudo:session): session closed for user root Feb 18 01:53:01 volumio volumio[1388]: info: peppymeterbasic Daemon Stop Feb 18 01:53:03 volumio volumio[1388]: info: CoreCommandRouter::volumioPrevious Feb 18 01:53:03 volumio volumio[1388]: info: CoreStateMachine::previous Feb 18 01:53:03 volumio volumio[1388]: info: CoreStateMachine::updateTrackBlock Feb 18 01:53:03 volumio volumio[1388]: info: CorePlayQueue::getTrackBlock Feb 18 01:53:03 volumio volumio[1388]: info: CoreStateMachine::serviceClearAddPlay Feb 18 01:53:03 volumio volumio[1388]: info: CoreCommandRouter::serviceClearAddPlayTracks Feb 18 01:53:03 volumio volumio[1388]: error: WARNING: No clearAddPlayTracks method for service mpd Feb 18 01:53:07 volumio volumio[1388]: info: CoreCommandRouter::volumioPrevious Feb 18 01:53:07 volumio volumio[1388]: info: CoreStateMachine::previous Feb 18 01:53:07 volumio volumio[1388]: info: CoreStateMachine::updateTrackBlock Feb 18 01:53:07 volumio volumio[1388]: info: CorePlayQueue::getTrackBlock Feb 18 01:53:07 volumio volumio[1388]: info: CoreStateMachine::serviceClearAddPlay Feb 18 01:53:07 volumio volumio[1388]: info: CoreCommandRouter::serviceClearAddPlayTracks Feb 18 01:53:07 volumio volumio[1388]: error: WARNING: No clearAddPlayTracks method for service mpd Feb 18 01:53:09 volumio volumio[1388]: info: CoreCommandRouter::volumioPlay Feb 18 01:53:09 volumio volumio[1388]: info: CoreStateMachine::play index undefined Feb 18 01:53:09 volumio volumio[1388]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 01:53:09 volumio volumio[1388]: info: CorePlayQueue::getTrack 0 Feb 18 01:53:09 volumio volumio[1388]: info: CoreStateMachine::startPlaybackTimer Feb 18 01:53:09 volumio volumio[1388]: info: CorePlayQueue::getTrack 0 Feb 18 01:53:09 volumio volumio[1388]: info: ControllerMpd::resume Feb 18 01:53:09 volumio volumio[1388]: verbose: ControllerMpd::sendMpdCommand play Feb 18 01:53:09 volumio volumio[1388]: info: Feb 18 01:53:09 volumio volumio[1388]: ---------------------------- MPD announces state update: player Feb 18 01:53:09 volumio volumio[1388]: info: sendMpdCommand play took 10 milliseconds Feb 18 01:53:09 volumio volumio[1388]: info: ControllerMpd::getState Feb 18 01:53:09 volumio volumio[1388]: verbose: ControllerMpd::sendMpdCommand status Feb 18 01:53:09 volumio volumio[1388]: info: Feb 18 01:53:09 volumio volumio[1388]: ---------------------------- MPD announces state update: player Feb 18 01:53:09 volumio volumio[1388]: info: ControllerMpd::getState Feb 18 01:53:09 volumio volumio[1388]: verbose: ControllerMpd::sendMpdCommand status Feb 18 01:53:09 volumio volumio[1388]: info: sendMpdCommand status took 1 milliseconds Feb 18 01:53:09 volumio volumio[1388]: info: sendMpdCommand status took 1 milliseconds Feb 18 01:53:09 volumio volumio[1388]: verbose: ControllerMpd::parseState Feb 18 01:53:09 volumio volumio[1388]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 01:53:09 volumio volumio[1388]: verbose: ControllerMpd::parseState Feb 18 01:53:09 volumio volumio[1388]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 01:53:09 volumio volumio[1388]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 18 01:53:09 volumio volumio[1388]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 18 01:53:09 volumio volumio[1388]: verbose: ControllerMpd::parseTrackInfo Feb 18 01:53:09 volumio volumio[1388]: verbose: ControllerMpd::parseTrackInfo Feb 18 01:53:09 volumio volumio[1388]: info: ControllerMpd::pushState Feb 18 01:53:09 volumio volumio[1388]: info: CoreCommandRouter::servicePushState Feb 18 01:53:09 volumio volumio[1388]: info: CorePlayQueue::getTrack 0 Feb 18 01:53:09 volumio volumio[1388]: verbose: STATE SERVICE {"status":"play","position":0,"seek":40348,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"人间烟火","artist":"程响","album":"人间烟火","uri":"NAS/WTRNAS/人间烟火-程响.mp3","trackType":"mp3"} Feb 18 01:53:09 volumio volumio[1388]: verbose: CURRENT POSITION 0 Feb 18 01:53:09 volumio volumio[1388]: info: CoreStateMachine::syncState stateService play Feb 18 01:53:09 volumio volumio[1388]: info: CoreStateMachine::syncState currentStatus pause Feb 18 01:53:09 volumio volumio[1388]: info: CoreStateMachine::pushState Feb 18 01:53:09 volumio volumio[1388]: info: CorePlayQueue::getTrack 0 Feb 18 01:53:09 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 01:53:09 volumio volumio[1388]: info: CoreCommandRouter::volumioPushState Feb 18 01:53:09 volumio volumio[1388]: info: ControllerMpd::pushState Feb 18 01:53:09 volumio volumio[1388]: info: CoreCommandRouter::servicePushState Feb 18 01:53:09 volumio volumio[1388]: info: CorePlayQueue::getTrack 0 Feb 18 01:53:09 volumio volumio[1388]: verbose: STATE SERVICE {"status":"play","position":0,"seek":40348,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"人间烟火","artist":"程响","album":"人间烟火","uri":"NAS/WTRNAS/人间烟火-程响.mp3","trackType":"mp3"} Feb 18 01:53:09 volumio volumio[1388]: verbose: CURRENT POSITION 0 Feb 18 01:53:09 volumio volumio[1388]: info: CoreStateMachine::syncState stateService play Feb 18 01:53:09 volumio volumio[1388]: info: CoreStateMachine::syncState currentStatus play Feb 18 01:53:09 volumio volumio[1388]: info: Received an update from plugin. extracting info from payload Feb 18 01:53:09 volumio volumio[1388]: info: CoreStateMachine::pushState Feb 18 01:53:09 volumio volumio[1388]: info: CorePlayQueue::getTrack 0 Feb 18 01:53:09 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 01:53:09 volumio volumio[1388]: info: CoreCommandRouter::volumioPushState Feb 18 01:53:09 volumio volumio[1388]: info: CoreStateMachine::pushState Feb 18 01:53:09 volumio volumio[1388]: info: CorePlayQueue::getTrack 0 Feb 18 01:53:09 volumio volumio[1388]: info: CoreCommandRouter::volumioPushState Feb 18 01:53:09 volumio volumio[1388]: info: ------------------------------ 20ms Feb 18 01:53:09 volumio volumio[1388]: info: ------------------------------ 20ms Feb 18 01:53:09 volumio volumio[1388]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Feb 18 01:53:09 volumio volumio[1388]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Feb 18 01:53:09 volumio volumio[1388]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Feb 18 01:53:09 volumio volumio[1388]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 18 01:53:09 volumio volumio[1388]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 18 01:53:09 volumio volumio[1388]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 18 01:53:09 volumio sudo[3364]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 18 01:53:09 volumio sudo[3364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:53:09 volumio sudo[3366]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 18 01:53:09 volumio sudo[3366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:53:09 volumio sudo[3369]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 18 01:53:09 volumio sudo[3369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:53:09 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Feb 18 01:53:09 volumio sudo[3366]: pam_unix(sudo:session): session closed for user root Feb 18 01:53:09 volumio sudo[3364]: pam_unix(sudo:session): session closed for user root Feb 18 01:53:09 volumio volumio[1388]: info: peppymeterbasic Daemon Started Feb 18 01:53:09 volumio volumio[1388]: info: peppymeterbasic Daemon Started Feb 18 01:53:09 volumio sudo[3369]: pam_unix(sudo:session): session closed for user root Feb 18 01:53:09 volumio volumio[1388]: info: peppymeterbasic Daemon Started Feb 18 01:53:10 volumio volumio[1388]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 18 01:53:10 volumio volumio[1388]: info: Retrying Login Due to Network Error Feb 18 01:53:10 volumio volumio[3374]: libpng warning: iCCP: known incorrect sRGB profile Feb 18 01:53:10 volumio volumio[3374]: libpng warning: iCCP: known incorrect sRGB profile Feb 18 01:53:20 volumio volumio[1388]: info: MyVolumio login type: Token Feb 18 01:53:21 volumio volumio[1388]: (node:1388) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit Feb 18 01:53:30 volumio volumio[3374]: libpng warning: iCCP: known incorrect sRGB profile Feb 18 01:53:43 volumio volumio[1388]: info: CoreCommandRouter::volumioPause Feb 18 01:53:43 volumio volumio[1388]: info: CoreStateMachine::pause Feb 18 01:53:43 volumio volumio[1388]: info: CoreStateMachine::stPlaybackTimer Feb 18 01:53:43 volumio volumio[1388]: info: CoreStateMachine::servicePause Feb 18 01:53:43 volumio volumio[1388]: info: CorePlayQueue::getTrack 0 Feb 18 01:53:43 volumio volumio[1388]: info: CoreCommandRouter::servicePause Feb 18 01:53:43 volumio volumio[1388]: info: ControllerMpd::pause Feb 18 01:53:43 volumio volumio[1388]: verbose: ControllerMpd::sendMpdCommand pause Feb 18 01:53:43 volumio volumio[1388]: info: Feb 18 01:53:43 volumio volumio[1388]: ---------------------------- MPD announces state update: player Feb 18 01:53:43 volumio volumio[1388]: info: sendMpdCommand pause took 1 milliseconds Feb 18 01:53:43 volumio volumio[1388]: info: ControllerMpd::getState Feb 18 01:53:43 volumio volumio[1388]: verbose: ControllerMpd::sendMpdCommand status Feb 18 01:53:43 volumio volumio[1388]: info: Feb 18 01:53:43 volumio volumio[1388]: ---------------------------- MPD announces state update: player Feb 18 01:53:43 volumio volumio[1388]: info: ControllerMpd::getState Feb 18 01:53:43 volumio volumio[1388]: verbose: ControllerMpd::sendMpdCommand status Feb 18 01:53:43 volumio volumio[1388]: info: sendMpdCommand status took 1 milliseconds Feb 18 01:53:43 volumio volumio[1388]: verbose: ControllerMpd::parseState Feb 18 01:53:43 volumio volumio[1388]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 01:53:43 volumio volumio[1388]: info: sendMpdCommand status took 3 milliseconds Feb 18 01:53:43 volumio volumio[1388]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 18 01:53:43 volumio volumio[1388]: verbose: ControllerMpd::parseState Feb 18 01:53:43 volumio volumio[1388]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 01:53:43 volumio volumio[1388]: verbose: ControllerMpd::parseTrackInfo Feb 18 01:53:43 volumio volumio[1388]: info: ControllerMpd::pushState Feb 18 01:53:43 volumio volumio[1388]: info: CoreCommandRouter::servicePushState Feb 18 01:53:43 volumio volumio[1388]: info: CorePlayQueue::getTrack 0 Feb 18 01:53:43 volumio volumio[1388]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":74713,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"人间烟火","artist":"程响","album":"人间烟火","uri":"NAS/WTRNAS/人间烟火-程响.mp3","trackType":"mp3"} Feb 18 01:53:43 volumio volumio[1388]: verbose: CURRENT POSITION 0 Feb 18 01:53:43 volumio volumio[1388]: info: CoreStateMachine::syncState stateService pause Feb 18 01:53:43 volumio volumio[1388]: info: CoreStateMachine::syncState currentStatus pause Feb 18 01:53:43 volumio volumio[1388]: info: CoreStateMachine::pushState Feb 18 01:53:43 volumio volumio[1388]: info: CorePlayQueue::getTrack 0 Feb 18 01:53:43 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 01:53:43 volumio volumio[1388]: info: CoreCommandRouter::volumioPushState Feb 18 01:53:43 volumio volumio[1388]: info: CoreStateMachine::stPlaybackTimer Feb 18 01:53:43 volumio volumio[1388]: info: ------------------------------ 10ms Feb 18 01:53:43 volumio volumio[1388]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 18 01:53:43 volumio volumio[1388]: verbose: ControllerMpd::parseTrackInfo Feb 18 01:53:43 volumio volumio[1388]: info: ControllerMpd::pushState Feb 18 01:53:43 volumio volumio[1388]: info: CoreCommandRouter::servicePushState Feb 18 01:53:43 volumio volumio[1388]: info: CorePlayQueue::getTrack 0 Feb 18 01:53:43 volumio volumio[1388]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":74713,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"人间烟火","artist":"程响","album":"人间烟火","uri":"NAS/WTRNAS/人间烟火-程响.mp3","trackType":"mp3"} Feb 18 01:53:43 volumio volumio[1388]: verbose: CURRENT POSITION 0 Feb 18 01:53:43 volumio volumio[1388]: info: CoreStateMachine::syncState stateService pause Feb 18 01:53:43 volumio volumio[1388]: info: CoreStateMachine::syncState currentStatus pause Feb 18 01:53:43 volumio volumio[1388]: info: CoreStateMachine::pushState Feb 18 01:53:43 volumio volumio[1388]: info: CorePlayQueue::getTrack 0 Feb 18 01:53:43 volumio volumio[1388]: info: CoreCommandRouter::volumioPushState Feb 18 01:53:43 volumio volumio[1388]: info: CoreStateMachine::stPlaybackTimer Feb 18 01:53:43 volumio volumio[1388]: info: ------------------------------ 19ms Feb 18 01:53:43 volumio volumio[1388]: info: peppy_screensaver: pushState - status=pause service=mpd volatile=false Feb 18 01:53:43 volumio volumio[1388]: info: peppy_screensaver: pushState - status=pause service=mpd volatile=false Feb 18 01:53:43 volumio volumio[1388]: info: PeppyMeterBasic ---peppymeterbasic status pause Feb 18 01:53:43 volumio volumio[1388]: info: PeppyMeterBasic ---peppymeterbasic status pause Feb 18 01:53:43 volumio sudo[3432]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 18 01:53:43 volumio sudo[3432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:53:43 volumio sudo[3433]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 18 01:53:43 volumio sudo[3433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:53:43 volumio volumio[1388]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 18 01:53:43 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Feb 18 01:53:44 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Feb 18 01:53:44 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Feb 18 01:53:44 volumio systemd[1]: peppymeterbasic.service: Consumed 1.110s CPU time. Feb 18 01:53:44 volumio sudo[3432]: pam_unix(sudo:session): session closed for user root Feb 18 01:53:44 volumio sudo[3433]: pam_unix(sudo:session): session closed for user root Feb 18 01:53:44 volumio volumio[1388]: info: peppymeterbasic Daemon Stop Feb 18 01:53:44 volumio volumio[1388]: info: peppymeterbasic Daemon Stop Feb 18 01:53:49 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 18 01:53:50 volumio volumio[1388]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 18 01:53:50 volumio volumio[1388]: info: Retrying Login Due to Network Error Feb 18 01:53:51 volumio volumio[1388]: info: CoreCommandRouter::Close All Modals sent Feb 18 01:53:57 volumio volumio[1388]: info: Stopping peppymeterbasic service Feb 18 01:53:57 volumio volumio[1388]: info: CoreStateMachine::stop Feb 18 01:53:57 volumio volumio[1388]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 01:53:57 volumio volumio[1388]: info: CoreStateMachine::updateTrackBlock Feb 18 01:53:57 volumio volumio[1388]: info: CorePlayQueue::getTrackBlock Feb 18 01:53:57 volumio volumio[1388]: info: CoreStateMachine::stPlaybackTimer Feb 18 01:53:57 volumio volumio[1388]: info: CoreStateMachine::pushState Feb 18 01:53:57 volumio volumio[1388]: info: CorePlayQueue::getTrack 0 Feb 18 01:53:57 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 01:53:57 volumio volumio[1388]: info: CoreCommandRouter::volumioPushState Feb 18 01:53:57 volumio volumio[1388]: info: CoreStateMachine::serviceStop Feb 18 01:53:57 volumio volumio[1388]: info: CorePlayQueue::getTrack 0 Feb 18 01:53:57 volumio volumio[1388]: info: CoreCommandRouter::serviceStop Feb 18 01:53:57 volumio volumio[1388]: info: ControllerMpd::stop Feb 18 01:53:57 volumio volumio[1388]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 01:53:57 volumio volumio[1388]: info: Disabling plugin peppymeterbasic Feb 18 01:53:57 volumio volumio[1388]: info: Preparing to generate the ALSA configuration file Feb 18 01:53:57 volumio volumio[1388]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Feb 18 01:53:57 volumio volumio[1388]: info: PeppyMeterBasic ---peppymeterbasic status stop Feb 18 01:53:57 volumio volumio[1388]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 18 01:53:57 volumio volumio[1388]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Feb 18 01:53:57 volumio volumio[1388]: info: Reading ALSA contributions from plugins. Feb 18 01:53:57 volumio volumio[1388]: info: Feb 18 01:53:57 volumio volumio[1388]: ---------------------------- MPD announces state update: player Feb 18 01:53:57 volumio volumio[1388]: info: sendMpdCommand stop took 23 milliseconds Feb 18 01:53:57 volumio volumio[1388]: info: ControllerMpd::getState Feb 18 01:53:57 volumio volumio[1388]: verbose: ControllerMpd::sendMpdCommand status Feb 18 01:53:57 volumio sudo[3473]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 18 01:53:57 volumio sudo[3473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:53:57 volumio volumio[1388]: info: Feb 18 01:53:57 volumio volumio[1388]: ---------------------------- MPD announces state update: player Feb 18 01:53:57 volumio volumio[1388]: info: ControllerMpd::getState Feb 18 01:53:57 volumio volumio[1388]: verbose: ControllerMpd::sendMpdCommand status Feb 18 01:53:57 volumio volumio[1388]: info: sendMpdCommand status took 7 milliseconds Feb 18 01:53:57 volumio volumio[1388]: info: sendMpdCommand status took 1 milliseconds Feb 18 01:53:57 volumio volumio[1388]: verbose: ControllerMpd::parseState Feb 18 01:53:57 volumio volumio[1388]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 01:53:57 volumio volumio[1388]: verbose: ControllerMpd::parseState Feb 18 01:53:57 volumio volumio[1388]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 01:53:57 volumio volumio[1388]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 18 01:53:57 volumio volumio[1388]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 18 01:53:57 volumio volumio[1388]: verbose: ControllerMpd::parseTrackInfo Feb 18 01:53:57 volumio volumio[1388]: verbose: ControllerMpd::parseTrackInfo Feb 18 01:53:57 volumio volumio[1388]: info: ControllerMpd::pushState Feb 18 01:53:57 volumio volumio[1388]: info: CoreCommandRouter::servicePushState Feb 18 01:53:57 volumio volumio[1388]: info: CoreStateMachine::pushState Feb 18 01:53:57 volumio volumio[1388]: info: CorePlayQueue::getTrack 0 Feb 18 01:53:57 volumio volumio[1388]: info: CoreCommandRouter::volumioPushState Feb 18 01:53:57 volumio sudo[3475]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 18 01:53:57 volumio volumio[1388]: info: CorePlayQueue::getTrack 0 Feb 18 01:53:57 volumio volumio[1388]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"人间烟火","artist":"程响","album":"人间烟火","uri":"NAS/WTRNAS/人间烟火-程响.mp3","trackType":"mp3"} Feb 18 01:53:57 volumio volumio[1388]: verbose: CURRENT POSITION 0 Feb 18 01:53:57 volumio volumio[1388]: info: CoreStateMachine::syncState stateService stop Feb 18 01:53:57 volumio sudo[3475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:53:57 volumio volumio[1388]: info: CoreStateMachine::syncState currentStatus stop Feb 18 01:53:57 volumio volumio[1388]: info: CoreStateMachine::pushState Feb 18 01:53:57 volumio volumio[1388]: info: CorePlayQueue::getTrack 0 Feb 18 01:53:57 volumio volumio[1388]: info: CoreCommandRouter::volumioPushState Feb 18 01:53:57 volumio volumio[1388]: info: No code Feb 18 01:53:57 volumio volumio[1388]: info: CoreStateMachine::pushState Feb 18 01:53:57 volumio volumio[1388]: info: CorePlayQueue::getTrack 0 Feb 18 01:53:57 volumio volumio[1388]: info: CoreCommandRouter::volumioPushState Feb 18 01:53:57 volumio volumio[1388]: info: ControllerMpd::pushState Feb 18 01:53:57 volumio volumio[1388]: info: CoreCommandRouter::servicePushState Feb 18 01:53:57 volumio volumio[1388]: info: CoreStateMachine::pushState Feb 18 01:53:57 volumio volumio[1388]: info: CorePlayQueue::getTrack 0 Feb 18 01:53:57 volumio volumio[1388]: info: CoreCommandRouter::volumioPushState Feb 18 01:53:57 volumio volumio[1388]: info: CorePlayQueue::getTrack 0 Feb 18 01:53:57 volumio volumio[1388]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"人间烟火","artist":"程响","album":"人间烟火","uri":"NAS/WTRNAS/人间烟火-程响.mp3","trackType":"mp3"} Feb 18 01:53:57 volumio volumio[1388]: verbose: CURRENT POSITION 0 Feb 18 01:53:57 volumio volumio[1388]: info: CoreStateMachine::syncState stateService stop Feb 18 01:53:57 volumio volumio[1388]: info: CoreStateMachine::syncState currentStatus stop Feb 18 01:53:57 volumio volumio[1388]: info: CoreStateMachine::pushState Feb 18 01:53:57 volumio volumio[1388]: info: CorePlayQueue::getTrack 0 Feb 18 01:53:57 volumio volumio[1388]: info: CoreCommandRouter::volumioPushState Feb 18 01:53:57 volumio volumio[1388]: info: No code Feb 18 01:53:57 volumio volumio[1388]: info: CoreStateMachine::pushState Feb 18 01:53:57 volumio volumio[1388]: info: CorePlayQueue::getTrack 0 Feb 18 01:53:57 volumio volumio[1388]: info: CoreCommandRouter::volumioPushState Feb 18 01:53:57 volumio sudo[3473]: pam_unix(sudo:session): session closed for user root Feb 18 01:53:57 volumio volumio[1388]: info: ------------------------------ 70ms Feb 18 01:53:57 volumio volumio[1388]: info: ------------------------------ 65ms Feb 18 01:53:57 volumio volumio[1388]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Feb 18 01:53:57 volumio volumio[1388]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Feb 18 01:53:57 volumio volumio[1388]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Feb 18 01:53:57 volumio volumio[1388]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Feb 18 01:53:57 volumio volumio[1388]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Feb 18 01:53:57 volumio volumio[1388]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Feb 18 01:53:57 volumio sudo[3475]: pam_unix(sudo:session): session closed for user root Feb 18 01:53:57 volumio volumio[1388]: info: peppymeterbasic Daemon Stop Feb 18 01:53:57 volumio volumio[1388]: info: Asound.conf file written Feb 18 01:53:57 volumio sudo[3480]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 18 01:53:57 volumio sudo[3480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:53:57 volumio sudo[3480]: pam_unix(sudo:session): session closed for user root Feb 18 01:53:57 volumio volumio[1388]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 18 01:53:57 volumio volumio[1388]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 18 01:53:57 volumio volumio[1388]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Feb 18 01:53:57 volumio volumio[1388]: info: Output device has changed, restarting MPD Feb 18 01:53:57 volumio volumio[1388]: info: Output device has changed, restarting Shairport Sync Feb 18 01:53:57 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:53:57 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 01:53:57 volumio sudo[3487]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 01:53:57 volumio sudo[3487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:53:57 volumio sudo[3489]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 01:53:57 volumio sudo[3489]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:53:57 volumio sudo[3487]: pam_unix(sudo:session): session closed for user root Feb 18 01:53:57 volumio volumio[1388]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 01:53:57 volumio volumio[1388]: info: Done. Feb 18 01:53:57 volumio volumio[1388]: info: MPD Permissions set Feb 18 01:53:57 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:53:57 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:53:57 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:53:57 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:53:57 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:53:57 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:53:57 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:53:57 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:53:57 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 18 01:53:57 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 01:53:57 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:53:57 volumio volumio[1388]: info: Starting Shairport Sync Feb 18 01:53:57 volumio sudo[3502]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 01:53:57 volumio sudo[3502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:53:57 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 18 01:53:57 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 18 01:53:57 volumio systemd[1]: mpd.service: Consumed 1.625s CPU time. Feb 18 01:53:57 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 18 01:53:57 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 18 01:53:57 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 18 01:53:57 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 18 01:53:57 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 18 01:53:57 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 18 01:53:57 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 18 01:53:57 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 01:53:57 volumio systemd[1]: shairport-sync.service: Consumed 1.769s CPU time. Feb 18 01:53:57 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 01:53:57 volumio sudo[3502]: pam_unix(sudo:session): session closed for user root Feb 18 01:53:57 volumio volumio[1388]: info: Shairport-Sync Started Feb 18 01:53:57 volumio sudo[3505]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 18 01:53:57 volumio sudo[3505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 18 01:53:57 volumio sudo[3505]: pam_unix(sudo:session): session closed for user root Feb 18 01:53:58 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:53:58 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:53:58 volumio mpd[3522]: 2026-02-18T01:53:58 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 18 01:53:58 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 18 01:53:58 volumio sudo[3489]: pam_unix(sudo:session): session closed for user root Feb 18 01:53:58 volumio volumio[1388]: error: updateQueue error: null Feb 18 01:54:00 volumio volumio[1388]: info: MyVolumio login type: Token Feb 18 01:54:00 volumio volumio[1388]: info: Enabling plugin peppyspectrum Feb 18 01:54:00 volumio volumio[1388]: info: Loading plugin "peppyspectrum"... Feb 18 01:54:01 volumio volumio[1388]: info: Preparing to generate the ALSA configuration file Feb 18 01:54:01 volumio volumio[1388]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 18 01:54:01 volumio volumio[1388]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Feb 18 01:54:01 volumio volumio[1388]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 18 01:54:01 volumio volumio[1388]: info: Reading ALSA contributions from plugins. Feb 18 01:54:01 volumio volumio[1388]: info: Asound.conf file written Feb 18 01:54:01 volumio sudo[3530]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 18 01:54:01 volumio sudo[3530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:54:01 volumio sudo[3530]: pam_unix(sudo:session): session closed for user root Feb 18 01:54:01 volumio volumio[1388]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 18 01:54:01 volumio volumio[1388]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 18 01:54:01 volumio volumio[1388]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Feb 18 01:54:01 volumio volumio[1388]: info: Output device has changed, restarting MPD Feb 18 01:54:01 volumio volumio[1388]: info: Output device has changed, restarting Shairport Sync Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 01:54:01 volumio sudo[3538]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 01:54:01 volumio sudo[3538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:54:01 volumio sudo[3538]: pam_unix(sudo:session): session closed for user root Feb 18 01:54:01 volumio sudo[3541]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 01:54:01 volumio sudo[3541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:54:01 volumio volumio[1388]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 01:54:01 volumio volumio[1388]: info: PLUGIN START: peppyspectrum Feb 18 01:54:01 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 18 01:54:01 volumio volumio[1388]: info: Preparing to generate the ALSA configuration file Feb 18 01:54:01 volumio volumio[1388]: info: Done. Feb 18 01:54:01 volumio sudo[3548]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-aloop index=7 pcm_substreams=1 Feb 18 01:54:01 volumio sudo[3548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:54:01 volumio volumio[1388]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 18 01:54:01 volumio volumio[1388]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 18 01:54:01 volumio volumio[1388]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Feb 18 01:54:01 volumio volumio[1388]: info: Reading ALSA contributions from plugins. Feb 18 01:54:01 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 18 01:54:01 volumio volumio[1388]: info: MPD Permissions set Feb 18 01:54:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 18 01:54:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 18 01:54:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 18 01:54:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 18 01:54:01 volumio volumio[1388]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:01 volumio kernel: snd_aloop snd_aloop.0: cannot find the slot for index 7 (range 0-7), error: -16 Feb 18 01:54:01 volumio kernel: snd_aloop snd_aloop.0: probe with driver snd_aloop failed with error -16 Feb 18 01:54:01 volumio kernel: aloop: No loopback enabled Feb 18 01:54:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 18 01:54:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:01 volumio volumio[1388]: info: Starting Shairport Sync Feb 18 01:54:01 volumio volumio[1388]: info: Asound.conf file unchanged, so no further update is needed Feb 18 01:54:01 volumio volumio[1388]: info: Output device has changed, restarting MPD Feb 18 01:54:01 volumio sudo[3560]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 01:54:01 volumio sudo[3560]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:54:01 volumio volumio[1388]: info: Output device has changed, restarting Shairport Sync Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 01:54:01 volumio sudo[3564]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 01:54:01 volumio sudo[3564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:54:01 volumio sudo[3564]: pam_unix(sudo:session): session closed for user root Feb 18 01:54:01 volumio sudo[3548]: pam_unix(sudo:session): session closed for user root Feb 18 01:54:01 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 18 01:54:01 volumio sudo[3565]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 01:54:01 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 18 01:54:01 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 01:54:01 volumio systemd[1]: shairport-sync.service: Consumed 1.534s CPU time. Feb 18 01:54:01 volumio sudo[3565]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:54:01 volumio volumio[1388]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 01:54:01 volumio volumio[1388]: info: MPD Permissions set Feb 18 01:54:01 volumio volumio[1388]: error: failed to load snd_dummy: Error: Command failed: /usr/bin/sudo /sbin/modprobe snd-aloop index=7 pcm_substreams=1 Feb 18 01:54:01 volumio volumio[1388]: modprobe: ERROR: could not insert 'snd_aloop': No such device Feb 18 01:54:01 volumio sudo[3556]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 18 01:54:01 volumio sudo[3556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:01 volumio sudo[3556]: pam_unix(sudo:session): session closed for user root Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:01 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:01 volumio volumio[1388]: info: Starting Shairport Sync Feb 18 01:54:01 volumio sudo[3560]: pam_unix(sudo:session): session closed for user root Feb 18 01:54:01 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 18 01:54:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 18 01:54:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 18 01:54:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 18 01:54:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 18 01:54:01 volumio sudo[3584]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 01:54:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 18 01:54:01 volumio volumio[1388]: info: Shairport-Sync Started Feb 18 01:54:01 volumio sudo[3584]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:54:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 18 01:54:01 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 18 01:54:01 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 18 01:54:01 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 01:54:01 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 01:54:01 volumio sudo[3584]: pam_unix(sudo:session): session closed for user root Feb 18 01:54:01 volumio volumio[1388]: info: Shairport-Sync Started Feb 18 01:54:01 volumio sudo[3599]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 18 01:54:01 volumio sudo[3599]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 18 01:54:01 volumio sudo[3599]: pam_unix(sudo:session): session closed for user root Feb 18 01:54:01 volumio sudo[3620]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 18 01:54:01 volumio sudo[3620]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:54:01 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Feb 18 01:54:01 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Feb 18 01:54:01 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:01 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 01:54:02 volumio volumio[1388]: (node:1388) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit Feb 18 01:54:02 volumio mpd[3618]: 2026-02-18T01:54:02 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 18 01:54:02 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 18 01:54:02 volumio sudo[3565]: pam_unix(sudo:session): session closed for user root Feb 18 01:54:02 volumio sudo[3541]: pam_unix(sudo:session): session closed for user root Feb 18 01:54:02 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 18 01:54:02 volumio mpd_monitor.sh[3624]: MPD Monitor Service: Starting MPD Monitor Service Feb 18 01:54:02 volumio volumio[1388]: error: MPD error: The expression evaluated to a falsy value: Feb 18 01:54:02 volumio volumio[1388]: assert.ok(self.idling) Feb 18 01:54:02 volumio volumio[1388]: error: The expression evaluated to a falsy value: Feb 18 01:54:02 volumio volumio[1388]: assert.ok(self.idling) Feb 18 01:54:02 volumio volumio[1388]: error: updateQueue error: null Feb 18 01:54:02 volumio sudo[3620]: pam_unix(sudo:session): session closed for user root Feb 18 01:54:02 volumio volumio[1388]: info: Successfully started MPD Monitor Feb 18 01:54:03 volumio volumio[1388]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 18 01:54:05 volumio sudo[3633]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 18 01:54:05 volumio sudo[3633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:54:05 volumio sudo[3636]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 18 01:54:05 volumio sudo[3636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:54:05 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Feb 18 01:54:05 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Feb 18 01:54:05 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Feb 18 01:54:05 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 18 01:54:05 volumio sudo[3633]: pam_unix(sudo:session): session closed for user root Feb 18 01:54:05 volumio mpd_monitor.sh[3638]: MPD Monitor Service: Starting MPD Monitor Service Feb 18 01:54:05 volumio volumio[1388]: info: Successfully started MPD Monitor Feb 18 01:54:05 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Feb 18 01:54:05 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Feb 18 01:54:05 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Feb 18 01:54:05 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 18 01:54:05 volumio mpd_monitor.sh[3642]: MPD Monitor Service: Starting MPD Monitor Service Feb 18 01:54:05 volumio sudo[3636]: pam_unix(sudo:session): session closed for user root Feb 18 01:54:05 volumio volumio[1388]: info: Successfully started MPD Monitor Feb 18 01:54:14 volumio volumio[1388]: info: CALLMETHOD: user_interface peppyspectrum savepeppy [object Object] Feb 18 01:54:14 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy Feb 18 01:54:14 volumio volumio[1388]: aaaaaaaaaaa 1280,400 Feb 18 01:54:14 volumio volumio[1388]: info: PeppySpectrum ---1280400 Feb 18 01:54:14 volumio volumio[1388]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Feb 18 01:54:15 volumio volumio[1388]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 18 01:54:15 volumio volumio[1388]: info: CoreCommandRouter::Close All Modals sent Feb 18 01:54:24 volumio volumio[1388]: info: CALLMETHOD: user_interface peppyspectrum savepeppy1 [object Object] Feb 18 01:54:24 volumio volumio[1388]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy1 Feb 18 01:54:24 volumio volumio[1388]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Feb 18 01:54:24 volumio volumio[1388]: info: PeppySpectrum -----------------------spectrums.6 Feb 18 01:54:24 volumio volumio[1388]: info: PeppySpectrum -----------------------$basefolder Feb 18 01:54:24 volumio volumio[1388]: info: PeppySpectrum -----------------------screensize1280x400 Feb 18 01:54:24 volumio volumio[1388]: info: PeppySpectrum -----------------------screenwidth Feb 18 01:54:24 volumio volumio[1388]: info: PeppySpectrum -----------------------screenheight Feb 18 01:54:24 volumio volumio[1388]: info: PeppySpectrum -----------------------spectrumsize30 Feb 18 01:54:24 volumio volumio[1388]: error: PeppySpectrum ---Error writing config null Feb 18 01:54:24 volumio sudo[3680]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart peppyspectrum.service Feb 18 01:54:24 volumio sudo[3680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 01:54:24 volumio systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Feb 18 01:54:24 volumio sudo[3680]: pam_unix(sudo:session): session closed for user root Feb 18 01:54:24 volumio volumio[1388]: info: peppyspectrum Daemon Started Feb 18 01:54:24 volumio volumio[1388]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 18 01:54:24 volumio volumio[1388]: info: CoreCommandRouter::Close All Modals sent Feb 18 01:54:25 volumio startpeppyspectrum.sh[3683]: libpng warning: iCCP: known incorrect sRGB profile Feb 18 01:54:30 volumio volumio[1388]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 18 01:54:30 volumio volumio[1388]: info: Retrying Login Due to Network Error Feb 18 01:54:37 volumio volumio[1388]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 01:54:37 volumio volumio[1388]: Error: connect ETIMEDOUT 103.97.176.73:443 Feb 18 01:54:37 volumio volumio[1388]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 18 01:54:37 volumio volumio[1388]: errno: -110, Feb 18 01:54:37 volumio volumio[1388]: code: 'ETIMEDOUT', Feb 18 01:54:37 volumio volumio[1388]: syscall: 'connect', Feb 18 01:54:37 volumio volumio[1388]: address: '103.97.176.73', Feb 18 01:54:37 volumio volumio[1388]: port: 443 Feb 18 01:54:37 volumio volumio[1388]: } Feb 18 01:54:37 volumio volumio[1388]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 01:54:37 volumio sudo[3741]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-18 01:53' Feb 18 01:54:37 volumio sudo[3741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"