-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Mon 2025-06-09 16:51:36 CEST. -- Jun 09 16:50:01 volumio volumio[7987]: info: Preload queue cleared Jun 09 16:50:01 volumio volumio[7987]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::ClearQueue Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::stop Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::stPlaybackTimer Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::updateTrackBlock Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::getTrackBlock Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:01 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:50:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::serviceStop Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:01 volumio volumio[7987]: info: CoreCommandRouter::serviceStop Jun 09 16:50:01 volumio volumio[7987]: info: [1749480601160] ControllerUPNPBrowser::stop Jun 09 16:50:01 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand stop Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::clearPlayQueue Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::saveQueue Jun 09 16:50:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushQueue Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::addQueueItems Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::addQueueItems Jun 09 16:50:01 volumio volumio[7987]: info: Preload queue cleared Jun 09 16:50:01 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46864 Jun 09 16:50:01 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46864 Jun 09 16:50:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushQueue Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::saveQueue Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::updateTrackBlock Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::getTrackBlock Jun 09 16:50:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPlay Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::play index 0 Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::addQueueItems Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::addQueueItems Jun 09 16:50:01 volumio volumio[7987]: info: Preload queue cleared Jun 09 16:50:01 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46861 Jun 09 16:50:01 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46861 Jun 09 16:50:01 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46860 Jun 09 16:50:01 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46860 Jun 09 16:50:01 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46862 Jun 09 16:50:01 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46862 Jun 09 16:50:01 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46859 Jun 09 16:50:01 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46859 Jun 09 16:50:01 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46863 Jun 09 16:50:01 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46863 Jun 09 16:50:01 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46865 Jun 09 16:50:01 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46865 Jun 09 16:50:01 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46866 Jun 09 16:50:01 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46866 Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::stop Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushQueue Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::saveQueue Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::play index undefined Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::updateTrackBlock Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::getTrackBlock Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:01 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:50:01 volumio volumio[7987]: info: Jun 09 16:50:01 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:50:01 volumio volumio[7987]: info: sendMpdCommand stop took 47 milliseconds Jun 09 16:50:01 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:50:01 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:50:01 volumio volumio[7987]: info: Jun 09 16:50:01 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:50:01 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:50:01 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:50:01 volumio volumio[7987]: info: sendMpdCommand status took 3 milliseconds Jun 09 16:50:01 volumio volumio[7987]: info: sendMpdCommand status took 1 milliseconds Jun 09 16:50:01 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:50:01 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:50:01 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:50:01 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:50:01 volumio volumio[7987]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 09 16:50:01 volumio volumio[7987]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 09 16:50:01 volumio volumio[7987]: verbose: ControllerMpd::parseTrackInfo Jun 09 16:50:01 volumio volumio[7987]: verbose: ControllerMpd::parseTrackInfo Jun 09 16:50:01 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:50:01 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:01 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:50:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:01 volumio volumio[7987]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=46864.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46864.wav?format=WAV&downsample=false","trackType":""} Jun 09 16:50:01 volumio volumio[7987]: verbose: CURRENT POSITION 0 Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::syncState stateService stop Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus stop Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:01 volumio volumio[7987]: info: No code Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:01 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:50:01 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:01 volumio volumio[7987]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=46864.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46864.wav?format=WAV&downsample=false","trackType":""} Jun 09 16:50:01 volumio volumio[7987]: verbose: CURRENT POSITION 0 Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::syncState stateService stop Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus stop Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:01 volumio volumio[7987]: info: No code Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:01 volumio sudo[11574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:50:01 volumio sudo[11574]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:01 volumio volumio[7987]: info: ------------------------------ 36ms Jun 09 16:50:01 volumio volumio[7987]: info: ------------------------------ 36ms Jun 09 16:50:01 volumio systemd[1]: Stopping peppymeterbasic Daemon... Jun 09 16:50:01 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Jun 09 16:50:01 volumio systemd[1]: peppymeterbasic.service: Succeeded. Jun 09 16:50:01 volumio systemd[1]: Stopped peppymeterbasic Daemon. Jun 09 16:50:01 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:50:01 volumio sudo[11574]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:01 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:50:01 volumio sudo[11577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:50:01 volumio sudo[11577]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:01 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:50:01 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:50:01 volumio sudo[11580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:50:01 volumio sudo[11580]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:01 volumio sudo[11577]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:01 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:50:01 volumio sudo[11582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:50:01 volumio sudo[11582]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:01 volumio sudo[11585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:50:01 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:50:01 volumio sudo[11585]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:01 volumio sudo[11580]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:01 volumio sudo[11588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:50:01 volumio sudo[11588]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:01 volumio volumio[7987]: info: Preload queue cleared Jun 09 16:50:01 volumio volumio[7987]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::ClearQueue Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::stop Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::clearPlayQueue Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::saveQueue Jun 09 16:50:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushQueue Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::addQueueItems Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::addQueueItems Jun 09 16:50:01 volumio volumio[7987]: info: Preload queue cleared Jun 09 16:50:01 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46864 Jun 09 16:50:01 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46864 Jun 09 16:50:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushQueue Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::saveQueue Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::updateTrackBlock Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::getTrackBlock Jun 09 16:50:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPlay Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::play index 0 Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::addQueueItems Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::addQueueItems Jun 09 16:50:01 volumio volumio[7987]: info: Preload queue cleared Jun 09 16:50:01 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46861 Jun 09 16:50:01 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46861 Jun 09 16:50:01 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46860 Jun 09 16:50:01 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46860 Jun 09 16:50:01 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46862 Jun 09 16:50:01 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46862 Jun 09 16:50:01 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46859 Jun 09 16:50:01 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46859 Jun 09 16:50:01 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46863 Jun 09 16:50:01 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46863 Jun 09 16:50:01 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46865 Jun 09 16:50:01 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46865 Jun 09 16:50:01 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46866 Jun 09 16:50:01 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46866 Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::stop Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushQueue Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::saveQueue Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::play index undefined Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:01 volumio volumio[7987]: info: CoreStateMachine::updateTrackBlock Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::getTrackBlock Jun 09 16:50:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:01 volumio sudo[11591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:50:01 volumio volumio[7987]: info: peppymeterbasic Daemon Stop Jun 09 16:50:01 volumio volumio[7987]: info: peppymeterbasic Daemon Stop Jun 09 16:50:01 volumio volumio[7987]: info: peppymeterbasic Daemon Stop Jun 09 16:50:01 volumio sudo[11591]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:01 volumio sudo[11585]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:01 volumio sudo[11582]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:01 volumio volumio[7987]: info: peppymeterbasic Daemon Stop Jun 09 16:50:01 volumio volumio[7987]: info: peppymeterbasic Daemon Stop Jun 09 16:50:01 volumio sudo[11588]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:01 volumio volumio[7987]: info: peppymeterbasic Daemon Stop Jun 09 16:50:01 volumio sudo[11591]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:01 volumio volumio[7987]: info: peppymeterbasic Daemon Stop Jun 09 16:50:02 volumio volumio[7987]: info: Preload queue cleared Jun 09 16:50:02 volumio volumio[7987]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::ClearQueue Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::stop Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:02 volumio volumio[7987]: info: CorePlayQueue::clearPlayQueue Jun 09 16:50:02 volumio volumio[7987]: info: CorePlayQueue::saveQueue Jun 09 16:50:02 volumio volumio[7987]: info: CoreCommandRouter::volumioPushQueue Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::addQueueItems Jun 09 16:50:02 volumio volumio[7987]: info: CorePlayQueue::addQueueItems Jun 09 16:50:02 volumio volumio[7987]: info: Preload queue cleared Jun 09 16:50:02 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46864 Jun 09 16:50:02 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46864 Jun 09 16:50:02 volumio volumio[7987]: info: CoreCommandRouter::volumioPushQueue Jun 09 16:50:02 volumio volumio[7987]: info: CorePlayQueue::saveQueue Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::updateTrackBlock Jun 09 16:50:02 volumio volumio[7987]: info: CorePlayQueue::getTrackBlock Jun 09 16:50:02 volumio volumio[7987]: info: CoreCommandRouter::volumioPlay Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::play index 0 Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::addQueueItems Jun 09 16:50:02 volumio volumio[7987]: info: CorePlayQueue::addQueueItems Jun 09 16:50:02 volumio volumio[7987]: info: Preload queue cleared Jun 09 16:50:02 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46861 Jun 09 16:50:02 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46861 Jun 09 16:50:02 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46860 Jun 09 16:50:02 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46860 Jun 09 16:50:02 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46862 Jun 09 16:50:02 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46862 Jun 09 16:50:02 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46859 Jun 09 16:50:02 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46859 Jun 09 16:50:02 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46863 Jun 09 16:50:02 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46863 Jun 09 16:50:02 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46865 Jun 09 16:50:02 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46865 Jun 09 16:50:02 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46866 Jun 09 16:50:02 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46866 Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::stop Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:02 volumio volumio[7987]: info: CoreCommandRouter::volumioPushQueue Jun 09 16:50:02 volumio volumio[7987]: info: CorePlayQueue::saveQueue Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::play index undefined Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::updateTrackBlock Jun 09 16:50:02 volumio volumio[7987]: info: CorePlayQueue::getTrackBlock Jun 09 16:50:02 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:02 volumio volumio[7987]: info: Preload queue cleared Jun 09 16:50:02 volumio volumio[7987]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::ClearQueue Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::stop Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:02 volumio volumio[7987]: info: CorePlayQueue::clearPlayQueue Jun 09 16:50:02 volumio volumio[7987]: info: CorePlayQueue::saveQueue Jun 09 16:50:02 volumio volumio[7987]: info: CoreCommandRouter::volumioPushQueue Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::addQueueItems Jun 09 16:50:02 volumio volumio[7987]: info: CorePlayQueue::addQueueItems Jun 09 16:50:02 volumio volumio[7987]: info: Preload queue cleared Jun 09 16:50:02 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46864 Jun 09 16:50:02 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46864 Jun 09 16:50:02 volumio volumio[7987]: info: CoreCommandRouter::volumioPushQueue Jun 09 16:50:02 volumio volumio[7987]: info: CorePlayQueue::saveQueue Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::updateTrackBlock Jun 09 16:50:02 volumio volumio[7987]: info: CorePlayQueue::getTrackBlock Jun 09 16:50:02 volumio volumio[7987]: info: CoreCommandRouter::volumioPlay Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::play index 0 Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::addQueueItems Jun 09 16:50:02 volumio volumio[7987]: info: CorePlayQueue::addQueueItems Jun 09 16:50:02 volumio volumio[7987]: info: Preload queue cleared Jun 09 16:50:02 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46861 Jun 09 16:50:02 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46861 Jun 09 16:50:02 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46860 Jun 09 16:50:02 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46860 Jun 09 16:50:02 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46862 Jun 09 16:50:02 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46862 Jun 09 16:50:02 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46859 Jun 09 16:50:02 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46859 Jun 09 16:50:02 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46863 Jun 09 16:50:02 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46863 Jun 09 16:50:02 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46865 Jun 09 16:50:02 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46865 Jun 09 16:50:02 volumio volumio[7987]: info: Adding Item to queue: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46866 Jun 09 16:50:02 volumio volumio[7987]: info: Using cached record of: upnp/http://192.168.1.220:50001/ContentDirectory/control@22$@46866 Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::stop Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:02 volumio volumio[7987]: info: CoreCommandRouter::volumioPushQueue Jun 09 16:50:02 volumio volumio[7987]: info: CorePlayQueue::saveQueue Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::play index undefined Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:02 volumio volumio[7987]: info: CoreStateMachine::updateTrackBlock Jun 09 16:50:02 volumio volumio[7987]: info: CorePlayQueue::getTrackBlock Jun 09 16:50:02 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:11 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 09 16:50:11 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 09 16:50:11 volumio volumio[7987]: info: Discovery: Getting this device information Jun 09 16:50:11 volumio volumio[7987]: info: CoreCommandRouter::volumioGetState Jun 09 16:50:11 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:11 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 09 16:50:14 volumio volumio[7987]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 09 16:50:14 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 09 16:50:14 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 16:50:14 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 09 16:50:14 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 16:50:14 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 16:50:14 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 16:50:14 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 16:50:14 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 16:50:14 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 16:50:14 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 16:50:14 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 09 16:50:14 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 09 16:50:30 volumio volumio[7987]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 09 16:50:30 volumio volumio[7987]: info: Preparing to save Alsa Options, stopping services first Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::volumioGetState Jun 09 16:50:30 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::volumioPause Jun 09 16:50:30 volumio volumio[7987]: info: CoreStateMachine::pause Jun 09 16:50:30 volumio volumio[7987]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"Hifiberry DAC"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 09 16:50:30 volumio volumio[7987]: info: Found match in Cards Database: setting mixer undefined for card Hifiberry DAC Jun 09 16:50:30 volumio volumio[7987]: info: Device 1 does not have any Mixer Control Available Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 09 16:50:30 volumio volumio[7987]: info: Updating Volume Controller Parameters: Device: 1 Name: Hifiberry DAC Mixer: undefined Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 09 16:50:30 volumio volumio[7987]: info: Disabling external Volume Control Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 09 16:50:30 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:30 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:30 volumio volumio[7987]: info: Preparing to generate the ALSA configuration file Jun 09 16:50:30 volumio volumio[7987]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 09 16:50:30 volumio volumio[7987]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 09 16:50:30 volumio volumio[7987]: info: Reading ALSA contributions from plugins. Jun 09 16:50:30 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:50:30 volumio sudo[11662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:50:30 volumio sudo[11662]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:30 volumio sudo[11662]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 09 16:50:30 volumio volumio[7987]: info: peppymeterbasic Daemon Stop Jun 09 16:50:30 volumio volumio[7987]: info: Asound.conf file written Jun 09 16:50:30 volumio sudo[11669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 09 16:50:30 volumio sudo[11669]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:30 volumio sudo[11669]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:30 volumio volumio[7987]: No state is present for card Headphones Jun 09 16:50:30 volumio volumio[7987]: /usr/share/alsa/init/bcm2835:20: control element not found Jun 09 16:50:30 volumio volumio[7987]: No state is present for card Headphones Jun 09 16:50:30 volumio volumio[7987]: No state is present for card sndrpihifiberry Jun 09 16:50:30 volumio volumio[7987]: Found hardware: "RPi-simple" "" "" "" "" Jun 09 16:50:30 volumio volumio[7987]: Hardware is initialized using a generic method Jun 09 16:50:30 volumio volumio[7987]: No state is present for card sndrpihifiberry Jun 09 16:50:30 volumio volumio[7987]: info: Output device has changed, restarting MPD Jun 09 16:50:30 volumio sudo[11674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 09 16:50:30 volumio sudo[11674]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:30 volumio sudo[11674]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:30 volumio volumio[7987]: info: Output device has changed, restarting Shairport Sync Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 16:50:30 volumio sudo[11677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 09 16:50:30 volumio sudo[11677]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:30 volumio systemd[1]: Stopping Music Player Daemon... Jun 09 16:50:30 volumio volumio[7987]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 09 16:50:30 volumio volumio[7987]: info: Output device has changed, restarting MPD Jun 09 16:50:30 volumio systemd[1]: mpd.service: Succeeded. Jun 09 16:50:30 volumio systemd[1]: Stopped Music Player Daemon. Jun 09 16:50:30 volumio sudo[11684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 09 16:50:30 volumio volumio[7987]: info: Output device has changed, restarting Shairport Sync Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 16:50:30 volumio systemd[1]: Starting Music Player Daemon... Jun 09 16:50:30 volumio sudo[11684]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:30 volumio sudo[11684]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:30 volumio sudo[11687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 09 16:50:30 volumio sudo[11687]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:30 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 09 16:50:30 volumio systemd[1]: mpd.service: Succeeded. Jun 09 16:50:30 volumio systemd[1]: Stopped Music Player Daemon. Jun 09 16:50:30 volumio systemd[1]: Starting Music Player Daemon... Jun 09 16:50:30 volumio volumio[7987]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 09 16:50:30 volumio volumio[7987]: info: MPD Permissions set Jun 09 16:50:30 volumio volumio[7987]: info: MPD Permissions set Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 16:50:30 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:50:31 volumio volumio[7987]: info: Starting Shairport Sync Jun 09 16:50:31 volumio sudo[11692]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 09 16:50:31 volumio sudo[11692]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:31 volumio sudo[11692]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:31 volumio volumio[7987]: info: Starting Shairport Sync Jun 09 16:50:31 volumio sudo[11703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 09 16:50:31 volumio sudo[11703]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:31 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 09 16:50:31 volumio sudo[11707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 09 16:50:31 volumio sudo[11707]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:31 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 09 16:50:31 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 09 16:50:31 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 09 16:50:31 volumio sudo[11703]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:31 volumio volumio[7987]: info: Shairport-Sync Started Jun 09 16:50:31 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 09 16:50:31 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 09 16:50:31 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 09 16:50:31 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 09 16:50:31 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 09 16:50:31 volumio sudo[11707]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:31 volumio volumio[7987]: info: Shairport-Sync Started Jun 09 16:50:31 volumio volumio[7987]: (node:7987) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 09 16:50:31 volumio volumio[7987]: (Use `node --trace-warnings ...` to show where the warning was created) Jun 09 16:50:31 volumio volumio[7987]: (node:7987) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 09 16:50:31 volumio volumio[7987]: (node:7987) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 09 16:50:31 volumio volumio[7987]: (node:7987) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 09 16:50:31 volumio volumio[7987]: (node:7987) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 09 16:50:31 volumio mpd[11704]: Jun 09 16:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 09 16:50:31 volumio systemd[1]: Started Music Player Daemon. Jun 09 16:50:31 volumio sudo[11677]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:31 volumio sudo[11687]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:31 volumio volumio[7987]: error: MPD error: The expression evaluated to a falsy value: Jun 09 16:50:31 volumio volumio[7987]: assert.ok(self.idling) Jun 09 16:50:31 volumio volumio[7987]: error: The expression evaluated to a falsy value: Jun 09 16:50:31 volumio volumio[7987]: assert.ok(self.idling) Jun 09 16:50:31 volumio volumio[7987]: error: updateQueue error: null Jun 09 16:50:34 volumio volumio[7987]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 09 16:50:34 volumio volumio[7987]: info: CoreStateMachine::getcurrentVolume Jun 09 16:50:34 volumio volumio[7987]: info: CoreCommandRouter::volumioRetrievevolume Jun 09 16:50:34 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:34 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:34 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:34 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:50:34 volumio sudo[11734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:50:34 volumio sudo[11734]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:34 volumio sudo[11734]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:34 volumio volumio[7987]: info: peppymeterbasic Daemon Stop Jun 09 16:50:38 volumio volumio[7987]: info: CoreCommandRouter::volumioPlay Jun 09 16:50:38 volumio volumio[7987]: info: CoreStateMachine::play index undefined Jun 09 16:50:38 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:38 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:39 volumio volumio[7987]: info: CoreCommandRouter::volumioPlay Jun 09 16:50:39 volumio volumio[7987]: info: CoreStateMachine::play index undefined Jun 09 16:50:39 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:39 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:40 volumio volumio[7987]: info: CoreCommandRouter::volumioPlay Jun 09 16:50:40 volumio volumio[7987]: info: CoreStateMachine::play index undefined Jun 09 16:50:40 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:40 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:40 volumio volumio[7987]: info: CoreCommandRouter::volumioPlay Jun 09 16:50:40 volumio volumio[7987]: info: CoreStateMachine::play index undefined Jun 09 16:50:40 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:40 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:41 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 09 16:50:41 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 09 16:50:41 volumio volumio[7987]: info: Discovery: Getting this device information Jun 09 16:50:41 volumio volumio[7987]: info: CoreCommandRouter::volumioGetState Jun 09 16:50:41 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:41 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 09 16:50:43 volumio volumio[7987]: info: CoreCommandRouter::volumioPlay Jun 09 16:50:43 volumio volumio[7987]: info: CoreStateMachine::play index undefined Jun 09 16:50:43 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:43 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:46 volumio volumio[7987]: info: Preload queue cleared Jun 09 16:50:46 volumio volumio[7987]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 09 16:50:46 volumio volumio[7987]: info: CoreStateMachine::ClearQueue Jun 09 16:50:46 volumio volumio[7987]: info: CoreStateMachine::stop Jun 09 16:50:46 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:46 volumio volumio[7987]: info: CorePlayQueue::clearPlayQueue Jun 09 16:50:46 volumio volumio[7987]: info: CorePlayQueue::saveQueue Jun 09 16:50:46 volumio volumio[7987]: info: CoreCommandRouter::volumioPushQueue Jun 09 16:50:46 volumio volumio[7987]: info: CoreStateMachine::addQueueItems Jun 09 16:50:46 volumio volumio[7987]: info: CorePlayQueue::addQueueItems Jun 09 16:50:46 volumio volumio[7987]: info: Preload queue cleared Jun 09 16:50:46 volumio volumio[7987]: info: Adding Item to queue: upnp/folder/http://192.168.1.220:50001/ContentDirectory/control@22$5004 Jun 09 16:50:46 volumio volumio[7987]: info: Using cached record of: upnp/folder/http://192.168.1.220:50001/ContentDirectory/control@22$5004 Jun 09 16:50:46 volumio volumio[7987]: info: CoreCommandRouter::volumioPushQueue Jun 09 16:50:47 volumio volumio[7987]: info: CorePlayQueue::saveQueue Jun 09 16:50:47 volumio volumio[7987]: info: CoreStateMachine::updateTrackBlock Jun 09 16:50:47 volumio volumio[7987]: info: CorePlayQueue::getTrackBlock Jun 09 16:50:47 volumio volumio[7987]: info: CoreCommandRouter::volumioPlay Jun 09 16:50:47 volumio volumio[7987]: info: CoreStateMachine::play index 0 Jun 09 16:50:47 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:47 volumio volumio[7987]: info: CoreStateMachine::stop Jun 09 16:50:47 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:47 volumio volumio[7987]: info: CoreStateMachine::play index undefined Jun 09 16:50:47 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:47 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:47 volumio volumio[7987]: info: CoreStateMachine::startPlaybackTimer Jun 09 16:50:47 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:47 volumio volumio[7987]: info: [1749480647002] ControllerUPNPBrowser::clearAddPlayTrack Jun 09 16:50:47 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand stop Jun 09 16:50:47 volumio volumio[7987]: info: sendMpdCommand stop took 3 milliseconds Jun 09 16:50:47 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand clear Jun 09 16:50:47 volumio volumio[7987]: info: Jun 09 16:50:47 volumio volumio[7987]: ---------------------------- MPD announces system playlist update Jun 09 16:50:47 volumio volumio[7987]: info: Ignoring MPD Status Update Jun 09 16:50:47 volumio volumio[7987]: info: sendMpdCommand clear took 1 milliseconds Jun 09 16:50:47 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46864.wav?format=WAV&downsample=false" Jun 09 16:50:47 volumio volumio[7987]: info: Jun 09 16:50:47 volumio volumio[7987]: ---------------------------- MPD announces system playlist update Jun 09 16:50:47 volumio volumio[7987]: info: Ignoring MPD Status Update Jun 09 16:50:47 volumio volumio[7987]: error: updateQueue error: null Jun 09 16:50:47 volumio volumio[7987]: info: ------------------------------ 2ms Jun 09 16:50:47 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46864.wav?format=WAV&downsample=false" Jun 09 16:50:47 volumio volumio[7987]: error: updateQueue error: null Jun 09 16:50:47 volumio volumio[7987]: info: Jun 09 16:50:47 volumio volumio[7987]: ---------------------------- MPD announces system playlist update Jun 09 16:50:47 volumio volumio[7987]: info: Ignoring MPD Status Update Jun 09 16:50:47 volumio volumio[7987]: info: ------------------------------ 67ms Jun 09 16:50:47 volumio volumio[7987]: info: sendMpdCommand add "http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46864.wav?format=WAV&downsample=false" took 1 milliseconds Jun 09 16:50:47 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 09 16:50:47 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand play Jun 09 16:50:47 volumio volumio[7987]: info: ------------------------------ 1ms Jun 09 16:50:47 volumio volumio[7987]: info: Jun 09 16:50:47 volumio volumio[7987]: ---------------------------- MPD announces system playlist update Jun 09 16:50:47 volumio volumio[7987]: info: Ignoring MPD Status Update Jun 09 16:50:47 volumio volumio[7987]: info: sendMpdCommand play took 2 milliseconds Jun 09 16:50:47 volumio volumio[7987]: info: ------------------------------ 1ms Jun 09 16:50:47 volumio volumio[7987]: info: Jun 09 16:50:47 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:50:47 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:50:47 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:50:47 volumio volumio[7987]: info: Jun 09 16:50:47 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:50:47 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:50:47 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:50:47 volumio volumio[7987]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Jun 09 16:50:47 volumio volumio[7987]: info: FusionDsp - ---- read samplerate from file: 96000 Jun 09 16:50:47 volumio volumio[7987]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 09 16:50:47 volumio volumio[7987]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Jun 09 16:50:47 volumio volumio[7987]: info: FusionDsp - ---- read samplerate from file: 96000 Jun 09 16:50:47 volumio volumio[7987]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 09 16:50:47 volumio volumio[7987]: info: Jun 09 16:50:47 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:50:47 volumio volumio[7987]: info: sendMpdCommand status took 23 milliseconds Jun 09 16:50:47 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:50:47 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:50:47 volumio volumio[7987]: info: sendMpdCommand status took 24 milliseconds Jun 09 16:50:47 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:50:47 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:50:47 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:50:47 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:50:47 volumio volumio[7987]: info: Jun 09 16:50:47 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:50:47 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:50:47 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:50:47 volumio volumio[7987]: info: sendMpdCommand status took 2 milliseconds Jun 09 16:50:47 volumio volumio[7987]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 09 16:50:47 volumio volumio[7987]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 09 16:50:47 volumio volumio[7987]: info: sendMpdCommand status took 2 milliseconds Jun 09 16:50:47 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:50:47 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:50:47 volumio volumio[7987]: verbose: ControllerMpd::parseTrackInfo Jun 09 16:50:47 volumio volumio[7987]: verbose: ControllerMpd::parseTrackInfo Jun 09 16:50:47 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:50:47 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:50:47 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:50:47 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:50:47 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:47 volumio volumio[7987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":478,"samplerate":"192 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=46864.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46864.wav?format=WAV&downsample=false","trackType":""} Jun 09 16:50:47 volumio volumio[7987]: verbose: CURRENT POSITION 0 Jun 09 16:50:47 volumio volumio[7987]: info: CoreStateMachine::syncState stateService play Jun 09 16:50:47 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus stop Jun 09 16:50:47 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:50:47 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:50:47 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:47 volumio volumio[7987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":478,"samplerate":"192 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=46864.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46864.wav?format=WAV&downsample=false","trackType":""} Jun 09 16:50:47 volumio volumio[7987]: verbose: CURRENT POSITION 0 Jun 09 16:50:47 volumio volumio[7987]: info: CoreStateMachine::syncState stateService play Jun 09 16:50:47 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus play Jun 09 16:50:47 volumio volumio[7987]: info: Received an update from plugin. extracting info from payload Jun 09 16:50:47 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:47 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:50:47 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:47 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:47 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:47 volumio volumio[7987]: info: ------------------------------ 33ms Jun 09 16:50:47 volumio volumio[7987]: info: ------------------------------ 34ms Jun 09 16:50:47 volumio volumio[7987]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 09 16:50:47 volumio volumio[7987]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 09 16:50:47 volumio volumio[7987]: verbose: ControllerMpd::parseTrackInfo Jun 09 16:50:47 volumio volumio[7987]: verbose: ControllerMpd::parseTrackInfo Jun 09 16:50:47 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:50:47 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:50:47 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:47 volumio volumio[7987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":478,"samplerate":"192 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=46864.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46864.wav?format=WAV&downsample=false","trackType":""} Jun 09 16:50:47 volumio volumio[7987]: verbose: CURRENT POSITION 0 Jun 09 16:50:47 volumio volumio[7987]: info: CoreStateMachine::syncState stateService play Jun 09 16:50:47 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus play Jun 09 16:50:47 volumio volumio[7987]: info: Received an update from plugin. extracting info from payload Jun 09 16:50:47 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:47 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:47 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:47 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:47 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:50:47 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:50:47 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:47 volumio volumio[7987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":478,"samplerate":"192 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=46864.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46864.wav?format=WAV&downsample=false","trackType":""} Jun 09 16:50:47 volumio volumio[7987]: verbose: CURRENT POSITION 0 Jun 09 16:50:47 volumio volumio[7987]: info: CoreStateMachine::syncState stateService play Jun 09 16:50:47 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus play Jun 09 16:50:47 volumio volumio[7987]: info: Received an update from plugin. extracting info from payload Jun 09 16:50:47 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:47 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:47 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:47 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:47 volumio volumio[7987]: info: ------------------------------ 25ms Jun 09 16:50:47 volumio volumio[7987]: info: ------------------------------ 24ms Jun 09 16:50:47 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:50:47 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:50:47 volumio sudo[11758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:50:47 volumio sudo[11758]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:47 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:50:47 volumio sudo[11761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:50:47 volumio sudo[11761]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:47 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:50:47 volumio sudo[11764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:50:47 volumio sudo[11764]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:47 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:50:47 volumio sudo[11767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:50:47 volumio sudo[11767]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:47 volumio systemd[1]: Started peppymeterbasic Daemon. Jun 09 16:50:47 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:50:47 volumio sudo[11758]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:47 volumio sudo[11771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:50:47 volumio sudo[11771]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:47 volumio sudo[11761]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:47 volumio sudo[11767]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:47 volumio sudo[11764]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:47 volumio sudo[11771]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:47 volumio volumio[7987]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 09 16:50:47 volumio volumio[7987]: info: peppymeterbasic Daemon Started Jun 09 16:50:47 volumio volumio[7987]: info: peppymeterbasic Daemon Started Jun 09 16:50:47 volumio volumio[7987]: info: peppymeterbasic Daemon Started Jun 09 16:50:47 volumio volumio[7987]: info: peppymeterbasic Daemon Started Jun 09 16:50:47 volumio volumio[7987]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 09 16:50:47 volumio sudo[11775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:50:47 volumio sudo[11775]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:47 volumio sudo[11775]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:47 volumio volumio[7987]: info: peppymeterbasic Daemon Started Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:48 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:48 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:48 volumio volumio[11768]: pygame 1.9.4.post1 Jun 09 16:50:48 volumio volumio[11768]: Hello from the pygame community. https://www.pygame.org/contribute.html Jun 09 16:50:55 volumio volumio[7987]: info: CoreCommandRouter::volumioNext Jun 09 16:50:55 volumio volumio[7987]: info: CoreStateMachine::next Jun 09 16:50:55 volumio volumio[7987]: info: ControllerMpd::next Jun 09 16:50:55 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand next Jun 09 16:50:55 volumio volumio[7987]: info: Jun 09 16:50:55 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:50:55 volumio volumio[7987]: info: sendMpdCommand next took 4 milliseconds Jun 09 16:50:55 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:50:55 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:50:55 volumio volumio[7987]: info: Jun 09 16:50:55 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:50:55 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:50:55 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:50:55 volumio volumio[7987]: info: sendMpdCommand status took 1 milliseconds Jun 09 16:50:55 volumio volumio[7987]: info: sendMpdCommand status took 1 milliseconds Jun 09 16:50:55 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:50:55 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:50:55 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:50:55 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:50:55 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:55 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:50:55 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:55 volumio volumio[7987]: info: CorePlayQueue::getTrack 0 Jun 09 16:50:55 volumio volumio[7987]: 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} Jun 09 16:50:55 volumio volumio[7987]: verbose: CURRENT POSITION 0 Jun 09 16:50:55 volumio volumio[7987]: info: CoreStateMachine::syncState stateService stop Jun 09 16:50:55 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus play Jun 09 16:50:55 volumio volumio[7987]: info: CoreStateMachine::play index undefined Jun 09 16:50:55 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:50:55 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:55 volumio volumio[7987]: info: CorePlayQueue::getTrack 1 Jun 09 16:50:55 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:50:55 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:55 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:50:55 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:50:55 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:55 volumio volumio[7987]: info: CorePlayQueue::getTrack 1 Jun 09 16:50:55 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:55 volumio volumio[7987]: info: CorePlayQueue::getTrack 1 Jun 09 16:50:55 volumio volumio[7987]: 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} Jun 09 16:50:55 volumio volumio[7987]: verbose: CURRENT POSITION 1 Jun 09 16:50:55 volumio volumio[7987]: info: CoreStateMachine::syncState stateService stop Jun 09 16:50:55 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus stop Jun 09 16:50:55 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:55 volumio volumio[7987]: info: CorePlayQueue::getTrack 1 Jun 09 16:50:55 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:55 volumio volumio[7987]: info: No code Jun 09 16:50:55 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:55 volumio volumio[7987]: info: CorePlayQueue::getTrack 1 Jun 09 16:50:55 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:55 volumio volumio[7987]: info: CorePlayQueue::getTrack 1 Jun 09 16:50:55 volumio volumio[7987]: info: CoreStateMachine::startPlaybackTimer Jun 09 16:50:55 volumio volumio[7987]: info: CorePlayQueue::getTrack 1 Jun 09 16:50:55 volumio volumio[7987]: info: [1749480655732] ControllerUPNPBrowser::clearAddPlayTrack Jun 09 16:50:55 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand stop Jun 09 16:50:55 volumio volumio[7987]: info: ------------------------------ 15ms Jun 09 16:50:55 volumio volumio[7987]: info: ------------------------------ 15ms Jun 09 16:50:55 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:55 volumio volumio[7987]: info: CorePlayQueue::getTrack 1 Jun 09 16:50:55 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:50:55 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:55 volumio volumio[7987]: info: sendMpdCommand stop took 5 milliseconds Jun 09 16:50:55 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand clear Jun 09 16:50:55 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:50:55 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:50:55 volumio sudo[11835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:50:55 volumio sudo[11835]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:55 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:50:55 volumio sudo[11835]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:55 volumio sudo[11838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:50:55 volumio sudo[11838]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:55 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:50:55 volumio systemd[1]: Stopping peppymeterbasic Daemon... Jun 09 16:50:55 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Jun 09 16:50:55 volumio systemd[1]: peppymeterbasic.service: Succeeded. Jun 09 16:50:55 volumio systemd[1]: Stopped peppymeterbasic Daemon. Jun 09 16:50:55 volumio sudo[11838]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:55 volumio sudo[11841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:50:55 volumio sudo[11841]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:55 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:50:55 volumio sudo[11844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:50:55 volumio sudo[11844]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:55 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:50:55 volumio sudo[11841]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:55 volumio sudo[11847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:50:55 volumio sudo[11847]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:55 volumio volumio[7987]: info: Jun 09 16:50:55 volumio volumio[7987]: ---------------------------- MPD announces system playlist update Jun 09 16:50:55 volumio volumio[7987]: info: Ignoring MPD Status Update Jun 09 16:50:55 volumio volumio[7987]: info: sendMpdCommand clear took 147 milliseconds Jun 09 16:50:55 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46861.wav?format=WAV&downsample=false" Jun 09 16:50:55 volumio volumio[7987]: info: Jun 09 16:50:55 volumio volumio[7987]: ---------------------------- MPD announces system playlist update Jun 09 16:50:55 volumio volumio[7987]: info: Ignoring MPD Status Update Jun 09 16:50:55 volumio sudo[11850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:50:55 volumio volumio[7987]: info: peppymeterbasic Daemon Stop Jun 09 16:50:55 volumio volumio[7987]: info: peppymeterbasic Daemon Stop Jun 09 16:50:55 volumio volumio[7987]: info: peppymeterbasic Daemon Started Jun 09 16:50:55 volumio sudo[11850]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:55 volumio volumio[7987]: error: updateQueue error: null Jun 09 16:50:55 volumio volumio[7987]: info: ------------------------------ 6ms Jun 09 16:50:55 volumio sudo[11844]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:55 volumio volumio[7987]: info: peppymeterbasic Daemon Stop Jun 09 16:50:55 volumio sudo[11847]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:55 volumio volumio[7987]: info: peppymeterbasic Daemon Stop Jun 09 16:50:55 volumio sudo[11850]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:55 volumio volumio[7987]: info: peppymeterbasic Daemon Stop Jun 09 16:50:55 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46861.wav?format=WAV&downsample=false" Jun 09 16:50:55 volumio volumio[7987]: error: updateQueue error: null Jun 09 16:50:55 volumio volumio[7987]: info: ------------------------------ 88ms Jun 09 16:50:55 volumio volumio[7987]: info: Jun 09 16:50:55 volumio volumio[7987]: ---------------------------- MPD announces system playlist update Jun 09 16:50:55 volumio volumio[7987]: info: Ignoring MPD Status Update Jun 09 16:50:55 volumio volumio[7987]: info: sendMpdCommand add "http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46861.wav?format=WAV&downsample=false" took 2 milliseconds Jun 09 16:50:55 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 09 16:50:55 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand play Jun 09 16:50:55 volumio volumio[7987]: info: Jun 09 16:50:55 volumio volumio[7987]: ---------------------------- MPD announces system playlist update Jun 09 16:50:55 volumio volumio[7987]: info: Ignoring MPD Status Update Jun 09 16:50:55 volumio volumio[7987]: info: ------------------------------ 3ms Jun 09 16:50:55 volumio volumio[7987]: info: sendMpdCommand play took 2 milliseconds Jun 09 16:50:55 volumio volumio[7987]: info: ------------------------------ 1ms Jun 09 16:50:56 volumio volumio[7987]: info: Jun 09 16:50:56 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:50:56 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:50:56 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:50:56 volumio volumio[7987]: info: Jun 09 16:50:56 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:50:56 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:50:56 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:50:56 volumio volumio[7987]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Jun 09 16:50:56 volumio volumio[7987]: info: FusionDsp - ---- read samplerate from file: 96000 Jun 09 16:50:56 volumio volumio[7987]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 09 16:50:56 volumio volumio[7987]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Jun 09 16:50:56 volumio volumio[7987]: info: FusionDsp - ---- read samplerate from file: 96000 Jun 09 16:50:56 volumio volumio[7987]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 09 16:50:56 volumio volumio[7987]: info: Jun 09 16:50:56 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:50:56 volumio volumio[7987]: info: sendMpdCommand status took 24 milliseconds Jun 09 16:50:56 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:50:56 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:50:56 volumio volumio[7987]: info: sendMpdCommand status took 24 milliseconds Jun 09 16:50:56 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:50:56 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:50:56 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:50:56 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:50:56 volumio volumio[7987]: info: Jun 09 16:50:56 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:50:56 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:50:56 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:50:56 volumio volumio[7987]: info: sendMpdCommand status took 4 milliseconds Jun 09 16:50:56 volumio volumio[7987]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 09 16:50:56 volumio volumio[7987]: info: sendMpdCommand status took 2 milliseconds Jun 09 16:50:56 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:50:56 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:50:56 volumio volumio[7987]: verbose: ControllerMpd::parseTrackInfo Jun 09 16:50:56 volumio volumio[7987]: verbose: ControllerMpd::parseTrackInfo Jun 09 16:50:56 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:50:56 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:50:56 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:50:56 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:50:56 volumio volumio[7987]: info: CorePlayQueue::getTrack 1 Jun 09 16:50:56 volumio volumio[7987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":170,"samplerate":"192 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=46861.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46861.wav?format=WAV&downsample=false","trackType":""} Jun 09 16:50:56 volumio volumio[7987]: verbose: CURRENT POSITION 1 Jun 09 16:50:56 volumio volumio[7987]: info: CoreStateMachine::syncState stateService play Jun 09 16:50:56 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus stop Jun 09 16:50:56 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:50:56 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:50:56 volumio volumio[7987]: info: CorePlayQueue::getTrack 1 Jun 09 16:50:56 volumio volumio[7987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":170,"samplerate":"192 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=46861.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46861.wav?format=WAV&downsample=false","trackType":""} Jun 09 16:50:56 volumio volumio[7987]: verbose: CURRENT POSITION 1 Jun 09 16:50:56 volumio volumio[7987]: info: CoreStateMachine::syncState stateService play Jun 09 16:50:56 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus play Jun 09 16:50:56 volumio volumio[7987]: info: Received an update from plugin. extracting info from payload Jun 09 16:50:56 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:56 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:50:56 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:56 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:56 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:56 volumio volumio[7987]: info: ------------------------------ 35ms Jun 09 16:50:56 volumio volumio[7987]: info: ------------------------------ 37ms Jun 09 16:50:56 volumio volumio[7987]: error: FusionDsp - WebSocket error: [object Object] Jun 09 16:50:56 volumio volumio[7987]: error: FusionDsp - WebSocket error: [object Object] Jun 09 16:50:56 volumio volumio[7987]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 09 16:50:56 volumio volumio[7987]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 09 16:50:56 volumio volumio[7987]: verbose: ControllerMpd::parseTrackInfo Jun 09 16:50:56 volumio volumio[7987]: verbose: ControllerMpd::parseTrackInfo Jun 09 16:50:56 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:50:56 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:50:56 volumio volumio[7987]: info: CorePlayQueue::getTrack 1 Jun 09 16:50:56 volumio volumio[7987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":170,"samplerate":"192 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=46861.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46861.wav?format=WAV&downsample=false","trackType":""} Jun 09 16:50:56 volumio volumio[7987]: verbose: CURRENT POSITION 1 Jun 09 16:50:56 volumio volumio[7987]: info: CoreStateMachine::syncState stateService play Jun 09 16:50:56 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus play Jun 09 16:50:56 volumio volumio[7987]: info: Received an update from plugin. extracting info from payload Jun 09 16:50:56 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:56 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:56 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:56 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:56 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:50:56 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:50:56 volumio volumio[7987]: info: CorePlayQueue::getTrack 1 Jun 09 16:50:56 volumio volumio[7987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":170,"samplerate":"192 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=46861.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46861.wav?format=WAV&downsample=false","trackType":""} Jun 09 16:50:56 volumio volumio[7987]: verbose: CURRENT POSITION 1 Jun 09 16:50:56 volumio volumio[7987]: info: CoreStateMachine::syncState stateService play Jun 09 16:50:56 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus play Jun 09 16:50:56 volumio volumio[7987]: info: Received an update from plugin. extracting info from payload Jun 09 16:50:56 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:56 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:56 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:50:56 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:50:56 volumio volumio[7987]: info: ------------------------------ 30ms Jun 09 16:50:56 volumio volumio[7987]: info: ------------------------------ 27ms Jun 09 16:50:56 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:50:56 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:50:56 volumio sudo[11856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:50:56 volumio sudo[11856]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:56 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:50:56 volumio sudo[11859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:50:56 volumio sudo[11859]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:56 volumio systemd[1]: Started peppymeterbasic Daemon. Jun 09 16:50:56 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:50:56 volumio sudo[11856]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:56 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:50:56 volumio sudo[11863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:50:56 volumio sudo[11863]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:56 volumio sudo[11859]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:56 volumio sudo[11866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:50:56 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:50:56 volumio sudo[11866]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:56 volumio sudo[11863]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:56 volumio sudo[11866]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:56 volumio sudo[11869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:50:56 volumio sudo[11869]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:56 volumio sudo[11869]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:56 volumio sudo[11872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:50:56 volumio sudo[11872]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:50:56 volumio volumio[7987]: info: peppymeterbasic Daemon Started Jun 09 16:50:56 volumio volumio[7987]: info: peppymeterbasic Daemon Started Jun 09 16:50:56 volumio volumio[7987]: info: peppymeterbasic Daemon Started Jun 09 16:50:56 volumio volumio[7987]: info: peppymeterbasic Daemon Started Jun 09 16:50:56 volumio sudo[11872]: pam_unix(sudo:session): session closed for user root Jun 09 16:50:56 volumio volumio[7987]: info: peppymeterbasic Daemon Started Jun 09 16:50:56 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:56 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:56 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:56 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:56 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:56 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:56 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:56 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:56 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:56 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:56 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:56 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:50:57 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:50:57 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:50:57 volumio volumio[11861]: pygame 1.9.4.post1 Jun 09 16:50:57 volumio volumio[11861]: Hello from the pygame community. https://www.pygame.org/contribute.html Jun 09 16:51:00 volumio volumio[7987]: info: CoreCommandRouter::volumioNext Jun 09 16:51:00 volumio volumio[7987]: info: CoreStateMachine::next Jun 09 16:51:00 volumio volumio[7987]: info: ControllerMpd::next Jun 09 16:51:00 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand next Jun 09 16:51:00 volumio volumio[7987]: info: Jun 09 16:51:00 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:51:00 volumio volumio[7987]: info: sendMpdCommand next took 10 milliseconds Jun 09 16:51:00 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:51:00 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:51:00 volumio volumio[7987]: info: Jun 09 16:51:00 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:51:00 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:51:00 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:51:00 volumio volumio[7987]: info: sendMpdCommand status took 1 milliseconds Jun 09 16:51:00 volumio volumio[7987]: info: sendMpdCommand status took 1 milliseconds Jun 09 16:51:00 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:51:00 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:51:00 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:51:00 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:51:00 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:00 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:51:00 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:00 volumio volumio[7987]: info: CorePlayQueue::getTrack 1 Jun 09 16:51:00 volumio volumio[7987]: 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} Jun 09 16:51:00 volumio volumio[7987]: verbose: CURRENT POSITION 1 Jun 09 16:51:00 volumio volumio[7987]: info: CoreStateMachine::syncState stateService stop Jun 09 16:51:00 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus play Jun 09 16:51:00 volumio volumio[7987]: info: CoreStateMachine::play index undefined Jun 09 16:51:00 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:51:00 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:00 volumio volumio[7987]: info: CorePlayQueue::getTrack 2 Jun 09 16:51:00 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:51:00 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:00 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:51:00 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:51:00 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:00 volumio volumio[7987]: info: CorePlayQueue::getTrack 2 Jun 09 16:51:00 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:00 volumio volumio[7987]: info: CorePlayQueue::getTrack 2 Jun 09 16:51:00 volumio volumio[7987]: 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} Jun 09 16:51:00 volumio volumio[7987]: verbose: CURRENT POSITION 2 Jun 09 16:51:00 volumio volumio[7987]: info: CoreStateMachine::syncState stateService stop Jun 09 16:51:00 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus stop Jun 09 16:51:00 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:00 volumio volumio[7987]: info: CorePlayQueue::getTrack 2 Jun 09 16:51:00 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:00 volumio volumio[7987]: info: No code Jun 09 16:51:00 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:00 volumio volumio[7987]: info: CorePlayQueue::getTrack 2 Jun 09 16:51:00 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:00 volumio volumio[7987]: info: CorePlayQueue::getTrack 2 Jun 09 16:51:00 volumio volumio[7987]: info: CoreStateMachine::startPlaybackTimer Jun 09 16:51:00 volumio volumio[7987]: info: CorePlayQueue::getTrack 2 Jun 09 16:51:00 volumio volumio[7987]: info: [1749480660493] ControllerUPNPBrowser::clearAddPlayTrack Jun 09 16:51:00 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand stop Jun 09 16:51:00 volumio volumio[7987]: info: ------------------------------ 15ms Jun 09 16:51:00 volumio volumio[7987]: info: ------------------------------ 15ms Jun 09 16:51:00 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:00 volumio volumio[7987]: info: CorePlayQueue::getTrack 2 Jun 09 16:51:00 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:51:00 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:00 volumio volumio[7987]: info: sendMpdCommand stop took 5 milliseconds Jun 09 16:51:00 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand clear Jun 09 16:51:00 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:51:00 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:51:00 volumio sudo[11886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:51:00 volumio sudo[11886]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:00 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:51:00 volumio sudo[11889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:51:00 volumio sudo[11889]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:00 volumio sudo[11886]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:00 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:51:00 volumio sudo[11891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:51:00 volumio sudo[11891]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:00 volumio systemd[1]: Stopping peppymeterbasic Daemon... Jun 09 16:51:00 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Jun 09 16:51:00 volumio systemd[1]: peppymeterbasic.service: Succeeded. Jun 09 16:51:00 volumio systemd[1]: Stopped peppymeterbasic Daemon. Jun 09 16:51:00 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:51:00 volumio sudo[11895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:51:00 volumio sudo[11895]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:00 volumio sudo[11889]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:00 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:51:00 volumio sudo[11898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:51:00 volumio sudo[11898]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:00 volumio sudo[11891]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:00 volumio sudo[11895]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:00 volumio volumio[7987]: info: Jun 09 16:51:00 volumio volumio[7987]: ---------------------------- MPD announces system playlist update Jun 09 16:51:00 volumio volumio[7987]: info: Ignoring MPD Status Update Jun 09 16:51:00 volumio volumio[7987]: info: sendMpdCommand clear took 190 milliseconds Jun 09 16:51:00 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46860.wav?format=WAV&downsample=false" Jun 09 16:51:00 volumio volumio[7987]: info: Jun 09 16:51:00 volumio volumio[7987]: ---------------------------- MPD announces system playlist update Jun 09 16:51:00 volumio volumio[7987]: info: Ignoring MPD Status Update Jun 09 16:51:00 volumio volumio[7987]: info: peppymeterbasic Daemon Stop Jun 09 16:51:00 volumio volumio[7987]: info: peppymeterbasic Daemon Stop Jun 09 16:51:00 volumio volumio[7987]: info: peppymeterbasic Daemon Stop Jun 09 16:51:00 volumio volumio[7987]: info: peppymeterbasic Daemon Started Jun 09 16:51:00 volumio sudo[11898]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:00 volumio sudo[11901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:51:00 volumio sudo[11901]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:00 volumio volumio[7987]: info: peppymeterbasic Daemon Stop Jun 09 16:51:00 volumio sudo[11901]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:00 volumio volumio[7987]: info: peppymeterbasic Daemon Stop Jun 09 16:51:00 volumio volumio[7987]: error: updateQueue error: null Jun 09 16:51:00 volumio volumio[7987]: error: updateQueue error: null Jun 09 16:51:00 volumio volumio[7987]: info: ------------------------------ 131ms Jun 09 16:51:00 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46860.wav?format=WAV&downsample=false" Jun 09 16:51:00 volumio volumio[7987]: info: ------------------------------ 129ms Jun 09 16:51:00 volumio volumio[7987]: info: Jun 09 16:51:00 volumio volumio[7987]: ---------------------------- MPD announces system playlist update Jun 09 16:51:00 volumio volumio[7987]: info: Ignoring MPD Status Update Jun 09 16:51:00 volumio volumio[7987]: info: sendMpdCommand add "http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46860.wav?format=WAV&downsample=false" took 1 milliseconds Jun 09 16:51:00 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 09 16:51:00 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand play Jun 09 16:51:00 volumio volumio[7987]: info: Jun 09 16:51:00 volumio volumio[7987]: ---------------------------- MPD announces system playlist update Jun 09 16:51:00 volumio volumio[7987]: info: Ignoring MPD Status Update Jun 09 16:51:00 volumio volumio[7987]: info: ------------------------------ 2ms Jun 09 16:51:00 volumio volumio[7987]: info: sendMpdCommand play took 1 milliseconds Jun 09 16:51:00 volumio volumio[7987]: info: ------------------------------ 2ms Jun 09 16:51:01 volumio volumio[7987]: info: Jun 09 16:51:01 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:51:01 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:51:01 volumio volumio[7987]: info: Jun 09 16:51:01 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:51:01 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:51:01 volumio volumio[7987]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Jun 09 16:51:01 volumio volumio[7987]: info: FusionDsp - ---- read samplerate from file: 96000 Jun 09 16:51:01 volumio volumio[7987]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 09 16:51:01 volumio volumio[7987]: info: Jun 09 16:51:01 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:51:01 volumio volumio[7987]: info: sendMpdCommand status took 18 milliseconds Jun 09 16:51:01 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:51:01 volumio volumio[7987]: info: sendMpdCommand status took 18 milliseconds Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:51:01 volumio volumio[7987]: info: Jun 09 16:51:01 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:51:01 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:51:01 volumio volumio[7987]: info: sendMpdCommand status took 2 milliseconds Jun 09 16:51:01 volumio volumio[7987]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 09 16:51:01 volumio volumio[7987]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 09 16:51:01 volumio volumio[7987]: info: sendMpdCommand status took 2 milliseconds Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::parseTrackInfo Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::parseTrackInfo Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:51:01 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:51:01 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:51:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 2 Jun 09 16:51:01 volumio volumio[7987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"192 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=46860.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46860.wav?format=WAV&downsample=false","trackType":""} Jun 09 16:51:01 volumio volumio[7987]: verbose: CURRENT POSITION 2 Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::syncState stateService play Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus stop Jun 09 16:51:01 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:51:01 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:51:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 2 Jun 09 16:51:01 volumio volumio[7987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"192 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=46860.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46860.wav?format=WAV&downsample=false","trackType":""} Jun 09 16:51:01 volumio volumio[7987]: verbose: CURRENT POSITION 2 Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::syncState stateService play Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus play Jun 09 16:51:01 volumio volumio[7987]: info: Received an update from plugin. extracting info from payload Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:01 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:51:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:01 volumio volumio[7987]: info: ------------------------------ 28ms Jun 09 16:51:01 volumio volumio[7987]: info: ------------------------------ 30ms Jun 09 16:51:01 volumio volumio[7987]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 09 16:51:01 volumio volumio[7987]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::parseTrackInfo Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::parseTrackInfo Jun 09 16:51:01 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:51:01 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:51:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 2 Jun 09 16:51:01 volumio volumio[7987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"192 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=46860.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46860.wav?format=WAV&downsample=false","trackType":""} Jun 09 16:51:01 volumio volumio[7987]: verbose: CURRENT POSITION 2 Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::syncState stateService play Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus play Jun 09 16:51:01 volumio volumio[7987]: info: Received an update from plugin. extracting info from payload Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:01 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:51:01 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:51:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 2 Jun 09 16:51:01 volumio volumio[7987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"192 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=46860.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46860.wav?format=WAV&downsample=false","trackType":""} Jun 09 16:51:01 volumio volumio[7987]: verbose: CURRENT POSITION 2 Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::syncState stateService play Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus play Jun 09 16:51:01 volumio volumio[7987]: info: Received an update from plugin. extracting info from payload Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:01 volumio volumio[7987]: info: ------------------------------ 24ms Jun 09 16:51:01 volumio volumio[7987]: info: ------------------------------ 22ms Jun 09 16:51:01 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:51:01 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:51:01 volumio sudo[11914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:51:01 volumio sudo[11914]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:01 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:51:01 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:51:01 volumio sudo[11917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:51:01 volumio sudo[11917]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:01 volumio systemd[1]: Started peppymeterbasic Daemon. Jun 09 16:51:01 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:51:01 volumio sudo[11920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:51:01 volumio sudo[11920]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:01 volumio sudo[11924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:51:01 volumio sudo[11924]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:01 volumio sudo[11914]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:01 volumio sudo[11924]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:01 volumio sudo[11920]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:01 volumio sudo[11917]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:01 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:51:01 volumio sudo[11928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:51:01 volumio sudo[11928]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:01 volumio sudo[11928]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:01 volumio sudo[11931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:51:01 volumio sudo[11931]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:01 volumio volumio[7987]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 09 16:51:01 volumio volumio[7987]: info: peppymeterbasic Daemon Started Jun 09 16:51:01 volumio sudo[11931]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:01 volumio volumio[7987]: info: peppymeterbasic Daemon Started Jun 09 16:51:01 volumio volumio[7987]: info: CoreCommandRouter::volumioNext Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::next Jun 09 16:51:01 volumio volumio[7987]: info: ControllerMpd::next Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand next Jun 09 16:51:01 volumio volumio[7987]: info: Jun 09 16:51:01 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:51:01 volumio volumio[7987]: info: sendMpdCommand next took 4 milliseconds Jun 09 16:51:01 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:51:01 volumio volumio[7987]: info: Jun 09 16:51:01 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:51:01 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:51:01 volumio volumio[7987]: info: sendMpdCommand status took 2 milliseconds Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:51:01 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:51:01 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:01 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:51:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 2 Jun 09 16:51:01 volumio volumio[7987]: 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} Jun 09 16:51:01 volumio volumio[7987]: verbose: CURRENT POSITION 2 Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::syncState stateService stop Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus play Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::play index undefined Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 3 Jun 09 16:51:01 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:51:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 3 Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::startPlaybackTimer Jun 09 16:51:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 3 Jun 09 16:51:01 volumio volumio[7987]: info: [1749480661709] ControllerUPNPBrowser::clearAddPlayTrack Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand stop Jun 09 16:51:01 volumio volumio[7987]: info: ------------------------------ 12ms Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 3 Jun 09 16:51:01 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:51:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:01 volumio volumio[7987]: info: sendMpdCommand status took 17 milliseconds Jun 09 16:51:01 volumio volumio[7987]: info: sendMpdCommand stop took 7 milliseconds Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand clear Jun 09 16:51:01 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:51:01 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 3 Jun 09 16:51:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 3 Jun 09 16:51:01 volumio volumio[7987]: 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} Jun 09 16:51:01 volumio volumio[7987]: verbose: CURRENT POSITION 3 Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::syncState stateService stop Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus stop Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 3 Jun 09 16:51:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:01 volumio volumio[7987]: info: No code Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:01 volumio volumio[7987]: info: CorePlayQueue::getTrack 3 Jun 09 16:51:01 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:01 volumio volumio[7987]: info: ------------------------------ 30ms Jun 09 16:51:01 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:51:01 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:51:01 volumio sudo[11934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:51:01 volumio sudo[11934]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:01 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:51:01 volumio sudo[11937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:51:01 volumio sudo[11937]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:01 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:51:01 volumio sudo[11934]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:01 volumio sudo[11939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:51:01 volumio sudo[11939]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:01 volumio systemd[1]: Stopping peppymeterbasic Daemon... Jun 09 16:51:01 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Jun 09 16:51:01 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:51:01 volumio systemd[1]: peppymeterbasic.service: Succeeded. Jun 09 16:51:01 volumio systemd[1]: Stopped peppymeterbasic Daemon. Jun 09 16:51:01 volumio sudo[11943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:51:01 volumio sudo[11943]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:01 volumio sudo[11937]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:01 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 09 16:51:01 volumio sudo[11946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:51:01 volumio sudo[11946]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:01 volumio sudo[11939]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:01 volumio sudo[11943]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:01 volumio volumio[7987]: info: Jun 09 16:51:01 volumio volumio[7987]: ---------------------------- MPD announces system playlist update Jun 09 16:51:01 volumio volumio[7987]: info: Ignoring MPD Status Update Jun 09 16:51:01 volumio volumio[7987]: info: sendMpdCommand clear took 178 milliseconds Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46862.wav?format=WAV&downsample=false" Jun 09 16:51:01 volumio sudo[11949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 09 16:51:01 volumio sudo[11949]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:01 volumio volumio[7987]: info: Jun 09 16:51:01 volumio volumio[7987]: ---------------------------- MPD announces system playlist update Jun 09 16:51:01 volumio volumio[7987]: info: Ignoring MPD Status Update Jun 09 16:51:01 volumio volumio[7987]: info: peppymeterbasic Daemon Stop Jun 09 16:51:01 volumio volumio[7987]: info: peppymeterbasic Daemon Started Jun 09 16:51:01 volumio volumio[7987]: error: updateQueue error: null Jun 09 16:51:01 volumio volumio[7987]: info: ------------------------------ 17ms Jun 09 16:51:01 volumio sudo[11946]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:01 volumio volumio[7987]: info: peppymeterbasic Daemon Stop Jun 09 16:51:01 volumio sudo[11949]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:01 volumio volumio[7987]: info: peppymeterbasic Daemon Stop Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46862.wav?format=WAV&downsample=false" Jun 09 16:51:01 volumio volumio[7987]: error: updateQueue error: null Jun 09 16:51:01 volumio volumio[7987]: info: Jun 09 16:51:01 volumio volumio[7987]: ---------------------------- MPD announces system playlist update Jun 09 16:51:01 volumio volumio[7987]: info: Ignoring MPD Status Update Jun 09 16:51:01 volumio volumio[7987]: info: ------------------------------ 78ms Jun 09 16:51:01 volumio volumio[7987]: info: sendMpdCommand add "http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46862.wav?format=WAV&downsample=false" took 1 milliseconds Jun 09 16:51:01 volumio volumio[7987]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 09 16:51:01 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand play Jun 09 16:51:01 volumio volumio[7987]: info: ------------------------------ 1ms Jun 09 16:51:01 volumio volumio[7987]: info: Jun 09 16:51:01 volumio volumio[7987]: ---------------------------- MPD announces system playlist update Jun 09 16:51:01 volumio volumio[7987]: info: Ignoring MPD Status Update Jun 09 16:51:01 volumio volumio[7987]: info: sendMpdCommand play took 1 milliseconds Jun 09 16:51:01 volumio volumio[7987]: info: ------------------------------ 1ms Jun 09 16:51:02 volumio volumio[7987]: info: Jun 09 16:51:02 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:51:02 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:51:02 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:51:02 volumio volumio[7987]: info: Jun 09 16:51:02 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:51:02 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:51:02 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:51:02 volumio volumio[7987]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Jun 09 16:51:02 volumio volumio[7987]: info: FusionDsp - ---- read samplerate from file: 96000 Jun 09 16:51:02 volumio volumio[7987]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 09 16:51:02 volumio volumio[7987]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Jun 09 16:51:02 volumio volumio[7987]: info: FusionDsp - ---- read samplerate from file: 96000 Jun 09 16:51:02 volumio volumio[7987]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 09 16:51:02 volumio volumio[7987]: info: Jun 09 16:51:02 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:51:02 volumio volumio[7987]: info: sendMpdCommand status took 31 milliseconds Jun 09 16:51:02 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:51:02 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:51:02 volumio volumio[7987]: info: sendMpdCommand status took 22 milliseconds Jun 09 16:51:02 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:51:02 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:51:02 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:51:02 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:51:02 volumio volumio[7987]: info: Jun 09 16:51:02 volumio volumio[7987]: ---------------------------- MPD announces state update: player Jun 09 16:51:02 volumio volumio[7987]: info: ControllerMpd::getState Jun 09 16:51:02 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:51:02 volumio volumio[7987]: info: sendMpdCommand status took 3 milliseconds Jun 09 16:51:02 volumio volumio[7987]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 09 16:51:02 volumio volumio[7987]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 09 16:51:02 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:51:02 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:51:02 volumio volumio[7987]: verbose: ControllerMpd::parseTrackInfo Jun 09 16:51:02 volumio volumio[7987]: verbose: ControllerMpd::parseTrackInfo Jun 09 16:51:02 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:51:02 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:51:02 volumio volumio[7987]: info: CorePlayQueue::getTrack 3 Jun 09 16:51:02 volumio volumio[7987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":356,"samplerate":"192 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=46862.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46862.wav?format=WAV&downsample=false","trackType":""} Jun 09 16:51:02 volumio volumio[7987]: verbose: CURRENT POSITION 3 Jun 09 16:51:02 volumio volumio[7987]: info: CoreStateMachine::syncState stateService play Jun 09 16:51:02 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus stop Jun 09 16:51:02 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:51:02 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:51:02 volumio volumio[7987]: info: CorePlayQueue::getTrack 3 Jun 09 16:51:02 volumio volumio[7987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":356,"samplerate":"192 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=46862.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46862.wav?format=WAV&downsample=false","trackType":""} Jun 09 16:51:02 volumio volumio[7987]: verbose: CURRENT POSITION 3 Jun 09 16:51:02 volumio volumio[7987]: info: CoreStateMachine::syncState stateService play Jun 09 16:51:02 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus play Jun 09 16:51:02 volumio volumio[7987]: info: Received an update from plugin. extracting info from payload Jun 09 16:51:02 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:02 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:51:02 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:02 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:02 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:02 volumio volumio[7987]: info: ------------------------------ 40ms Jun 09 16:51:02 volumio volumio[7987]: info: ------------------------------ 35ms Jun 09 16:51:02 volumio volumio[7987]: info: sendMpdCommand status took 11 milliseconds Jun 09 16:51:02 volumio volumio[7987]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 09 16:51:02 volumio volumio[7987]: verbose: ControllerMpd::parseState Jun 09 16:51:02 volumio volumio[7987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:51:02 volumio volumio[7987]: verbose: ControllerMpd::parseTrackInfo Jun 09 16:51:02 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:51:02 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:51:02 volumio volumio[7987]: info: CorePlayQueue::getTrack 3 Jun 09 16:51:02 volumio volumio[7987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":356,"samplerate":"192 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=46862.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46862.wav?format=WAV&downsample=false","trackType":""} Jun 09 16:51:02 volumio volumio[7987]: verbose: CURRENT POSITION 3 Jun 09 16:51:02 volumio volumio[7987]: info: CoreStateMachine::syncState stateService play Jun 09 16:51:02 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus play Jun 09 16:51:02 volumio volumio[7987]: info: Received an update from plugin. extracting info from payload Jun 09 16:51:02 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:02 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:02 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:02 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:02 volumio volumio[7987]: info: ------------------------------ 21ms Jun 09 16:51:02 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:51:02 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:51:02 volumio sudo[11963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:51:02 volumio sudo[11963]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:02 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:51:02 volumio sudo[11965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:51:02 volumio sudo[11965]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:02 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:51:02 volumio sudo[11969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:51:02 volumio sudo[11969]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:02 volumio volumio[7987]: info: sendMpdCommand playlistinfo took 134 milliseconds Jun 09 16:51:02 volumio volumio[7987]: verbose: ControllerMpd::parseTrackInfo Jun 09 16:51:02 volumio volumio[7987]: info: ControllerMpd::pushState Jun 09 16:51:02 volumio volumio[7987]: info: CoreCommandRouter::servicePushState Jun 09 16:51:02 volumio volumio[7987]: info: CorePlayQueue::getTrack 3 Jun 09 16:51:02 volumio volumio[7987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":356,"samplerate":"192 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=46862.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.1.220:50002/transcoder/genericoder.cgi/id=46862.wav?format=WAV&downsample=false","trackType":""} Jun 09 16:51:02 volumio volumio[7987]: verbose: CURRENT POSITION 3 Jun 09 16:51:02 volumio volumio[7987]: info: CoreStateMachine::syncState stateService play Jun 09 16:51:02 volumio volumio[7987]: info: CoreStateMachine::syncState currentStatus play Jun 09 16:51:02 volumio volumio[7987]: info: Received an update from plugin. extracting info from payload Jun 09 16:51:02 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:02 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:02 volumio volumio[7987]: info: CoreStateMachine::pushState Jun 09 16:51:02 volumio volumio[7987]: info: CoreCommandRouter::volumioPushState Jun 09 16:51:02 volumio volumio[7987]: info: ------------------------------ 163ms Jun 09 16:51:02 volumio volumio[7987]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 09 16:51:02 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:51:02 volumio systemd[1]: Started peppymeterbasic Daemon. Jun 09 16:51:02 volumio sudo[11973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:51:02 volumio sudo[11973]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:02 volumio sudo[11963]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:02 volumio sudo[11965]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:02 volumio volumio[7987]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 09 16:51:02 volumio sudo[11969]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:02 volumio sudo[11973]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:02 volumio sudo[11977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:51:02 volumio sudo[11977]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:02 volumio volumio[7987]: info: peppymeterbasic Daemon Started Jun 09 16:51:02 volumio sudo[11980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 09 16:51:02 volumio sudo[11980]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:51:02 volumio sudo[11977]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:02 volumio volumio[7987]: info: peppymeterbasic Daemon Started Jun 09 16:51:02 volumio sudo[11980]: pam_unix(sudo:session): session closed for user root Jun 09 16:51:02 volumio volumio[7987]: info: peppymeterbasic Daemon Started Jun 09 16:51:03 volumio kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Jun 09 16:51:03 volumio kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Jun 09 16:51:03 volumio kernel: bcm2708_fb_pan_display(0,0) returns=-22 Jun 09 16:51:03 volumio volumio[11970]: pygame 1.9.4.post1 Jun 09 16:51:03 volumio volumio[11970]: Hello from the pygame community. https://www.pygame.org/contribute.html Jun 09 16:51:11 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 09 16:51:11 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 09 16:51:11 volumio volumio[7987]: info: Discovery: Getting this device information Jun 09 16:51:11 volumio volumio[7987]: info: CoreCommandRouter::volumioGetState Jun 09 16:51:11 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 09 16:51:29 volumio kernel: usb 1-1.2: new full-speed USB device number 6 using xhci_hcd Jun 09 16:51:29 volumio kernel: usb 1-1.2: New USB device found, idVendor=0451, idProduct=0003, bcdDevice= 1.02 Jun 09 16:51:29 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jun 09 16:51:29 volumio kernel: usb 1-1.2: Product: SMSL M-3 Desktop DAC Jun 09 16:51:29 volumio kernel: usb 1-1.2: Manufacturer: S.M.S.L Audio Jun 09 16:51:29 volumio kernel: usb 1-1.2: SerialNumber: (C) 2015 S.M.S.L Audio, ltd @SZ Jun 09 16:51:29 volumio kernel: usb 1-1.2: 1:1: cannot get freq at ep 0x1 Jun 09 16:51:29 volumio systemd-udevd[12033]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Jun 09 16:51:29 volumio volumio[7987]: info: Jun 09 16:51:29 volumio volumio[7987]: ---------------------------- USB Audio Device Attached Jun 09 16:51:29 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jun 09 16:51:36 volumio volumio[7987]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 09 16:51:36 volumio volumio[7987]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 16:51:36 volumio volumio[7987]: TypeError: Cannot read property 'length' of undefined Jun 09 16:51:36 volumio volumio[7987]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 09 16:51:36 volumio volumio[7987]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 09 16:51:36 volumio volumio[7987]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 09 16:51:36 volumio volumio[7987]: at Parser.emit (events.js:315:20) Jun 09 16:51:36 volumio volumio[7987]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 09 16:51:36 volumio volumio[7987]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 09 16:51:36 volumio volumio[7987]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 09 16:51:36 volumio volumio[7987]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 09 16:51:36 volumio volumio[7987]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 09 16:51:36 volumio volumio[7987]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 09 16:51:36 volumio volumio[7987]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 09 16:51:36 volumio volumio[7987]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 09 16:51:36 volumio volumio[7987]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 09 16:51:36 volumio volumio[7987]: at IncomingMessage.emit (events.js:327:22) Jun 09 16:51:36 volumio volumio[7987]: at endReadableNT (internal/streams/readable.js:1327:12) Jun 09 16:51:36 volumio volumio[7987]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 09 16:51:36 volumio volumio[7987]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 16:51:36 volumio sudo[12064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-09 16:50 Jun 09 16:51:36 volumio sudo[12064]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"