-- Logs begin at Wed 2025-05-07 17:11:40 CEST, end at Wed 2025-05-07 17:17:56 CEST. -- May 07 17:16:07 volumio.2 volumio[1040]: info: May 07 17:16:07 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:07 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:07 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:07 volumio.2 volumio[1040]: info: May 07 17:16:07 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:07 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:07 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:07 volumio.2 volumio[1040]: info: May 07 17:16:07 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:07 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:07 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:07 volumio.2 volumio[1040]: info: sendMpdCommand status took 7 milliseconds May 07 17:16:07 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:07 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:07 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:07 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:07 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 5 May 07 17:16:07 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:16:07 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:07 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 5 May 07 17:16:07 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 07 17:16:07 volumio.2 volumio[1040]: verbose: CURRENT POSITION 5 May 07 17:16:07 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService stop May 07 17:16:07 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:16:07 volumio.2 volumio[1040]: info: CoreStateMachine::play index undefined May 07 17:16:07 volumio.2 volumio[1040]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 17:16:07 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:07 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:07 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:16:07 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:07 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:07 volumio.2 volumio[1040]: info: CoreStateMachine::startPlaybackTimer May 07 17:16:07 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:07 volumio.2 volumio[1040]: verbose: ControllerMpd::clearAddPlayTracks USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/07. Mad Over You.flac May 07 17:16:07 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand stop May 07 17:16:07 volumio.2 volumio[1040]: info: ------------------------------ 68ms May 07 17:16:07 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:07 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:07 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:16:07 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:07 volumio.2 volumio[1040]: info: sendMpdCommand status took 100 milliseconds May 07 17:16:07 volumio.2 volumio[1040]: info: sendMpdCommand status took 99 milliseconds May 07 17:16:07 volumio.2 volumio[1040]: info: sendMpdCommand stop took 40 milliseconds May 07 17:16:07 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:07 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:07 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand clear May 07 17:16:07 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:07 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:07 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:07 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:07 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:16:07 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:07 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:07 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 07 17:16:07 volumio.2 volumio[1040]: verbose: CURRENT POSITION 6 May 07 17:16:07 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService stop May 07 17:16:07 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus stop May 07 17:16:07 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:07 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:07 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:07 volumio.2 volumio[1040]: info: No code May 07 17:16:07 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:07 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:07 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:07 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:07 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:07 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:07 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:07 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:07 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:07 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 07 17:16:07 volumio.2 volumio[1040]: verbose: CURRENT POSITION 6 May 07 17:16:07 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService stop May 07 17:16:07 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus stop May 07 17:16:07 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:07 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:07 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:07 volumio.2 volumio[1040]: info: No code May 07 17:16:07 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:07 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:07 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:07 volumio.2 volumio[1040]: info: ------------------------------ 294ms May 07 17:16:07 volumio.2 volumio[1040]: info: ------------------------------ 292ms May 07 17:16:07 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:07 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:07 volumio.2 sudo[3355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:07 volumio.2 sudo[3355]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:07 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:07 volumio.2 sudo[3358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:07 volumio.2 sudo[3358]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:07 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:07 volumio.2 sudo[3361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:07 volumio.2 sudo[3361]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:07 volumio.2 systemd[1]: Started peppymeterbasic Daemon. May 07 17:16:07 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:07 volumio.2 sudo[3365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:07 volumio.2 sudo[3365]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:07 volumio.2 sudo[3355]: pam_unix(sudo:session): session closed for user root May 07 17:16:07 volumio.2 systemd[1]: Stopping peppymeterbasic Daemon... May 07 17:16:07 volumio.2 systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM May 07 17:16:07 volumio.2 systemd[1]: peppymeterbasic.service: Succeeded. May 07 17:16:07 volumio.2 systemd[1]: Stopped peppymeterbasic Daemon. May 07 17:16:07 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:07 volumio.2 sudo[3358]: pam_unix(sudo:session): session closed for user root May 07 17:16:08 volumio.2 sudo[3361]: pam_unix(sudo:session): session closed for user root May 07 17:16:08 volumio.2 sudo[3365]: pam_unix(sudo:session): session closed for user root May 07 17:16:08 volumio.2 sudo[3369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:08 volumio.2 sudo[3369]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:08 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:08 volumio.2 sudo[3372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:08 volumio.2 sudo[3372]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:08 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:08 volumio.2 sudo[3375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:08 volumio.2 sudo[3375]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:08 volumio.2 sudo[3369]: pam_unix(sudo:session): session closed for user root May 07 17:16:08 volumio.2 sudo[3372]: pam_unix(sudo:session): session closed for user root May 07 17:16:08 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:08 volumio.2 sudo[3378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:08 volumio.2 sudo[3378]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:08 volumio.2 sudo[3375]: pam_unix(sudo:session): session closed for user root May 07 17:16:08 volumio.2 volumio[1040]: info: May 07 17:16:08 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:16:08 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:16:08 volumio.2 volumio[1040]: info: sendMpdCommand clear took 900 milliseconds May 07 17:16:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand add "USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/07. Mad Over You.flac" May 07 17:16:08 volumio.2 volumio[1040]: info: May 07 17:16:08 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:16:08 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:16:08 volumio.2 volumio[1040]: info: May 07 17:16:08 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:16:08 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:16:08 volumio.2 sudo[3381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:08 volumio.2 sudo[3381]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:08 volumio.2 sudo[3378]: pam_unix(sudo:session): session closed for user root May 07 17:16:08 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:16:08 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:16:08 volumio.2 sudo[3381]: pam_unix(sudo:session): session closed for user root May 07 17:16:08 volumio.2 volumio[1040]: error: updateQueue error: null May 07 17:16:08 volumio.2 volumio[1040]: info: May 07 17:16:08 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:16:08 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:16:08 volumio.2 volumio[1040]: info: ------------------------------ 131ms May 07 17:16:08 volumio.2 volumio[1040]: info: sendMpdCommand add "USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/07. Mad Over You.flac" took 132 milliseconds May 07 17:16:08 volumio.2 volumio[1040]: info: ------------------------------ 133ms May 07 17:16:08 volumio.2 volumio[1040]: info: ------------------------------ 132ms May 07 17:16:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand play May 07 17:16:08 volumio.2 volumio[1040]: info: May 07 17:16:08 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:16:08 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:16:08 volumio.2 volumio[1040]: info: May 07 17:16:08 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:16:08 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:16:08 volumio.2 volumio[1040]: info: touch_display: Setting screensaver timeout to 120 seconds. May 07 17:16:08 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:16:08 volumio.2 volumio[1040]: info: May 07 17:16:08 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:08 volumio.2 volumio[1040]: info: ------------------------------ 53ms May 07 17:16:08 volumio.2 volumio[1040]: info: sendMpdCommand play took 45 milliseconds May 07 17:16:08 volumio.2 volumio[1040]: info: ------------------------------ 44ms May 07 17:16:08 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:08 volumio.2 volumio[1040]: info: ------------------------------ 24ms May 07 17:16:08 volumio.2 volumio[1040]: info: May 07 17:16:08 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:08 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:08 volumio.2 volumio[1040]: info: May 07 17:16:08 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:08 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:08 volumio.2 volumio[1040]: info: May 07 17:16:08 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:08 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:08 volumio.2 volumio[1040]: info: sendMpdCommand status took 14 milliseconds May 07 17:16:08 volumio.2 volumio[1040]: info: sendMpdCommand status took 13 milliseconds May 07 17:16:08 volumio.2 volumio[1040]: info: sendMpdCommand status took 13 milliseconds May 07 17:16:08 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:16:08 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:16:08 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:16:08 volumio.2 volumio[1040]: info: May 07 17:16:08 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:08 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:08 volumio.2 volumio[1040]: info: May 07 17:16:08 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:08 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:08 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 07 17:16:08 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate from file: 44100 May 07 17:16:08 volumio.2 volumio[1040]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 07 17:16:08 volumio.2 volumio[1040]: info: sendMpdCommand status took 20 milliseconds May 07 17:16:08 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 17 milliseconds May 07 17:16:08 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 17 milliseconds May 07 17:16:08 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 17 milliseconds May 07 17:16:08 volumio.2 volumio[1040]: info: sendMpdCommand status took 15 milliseconds May 07 17:16:08 volumio.2 volumio[1040]: info: sendMpdCommand status took 14 milliseconds May 07 17:16:08 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:16:08 volumio.2 volumio[1040]: verbose: ControllerMpd::parseTrackInfo May 07 17:16:08 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:16:08 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:16:08 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:08 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:08 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"725 Kbps","isStreaming":false,"title":"Mad Over You","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/07. Mad Over You.flac","trackType":"flac"} May 07 17:16:08 volumio.2 volumio[1040]: verbose: CURRENT POSITION 6 May 07 17:16:08 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:16:08 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus stop May 07 17:16:08 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:08 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:08 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"682 Kbps","isStreaming":false,"title":"Mad Over You","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/07. Mad Over You.flac","trackType":"flac"} May 07 17:16:08 volumio.2 volumio[1040]: verbose: CURRENT POSITION 6 May 07 17:16:08 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:16:08 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:16:08 volumio.2 volumio[1040]: info: Received an update from plugin. extracting info from payload May 07 17:16:08 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:08 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:16:08 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:08 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:08 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:08 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:08 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:08 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"669 Kbps","isStreaming":false,"title":"Mad Over You","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/07. Mad Over You.flac","trackType":"flac"} May 07 17:16:08 volumio.2 volumio[1040]: verbose: CURRENT POSITION 6 May 07 17:16:08 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:16:08 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:16:08 volumio.2 volumio[1040]: info: Received an update from plugin. extracting info from payload May 07 17:16:08 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:08 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:08 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:08 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:08 volumio.2 volumio[1040]: info: ------------------------------ 83ms May 07 17:16:08 volumio.2 volumio[1040]: info: ------------------------------ 172ms May 07 17:16:08 volumio.2 volumio[1040]: info: ------------------------------ 172ms May 07 17:16:08 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 141 milliseconds May 07 17:16:08 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 140 milliseconds May 07 17:16:08 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 140 milliseconds May 07 17:16:08 volumio.2 volumio[1040]: verbose: ControllerMpd::parseTrackInfo May 07 17:16:08 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:08 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:08 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"669 Kbps","isStreaming":false,"title":"Mad Over You","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/07. Mad Over You.flac","trackType":"flac"} May 07 17:16:08 volumio.2 volumio[1040]: verbose: CURRENT POSITION 6 May 07 17:16:08 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:16:08 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:16:08 volumio.2 volumio[1040]: info: Received an update from plugin. extracting info from payload May 07 17:16:08 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:08 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:08 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:08 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:08 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:08 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:08 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"669 Kbps","isStreaming":false,"title":"Mad Over You","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/07. Mad Over You.flac","trackType":"flac"} May 07 17:16:08 volumio.2 volumio[1040]: verbose: CURRENT POSITION 6 May 07 17:16:08 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:16:08 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:16:08 volumio.2 volumio[1040]: info: Received an update from plugin. extracting info from payload May 07 17:16:08 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:08 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:08 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:08 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:08 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:08 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:08 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"669 Kbps","isStreaming":false,"title":"Mad Over You","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/07. Mad Over You.flac","trackType":"flac"} May 07 17:16:08 volumio.2 volumio[1040]: verbose: CURRENT POSITION 6 May 07 17:16:08 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:16:08 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:16:08 volumio.2 volumio[1040]: info: Received an update from plugin. extracting info from payload May 07 17:16:08 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:08 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:08 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:08 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:08 volumio.2 volumio[1040]: info: ------------------------------ 354ms May 07 17:16:08 volumio.2 volumio[1040]: info: ------------------------------ 347ms May 07 17:16:08 volumio.2 volumio[1040]: info: ------------------------------ 346ms May 07 17:16:08 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:08 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:08 volumio.2 sudo[3400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:08 volumio.2 sudo[3400]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:08 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:09 volumio.2 sudo[3403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:09 volumio.2 sudo[3403]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:09 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:09 volumio.2 sudo[3406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:09 volumio.2 sudo[3406]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:09 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:09 volumio.2 sudo[3410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:09 volumio.2 sudo[3410]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:09 volumio.2 systemd[1]: Started peppymeterbasic Daemon. May 07 17:16:09 volumio.2 sudo[3400]: pam_unix(sudo:session): session closed for user root May 07 17:16:09 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:09 volumio.2 sudo[3406]: pam_unix(sudo:session): session closed for user root May 07 17:16:09 volumio.2 sudo[3414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:09 volumio.2 sudo[3403]: pam_unix(sudo:session): session closed for user root May 07 17:16:09 volumio.2 sudo[3414]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:09 volumio.2 sudo[3410]: pam_unix(sudo:session): session closed for user root May 07 17:16:09 volumio.2 sudo[3414]: pam_unix(sudo:session): session closed for user root May 07 17:16:09 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:09 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:09 volumio.2 sudo[3417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:09 volumio.2 sudo[3417]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:09 volumio.2 sudo[3417]: pam_unix(sudo:session): session closed for user root May 07 17:16:09 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:09 volumio.2 sudo[3425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:09 volumio.2 sudo[3425]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:09 volumio.2 sudo[3419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:09 volumio.2 sudo[3419]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:09 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:09 volumio.2 sudo[3425]: pam_unix(sudo:session): session closed for user root May 07 17:16:09 volumio.2 sudo[3419]: pam_unix(sudo:session): session closed for user root May 07 17:16:09 volumio.2 sudo[3429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:09 volumio.2 sudo[3429]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:09 volumio.2 sudo[3429]: pam_unix(sudo:session): session closed for user root May 07 17:16:10 volumio.2 sudo[3432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:10 volumio.2 sudo[3432]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:10 volumio.2 sudo[3432]: pam_unix(sudo:session): session closed for user root May 07 17:16:10 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:16:10 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:16:10 volumio.2 volumio[1040]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 07 17:16:10 volumio.2 volumio[1040]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 May 07 17:16:10 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:16:10 volumio.2 volumio[1040]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 May 07 17:16:11 volumio.2 volumio[1040]: info: Received Get System Info May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 17:16:11 volumio.2 volumio[1040]: info: Discovery: Getting this device information May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioGetState May 07 17:16:11 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioGetState May 07 17:16:11 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioGetVisibleSources May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioGetState May 07 17:16:11 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioGetQueue May 07 17:16:11 volumio.2 volumio[1040]: info: CoreStateMachine::getQueue May 07 17:16:11 volumio.2 volumio[1040]: info: CorePlayQueue::getQueue May 07 17:16:11 volumio.2 volumio[1040]: info: Listing playlists May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 07 17:16:11 volumio.2 volumio[1040]: info: Received Get System Info May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 17:16:11 volumio.2 volumio[1040]: info: Discovery: Getting this device information May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioGetState May 07 17:16:11 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioGetState May 07 17:16:11 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioGetVisibleSources May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioGetState May 07 17:16:11 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioGetQueue May 07 17:16:11 volumio.2 volumio[1040]: info: CoreStateMachine::getQueue May 07 17:16:11 volumio.2 volumio[1040]: info: CorePlayQueue::getQueue May 07 17:16:11 volumio.2 volumio[1040]: info: Listing playlists May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 07 17:16:11 volumio.2 volumio[1040]: info: Received Get System Info May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 17:16:11 volumio.2 volumio[1040]: info: Discovery: Getting this device information May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioGetState May 07 17:16:11 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioGetState May 07 17:16:11 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 17:16:11 volumio.2 volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 17:16:11 volumio.2 volumio[1040]: info: FusionDsp - May 07 17:16:11 volumio.2 volumio[1040]: info: FusionDsp - May 07 17:16:12 volumio.2 volumio[3408]: libpng warning: iCCP: known incorrect sRGB profile May 07 17:16:12 volumio.2 volumio[1040]: info: FusionDsp - May 07 17:16:12 volumio.2 volumio[3408]: libpng warning: iCCP: known incorrect sRGB profile May 07 17:16:12 volumio.2 volumio[1040]: info: FusionDsp - May 07 17:16:13 volumio.2 volumio[3408]: libpng warning: iCCP: known incorrect sRGB profile May 07 17:16:13 volumio.2 volumio[1040]: info: FusionDsp - May 07 17:16:13 volumio.2 volumio[1040]: info: FusionDsp - May 07 17:16:13 volumio.2 volumio[1040]: info: FusionDsp - May 07 17:16:13 volumio.2 volumio[1040]: info: FusionDsp - May 07 17:16:14 volumio.2 volumio[1040]: info: FusionDsp - May 07 17:16:14 volumio.2 volumio[1040]: info: FusionDsp - undefined May 07 17:16:14 volumio.2 volumio[1040]: info: FusionDsp - undefined May 07 17:16:14 volumio.2 volumio[1040]: info: FusionDsp - undefined May 07 17:16:14 volumio.2 volumio[1040]: info: FusionDsp - undefined May 07 17:16:14 volumio.2 volumio[1040]: info: FusionDsp - undefined May 07 17:16:14 volumio.2 volumio[1040]: info: FusionDsp - undefined May 07 17:16:14 volumio.2 volumio[1040]: info: FusionDsp - undefined May 07 17:16:14 volumio.2 volumio[1040]: info: FusionDsp - undefined May 07 17:16:15 volumio.2 volumio[1040]: info: FusionDsp - undefined May 07 17:16:19 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 07 17:16:21 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 17:16:21 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 17:16:21 volumio.2 volumio[1040]: info: Discovery: Getting this device information May 07 17:16:21 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioGetState May 07 17:16:21 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:21 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 17:16:27 volumio.2 volumio[1040]: info: May 07 17:16:27 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:27 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:27 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:27 volumio.2 volumio[1040]: info: May 07 17:16:27 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:27 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:27 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:27 volumio.2 volumio[1040]: info: May 07 17:16:27 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:27 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:27 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:27 volumio.2 volumio[1040]: info: sendMpdCommand status took 12 milliseconds May 07 17:16:27 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:27 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:27 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:27 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:27 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:27 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:16:27 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:27 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 6 May 07 17:16:27 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 07 17:16:27 volumio.2 volumio[1040]: verbose: CURRENT POSITION 6 May 07 17:16:27 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService stop May 07 17:16:27 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:16:27 volumio.2 volumio[1040]: info: CoreStateMachine::play index undefined May 07 17:16:27 volumio.2 volumio[1040]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 17:16:27 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:27 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:27 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:16:27 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:27 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:27 volumio.2 volumio[1040]: info: CoreStateMachine::startPlaybackTimer May 07 17:16:27 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:27 volumio.2 volumio[1040]: verbose: ControllerMpd::clearAddPlayTracks USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/08. Why Can't We Take A Chance.flac May 07 17:16:27 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand stop May 07 17:16:27 volumio.2 volumio[1040]: info: ------------------------------ 76ms May 07 17:16:27 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:27 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:27 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:16:27 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:27 volumio.2 volumio[1040]: info: sendMpdCommand status took 133 milliseconds May 07 17:16:27 volumio.2 volumio[1040]: info: sendMpdCommand status took 125 milliseconds May 07 17:16:27 volumio.2 volumio[1040]: info: sendMpdCommand stop took 62 milliseconds May 07 17:16:27 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:27 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:27 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand clear May 07 17:16:27 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:27 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:27 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:27 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:27 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:16:27 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:27 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:28 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 07 17:16:28 volumio.2 volumio[1040]: verbose: CURRENT POSITION 7 May 07 17:16:28 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService stop May 07 17:16:28 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus stop May 07 17:16:28 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:28 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:28 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:28 volumio.2 volumio[1040]: info: No code May 07 17:16:28 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:28 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:28 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:28 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:28 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:28 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:28 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:28 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:28 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:28 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 07 17:16:28 volumio.2 volumio[1040]: verbose: CURRENT POSITION 7 May 07 17:16:28 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService stop May 07 17:16:28 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus stop May 07 17:16:28 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:28 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:28 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:28 volumio.2 volumio[1040]: info: No code May 07 17:16:28 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:28 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:28 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:28 volumio.2 volumio[1040]: info: ------------------------------ 312ms May 07 17:16:28 volumio.2 volumio[1040]: info: ------------------------------ 306ms May 07 17:16:28 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:28 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:28 volumio.2 sudo[3584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:28 volumio.2 sudo[3584]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:28 volumio.2 sudo[3584]: pam_unix(sudo:session): session closed for user root May 07 17:16:28 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:28 volumio.2 sudo[3587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:28 volumio.2 sudo[3587]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:28 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:28 volumio.2 systemd[1]: Stopping peppymeterbasic Daemon... May 07 17:16:28 volumio.2 systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM May 07 17:16:28 volumio.2 sudo[3590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:28 volumio.2 sudo[3590]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:28 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:28 volumio.2 systemd[1]: peppymeterbasic.service: Succeeded. May 07 17:16:28 volumio.2 sudo[3593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:28 volumio.2 systemd[1]: Stopped peppymeterbasic Daemon. May 07 17:16:28 volumio.2 sudo[3593]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:28 volumio.2 sudo[3590]: pam_unix(sudo:session): session closed for user root May 07 17:16:28 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:28 volumio.2 sudo[3596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:28 volumio.2 sudo[3587]: pam_unix(sudo:session): session closed for user root May 07 17:16:28 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:28 volumio.2 sudo[3596]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:28 volumio.2 sudo[3598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:28 volumio.2 sudo[3598]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:28 volumio.2 sudo[3593]: pam_unix(sudo:session): session closed for user root May 07 17:16:28 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:28 volumio.2 sudo[3602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:28 volumio.2 sudo[3602]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:28 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:28 volumio.2 sudo[3596]: pam_unix(sudo:session): session closed for user root May 07 17:16:28 volumio.2 sudo[3598]: pam_unix(sudo:session): session closed for user root May 07 17:16:28 volumio.2 sudo[3605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:28 volumio.2 sudo[3605]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:28 volumio.2 volumio[1040]: info: May 07 17:16:28 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:16:28 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:16:28 volumio.2 volumio[1040]: info: sendMpdCommand clear took 802 milliseconds May 07 17:16:28 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand add "USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/08. Why Can't We Take A Chance.flac" May 07 17:16:28 volumio.2 volumio[1040]: info: May 07 17:16:28 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:16:28 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:16:28 volumio.2 volumio[1040]: info: May 07 17:16:28 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:16:28 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:16:28 volumio.2 sudo[3607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:28 volumio.2 sudo[3607]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:28 volumio.2 sudo[3602]: pam_unix(sudo:session): session closed for user root May 07 17:16:28 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:16:28 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:16:28 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:16:28 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:16:28 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:16:28 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:16:28 volumio.2 sudo[3605]: pam_unix(sudo:session): session closed for user root May 07 17:16:28 volumio.2 sudo[3607]: pam_unix(sudo:session): session closed for user root May 07 17:16:28 volumio.2 volumio[1040]: error: updateQueue error: null May 07 17:16:28 volumio.2 volumio[1040]: info: May 07 17:16:28 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:16:28 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:16:28 volumio.2 volumio[1040]: info: ------------------------------ 129ms May 07 17:16:28 volumio.2 volumio[1040]: info: sendMpdCommand add "USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/08. Why Can't We Take A Chance.flac" took 128 milliseconds May 07 17:16:28 volumio.2 volumio[1040]: info: ------------------------------ 127ms May 07 17:16:28 volumio.2 volumio[1040]: info: ------------------------------ 125ms May 07 17:16:28 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand play May 07 17:16:28 volumio.2 volumio[1040]: info: May 07 17:16:28 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:16:28 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:16:28 volumio.2 volumio[1040]: info: May 07 17:16:28 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:16:28 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:16:28 volumio.2 volumio[1040]: info: touch_display: Setting screensaver timeout to 120 seconds. May 07 17:16:28 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:16:28 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:16:28 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:16:28 volumio.2 volumio[1040]: info: May 07 17:16:28 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:28 volumio.2 volumio[1040]: info: ------------------------------ 23ms May 07 17:16:28 volumio.2 volumio[1040]: info: sendMpdCommand play took 20 milliseconds May 07 17:16:28 volumio.2 volumio[1040]: info: ------------------------------ 18ms May 07 17:16:28 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:28 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:28 volumio.2 volumio[1040]: info: ------------------------------ 17ms May 07 17:16:28 volumio.2 volumio[1040]: info: May 07 17:16:28 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:28 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:28 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:28 volumio.2 volumio[1040]: info: May 07 17:16:28 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:28 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:28 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:28 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 07 17:16:28 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate from file: 44100 May 07 17:16:28 volumio.2 volumio[1040]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 07 17:16:28 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 07 17:16:28 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate from file: 44100 May 07 17:16:28 volumio.2 volumio[1040]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 07 17:16:28 volumio.2 volumio[1040]: info: May 07 17:16:28 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:28 volumio.2 volumio[1040]: info: sendMpdCommand status took 54 milliseconds May 07 17:16:28 volumio.2 volumio[1040]: info: sendMpdCommand status took 52 milliseconds May 07 17:16:28 volumio.2 volumio[1040]: info: sendMpdCommand status took 51 milliseconds May 07 17:16:28 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:28 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:28 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:28 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:16:28 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:28 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:16:28 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:28 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:16:28 volumio.2 volumio[1040]: info: May 07 17:16:28 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:28 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:28 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:28 volumio.2 volumio[1040]: info: May 07 17:16:28 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:28 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:28 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:28 volumio.2 volumio[1040]: info: sendMpdCommand status took 16 milliseconds May 07 17:16:28 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 15 milliseconds May 07 17:16:28 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 16 milliseconds May 07 17:16:28 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 16 milliseconds May 07 17:16:28 volumio.2 volumio[1040]: info: sendMpdCommand status took 12 milliseconds May 07 17:16:28 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:28 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:16:28 volumio.2 volumio[1040]: verbose: ControllerMpd::parseTrackInfo May 07 17:16:28 volumio.2 volumio[1040]: verbose: ControllerMpd::parseTrackInfo May 07 17:16:28 volumio.2 volumio[1040]: verbose: ControllerMpd::parseTrackInfo May 07 17:16:28 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:28 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:16:28 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:28 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:28 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:28 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Why Can't We Take A Chance","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/08. Why Can't We Take A Chance.flac","trackType":"flac"} May 07 17:16:28 volumio.2 volumio[1040]: verbose: CURRENT POSITION 7 May 07 17:16:28 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:16:28 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus stop May 07 17:16:28 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:28 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:28 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:28 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":457,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"846 Kbps","isStreaming":false,"title":"Why Can't We Take A Chance","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/08. Why Can't We Take A Chance.flac","trackType":"flac"} May 07 17:16:28 volumio.2 volumio[1040]: verbose: CURRENT POSITION 7 May 07 17:16:28 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:16:28 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:16:28 volumio.2 volumio[1040]: info: Received an update from plugin. extracting info from payload May 07 17:16:28 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:28 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:28 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:16:28 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:29 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:29 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:29 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:29 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:29 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:29 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:29 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":594,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"846 Kbps","isStreaming":false,"title":"Why Can't We Take A Chance","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/08. Why Can't We Take A Chance.flac","trackType":"flac"} May 07 17:16:29 volumio.2 volumio[1040]: verbose: CURRENT POSITION 7 May 07 17:16:29 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:16:29 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:16:29 volumio.2 volumio[1040]: info: Received an update from plugin. extracting info from payload May 07 17:16:29 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:29 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:29 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:16:29 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:29 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:29 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:29 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:29 volumio.2 volumio[1040]: info: ------------------------------ 118ms May 07 17:16:29 volumio.2 volumio[1040]: info: ------------------------------ 188ms May 07 17:16:29 volumio.2 volumio[1040]: info: ------------------------------ 187ms May 07 17:16:29 volumio.2 volumio[1040]: info: sendMpdCommand status took 129 milliseconds May 07 17:16:29 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 120 milliseconds May 07 17:16:29 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 118 milliseconds May 07 17:16:29 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:29 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:16:29 volumio.2 volumio[1040]: verbose: ControllerMpd::parseTrackInfo May 07 17:16:29 volumio.2 volumio[1040]: verbose: ControllerMpd::parseTrackInfo May 07 17:16:29 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:29 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:29 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:29 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"754 Kbps","isStreaming":false,"title":"Why Can't We Take A Chance","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/08. Why Can't We Take A Chance.flac","trackType":"flac"} May 07 17:16:29 volumio.2 volumio[1040]: verbose: CURRENT POSITION 7 May 07 17:16:29 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:16:29 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:16:29 volumio.2 volumio[1040]: info: Received an update from plugin. extracting info from payload May 07 17:16:29 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:29 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:29 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:16:29 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:29 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:29 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:29 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:29 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:29 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:29 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:29 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"754 Kbps","isStreaming":false,"title":"Why Can't We Take A Chance","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/08. Why Can't We Take A Chance.flac","trackType":"flac"} May 07 17:16:29 volumio.2 volumio[1040]: verbose: CURRENT POSITION 7 May 07 17:16:29 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:16:29 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:16:29 volumio.2 volumio[1040]: info: Received an update from plugin. extracting info from payload May 07 17:16:29 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:29 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:29 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:29 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:29 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:29 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:29 volumio.2 volumio[1040]: info: ------------------------------ 366ms May 07 17:16:29 volumio.2 volumio[1040]: info: ------------------------------ 359ms May 07 17:16:29 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:29 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:29 volumio.2 sudo[3628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:29 volumio.2 sudo[3628]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:29 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:29 volumio.2 sudo[3631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:29 volumio.2 sudo[3631]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:29 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:29 volumio.2 systemd[1]: Started peppymeterbasic Daemon. May 07 17:16:29 volumio.2 sudo[3638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:29 volumio.2 sudo[3638]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:29 volumio.2 sudo[3628]: pam_unix(sudo:session): session closed for user root May 07 17:16:29 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:29 volumio.2 sudo[3631]: pam_unix(sudo:session): session closed for user root May 07 17:16:29 volumio.2 sudo[3638]: pam_unix(sudo:session): session closed for user root May 07 17:16:29 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:29 volumio.2 sudo[3642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:29 volumio.2 sudo[3644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:29 volumio.2 sudo[3644]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:29 volumio.2 sudo[3642]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:29 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:29 volumio.2 sudo[3648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:29 volumio.2 sudo[3648]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:29 volumio.2 sudo[3644]: pam_unix(sudo:session): session closed for user root May 07 17:16:29 volumio.2 sudo[3642]: pam_unix(sudo:session): session closed for user root May 07 17:16:29 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:29 volumio.2 sudo[3648]: pam_unix(sudo:session): session closed for user root May 07 17:16:29 volumio.2 sudo[3651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:29 volumio.2 sudo[3651]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:30 volumio.2 sudo[3651]: pam_unix(sudo:session): session closed for user root May 07 17:16:30 volumio.2 sudo[3654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:30 volumio.2 sudo[3654]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:30 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 930 milliseconds May 07 17:16:30 volumio.2 volumio[1040]: verbose: ControllerMpd::parseTrackInfo May 07 17:16:30 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:30 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:30 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:30 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"754 Kbps","isStreaming":false,"title":"Why Can't We Take A Chance","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/08. Why Can't We Take A Chance.flac","trackType":"flac"} May 07 17:16:30 volumio.2 volumio[1040]: verbose: CURRENT POSITION 7 May 07 17:16:30 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:16:30 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:16:30 volumio.2 volumio[1040]: info: Received an update from plugin. extracting info from payload May 07 17:16:30 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:30 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:30 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:30 volumio.2 sudo[3654]: pam_unix(sudo:session): session closed for user root May 07 17:16:30 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:30 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:30 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:30 volumio.2 volumio[1040]: info: ------------------------------ 1210ms May 07 17:16:30 volumio.2 volumio[1040]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 07 17:16:30 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:16:30 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:16:30 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:16:30 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:16:30 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:16:30 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:16:30 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:16:30 volumio.2 volumio[1040]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 07 17:16:30 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:30 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:30 volumio.2 sudo[3657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:30 volumio.2 sudo[3657]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:30 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:16:30 volumio.2 sudo[3660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:30 volumio.2 sudo[3660]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:30 volumio.2 sudo[3657]: pam_unix(sudo:session): session closed for user root May 07 17:16:30 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:16:30 volumio.2 sudo[3660]: pam_unix(sudo:session): session closed for user root May 07 17:16:30 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:16:32 volumio.2 volumio[3635]: libpng warning: iCCP: known incorrect sRGB profile May 07 17:16:32 volumio.2 volumio[3635]: libpng warning: iCCP: known incorrect sRGB profile May 07 17:16:32 volumio.2 volumio[3635]: libpng warning: iCCP: known incorrect sRGB profile May 07 17:16:33 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 17:16:33 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 17:16:33 volumio.2 volumio[1040]: info: Discovery: Getting this device information May 07 17:16:33 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioGetState May 07 17:16:33 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:33 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 17:16:42 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 17:16:42 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 07 17:16:43 volumio.2 volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 17:16:43 volumio.2 volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 17:16:43 volumio.2 volumio[1040]: info: FusionDsp - May 07 17:16:43 volumio.2 volumio[1040]: info: FusionDsp - May 07 17:16:43 volumio.2 volumio[1040]: info: FusionDsp - undefined May 07 17:16:43 volumio.2 volumio[1040]: info: FusionDsp - undefined May 07 17:16:45 volumio.2 volumio[1040]: info: May 07 17:16:45 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:45 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:45 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:45 volumio.2 volumio[1040]: info: May 07 17:16:45 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:45 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:45 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:45 volumio.2 volumio[1040]: info: May 07 17:16:45 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:45 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:45 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:45 volumio.2 volumio[1040]: info: sendMpdCommand status took 5 milliseconds May 07 17:16:45 volumio.2 volumio[1040]: info: sendMpdCommand status took 3 milliseconds May 07 17:16:45 volumio.2 volumio[1040]: info: sendMpdCommand status took 3 milliseconds May 07 17:16:45 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:45 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:45 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:45 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:45 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:45 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:45 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:45 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:16:45 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:45 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 7 May 07 17:16:45 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 07 17:16:45 volumio.2 volumio[1040]: verbose: CURRENT POSITION 7 May 07 17:16:45 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService stop May 07 17:16:45 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:16:45 volumio.2 volumio[1040]: info: CoreStateMachine::play index undefined May 07 17:16:45 volumio.2 volumio[1040]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 17:16:45 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:45 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:45 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:16:45 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:45 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:45 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:45 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:45 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:45 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:45 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:45 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 07 17:16:45 volumio.2 volumio[1040]: verbose: CURRENT POSITION 8 May 07 17:16:45 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService stop May 07 17:16:45 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus stop May 07 17:16:45 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:45 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:45 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:45 volumio.2 volumio[1040]: info: No code May 07 17:16:45 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:45 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:45 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:45 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:45 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:45 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:45 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:45 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:45 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:45 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 07 17:16:45 volumio.2 volumio[1040]: verbose: CURRENT POSITION 8 May 07 17:16:45 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService stop May 07 17:16:45 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus stop May 07 17:16:45 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:45 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:45 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:45 volumio.2 volumio[1040]: info: No code May 07 17:16:45 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:45 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:45 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:45 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:45 volumio.2 volumio[1040]: info: CoreStateMachine::startPlaybackTimer May 07 17:16:45 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:45 volumio.2 volumio[1040]: verbose: ControllerMpd::clearAddPlayTracks USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/09. Year After Year.flac May 07 17:16:45 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand stop May 07 17:16:45 volumio.2 volumio[1040]: info: ------------------------------ 206ms May 07 17:16:45 volumio.2 volumio[1040]: info: ------------------------------ 205ms May 07 17:16:45 volumio.2 volumio[1040]: info: ------------------------------ 205ms May 07 17:16:45 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:45 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:45 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:16:45 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:45 volumio.2 volumio[1040]: info: sendMpdCommand stop took 50 milliseconds May 07 17:16:45 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand clear May 07 17:16:45 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:45 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:45 volumio.2 sudo[3774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:45 volumio.2 sudo[3774]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:45 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:45 volumio.2 sudo[3777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:45 volumio.2 sudo[3777]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:45 volumio.2 sudo[3774]: pam_unix(sudo:session): session closed for user root May 07 17:16:45 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:45 volumio.2 systemd[1]: Stopping peppymeterbasic Daemon... May 07 17:16:45 volumio.2 systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM May 07 17:16:45 volumio.2 sudo[3780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:45 volumio.2 sudo[3780]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:45 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:45 volumio.2 sudo[3783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:45 volumio.2 sudo[3783]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:45 volumio.2 systemd[1]: peppymeterbasic.service: Succeeded. May 07 17:16:45 volumio.2 systemd[1]: Stopped peppymeterbasic Daemon. May 07 17:16:45 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:45 volumio.2 sudo[3780]: pam_unix(sudo:session): session closed for user root May 07 17:16:45 volumio.2 sudo[3786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:45 volumio.2 sudo[3786]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:45 volumio.2 sudo[3777]: pam_unix(sudo:session): session closed for user root May 07 17:16:45 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:45 volumio.2 sudo[3789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:45 volumio.2 sudo[3783]: pam_unix(sudo:session): session closed for user root May 07 17:16:45 volumio.2 sudo[3789]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:45 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:45 volumio.2 sudo[3786]: pam_unix(sudo:session): session closed for user root May 07 17:16:45 volumio.2 sudo[3792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:45 volumio.2 sudo[3792]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:45 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:16:45 volumio.2 sudo[3789]: pam_unix(sudo:session): session closed for user root May 07 17:16:45 volumio.2 sudo[3795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:45 volumio.2 sudo[3795]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:45 volumio.2 sudo[3792]: pam_unix(sudo:session): session closed for user root May 07 17:16:45 volumio.2 sudo[3798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:16:45 volumio.2 sudo[3798]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:45 volumio.2 sudo[3795]: pam_unix(sudo:session): session closed for user root May 07 17:16:46 volumio.2 volumio[1040]: info: May 07 17:16:46 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:16:46 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:16:46 volumio.2 volumio[1040]: info: sendMpdCommand clear took 721 milliseconds May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand add "USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/09. Year After Year.flac" May 07 17:16:46 volumio.2 volumio[1040]: info: May 07 17:16:46 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:16:46 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:16:46 volumio.2 volumio[1040]: info: May 07 17:16:46 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:16:46 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:16:46 volumio.2 sudo[3798]: pam_unix(sudo:session): session closed for user root May 07 17:16:46 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:16:46 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:16:46 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:16:46 volumio.2 volumio[1040]: error: updateQueue error: null May 07 17:16:46 volumio.2 volumio[1040]: info: May 07 17:16:46 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:16:46 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:16:46 volumio.2 volumio[1040]: info: ------------------------------ 138ms May 07 17:16:46 volumio.2 volumio[1040]: info: sendMpdCommand add "USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/09. Year After Year.flac" took 134 milliseconds May 07 17:16:46 volumio.2 volumio[1040]: info: ------------------------------ 127ms May 07 17:16:46 volumio.2 volumio[1040]: info: ------------------------------ 125ms May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand play May 07 17:16:46 volumio.2 volumio[1040]: info: May 07 17:16:46 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:16:46 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:16:46 volumio.2 volumio[1040]: info: May 07 17:16:46 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:16:46 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:16:46 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:16:46 volumio.2 volumio[1040]: info: touch_display: Setting screensaver timeout to 120 seconds. May 07 17:16:46 volumio.2 volumio[1040]: info: May 07 17:16:46 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:46 volumio.2 volumio[1040]: info: ------------------------------ 38ms May 07 17:16:46 volumio.2 volumio[1040]: info: sendMpdCommand play took 35 milliseconds May 07 17:16:46 volumio.2 volumio[1040]: info: ------------------------------ 29ms May 07 17:16:46 volumio.2 volumio[1040]: info: ------------------------------ 27ms May 07 17:16:46 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:46 volumio.2 volumio[1040]: info: May 07 17:16:46 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:46 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:46 volumio.2 volumio[1040]: info: May 07 17:16:46 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:46 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:46 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 07 17:16:46 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate from file: 44100 May 07 17:16:46 volumio.2 volumio[1040]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 07 17:16:46 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 07 17:16:46 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate from file: 44100 May 07 17:16:46 volumio.2 volumio[1040]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 07 17:16:46 volumio.2 volumio[1040]: info: May 07 17:16:46 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:46 volumio.2 volumio[1040]: info: sendMpdCommand status took 32 milliseconds May 07 17:16:46 volumio.2 volumio[1040]: info: sendMpdCommand status took 33 milliseconds May 07 17:16:46 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:46 volumio.2 volumio[1040]: info: sendMpdCommand status took 34 milliseconds May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:16:46 volumio.2 volumio[1040]: info: May 07 17:16:46 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:46 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:46 volumio.2 volumio[1040]: info: May 07 17:16:46 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:16:46 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:16:46 volumio.2 volumio[1040]: info: sendMpdCommand status took 14 milliseconds May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:16:46 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 18 milliseconds May 07 17:16:46 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 18 milliseconds May 07 17:16:46 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 16 milliseconds May 07 17:16:46 volumio.2 volumio[1040]: info: sendMpdCommand status took 14 milliseconds May 07 17:16:46 volumio.2 volumio[1040]: info: sendMpdCommand status took 12 milliseconds May 07 17:16:46 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 8 milliseconds May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::parseTrackInfo May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::parseTrackInfo May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::parseTrackInfo May 07 17:16:46 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:46 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:46 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:46 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":163,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"596 Kbps","isStreaming":false,"title":"Year After Year","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/09. Year After Year.flac","trackType":"flac"} May 07 17:16:46 volumio.2 volumio[1040]: verbose: CURRENT POSITION 8 May 07 17:16:46 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:16:46 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus stop May 07 17:16:46 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:46 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:46 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:46 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":163,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"823 Kbps","isStreaming":false,"title":"Year After Year","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/09. Year After Year.flac","trackType":"flac"} May 07 17:16:46 volumio.2 volumio[1040]: verbose: CURRENT POSITION 8 May 07 17:16:46 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:16:46 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:16:46 volumio.2 volumio[1040]: info: Received an update from plugin. extracting info from payload May 07 17:16:46 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:46 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:46 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:16:46 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:46 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:46 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:46 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:46 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:46 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:46 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:46 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":163,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"823 Kbps","isStreaming":false,"title":"Year After Year","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/09. Year After Year.flac","trackType":"flac"} May 07 17:16:46 volumio.2 volumio[1040]: verbose: CURRENT POSITION 8 May 07 17:16:46 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:16:46 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:16:46 volumio.2 volumio[1040]: info: Received an update from plugin. extracting info from payload May 07 17:16:46 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:46 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:46 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:46 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:46 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:46 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:46 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:46 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:46 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:46 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":163,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"679 Kbps","isStreaming":false,"title":"Year After Year","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/09. Year After Year.flac","trackType":"flac"} May 07 17:16:46 volumio.2 volumio[1040]: verbose: CURRENT POSITION 8 May 07 17:16:46 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:16:46 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:16:46 volumio.2 volumio[1040]: info: Received an update from plugin. extracting info from payload May 07 17:16:46 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:46 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:46 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:46 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:46 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:46 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:46 volumio.2 volumio[1040]: info: ------------------------------ 116ms May 07 17:16:46 volumio.2 volumio[1040]: info: ------------------------------ 254ms May 07 17:16:46 volumio.2 volumio[1040]: info: ------------------------------ 253ms May 07 17:16:46 volumio.2 volumio[1040]: info: ------------------------------ 225ms May 07 17:16:46 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 196 milliseconds May 07 17:16:46 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 197 milliseconds May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::parseTrackInfo May 07 17:16:46 volumio.2 volumio[1040]: verbose: ControllerMpd::parseTrackInfo May 07 17:16:46 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:46 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:46 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:46 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":163,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"679 Kbps","isStreaming":false,"title":"Year After Year","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/09. Year After Year.flac","trackType":"flac"} May 07 17:16:46 volumio.2 volumio[1040]: verbose: CURRENT POSITION 8 May 07 17:16:46 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:16:46 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:16:46 volumio.2 volumio[1040]: info: Received an update from plugin. extracting info from payload May 07 17:16:46 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:46 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:46 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:46 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:46 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:46 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:46 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:16:46 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:16:46 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:46 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":163,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"679 Kbps","isStreaming":false,"title":"Year After Year","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/09. Year After Year.flac","trackType":"flac"} May 07 17:16:46 volumio.2 volumio[1040]: verbose: CURRENT POSITION 8 May 07 17:16:46 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:16:46 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:16:46 volumio.2 volumio[1040]: info: Received an update from plugin. extracting info from payload May 07 17:16:46 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:46 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:46 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:46 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:16:46 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:46 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:16:46 volumio.2 volumio[1040]: info: ------------------------------ 413ms May 07 17:16:46 volumio.2 volumio[1040]: info: ------------------------------ 412ms May 07 17:16:46 volumio.2 volumio[1040]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 07 17:16:46 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:46 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:46 volumio.2 sudo[3814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:46 volumio.2 sudo[3814]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:46 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:46 volumio.2 sudo[3817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:46 volumio.2 sudo[3817]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:46 volumio.2 systemd[1]: Started peppymeterbasic Daemon. May 07 17:16:46 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:46 volumio.2 sudo[3814]: pam_unix(sudo:session): session closed for user root May 07 17:16:46 volumio.2 sudo[3821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:46 volumio.2 sudo[3821]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:46 volumio.2 sudo[3817]: pam_unix(sudo:session): session closed for user root May 07 17:16:46 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:47 volumio.2 sudo[3821]: pam_unix(sudo:session): session closed for user root May 07 17:16:47 volumio.2 sudo[3825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:47 volumio.2 sudo[3825]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:47 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:47 volumio.2 sudo[3825]: pam_unix(sudo:session): session closed for user root May 07 17:16:47 volumio.2 sudo[3828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:47 volumio.2 sudo[3828]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:47 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:47 volumio.2 sudo[3828]: pam_unix(sudo:session): session closed for user root May 07 17:16:47 volumio.2 sudo[3831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:47 volumio.2 sudo[3831]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:47 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:47 volumio.2 sudo[3831]: pam_unix(sudo:session): session closed for user root May 07 17:16:47 volumio.2 sudo[3834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:47 volumio.2 sudo[3834]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:47 volumio.2 sudo[3834]: pam_unix(sudo:session): session closed for user root May 07 17:16:47 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:47 volumio.2 sudo[3837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:47 volumio.2 sudo[3837]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:47 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:16:47 volumio.2 sudo[3837]: pam_unix(sudo:session): session closed for user root May 07 17:16:47 volumio.2 sudo[3840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:47 volumio.2 sudo[3840]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:47 volumio.2 sudo[3843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:16:47 volumio.2 sudo[3843]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:47 volumio.2 sudo[3840]: pam_unix(sudo:session): session closed for user root May 07 17:16:47 volumio.2 volumio[1040]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 07 17:16:47 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:16:47 volumio.2 sudo[3843]: pam_unix(sudo:session): session closed for user root May 07 17:16:47 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:16:49 volumio.2 volumio[1040]: info: FusionDsp - Stopping FusionDsp service May 07 17:16:49 volumio.2 volumio[1040]: info: camilladsp stopping service pid 3854... May 07 17:16:49 volumio.2 volumio[1040]: info: camilladsp service terminated, instance 1 May 07 17:16:49 volumio.2 volumio[1040]: info: Disabling plugin fusiondsp May 07 17:16:49 volumio.2 volumio[1040]: info: Preparing to generate the ALSA configuration file May 07 17:16:49 volumio.2 sudo[3865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service May 07 17:16:49 volumio.2 sudo[3865]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:49 volumio.2 volumio[1040]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf May 07 17:16:49 volumio.2 volumio[1040]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf May 07 17:16:49 volumio.2 volumio[1040]: info: Reading ALSA contributions from plugins. May 07 17:16:49 volumio.2 volumio[1040]: info: Asound.conf file written May 07 17:16:49 volumio.2 systemd[1]: Stopping FusionDsp Daemon... May 07 17:16:49 volumio.2 volumio[1390]: Backend configuration: May 07 17:16:49 volumio.2 volumio[1390]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml May 07 17:16:49 volumio.2 volumio[1390]: active_config_txt: null May 07 17:16:49 volumio.2 volumio[1390]: camilla_host: 127.0.0.1 May 07 17:16:49 volumio.2 volumio[1390]: camilla_port: 9876 May 07 17:16:49 volumio.2 volumio[1390]: coeff_dir: /data/INTERNAL/FusionDsp/filters May 07 17:16:49 volumio.2 volumio[1390]: config_dir: /data/configuration/audio_interface/fusiondsp May 07 17:16:49 volumio.2 volumio[1390]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml May 07 17:16:49 volumio.2 volumio[1390]: log_file: /tmp/camilladsp.log May 07 17:16:49 volumio.2 volumio[1390]: on_get_active_config: null May 07 17:16:49 volumio.2 volumio[1390]: on_set_active_config: null May 07 17:16:49 volumio.2 volumio[1390]: port: 5011 May 07 17:16:49 volumio.2 volumio[1390]: supported_capture_types: null May 07 17:16:49 volumio.2 volumio[1390]: supported_playback_types: null May 07 17:16:49 volumio.2 volumio[1390]: update_config_symlink: false May 07 17:16:49 volumio.2 volumio[1390]: update_config_txt: false May 07 17:16:49 volumio.2 volumio[1390]: ======== Running on http://0.0.0.0:5011 ======== May 07 17:16:49 volumio.2 volumio[1390]: (Press CTRL+C to quit) May 07 17:16:49 volumio.2 sudo[3868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 07 17:16:49 volumio.2 sudo[3868]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:49 volumio.2 sudo[3868]: pam_unix(sudo:session): session closed for user root May 07 17:16:49 volumio.2 volumio[1040]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy May 07 17:16:49 volumio.2 volumio[1040]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy May 07 17:16:49 volumio.2 volumio[1040]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy May 07 17:16:49 volumio.2 volumio[1040]: info: Output device has changed, restarting MPD May 07 17:16:49 volumio.2 volumio[3818]: libpng warning: iCCP: known incorrect sRGB profile May 07 17:16:49 volumio.2 sudo[3876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 07 17:16:49 volumio.2 sudo[3876]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:49 volumio.2 sudo[3876]: pam_unix(sudo:session): session closed for user root May 07 17:16:49 volumio.2 systemd[1]: fusiondsp.service: Succeeded. May 07 17:16:49 volumio.2 systemd[1]: Stopped FusionDsp Daemon. May 07 17:16:49 volumio.2 volumio[1040]: info: Output device has changed, restarting Shairport Sync May 07 17:16:49 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:16:49 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 17:16:49 volumio.2 sudo[3865]: pam_unix(sudo:session): session closed for user root May 07 17:16:49 volumio.2 volumio[3818]: libpng warning: iCCP: known incorrect sRGB profile May 07 17:16:49 volumio.2 sudo[3880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 07 17:16:49 volumio.2 sudo[3880]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:49 volumio.2 systemd[1]: Stopping Music Player Daemon... May 07 17:16:49 volumio.2 volumio[1040]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 07 17:16:49 volumio.2 volumio[1040]: info: Done. May 07 17:16:49 volumio.2 volumio[1040]: info: MPD Permissions set May 07 17:16:49 volumio.2 volumio[1040]: info: FusionDsp - Reporting Fusion DSP Disabled May 07 17:16:49 volumio.2 volumio[1040]: info: Removing fusiondspeq DSP Signal Path Element May 07 17:16:49 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:16:49 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:16:49 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:16:49 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:16:49 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:16:49 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:16:49 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:16:49 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:16:49 volumio.2 systemd[1]: mpd.service: Succeeded. May 07 17:16:49 volumio.2 systemd[1]: Stopped Music Player Daemon. May 07 17:16:50 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 17:16:50 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:16:50 volumio.2 volumio[1040]: info: Starting Shairport Sync May 07 17:16:50 volumio.2 systemd[1]: Starting Music Player Daemon... May 07 17:16:50 volumio.2 sudo[3891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 07 17:16:50 volumio.2 sudo[3891]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:50 volumio.2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 07 17:16:50 volumio.2 systemd[1]: shairport-sync.service: Succeeded. May 07 17:16:50 volumio.2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 07 17:16:50 volumio.2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 07 17:16:50 volumio.2 sudo[3891]: pam_unix(sudo:session): session closed for user root May 07 17:16:50 volumio.2 volumio[1040]: info: Shairport-Sync Started May 07 17:16:50 volumio.2 sudo[3885]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 07 17:16:50 volumio.2 sudo[3885]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:50 volumio.2 sudo[3885]: pam_unix(sudo:session): session closed for user root May 07 17:16:50 volumio.2 volumio[3818]: libpng warning: iCCP: known incorrect sRGB profile May 07 17:16:50 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 17:16:50 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 17:16:50 volumio.2 volumio[1040]: info: Discovery: Getting this device information May 07 17:16:50 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioGetState May 07 17:16:50 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:16:50 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 17:16:52 volumio.2 mpd[3895]: May 07 17:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 07 17:16:52 volumio.2 systemd[1]: Started Music Player Daemon. May 07 17:16:52 volumio.2 sudo[3880]: pam_unix(sudo:session): session closed for user root May 07 17:16:52 volumio.2 volumio[1040]: error: updateQueue error: null May 07 17:16:59 volumio.2 volumio[1040]: info: Enabling plugin fusiondsp May 07 17:16:59 volumio.2 volumio[1040]: info: Loading plugin "fusiondsp"... May 07 17:16:59 volumio.2 volumio[1040]: info: Applying required configuration parameters for plugin fusiondsp May 07 17:16:59 volumio.2 volumio[1040]: info: Preparing to generate the ALSA configuration file May 07 17:16:59 volumio.2 volumio[1040]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 07 17:16:59 volumio.2 volumio[1040]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf May 07 17:16:59 volumio.2 volumio[1040]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf May 07 17:16:59 volumio.2 volumio[1040]: info: Reading ALSA contributions from plugins. May 07 17:16:59 volumio.2 volumio[1040]: info: Asound.conf file written May 07 17:16:59 volumio.2 sudo[3929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 07 17:16:59 volumio.2 sudo[3929]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:59 volumio.2 sudo[3929]: pam_unix(sudo:session): session closed for user root May 07 17:16:59 volumio.2 volumio[1040]: info: Output device has changed, restarting MPD May 07 17:16:59 volumio.2 sudo[3934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 07 17:16:59 volumio.2 sudo[3934]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:59 volumio.2 sudo[3934]: pam_unix(sudo:session): session closed for user root May 07 17:16:59 volumio.2 volumio[1040]: info: Output device has changed, restarting Shairport Sync May 07 17:16:59 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:16:59 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 17:16:59 volumio.2 sudo[3937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 07 17:16:59 volumio.2 sudo[3937]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:59 volumio.2 systemd[1]: Stopping Music Player Daemon... May 07 17:16:59 volumio.2 volumio[1040]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 07 17:16:59 volumio.2 volumio[1040]: info: PLUGIN START: fusiondsp May 07 17:16:59 volumio.2 volumio[1040]: info: Loading i18n strings for locale en May 07 17:16:59 volumio.2 systemd[1]: mpd.service: Succeeded. May 07 17:16:59 volumio.2 volumio[1040]: info: Plugin peppymeterbasic has duplicated i18n key APPLY. It is ignored. May 07 17:16:59 volumio.2 volumio[1040]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. May 07 17:16:59 volumio.2 volumio[1040]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. May 07 17:16:59 volumio.2 volumio[1040]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. May 07 17:16:59 volumio.2 systemd[1]: Stopped Music Player Daemon. May 07 17:16:59 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 07 17:16:59 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:16:59 volumio.2 volumio[1040]: info: FusionDsp - mixtype--------------------- Hardware May 07 17:16:59 volumio.2 volumio[1040]: info: Preparing to generate the ALSA configuration file May 07 17:16:59 volumio.2 volumio[1040]: info: Done. May 07 17:16:59 volumio.2 volumio[1040]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 07 17:16:59 volumio.2 volumio[1040]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf May 07 17:16:59 volumio.2 volumio[1040]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf May 07 17:16:59 volumio.2 volumio[1040]: info: Reading ALSA contributions from plugins. May 07 17:16:59 volumio.2 volumio[1040]: info: MPD Permissions set May 07 17:16:59 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:16:59 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:16:59 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:16:59 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:16:59 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:16:59 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:16:59 volumio.2 systemd[1]: Starting Music Player Daemon... May 07 17:16:59 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 17:16:59 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:16:59 volumio.2 volumio[1040]: info: Starting Shairport Sync May 07 17:16:59 volumio.2 volumio[1040]: info: Asound.conf file unchanged, so no further update is needed May 07 17:16:59 volumio.2 volumio[1040]: info: Output device has changed, restarting MPD May 07 17:16:59 volumio.2 sudo[3948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 07 17:16:59 volumio.2 sudo[3948]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:59 volumio.2 sudo[3942]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 07 17:16:59 volumio.2 sudo[3942]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:59 volumio.2 sudo[3951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 07 17:16:59 volumio.2 sudo[3951]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:59 volumio.2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 07 17:16:59 volumio.2 systemd[1]: shairport-sync.service: Succeeded. May 07 17:16:59 volumio.2 sudo[3942]: pam_unix(sudo:session): session closed for user root May 07 17:16:59 volumio.2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 07 17:16:59 volumio.2 sudo[3951]: pam_unix(sudo:session): session closed for user root May 07 17:16:59 volumio.2 volumio[1040]: info: Output device has changed, restarting Shairport Sync May 07 17:16:59 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:16:59 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 17:16:59 volumio.2 sudo[3956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 07 17:16:59 volumio.2 sudo[3956]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:16:59 volumio.2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 07 17:16:59 volumio.2 sudo[3948]: pam_unix(sudo:session): session closed for user root May 07 17:16:59 volumio.2 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 07 17:16:59 volumio.2 systemd[1]: mpd.service: Succeeded. May 07 17:16:59 volumio.2 systemd[1]: Stopped Music Player Daemon. May 07 17:16:59 volumio.2 volumio[1040]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 07 17:16:59 volumio.2 volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 17:17:00 volumio.2 volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 17:16:59 volumio.2 systemd[1]: Starting Music Player Daemon... May 07 17:17:00 volumio.2 volumio[1040]: info: MPD Permissions set May 07 17:17:00 volumio.2 volumio[1040]: info: Shairport-Sync Started May 07 17:17:00 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:00 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:00 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:00 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:00 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:00 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:00 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:00 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:00 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 17:17:00 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:00 volumio.2 volumio[1040]: info: FusionDsp - May 07 17:17:00 volumio.2 sudo[3962]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 07 17:17:00 volumio.2 sudo[3962]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:00 volumio.2 sudo[3962]: pam_unix(sudo:session): session closed for user root May 07 17:17:00 volumio.2 volumio[1040]: info: FusionDsp - May 07 17:17:00 volumio.2 volumio[1040]: info: FusionDsp - undefined May 07 17:17:00 volumio.2 volumio[1040]: info: FusionDsp - undefined May 07 17:17:00 volumio.2 volumio[1040]: info: Starting Shairport Sync May 07 17:17:00 volumio.2 sudo[3973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 07 17:17:00 volumio.2 sudo[3973]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:01 volumio.2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 07 17:17:01 volumio.2 systemd[1]: shairport-sync.service: Succeeded. May 07 17:17:01 volumio.2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 07 17:17:01 volumio.2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 07 17:17:01 volumio.2 sudo[3973]: pam_unix(sudo:session): session closed for user root May 07 17:17:01 volumio.2 volumio[1040]: info: Shairport-Sync Started May 07 17:17:01 volumio.2 volumio[1040]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 07 17:17:01 volumio.2 volumio[1040]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo May 07 17:17:01 volumio.2 volumio[1040]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 07 17:17:01 volumio.2 volumio[1040]: info: camilladsp service started and running in background, instance 2 May 07 17:17:01 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:01 volumio.2 volumio[1040]: cannot open device 'volumioHw': Device or resource busy May 07 17:17:01 volumio.2 volumio[1040]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 07 17:17:01 volumio.2 volumio[1040]: cannot open device 'volumioHw': Device or resource busy May 07 17:17:01 volumio.2 volumio[1040]: info: FusionDsp loaded May 07 17:17:01 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 17:17:01 volumio.2 sudo[3994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 07 17:17:01 volumio.2 sudo[3994]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:01 volumio.2 volumio[1040]: info: FusionDsp - Reporting Fusion DSP Enabled May 07 17:17:01 volumio.2 volumio[1040]: info: Adding Signal Path Element [object Object] May 07 17:17:01 volumio.2 volumio[1040]: info: Adding fusiondspeq DSP Signal Path Element May 07 17:17:01 volumio.2 volumio[1040]: info: FusionDsp - ---- installed callbackRead May 07 17:17:01 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 07 17:17:01 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate from file: 44100 May 07 17:17:01 volumio.2 volumio[1040]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 07 17:17:01 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 07 17:17:01 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate from file: 44100 May 07 17:17:01 volumio.2 volumio[1040]: info: camilladsp stopping service pid 3984... May 07 17:17:02 volumio.2 volumio[1040]: info: camilladsp service terminated, instance 2 May 07 17:17:02 volumio.2 volumio[1040]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 07 17:17:02 volumio.2 volumio[1040]: info: camilladsp service started and running in background, instance 2 May 07 17:17:02 volumio.2 volumio[1040]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 07 17:17:02 volumio.2 systemd[1]: Started FusionDsp Daemon. May 07 17:17:02 volumio.2 volumio[1040]: error: FusionDsp - WebSocket error: [object Object] May 07 17:17:02 volumio.2 sudo[3994]: pam_unix(sudo:session): session closed for user root May 07 17:17:02 volumio.2 volumio[1040]: error: FusionDsp - WebSocket error: [object Object] May 07 17:17:02 volumio.2 volumio[1040]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 07 17:17:02 volumio.2 mpd[3970]: May 07 17:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 07 17:17:02 volumio.2 systemd[1]: Started Music Player Daemon. May 07 17:17:02 volumio.2 sudo[3937]: pam_unix(sudo:session): session closed for user root May 07 17:17:02 volumio.2 sudo[3956]: pam_unix(sudo:session): session closed for user root May 07 17:17:02 volumio.2 volumio[1040]: error: MPD error: The expression evaluated to a falsy value: May 07 17:17:02 volumio.2 volumio[1040]: assert.ok(self.idling) May 07 17:17:02 volumio.2 volumio[1040]: error: The expression evaluated to a falsy value: May 07 17:17:02 volumio.2 volumio[1040]: assert.ok(self.idling) May 07 17:17:02 volumio.2 volumio[1040]: error: updateQueue error: null May 07 17:17:05 volumio.2 systemd[1]: peppymeterbasic.service: Succeeded. May 07 17:17:08 volumio.2 volumio[1040]: info: Preload queue cleared May 07 17:17:08 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioReplaceandPlayItems May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::ClearQueue May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::stop May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::stPlaybackTimer May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::updateTrackBlock May 07 17:17:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrackBlock May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:17:08 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:17:08 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::serviceStop May 07 17:17:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 8 May 07 17:17:08 volumio.2 volumio[1040]: info: CoreCommandRouter::serviceStop May 07 17:17:08 volumio.2 volumio[1040]: info: ControllerMpd::stop May 07 17:17:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand stop May 07 17:17:08 volumio.2 volumio[1040]: info: CorePlayQueue::clearPlayQueue May 07 17:17:08 volumio.2 volumio[1040]: info: CorePlayQueue::saveQueue May 07 17:17:08 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushQueue May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::addQueueItems May 07 17:17:08 volumio.2 volumio[1040]: info: CorePlayQueue::addQueueItems May 07 17:17:08 volumio.2 volumio[1040]: info: Preload queue cleared May 07 17:17:08 volumio.2 volumio[1040]: info: Adding Item to queue: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/01. I'm Glad There Is You.flac May 07 17:17:08 volumio.2 volumio[1040]: info: Using cached record of: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/01. I'm Glad There Is You.flac May 07 17:17:08 volumio.2 volumio[1040]: info: Adding Item to queue: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/02. Love's Mystery.flac May 07 17:17:08 volumio.2 volumio[1040]: info: Using cached record of: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/02. Love's Mystery.flac May 07 17:17:08 volumio.2 volumio[1040]: info: Adding Item to queue: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/03. Can We Bring It Back.flac May 07 17:17:08 volumio.2 volumio[1040]: info: Using cached record of: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/03. Can We Bring It Back.flac May 07 17:17:08 volumio.2 volumio[1040]: info: Adding Item to queue: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/04. Keep Me Loving You.flac May 07 17:17:08 volumio.2 volumio[1040]: info: Using cached record of: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/04. Keep Me Loving You.flac May 07 17:17:08 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushQueue May 07 17:17:08 volumio.2 volumio[1040]: info: CorePlayQueue::saveQueue May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::updateTrackBlock May 07 17:17:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrackBlock May 07 17:17:08 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPlay May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::play index 3 May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::addQueueItems May 07 17:17:08 volumio.2 volumio[1040]: info: CorePlayQueue::addQueueItems May 07 17:17:08 volumio.2 volumio[1040]: info: Preload queue cleared May 07 17:17:08 volumio.2 volumio[1040]: info: Adding Item to queue: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/05. In My Life.flac May 07 17:17:08 volumio.2 volumio[1040]: info: Using cached record of: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/05. In My Life.flac May 07 17:17:08 volumio.2 volumio[1040]: info: Adding Item to queue: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/06. Elusive Boogie.flac May 07 17:17:08 volumio.2 volumio[1040]: info: Using cached record of: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/06. Elusive Boogie.flac May 07 17:17:08 volumio.2 volumio[1040]: info: Adding Item to queue: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/07. Mad Over You.flac May 07 17:17:08 volumio.2 volumio[1040]: info: Using cached record of: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/07. Mad Over You.flac May 07 17:17:08 volumio.2 volumio[1040]: info: Adding Item to queue: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/08. Why Can't We Take A Chance.flac May 07 17:17:08 volumio.2 volumio[1040]: info: Using cached record of: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/08. Why Can't We Take A Chance.flac May 07 17:17:08 volumio.2 volumio[1040]: info: Adding Item to queue: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/09. Year After Year.flac May 07 17:17:08 volumio.2 volumio[1040]: info: Using cached record of: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/09. Year After Year.flac May 07 17:17:08 volumio.2 volumio[1040]: info: Adding Item to queue: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/10. Don't Say It's Over.flac May 07 17:17:08 volumio.2 volumio[1040]: info: Using cached record of: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/10. Don't Say It's Over.flac May 07 17:17:08 volumio.2 volumio[1040]: info: Adding Item to queue: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/play.m3u May 07 17:17:08 volumio.2 volumio[1040]: info: Using cached record of: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/play.m3u May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::stop May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 17:17:08 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushQueue May 07 17:17:08 volumio.2 volumio[1040]: info: CorePlayQueue::saveQueue May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::play index undefined May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::updateTrackBlock May 07 17:17:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrackBlock May 07 17:17:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 3 May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::startPlaybackTimer May 07 17:17:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 3 May 07 17:17:08 volumio.2 volumio[1040]: verbose: ControllerMpd::clearAddPlayTracks USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/04. Keep Me Loving You.flac May 07 17:17:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand stop May 07 17:17:08 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:17:08 volumio.2 volumio[1040]: info: sendMpdCommand stop took 215 milliseconds May 07 17:17:08 volumio.2 volumio[1040]: info: sendMpdCommand stop took 87 milliseconds May 07 17:17:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand clear May 07 17:17:08 volumio.2 sudo[4036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:17:08 volumio.2 sudo[4036]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:08 volumio.2 volumio[1040]: info: May 07 17:17:08 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:17:08 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:17:08 volumio.2 volumio[1040]: info: sendMpdCommand clear took 66 milliseconds May 07 17:17:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand add "USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/04. Keep Me Loving You.flac" May 07 17:17:08 volumio.2 volumio[1040]: info: May 07 17:17:08 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:17:08 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:17:08 volumio.2 volumio[1040]: error: updateQueue error: null May 07 17:17:08 volumio.2 volumio[1040]: info: May 07 17:17:08 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:17:08 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:17:08 volumio.2 volumio[1040]: info: ------------------------------ 19ms May 07 17:17:08 volumio.2 volumio[1040]: info: sendMpdCommand add "USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/04. Keep Me Loving You.flac" took 18 milliseconds May 07 17:17:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand play May 07 17:17:08 volumio.2 volumio[1040]: info: May 07 17:17:08 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:17:08 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:17:08 volumio.2 volumio[1040]: info: ------------------------------ 12ms May 07 17:17:08 volumio.2 volumio[1040]: info: ------------------------------ 9ms May 07 17:17:08 volumio.2 sudo[4036]: pam_unix(sudo:session): session closed for user root May 07 17:17:08 volumio.2 volumio[1040]: info: May 07 17:17:08 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:17:08 volumio.2 volumio[1040]: info: sendMpdCommand play took 17 milliseconds May 07 17:17:08 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:17:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:17:08 volumio.2 volumio[1040]: info: ------------------------------ 16ms May 07 17:17:08 volumio.2 volumio[1040]: info: May 07 17:17:08 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:17:08 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:17:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:17:08 volumio.2 volumio[1040]: info: touch_display: Setting screensaver timeout to 120 seconds. May 07 17:17:08 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:17:08 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 07 17:17:08 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate from file: 44100 May 07 17:17:08 volumio.2 volumio[1040]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 07 17:17:08 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 07 17:17:08 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate from file: 44100 May 07 17:17:08 volumio.2 volumio[1040]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 07 17:17:08 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 07 17:17:08 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate from file: 44100 May 07 17:17:08 volumio.2 volumio[1040]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 07 17:17:08 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 07 17:17:08 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate from file: 44100 May 07 17:17:08 volumio.2 volumio[1040]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 07 17:17:08 volumio.2 volumio[1040]: info: May 07 17:17:08 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:17:08 volumio.2 volumio[1040]: info: sendMpdCommand status took 132 milliseconds May 07 17:17:08 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:17:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:17:08 volumio.2 volumio[1040]: info: sendMpdCommand status took 131 milliseconds May 07 17:17:08 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:17:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:17:08 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:17:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:17:08 volumio.2 volumio[1040]: info: May 07 17:17:08 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:17:08 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:17:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:17:08 volumio.2 volumio[1040]: info: sendMpdCommand status took 20 milliseconds May 07 17:17:08 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 20 milliseconds May 07 17:17:08 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 18 milliseconds May 07 17:17:08 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:17:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:17:08 volumio.2 volumio[1040]: verbose: ControllerMpd::parseTrackInfo May 07 17:17:08 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:17:08 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:17:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 3 May 07 17:17:08 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1084 Kbps","isStreaming":false,"title":"Keep Me Loving You","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/04. Keep Me Loving You.flac","trackType":"flac"} May 07 17:17:08 volumio.2 volumio[1040]: verbose: CURRENT POSITION 3 May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus stop May 07 17:17:08 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:17:08 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:17:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 3 May 07 17:17:08 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"822 Kbps","isStreaming":false,"title":"Keep Me Loving You","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/04. Keep Me Loving You.flac","trackType":"flac"} May 07 17:17:08 volumio.2 volumio[1040]: verbose: CURRENT POSITION 3 May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:17:08 volumio.2 volumio[1040]: info: Received an update from plugin. extracting info from payload May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 3 May 07 17:17:08 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:17:08 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 3 May 07 17:17:08 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:08 volumio.2 volumio[1040]: info: ------------------------------ 186ms May 07 17:17:08 volumio.2 volumio[1040]: info: ------------------------------ 227ms May 07 17:17:08 volumio.2 volumio[1040]: info: sendMpdCommand status took 83 milliseconds May 07 17:17:08 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 78 milliseconds May 07 17:17:08 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:17:08 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:17:08 volumio.2 volumio[1040]: verbose: ControllerMpd::parseTrackInfo May 07 17:17:08 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:17:08 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:17:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 3 May 07 17:17:08 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1830,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"886 Kbps","isStreaming":false,"title":"Keep Me Loving You","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/04. Keep Me Loving You.flac","trackType":"flac"} May 07 17:17:08 volumio.2 volumio[1040]: verbose: CURRENT POSITION 3 May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:17:08 volumio.2 volumio[1040]: info: Received an update from plugin. extracting info from payload May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 3 May 07 17:17:08 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:17:08 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:08 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:08 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 3 May 07 17:17:08 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:08 volumio.2 volumio[1040]: info: ------------------------------ 213ms May 07 17:17:08 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:17:08 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:17:08 volumio.2 sudo[4049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:17:08 volumio.2 sudo[4049]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:09 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:17:09 volumio.2 systemd[1]: Started peppymeterbasic Daemon. May 07 17:17:09 volumio.2 sudo[4049]: pam_unix(sudo:session): session closed for user root May 07 17:17:09 volumio.2 sudo[4052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:17:09 volumio.2 sudo[4052]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:09 volumio.2 sudo[4052]: pam_unix(sudo:session): session closed for user root May 07 17:17:09 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:17:09 volumio.2 sudo[4057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:17:09 volumio.2 sudo[4057]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:09 volumio.2 sudo[4057]: pam_unix(sudo:session): session closed for user root May 07 17:17:09 volumio.2 volumio[1040]: info: Preload queue cleared May 07 17:17:09 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioReplaceandPlayItems May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::ClearQueue May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::stop May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::stPlaybackTimer May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::updateTrackBlock May 07 17:17:09 volumio.2 volumio[1040]: info: CorePlayQueue::getTrackBlock May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:09 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 3 May 07 17:17:09 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:17:09 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:09 volumio.2 sudo[4060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:17:09 volumio.2 sudo[4060]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::serviceStop May 07 17:17:09 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 3 May 07 17:17:09 volumio.2 volumio[1040]: info: CoreCommandRouter::serviceStop May 07 17:17:09 volumio.2 volumio[1040]: info: ControllerMpd::stop May 07 17:17:09 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand stop May 07 17:17:09 volumio.2 volumio[1040]: info: CorePlayQueue::clearPlayQueue May 07 17:17:09 volumio.2 volumio[1040]: info: CorePlayQueue::saveQueue May 07 17:17:09 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushQueue May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::addQueueItems May 07 17:17:09 volumio.2 volumio[1040]: info: CorePlayQueue::addQueueItems May 07 17:17:09 volumio.2 volumio[1040]: info: Preload queue cleared May 07 17:17:09 volumio.2 volumio[1040]: info: Adding Item to queue: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/01. I'm Glad There Is You.flac May 07 17:17:09 volumio.2 volumio[1040]: info: Using cached record of: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/01. I'm Glad There Is You.flac May 07 17:17:09 volumio.2 volumio[1040]: info: Adding Item to queue: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/02. Love's Mystery.flac May 07 17:17:09 volumio.2 volumio[1040]: info: Using cached record of: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/02. Love's Mystery.flac May 07 17:17:09 volumio.2 volumio[1040]: info: Adding Item to queue: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/03. Can We Bring It Back.flac May 07 17:17:09 volumio.2 volumio[1040]: info: Using cached record of: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/03. Can We Bring It Back.flac May 07 17:17:09 volumio.2 volumio[1040]: info: Adding Item to queue: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/04. Keep Me Loving You.flac May 07 17:17:09 volumio.2 volumio[1040]: info: Using cached record of: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/04. Keep Me Loving You.flac May 07 17:17:09 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushQueue May 07 17:17:09 volumio.2 volumio[1040]: info: CorePlayQueue::saveQueue May 07 17:17:09 volumio.2 sudo[4060]: pam_unix(sudo:session): session closed for user root May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::updateTrackBlock May 07 17:17:09 volumio.2 volumio[1040]: info: CorePlayQueue::getTrackBlock May 07 17:17:09 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPlay May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::play index 3 May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::addQueueItems May 07 17:17:09 volumio.2 volumio[1040]: info: CorePlayQueue::addQueueItems May 07 17:17:09 volumio.2 volumio[1040]: info: Preload queue cleared May 07 17:17:09 volumio.2 volumio[1040]: info: Adding Item to queue: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/05. In My Life.flac May 07 17:17:09 volumio.2 volumio[1040]: info: Using cached record of: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/05. In My Life.flac May 07 17:17:09 volumio.2 volumio[1040]: info: Adding Item to queue: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/06. Elusive Boogie.flac May 07 17:17:09 volumio.2 volumio[1040]: info: Using cached record of: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/06. Elusive Boogie.flac May 07 17:17:09 volumio.2 volumio[1040]: info: Adding Item to queue: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/07. Mad Over You.flac May 07 17:17:09 volumio.2 volumio[1040]: info: Using cached record of: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/07. Mad Over You.flac May 07 17:17:09 volumio.2 volumio[1040]: info: Adding Item to queue: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/08. Why Can't We Take A Chance.flac May 07 17:17:09 volumio.2 volumio[1040]: info: Using cached record of: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/08. Why Can't We Take A Chance.flac May 07 17:17:09 volumio.2 volumio[1040]: info: Adding Item to queue: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/09. Year After Year.flac May 07 17:17:09 volumio.2 volumio[1040]: info: Using cached record of: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/09. Year After Year.flac May 07 17:17:09 volumio.2 volumio[1040]: info: Adding Item to queue: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/10. Don't Say It's Over.flac May 07 17:17:09 volumio.2 volumio[1040]: info: Using cached record of: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/10. Don't Say It's Over.flac May 07 17:17:09 volumio.2 volumio[1040]: info: Adding Item to queue: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/play.m3u May 07 17:17:09 volumio.2 volumio[1040]: info: Using cached record of: music-library/USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/play.m3u May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::stop May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 17:17:09 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushQueue May 07 17:17:09 volumio.2 volumio[1040]: info: CorePlayQueue::saveQueue May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::play index undefined May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::updateTrackBlock May 07 17:17:09 volumio.2 volumio[1040]: info: CorePlayQueue::getTrackBlock May 07 17:17:09 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 3 May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::startPlaybackTimer May 07 17:17:09 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 3 May 07 17:17:09 volumio.2 volumio[1040]: verbose: ControllerMpd::clearAddPlayTracks USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/04. Keep Me Loving You.flac May 07 17:17:09 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand stop May 07 17:17:09 volumio.2 volumio[1040]: info: May 07 17:17:09 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:17:09 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 945 milliseconds May 07 17:17:09 volumio.2 volumio[1040]: info: sendMpdCommand stop took 146 milliseconds May 07 17:17:09 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:17:09 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:17:09 volumio.2 volumio[1040]: info: sendMpdCommand stop took 23 milliseconds May 07 17:17:09 volumio.2 volumio[1040]: verbose: ControllerMpd::parseTrackInfo May 07 17:17:09 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand clear May 07 17:17:09 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:17:09 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:17:09 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 3 May 07 17:17:09 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1830,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"886 Kbps","isStreaming":false,"title":"Keep Me Loving You","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/04. Keep Me Loving You.flac","trackType":"flac"} May 07 17:17:09 volumio.2 volumio[1040]: verbose: CURRENT POSITION 3 May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus stop May 07 17:17:09 volumio.2 volumio[1040]: info: ------------------------------ 1052ms May 07 17:17:09 volumio.2 volumio[1040]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 07 17:17:09 volumio.2 volumio[1040]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 07 17:17:09 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:17:09 volumio.2 volumio[1040]: info: May 07 17:17:09 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:17:09 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:17:09 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:17:09 volumio.2 sudo[4066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:17:09 volumio.2 sudo[4066]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:09 volumio.2 systemd[1]: Stopping peppymeterbasic Daemon... May 07 17:17:09 volumio.2 systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM May 07 17:17:09 volumio.2 systemd[1]: peppymeterbasic.service: Succeeded. May 07 17:17:09 volumio.2 systemd[1]: Stopped peppymeterbasic Daemon. May 07 17:17:09 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:17:09 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:17:09 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:17:09 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:17:09 volumio.2 sudo[4066]: pam_unix(sudo:session): session closed for user root May 07 17:17:09 volumio.2 volumio[1040]: info: May 07 17:17:09 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:17:09 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:17:09 volumio.2 volumio[1040]: info: sendMpdCommand status took 218 milliseconds May 07 17:17:09 volumio.2 volumio[1040]: info: sendMpdCommand clear took 215 milliseconds May 07 17:17:09 volumio.2 volumio[1040]: info: sendMpdCommand status took 138 milliseconds May 07 17:17:09 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:17:09 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:17:09 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand add "USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/04. Keep Me Loving You.flac" May 07 17:17:09 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:17:09 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:17:09 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:09 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 3 May 07 17:17:09 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:17:09 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:09 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 3 May 07 17:17:09 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 07 17:17:09 volumio.2 volumio[1040]: verbose: CURRENT POSITION 3 May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService stop May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::play index undefined May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:09 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 4 May 07 17:17:09 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:17:09 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:09 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 4 May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::startPlaybackTimer May 07 17:17:09 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 4 May 07 17:17:09 volumio.2 volumio[1040]: verbose: ControllerMpd::clearAddPlayTracks USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/05. In My Life.flac May 07 17:17:09 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand stop May 07 17:17:09 volumio.2 volumio[1040]: info: ------------------------------ 215ms May 07 17:17:09 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:09 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 4 May 07 17:17:09 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:17:09 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:09 volumio.2 volumio[1040]: info: May 07 17:17:09 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:17:09 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:17:10 volumio.2 volumio[1040]: info: touch_display: Setting screensaver timeout to 120 seconds. May 07 17:17:10 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:17:10 volumio.2 volumio[1040]: error: updateQueue error: null May 07 17:17:10 volumio.2 volumio[1040]: info: May 07 17:17:10 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:17:10 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:17:10 volumio.2 volumio[1040]: info: ------------------------------ 177ms May 07 17:17:10 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 174 milliseconds May 07 17:17:10 volumio.2 volumio[1040]: info: sendMpdCommand add "USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/04. Keep Me Loving You.flac" took 174 milliseconds May 07 17:17:10 volumio.2 volumio[1040]: info: sendMpdCommand stop took 102 milliseconds May 07 17:17:10 volumio.2 volumio[1040]: info: ------------------------------ 29ms May 07 17:17:10 volumio.2 volumio[1040]: verbose: ControllerMpd::parseTrackInfo May 07 17:17:10 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand play May 07 17:17:10 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand clear May 07 17:17:10 volumio.2 volumio[1040]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 07 17:17:10 volumio.2 volumio[1040]: info: ------------------------------ 408ms May 07 17:17:10 volumio.2 volumio[1040]: info: May 07 17:17:10 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:17:10 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:17:10 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:17:10 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:17:10 volumio.2 sudo[4080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:17:10 volumio.2 sudo[4080]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:10 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:17:10 volumio.2 sudo[4083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:17:10 volumio.2 sudo[4083]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:10 volumio.2 volumio[1040]: info: May 07 17:17:10 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:17:10 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:17:10 volumio.2 volumio[1040]: info: May 07 17:17:10 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:17:10 volumio.2 volumio[1040]: error: updateQueue error: null May 07 17:17:10 volumio.2 volumio[1040]: info: ------------------------------ 204ms May 07 17:17:10 volumio.2 volumio[1040]: info: sendMpdCommand play took 198 milliseconds May 07 17:17:10 volumio.2 volumio[1040]: info: sendMpdCommand clear took 197 milliseconds May 07 17:17:10 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:17:10 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:17:10 volumio.2 volumio[1040]: info: ------------------------------ 198ms May 07 17:17:10 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand add "USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/05. In My Life.flac" May 07 17:17:10 volumio.2 volumio[1040]: info: May 07 17:17:10 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:17:10 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:17:10 volumio.2 volumio[1040]: info: May 07 17:17:10 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:17:10 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:17:10 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:17:10 volumio.2 sudo[4088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:17:10 volumio.2 sudo[4088]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:10 volumio.2 systemd[1]: Started peppymeterbasic Daemon. May 07 17:17:10 volumio.2 systemd[1]: Stopping peppymeterbasic Daemon... May 07 17:17:10 volumio.2 systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM May 07 17:17:10 volumio.2 sudo[4080]: pam_unix(sudo:session): session closed for user root May 07 17:17:10 volumio.2 systemd[1]: peppymeterbasic.service: Succeeded. May 07 17:17:10 volumio.2 systemd[1]: Stopped peppymeterbasic Daemon. May 07 17:17:10 volumio.2 volumio[1040]: error: updateQueue error: null May 07 17:17:10 volumio.2 volumio[1040]: info: May 07 17:17:10 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:17:10 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:17:10 volumio.2 volumio[1040]: info: ------------------------------ 92ms May 07 17:17:10 volumio.2 volumio[1040]: info: sendMpdCommand status took 83 milliseconds May 07 17:17:10 volumio.2 volumio[1040]: info: sendMpdCommand add "USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/05. In My Life.flac" took 82 milliseconds May 07 17:17:10 volumio.2 volumio[1040]: info: ------------------------------ 80ms May 07 17:17:10 volumio.2 volumio[1040]: info: sendMpdCommand status took 80 milliseconds May 07 17:17:10 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:17:10 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand play May 07 17:17:10 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:17:10 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:17:10 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:17:10 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:10 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 4 May 07 17:17:10 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:17:10 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:10 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 4 May 07 17:17:10 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 07 17:17:10 volumio.2 volumio[1040]: verbose: CURRENT POSITION 4 May 07 17:17:10 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService stop May 07 17:17:10 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus stop May 07 17:17:10 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:10 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 4 May 07 17:17:10 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:10 volumio.2 sudo[4083]: pam_unix(sudo:session): session closed for user root May 07 17:17:10 volumio.2 volumio[1040]: info: No code May 07 17:17:10 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:10 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 4 May 07 17:17:10 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:10 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:17:10 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:17:10 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:10 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 4 May 07 17:17:10 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:10 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 4 May 07 17:17:10 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 07 17:17:10 volumio.2 volumio[1040]: verbose: CURRENT POSITION 4 May 07 17:17:10 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService stop May 07 17:17:10 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus stop May 07 17:17:10 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:10 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 4 May 07 17:17:10 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:10 volumio.2 volumio[1040]: info: No code May 07 17:17:10 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:10 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 4 May 07 17:17:10 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:10 volumio.2 sudo[4088]: pam_unix(sudo:session): session closed for user root May 07 17:17:10 volumio.2 volumio[1040]: info: ------------------------------ 333ms May 07 17:17:10 volumio.2 volumio[1040]: info: ------------------------------ 321ms May 07 17:17:10 volumio.2 volumio[1040]: info: May 07 17:17:10 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:17:10 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:17:10 volumio.2 volumio[1040]: info: May 07 17:17:10 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:17:10 volumio.2 volumio[1040]: info: May 07 17:17:10 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:17:10 volumio.2 volumio[1040]: info: ------------------------------ 267ms May 07 17:17:10 volumio.2 volumio[1040]: info: sendMpdCommand play took 262 milliseconds May 07 17:17:10 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:17:10 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:17:10 volumio.2 volumio[1040]: info: ------------------------------ 21ms May 07 17:17:10 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:17:10 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:17:10 volumio.2 volumio[1040]: info: May 07 17:17:10 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:17:10 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:17:10 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:17:10 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:17:10 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:17:10 volumio.2 sudo[4095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:17:10 volumio.2 sudo[4095]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:10 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:17:10 volumio.2 sudo[4098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:17:10 volumio.2 sudo[4098]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:10 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:17:10 volumio.2 sudo[4095]: pam_unix(sudo:session): session closed for user root May 07 17:17:10 volumio.2 sudo[4101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:17:10 volumio.2 sudo[4101]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:10 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:17:10 volumio.2 sudo[4098]: pam_unix(sudo:session): session closed for user root May 07 17:17:10 volumio.2 sudo[4104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:17:10 volumio.2 sudo[4104]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:10 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:17:10 volumio.2 sudo[4101]: pam_unix(sudo:session): session closed for user root May 07 17:17:10 volumio.2 sudo[4107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:17:10 volumio.2 sudo[4107]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:11 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 07 17:17:11 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate from file: 44100 May 07 17:17:11 volumio.2 volumio[1040]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 07 17:17:11 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 07 17:17:11 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate from file: 44100 May 07 17:17:11 volumio.2 volumio[1040]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 07 17:17:11 volumio.2 sudo[4107]: pam_unix(sudo:session): session closed for user root May 07 17:17:11 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:17:11 volumio.2 volumio[1040]: info: touch_display: Setting screensaver timeout to 120 seconds. May 07 17:17:11 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:17:11 volumio.2 sudo[4104]: pam_unix(sudo:session): session closed for user root May 07 17:17:11 volumio.2 volumio[1040]: info: sendMpdCommand status took 523 milliseconds May 07 17:17:11 volumio.2 volumio[1040]: info: sendMpdCommand status took 521 milliseconds May 07 17:17:11 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:17:11 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:17:11 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:17:11 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:17:11 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:17:11 volumio.2 sudo[4110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:17:11 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:17:11 volumio.2 sudo[4110]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:11 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:17:11 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:17:11 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:17:11 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 21 milliseconds May 07 17:17:11 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 22 milliseconds May 07 17:17:11 volumio.2 volumio[1040]: verbose: ControllerMpd::parseTrackInfo May 07 17:17:11 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:17:11 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:17:11 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 4 May 07 17:17:11 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1967,"duration":372,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"674 Kbps","isStreaming":false,"title":"In My Life","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/05. In My Life.flac","trackType":"flac"} May 07 17:17:11 volumio.2 volumio[1040]: verbose: CURRENT POSITION 4 May 07 17:17:11 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:17:11 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus stop May 07 17:17:11 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:17:11 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:17:11 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 4 May 07 17:17:11 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1967,"duration":372,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"674 Kbps","isStreaming":false,"title":"In My Life","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/05. In My Life.flac","trackType":"flac"} May 07 17:17:11 volumio.2 volumio[1040]: verbose: CURRENT POSITION 4 May 07 17:17:11 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:17:11 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:17:11 volumio.2 volumio[1040]: info: Received an update from plugin. extracting info from payload May 07 17:17:11 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:11 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 4 May 07 17:17:11 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:17:11 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:11 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:11 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 4 May 07 17:17:11 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:11 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:17:11 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:17:11 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 4 May 07 17:17:11 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1967,"duration":372,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"674 Kbps","isStreaming":false,"title":"In My Life","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/05. In My Life.flac","trackType":"flac"} May 07 17:17:11 volumio.2 volumio[1040]: verbose: CURRENT POSITION 4 May 07 17:17:11 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:17:11 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:17:11 volumio.2 volumio[1040]: info: Received an update from plugin. extracting info from payload May 07 17:17:11 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:11 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 4 May 07 17:17:11 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:11 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:11 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 4 May 07 17:17:11 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:11 volumio.2 volumio[1040]: info: ------------------------------ 591ms May 07 17:17:11 volumio.2 sudo[4110]: pam_unix(sudo:session): session closed for user root May 07 17:17:11 volumio.2 volumio[1040]: info: ------------------------------ 722ms May 07 17:17:11 volumio.2 volumio[1040]: info: ------------------------------ 732ms May 07 17:17:11 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:17:11 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:17:11 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:17:11 volumio.2 sudo[4115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:17:11 volumio.2 sudo[4115]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:11 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:17:11 volumio.2 sudo[4118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:17:11 volumio.2 sudo[4118]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:11 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:17:11 volumio.2 sudo[4122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:17:11 volumio.2 sudo[4122]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:11 volumio.2 systemd[1]: Started peppymeterbasic Daemon. May 07 17:17:11 volumio.2 sudo[4125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:17:11 volumio.2 sudo[4125]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:11 volumio.2 sudo[4115]: pam_unix(sudo:session): session closed for user root May 07 17:17:11 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:17:11 volumio.2 sudo[4118]: pam_unix(sudo:session): session closed for user root May 07 17:17:11 volumio.2 sudo[4122]: pam_unix(sudo:session): session closed for user root May 07 17:17:11 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:17:11 volumio.2 sudo[4125]: pam_unix(sudo:session): session closed for user root May 07 17:17:11 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:17:11 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:17:11 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:17:13 volumio.2 volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 17:17:13 volumio.2 volumio[1040]: info: FusionDsp - May 07 17:17:14 volumio.2 volumio[4121]: libpng warning: iCCP: known incorrect sRGB profile May 07 17:17:14 volumio.2 volumio[1040]: info: FusionDsp - undefined May 07 17:17:14 volumio.2 volumio[4121]: libpng warning: iCCP: known incorrect sRGB profile May 07 17:17:15 volumio.2 volumio[4121]: libpng warning: iCCP: known incorrect sRGB profile May 07 17:17:20 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 17:17:20 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 17:17:20 volumio.2 volumio[1040]: info: Discovery: Getting this device information May 07 17:17:20 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioGetState May 07 17:17:20 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 4 May 07 17:17:20 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 17:17:23 volumio.2 volumio[1040]: info: CALLMETHOD: audio_interface fusiondsp choosedsp [object Object] May 07 17:17:23 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , choosedsp May 07 17:17:24 volumio.2 volumio[1040]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 07 17:17:24 volumio.2 volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 17:17:24 volumio.2 volumio[1040]: info: CoreCommandRouter::Close All Modals sent May 07 17:17:24 volumio.2 volumio[1040]: info: FusionDsp - Choose a preset May 07 17:17:24 volumio.2 volumio[1040]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 07 17:17:24 volumio.2 volumio[1040]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 07 17:17:32 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 17:17:32 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 07 17:17:32 volumio.2 volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 17:17:32 volumio.2 volumio[1040]: info: FusionDsp - Choose a preset May 07 17:17:32 volumio.2 volumio[1040]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 07 17:17:35 volumio.2 volumio[1040]: info: May 07 17:17:35 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:17:35 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:17:35 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:17:35 volumio.2 volumio[1040]: info: May 07 17:17:35 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:17:35 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:17:35 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:17:35 volumio.2 volumio[1040]: info: sendMpdCommand status took 4 milliseconds May 07 17:17:35 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:17:35 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:17:35 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:17:35 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:35 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 4 May 07 17:17:35 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:17:35 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:35 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 4 May 07 17:17:35 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 07 17:17:35 volumio.2 volumio[1040]: verbose: CURRENT POSITION 4 May 07 17:17:35 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService stop May 07 17:17:35 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:17:35 volumio.2 volumio[1040]: info: CoreStateMachine::play index undefined May 07 17:17:35 volumio.2 volumio[1040]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 17:17:35 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:35 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 5 May 07 17:17:35 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:17:35 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:35 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 5 May 07 17:17:35 volumio.2 volumio[1040]: info: CoreStateMachine::startPlaybackTimer May 07 17:17:35 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 5 May 07 17:17:35 volumio.2 volumio[1040]: verbose: ControllerMpd::clearAddPlayTracks USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/06. Elusive Boogie.flac May 07 17:17:35 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand stop May 07 17:17:35 volumio.2 volumio[1040]: info: ------------------------------ 80ms May 07 17:17:35 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:35 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 5 May 07 17:17:35 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:17:35 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:35 volumio.2 volumio[1040]: info: sendMpdCommand status took 116 milliseconds May 07 17:17:35 volumio.2 volumio[1040]: info: sendMpdCommand stop took 40 milliseconds May 07 17:17:35 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:17:35 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand clear May 07 17:17:35 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:17:35 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:17:35 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:35 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 5 May 07 17:17:35 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:35 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 5 May 07 17:17:35 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 07 17:17:35 volumio.2 volumio[1040]: verbose: CURRENT POSITION 5 May 07 17:17:35 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService stop May 07 17:17:35 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus stop May 07 17:17:35 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:35 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 5 May 07 17:17:35 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:35 volumio.2 volumio[1040]: info: No code May 07 17:17:35 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:35 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 5 May 07 17:17:35 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:35 volumio.2 volumio[1040]: info: ------------------------------ 216ms May 07 17:17:35 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:17:35 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:17:35 volumio.2 sudo[4298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:17:35 volumio.2 sudo[4298]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:35 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:17:35 volumio.2 sudo[4301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:17:35 volumio.2 sudo[4301]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:35 volumio.2 sudo[4298]: pam_unix(sudo:session): session closed for user root May 07 17:17:35 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:17:35 volumio.2 systemd[1]: Stopping peppymeterbasic Daemon... May 07 17:17:35 volumio.2 systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM May 07 17:17:35 volumio.2 sudo[4304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:17:35 volumio.2 sudo[4304]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:35 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:17:35 volumio.2 sudo[4307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:17:36 volumio.2 sudo[4307]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:36 volumio.2 systemd[1]: peppymeterbasic.service: Succeeded. May 07 17:17:36 volumio.2 systemd[1]: Stopped peppymeterbasic Daemon. May 07 17:17:36 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status stop May 07 17:17:36 volumio.2 sudo[4301]: pam_unix(sudo:session): session closed for user root May 07 17:17:36 volumio.2 sudo[4310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:17:36 volumio.2 sudo[4310]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:36 volumio.2 sudo[4304]: pam_unix(sudo:session): session closed for user root May 07 17:17:36 volumio.2 sudo[4307]: pam_unix(sudo:session): session closed for user root May 07 17:17:36 volumio.2 sudo[4310]: pam_unix(sudo:session): session closed for user root May 07 17:17:36 volumio.2 sudo[4313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 07 17:17:36 volumio.2 sudo[4313]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:36 volumio.2 volumio[1040]: info: May 07 17:17:36 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:17:36 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:17:36 volumio.2 volumio[1040]: info: sendMpdCommand clear took 685 milliseconds May 07 17:17:36 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand add "USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/06. Elusive Boogie.flac" May 07 17:17:36 volumio.2 volumio[1040]: info: May 07 17:17:36 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:17:36 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:17:36 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:17:36 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:17:36 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:17:36 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:17:36 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:17:36 volumio.2 sudo[4313]: pam_unix(sudo:session): session closed for user root May 07 17:17:36 volumio.2 volumio[1040]: error: updateQueue error: null May 07 17:17:36 volumio.2 volumio[1040]: info: May 07 17:17:36 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:17:36 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:17:36 volumio.2 volumio[1040]: info: ------------------------------ 91ms May 07 17:17:36 volumio.2 volumio[1040]: info: sendMpdCommand add "USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/06. Elusive Boogie.flac" took 91 milliseconds May 07 17:17:36 volumio.2 volumio[1040]: info: ------------------------------ 89ms May 07 17:17:36 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand play May 07 17:17:36 volumio.2 volumio[1040]: info: May 07 17:17:36 volumio.2 volumio[1040]: ---------------------------- MPD announces system playlist update May 07 17:17:36 volumio.2 volumio[1040]: info: Ignoring MPD Status Update May 07 17:17:36 volumio.2 volumio[1040]: info: touch_display: Setting screensaver timeout to 120 seconds. May 07 17:17:36 volumio.2 volumio[1040]: info: May 07 17:17:36 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:17:36 volumio.2 volumio[1040]: info: ------------------------------ 26ms May 07 17:17:36 volumio.2 volumio[1040]: info: sendMpdCommand play took 24 milliseconds May 07 17:17:36 volumio.2 volumio[1040]: info: ------------------------------ 17ms May 07 17:17:36 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:17:36 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:17:36 volumio.2 volumio[1040]: info: May 07 17:17:36 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:17:36 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:17:36 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:17:36 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Stop May 07 17:17:36 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 07 17:17:36 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate from file: 44100 May 07 17:17:36 volumio.2 volumio[1040]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 07 17:17:36 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 07 17:17:36 volumio.2 volumio[1040]: info: FusionDsp - ---- read samplerate from file: 44100 May 07 17:17:36 volumio.2 volumio[1040]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 07 17:17:36 volumio.2 volumio[1040]: info: May 07 17:17:36 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:17:36 volumio.2 volumio[1040]: info: sendMpdCommand status took 50 milliseconds May 07 17:17:36 volumio.2 volumio[1040]: info: sendMpdCommand status took 75 milliseconds May 07 17:17:36 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:17:36 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:17:36 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:17:36 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:17:36 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:17:36 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:17:36 volumio.2 volumio[1040]: info: May 07 17:17:36 volumio.2 volumio[1040]: ---------------------------- MPD announces state update: player May 07 17:17:36 volumio.2 volumio[1040]: info: ControllerMpd::getState May 07 17:17:36 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand status May 07 17:17:36 volumio.2 volumio[1040]: info: sendMpdCommand status took 17 milliseconds May 07 17:17:36 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 18 milliseconds May 07 17:17:36 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 19 milliseconds May 07 17:17:36 volumio.2 volumio[1040]: info: sendMpdCommand status took 9 milliseconds May 07 17:17:36 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:17:36 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:17:36 volumio.2 volumio[1040]: verbose: ControllerMpd::parseTrackInfo May 07 17:17:36 volumio.2 volumio[1040]: verbose: ControllerMpd::parseState May 07 17:17:36 volumio.2 volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:17:36 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:17:36 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:17:36 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 5 May 07 17:17:36 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Elusive Boogie","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/06. Elusive Boogie.flac","trackType":"flac"} May 07 17:17:36 volumio.2 volumio[1040]: verbose: CURRENT POSITION 5 May 07 17:17:36 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:17:36 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus stop May 07 17:17:36 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:17:36 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:17:36 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 5 May 07 17:17:36 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":343,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"956 Kbps","isStreaming":false,"title":"Elusive Boogie","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/06. Elusive Boogie.flac","trackType":"flac"} May 07 17:17:36 volumio.2 volumio[1040]: verbose: CURRENT POSITION 5 May 07 17:17:36 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:17:36 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:17:36 volumio.2 volumio[1040]: info: Received an update from plugin. extracting info from payload May 07 17:17:36 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:36 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 5 May 07 17:17:36 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:17:36 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:36 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:36 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 5 May 07 17:17:36 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:36 volumio.2 volumio[1040]: info: ------------------------------ 136ms May 07 17:17:36 volumio.2 volumio[1040]: info: ------------------------------ 179ms May 07 17:17:36 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 84 milliseconds May 07 17:17:36 volumio.2 volumio[1040]: info: sendMpdCommand playlistinfo took 84 milliseconds May 07 17:17:36 volumio.2 volumio[1040]: verbose: ControllerMpd::parseTrackInfo May 07 17:17:36 volumio.2 volumio[1040]: verbose: ControllerMpd::parseTrackInfo May 07 17:17:36 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:17:36 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:17:36 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 5 May 07 17:17:36 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1176 Kbps","isStreaming":false,"title":"Elusive Boogie","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/06. Elusive Boogie.flac","trackType":"flac"} May 07 17:17:36 volumio.2 volumio[1040]: verbose: CURRENT POSITION 5 May 07 17:17:36 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:17:36 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:17:36 volumio.2 volumio[1040]: info: Received an update from plugin. extracting info from payload May 07 17:17:36 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:36 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 5 May 07 17:17:36 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:17:36 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:36 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:36 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 5 May 07 17:17:36 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:36 volumio.2 volumio[1040]: info: ControllerMpd::pushState May 07 17:17:36 volumio.2 volumio[1040]: info: CoreCommandRouter::servicePushState May 07 17:17:36 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 5 May 07 17:17:36 volumio.2 volumio[1040]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1176 Kbps","isStreaming":false,"title":"Elusive Boogie","artist":"Randy Crawford","album":"Don't Say It's Over","uri":"USB/34AADC30AADBEC7C/MUSICA MIA/FLAC VARIE/1993. Randy Crawford - Don't Say It's Over/06. Elusive Boogie.flac","trackType":"flac"} May 07 17:17:36 volumio.2 volumio[1040]: verbose: CURRENT POSITION 5 May 07 17:17:36 volumio.2 volumio[1040]: info: CoreStateMachine::syncState stateService play May 07 17:17:36 volumio.2 volumio[1040]: info: CoreStateMachine::syncState currentStatus play May 07 17:17:36 volumio.2 volumio[1040]: info: Received an update from plugin. extracting info from payload May 07 17:17:36 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:36 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 5 May 07 17:17:36 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:36 volumio.2 volumio[1040]: info: CoreStateMachine::pushState May 07 17:17:36 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 5 May 07 17:17:36 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioPushState May 07 17:17:36 volumio.2 volumio[1040]: info: ------------------------------ 339ms May 07 17:17:36 volumio.2 volumio[1040]: info: ------------------------------ 304ms May 07 17:17:36 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:17:36 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:17:36 volumio.2 sudo[4329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:17:36 volumio.2 sudo[4329]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:36 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:17:36 volumio.2 sudo[4332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:17:36 volumio.2 sudo[4332]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:37 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:17:37 volumio.2 sudo[4336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:17:37 volumio.2 sudo[4336]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:37 volumio.2 systemd[1]: Started peppymeterbasic Daemon. May 07 17:17:37 volumio.2 sudo[4329]: pam_unix(sudo:session): session closed for user root May 07 17:17:37 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:17:37 volumio.2 sudo[4332]: pam_unix(sudo:session): session closed for user root May 07 17:17:37 volumio.2 sudo[4336]: pam_unix(sudo:session): session closed for user root May 07 17:17:37 volumio.2 sudo[4340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:17:37 volumio.2 sudo[4340]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:37 volumio.2 volumio[1040]: info: PeppyMeterBasic ---peppymeterbasic status play May 07 17:17:37 volumio.2 sudo[4343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:17:37 volumio.2 sudo[4343]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:37 volumio.2 sudo[4340]: pam_unix(sudo:session): session closed for user root May 07 17:17:37 volumio.2 sudo[4346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 07 17:17:37 volumio.2 sudo[4346]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:37 volumio.2 sudo[4343]: pam_unix(sudo:session): session closed for user root May 07 17:17:37 volumio.2 volumio[1040]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 07 17:17:37 volumio.2 volumio[1040]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 07 17:17:37 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:17:37 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:17:37 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:17:37 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:17:37 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:17:37 volumio.2 sudo[4346]: pam_unix(sudo:session): session closed for user root May 07 17:17:37 volumio.2 volumio[1040]: info: peppymeterbasic Daemon Started May 07 17:17:39 volumio.2 volumio[4335]: libpng warning: iCCP: known incorrect sRGB profile May 07 17:17:39 volumio.2 volumio[4335]: libpng warning: iCCP: known incorrect sRGB profile May 07 17:17:40 volumio.2 volumio[4335]: libpng warning: iCCP: known incorrect sRGB profile May 07 17:17:42 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 07 17:17:43 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 07 17:17:50 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 17:17:50 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 17:17:50 volumio.2 volumio[1040]: info: Discovery: Getting this device information May 07 17:17:50 volumio.2 volumio[1040]: info: CoreCommandRouter::volumioGetState May 07 17:17:50 volumio.2 volumio[1040]: info: CorePlayQueue::getTrack 5 May 07 17:17:50 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 17:17:51 volumio.2 volumio[1040]: info: FusionDsp - Stopping FusionDsp service May 07 17:17:51 volumio.2 volumio[1040]: info: camilladsp stopping service pid 4317... May 07 17:17:51 volumio.2 volumio[1040]: info: camilladsp service terminated, instance 2 May 07 17:17:51 volumio.2 volumio[1040]: info: Disabling plugin fusiondsp May 07 17:17:51 volumio.2 volumio[1040]: info: Preparing to generate the ALSA configuration file May 07 17:17:51 volumio.2 sudo[4400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service May 07 17:17:51 volumio.2 sudo[4400]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:51 volumio.2 volumio[1040]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf May 07 17:17:51 volumio.2 volumio[1040]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf May 07 17:17:51 volumio.2 volumio[1040]: info: Reading ALSA contributions from plugins. May 07 17:17:51 volumio.2 volumio[1040]: info: Asound.conf file written May 07 17:17:51 volumio.2 systemd[1]: Stopping FusionDsp Daemon... May 07 17:17:51 volumio.2 volumio[3996]: Backend configuration: May 07 17:17:51 volumio.2 volumio[3996]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml May 07 17:17:51 volumio.2 volumio[3996]: active_config_txt: null May 07 17:17:51 volumio.2 volumio[3996]: camilla_host: 127.0.0.1 May 07 17:17:51 volumio.2 volumio[3996]: camilla_port: 9876 May 07 17:17:51 volumio.2 volumio[3996]: coeff_dir: /data/INTERNAL/FusionDsp/filters May 07 17:17:51 volumio.2 volumio[3996]: config_dir: /data/configuration/audio_interface/fusiondsp May 07 17:17:51 volumio.2 volumio[3996]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml May 07 17:17:51 volumio.2 volumio[3996]: log_file: /tmp/camilladsp.log May 07 17:17:51 volumio.2 volumio[3996]: on_get_active_config: null May 07 17:17:51 volumio.2 volumio[3996]: on_set_active_config: null May 07 17:17:51 volumio.2 volumio[3996]: port: 5011 May 07 17:17:51 volumio.2 volumio[3996]: supported_capture_types: null May 07 17:17:51 volumio.2 volumio[3996]: supported_playback_types: null May 07 17:17:51 volumio.2 volumio[3996]: update_config_symlink: false May 07 17:17:51 volumio.2 volumio[3996]: update_config_txt: false May 07 17:17:51 volumio.2 volumio[3996]: ======== Running on http://0.0.0.0:5011 ======== May 07 17:17:51 volumio.2 volumio[3996]: (Press CTRL+C to quit) May 07 17:17:51 volumio.2 sudo[4403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 07 17:17:51 volumio.2 sudo[4403]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:51 volumio.2 sudo[4403]: pam_unix(sudo:session): session closed for user root May 07 17:17:51 volumio.2 volumio[1040]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy May 07 17:17:51 volumio.2 volumio[1040]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy May 07 17:17:51 volumio.2 volumio[1040]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy May 07 17:17:51 volumio.2 volumio[1040]: info: Output device has changed, restarting MPD May 07 17:17:51 volumio.2 systemd[1]: fusiondsp.service: Succeeded. May 07 17:17:51 volumio.2 systemd[1]: Stopped FusionDsp Daemon. May 07 17:17:51 volumio.2 sudo[4408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 07 17:17:51 volumio.2 sudo[4400]: pam_unix(sudo:session): session closed for user root May 07 17:17:51 volumio.2 sudo[4408]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:51 volumio.2 sudo[4408]: pam_unix(sudo:session): session closed for user root May 07 17:17:51 volumio.2 volumio[1040]: info: Output device has changed, restarting Shairport Sync May 07 17:17:51 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:51 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 17:17:51 volumio.2 sudo[4411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 07 17:17:51 volumio.2 sudo[4411]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:51 volumio.2 systemd[1]: Stopping Music Player Daemon... May 07 17:17:51 volumio.2 volumio[1040]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 07 17:17:51 volumio.2 volumio[1040]: info: Done. May 07 17:17:51 volumio.2 volumio[1040]: info: MPD Permissions set May 07 17:17:51 volumio.2 volumio[1040]: info: FusionDsp - Reporting Fusion DSP Disabled May 07 17:17:51 volumio.2 volumio[1040]: info: Removing fusiondspeq DSP Signal Path Element May 07 17:17:51 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:51 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:51 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:51 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:51 volumio.2 systemd[1]: mpd.service: Succeeded. May 07 17:17:51 volumio.2 systemd[1]: Stopped Music Player Daemon. May 07 17:17:51 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 17:17:51 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:51 volumio.2 volumio[1040]: info: Starting Shairport Sync May 07 17:17:51 volumio.2 systemd[1]: Starting Music Player Daemon... May 07 17:17:51 volumio.2 sudo[4422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 07 17:17:51 volumio.2 sudo[4422]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:51 volumio.2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 07 17:17:51 volumio.2 systemd[1]: shairport-sync.service: Succeeded. May 07 17:17:51 volumio.2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 07 17:17:51 volumio.2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 07 17:17:51 volumio.2 sudo[4422]: pam_unix(sudo:session): session closed for user root May 07 17:17:51 volumio.2 volumio[1040]: info: Shairport-Sync Started May 07 17:17:52 volumio.2 volumio[1040]: error: Upnp client error: Error: This socket has been ended by the other party May 07 17:17:52 volumio.2 sudo[4416]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 07 17:17:52 volumio.2 sudo[4416]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:52 volumio.2 sudo[4416]: pam_unix(sudo:session): session closed for user root May 07 17:17:53 volumio.2 mpd[4429]: May 07 17:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 07 17:17:53 volumio.2 systemd[1]: Started Music Player Daemon. May 07 17:17:53 volumio.2 sudo[4411]: pam_unix(sudo:session): session closed for user root May 07 17:17:53 volumio.2 volumio[1040]: error: updateQueue error: null May 07 17:17:54 volumio.2 volumio[1040]: info: Enabling plugin fusiondsp May 07 17:17:54 volumio.2 volumio[1040]: info: Loading plugin "fusiondsp"... May 07 17:17:54 volumio.2 volumio[1040]: info: Applying required configuration parameters for plugin fusiondsp May 07 17:17:54 volumio.2 volumio[1040]: info: Preparing to generate the ALSA configuration file May 07 17:17:54 volumio.2 volumio[1040]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 07 17:17:54 volumio.2 volumio[1040]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf May 07 17:17:54 volumio.2 volumio[1040]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf May 07 17:17:54 volumio.2 volumio[1040]: info: Reading ALSA contributions from plugins. May 07 17:17:54 volumio.2 volumio[1040]: info: Asound.conf file written May 07 17:17:54 volumio.2 sudo[4450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 07 17:17:54 volumio.2 sudo[4450]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:54 volumio.2 sudo[4450]: pam_unix(sudo:session): session closed for user root May 07 17:17:54 volumio.2 volumio[1040]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy May 07 17:17:54 volumio.2 volumio[1040]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy May 07 17:17:54 volumio.2 volumio[1040]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy May 07 17:17:54 volumio.2 volumio[1040]: info: Output device has changed, restarting MPD May 07 17:17:54 volumio.2 sudo[4455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 07 17:17:54 volumio.2 sudo[4455]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:54 volumio.2 sudo[4455]: pam_unix(sudo:session): session closed for user root May 07 17:17:54 volumio.2 volumio[1040]: info: Output device has changed, restarting Shairport Sync May 07 17:17:54 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:54 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 17:17:54 volumio.2 sudo[4458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 07 17:17:54 volumio.2 sudo[4458]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:54 volumio.2 systemd[1]: Stopping Music Player Daemon... May 07 17:17:54 volumio.2 volumio[1040]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 07 17:17:54 volumio.2 volumio[1040]: info: PLUGIN START: fusiondsp May 07 17:17:54 volumio.2 volumio[1040]: info: Loading i18n strings for locale en May 07 17:17:54 volumio.2 systemd[1]: mpd.service: Succeeded. May 07 17:17:54 volumio.2 systemd[1]: Stopped Music Player Daemon. May 07 17:17:54 volumio.2 volumio[1040]: info: Plugin peppymeterbasic has duplicated i18n key APPLY. It is ignored. May 07 17:17:54 volumio.2 volumio[1040]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. May 07 17:17:54 volumio.2 volumio[1040]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. May 07 17:17:54 volumio.2 volumio[1040]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. May 07 17:17:54 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 07 17:17:54 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:54 volumio.2 volumio[1040]: info: FusionDsp - mixtype--------------------- Hardware May 07 17:17:54 volumio.2 volumio[1040]: info: Preparing to generate the ALSA configuration file May 07 17:17:54 volumio.2 volumio[1040]: info: Done. May 07 17:17:54 volumio.2 volumio[1040]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 07 17:17:54 volumio.2 volumio[1040]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf May 07 17:17:54 volumio.2 volumio[1040]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf May 07 17:17:54 volumio.2 volumio[1040]: info: Reading ALSA contributions from plugins. May 07 17:17:54 volumio.2 volumio[1040]: info: MPD Permissions set May 07 17:17:54 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:54 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:54 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:54 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:54 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:54 volumio.2 systemd[1]: Starting Music Player Daemon... May 07 17:17:54 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 17:17:54 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:54 volumio.2 volumio[1040]: info: Starting Shairport Sync May 07 17:17:54 volumio.2 volumio[1040]: info: Asound.conf file unchanged, so no further update is needed May 07 17:17:54 volumio.2 volumio[1040]: info: Output device has changed, restarting MPD May 07 17:17:55 volumio.2 sudo[4469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 07 17:17:55 volumio.2 sudo[4469]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:55 volumio.2 sudo[4472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 07 17:17:55 volumio.2 sudo[4472]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:55 volumio.2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 07 17:17:55 volumio.2 sudo[4463]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 07 17:17:55 volumio.2 sudo[4463]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:55 volumio.2 systemd[1]: shairport-sync.service: Succeeded. May 07 17:17:55 volumio.2 volumio[1040]: info: Output device has changed, restarting Shairport Sync May 07 17:17:55 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:55 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 17:17:55 volumio.2 sudo[4472]: pam_unix(sudo:session): session closed for user root May 07 17:17:55 volumio.2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 07 17:17:55 volumio.2 sudo[4463]: pam_unix(sudo:session): session closed for user root May 07 17:17:55 volumio.2 sudo[4476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 07 17:17:55 volumio.2 sudo[4476]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:55 volumio.2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 07 17:17:55 volumio.2 sudo[4469]: pam_unix(sudo:session): session closed for user root May 07 17:17:55 volumio.2 volumio[1040]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 07 17:17:55 volumio.2 volumio[1040]: error: Upnp client error: Error: This socket has been ended by the other party May 07 17:17:55 volumio.2 volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 17:17:55 volumio.2 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 07 17:17:55 volumio.2 systemd[1]: mpd.service: Succeeded. May 07 17:17:55 volumio.2 systemd[1]: Stopped Music Player Daemon. May 07 17:17:55 volumio.2 volumio[1040]: info: MPD Permissions set May 07 17:17:55 volumio.2 volumio[1040]: info: Shairport-Sync Started May 07 17:17:55 volumio.2 systemd[1]: Starting Music Player Daemon... May 07 17:17:55 volumio.2 volumio[1040]: (node:1040) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 07 17:17:55 volumio.2 volumio[1040]: (Use `node --trace-warnings ...` to show where the warning was created) May 07 17:17:55 volumio.2 volumio[1040]: (node:1040) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 07 17:17:55 volumio.2 volumio[1040]: (node:1040) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 07 17:17:55 volumio.2 volumio[1040]: (node:1040) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 07 17:17:55 volumio.2 volumio[1040]: (node:1040) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 07 17:17:55 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:55 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:55 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:55 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:55 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:55 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 17:17:55 volumio.2 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 17:17:55 volumio.2 volumio[1040]: info: FusionDsp - Choose a preset May 07 17:17:55 volumio.2 sudo[4484]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 07 17:17:55 volumio.2 sudo[4484]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:17:55 volumio.2 sudo[4484]: pam_unix(sudo:session): session closed for user root May 07 17:17:55 volumio.2 volumio[1040]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 07 17:17:55 volumio.2 volumio[1040]: TypeError: Cannot read property 'off' of undefined May 07 17:17:55 volumio.2 volumio[1040]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) May 07 17:17:55 volumio.2 volumio[1040]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 07 17:17:55 volumio.2 volumio[1040]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) May 07 17:17:55 volumio.2 volumio[1040]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) May 07 17:17:55 volumio.2 volumio[1040]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) May 07 17:17:55 volumio.2 volumio[1040]: at Socket.emit (events.js:315:20) May 07 17:17:55 volumio.2 volumio[1040]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 07 17:17:55 volumio.2 volumio[1040]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 07 17:17:55 volumio.2 volumio[1040]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 07 17:17:56 volumio.2 sudo[4501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-07 17:16 May 07 17:17:56 volumio.2 sudo[4501]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"