-- Logs begin at Thu 2019-02-14 21:11:59 AEDT, end at Mon 2025-09-15 19:12:25 AEST. -- Sep 15 19:11:14 stream-7 volumio[994]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Sep 15 19:11:14 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Sep 15 19:11:14 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Sep 15 19:11:14 stream-7 sudo[1881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 19:11:14 stream-7 sudo[1881]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:11:14 stream-7 sudo[1881]: pam_unix(sudo:session): session closed for user root Sep 15 19:11:14 stream-7 sudo[1883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 19:11:14 stream-7 sudo[1883]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:11:14 stream-7 volumio[994]: info: MPD Permissions set Sep 15 19:11:14 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:11:14 stream-7 systemd[1]: Stopping Music Player Daemon... Sep 15 19:11:14 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:11:14 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:11:14 stream-7 systemd[1]: mpd.service: Succeeded. Sep 15 19:11:14 stream-7 systemd[1]: Stopped Music Player Daemon. Sep 15 19:11:14 stream-7 systemd[1]: Starting Music Player Daemon... Sep 15 19:11:14 stream-7 sudo[1890]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 19:11:14 stream-7 sudo[1890]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:11:14 stream-7 sudo[1890]: pam_unix(sudo:session): session closed for user root Sep 15 19:11:16 stream-7 mpd[1892]: Sep 15 19:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 15 19:11:16 stream-7 systemd[1]: Started Music Player Daemon. Sep 15 19:11:16 stream-7 sudo[1883]: pam_unix(sudo:session): session closed for user root Sep 15 19:11:16 stream-7 volumio[994]: error: updateQueue error: null Sep 15 19:11:24 stream-7 volumio[994]: info: CoreCommandRouter::volumioPlay Sep 15 19:11:24 stream-7 volumio[994]: info: CoreStateMachine::play index undefined Sep 15 19:11:24 stream-7 volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 19:11:24 stream-7 volumio[994]: info: CorePlayQueue::getTrack 1 Sep 15 19:11:24 stream-7 volumio[994]: info: CoreStateMachine::startPlaybackTimer Sep 15 19:11:24 stream-7 volumio[994]: info: CorePlayQueue::getTrack 1 Sep 15 19:11:24 stream-7 volumio[994]: info: ControllerMpd::resume Sep 15 19:11:24 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand play Sep 15 19:11:24 stream-7 volumio[994]: info: sendMpdCommand play took 1 milliseconds Sep 15 19:11:29 stream-7 volumio[994]: info: CoreCommandRouter::volumioPlay Sep 15 19:11:29 stream-7 volumio[994]: info: CoreStateMachine::play index undefined Sep 15 19:11:29 stream-7 volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 19:11:29 stream-7 volumio[994]: info: CorePlayQueue::getTrack 1 Sep 15 19:11:29 stream-7 volumio[994]: info: CoreStateMachine::startPlaybackTimer Sep 15 19:11:29 stream-7 volumio[994]: info: CorePlayQueue::getTrack 1 Sep 15 19:11:29 stream-7 volumio[994]: info: ControllerMpd::resume Sep 15 19:11:29 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand play Sep 15 19:11:29 stream-7 volumio[994]: info: sendMpdCommand play took 2 milliseconds Sep 15 19:11:30 stream-7 volumio[994]: info: CoreCommandRouter::volumioNext Sep 15 19:11:30 stream-7 volumio[994]: info: CoreStateMachine::next Sep 15 19:11:30 stream-7 volumio[994]: info: CoreStateMachine::stop Sep 15 19:11:30 stream-7 volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 19:11:30 stream-7 volumio[994]: info: CoreStateMachine::updateTrackBlock Sep 15 19:11:30 stream-7 volumio[994]: info: CorePlayQueue::getTrackBlock Sep 15 19:11:30 stream-7 volumio[994]: info: CoreStateMachine::stPlaybackTimer Sep 15 19:11:30 stream-7 volumio[994]: info: CoreStateMachine::pushState Sep 15 19:11:30 stream-7 volumio[994]: info: CorePlayQueue::getTrack 1 Sep 15 19:11:30 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 19:11:30 stream-7 volumio[994]: info: CoreCommandRouter::volumioPushState Sep 15 19:11:30 stream-7 volumio[994]: info: CoreStateMachine::serviceStop Sep 15 19:11:30 stream-7 volumio[994]: info: CorePlayQueue::getTrack 1 Sep 15 19:11:30 stream-7 volumio[994]: info: CoreCommandRouter::serviceStop Sep 15 19:11:30 stream-7 volumio[994]: info: ControllerMpd::stop Sep 15 19:11:30 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Sep 15 19:11:30 stream-7 volumio[994]: info: [GPIO_Control] Status changed: stop Sep 15 19:11:30 stream-7 volumio[994]: info: [GPIO_Control] Handling event: musicStop Sep 15 19:11:30 stream-7 volumio[994]: info: sendMpdCommand stop took 20 milliseconds Sep 15 19:11:30 stream-7 volumio[994]: info: CoreStateMachine::play index undefined Sep 15 19:11:30 stream-7 volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 19:11:30 stream-7 volumio[994]: info: CorePlayQueue::getTrack 2 Sep 15 19:11:30 stream-7 volumio[994]: info: CoreStateMachine::startPlaybackTimer Sep 15 19:11:30 stream-7 volumio[994]: info: CorePlayQueue::getTrack 2 Sep 15 19:11:30 stream-7 volumio[994]: verbose: ControllerMpd::clearAddPlayTracks USB/8859-1E12/_rainbow_ Israel Kamakawiwo'ole _heavy_minus_sign_ 'Over The Rainbow' & 'What A Wonderful World' Medley _heavy_minus_sign_ 1993.mp3 Sep 15 19:11:30 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Sep 15 19:11:30 stream-7 volumio[994]: info: CoreStateMachine::updateTrackBlock Sep 15 19:11:30 stream-7 volumio[994]: info: CorePlayQueue::getTrackBlock Sep 15 19:11:30 stream-7 volumio[994]: info: sendMpdCommand stop took 2 milliseconds Sep 15 19:11:30 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand clear Sep 15 19:11:30 stream-7 volumio[994]: info: Sep 15 19:11:30 stream-7 volumio[994]: ---------------------------- MPD announces system playlist update Sep 15 19:11:30 stream-7 volumio[994]: info: Ignoring MPD Status Update Sep 15 19:11:30 stream-7 volumio[994]: info: sendMpdCommand clear took 2 milliseconds Sep 15 19:11:30 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand add "USB/8859-1E12/_rainbow_ Israel Kamakawiwo'ole _heavy_minus_sign_ 'Over The Rainbow' & 'What A Wonderful World' Medley _heavy_minus_sign_ 1993.mp3" Sep 15 19:11:30 stream-7 volumio[994]: error: updateQueue error: null Sep 15 19:11:30 stream-7 volumio[994]: info: Sep 15 19:11:30 stream-7 volumio[994]: ---------------------------- MPD announces system playlist update Sep 15 19:11:30 stream-7 volumio[994]: info: Ignoring MPD Status Update Sep 15 19:11:30 stream-7 volumio[994]: info: ------------------------------ 5ms Sep 15 19:11:30 stream-7 volumio[994]: info: sendMpdCommand add "USB/8859-1E12/_rainbow_ Israel Kamakawiwo'ole _heavy_minus_sign_ 'Over The Rainbow' & 'What A Wonderful World' Medley _heavy_minus_sign_ 1993.mp3" took 3 milliseconds Sep 15 19:11:30 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand play Sep 15 19:11:30 stream-7 volumio[994]: info: ------------------------------ 5ms Sep 15 19:11:30 stream-7 volumio[994]: info: sendMpdCommand play took 4 milliseconds Sep 15 19:11:30 stream-7 volumio[994]: info: Sep 15 19:11:30 stream-7 volumio[994]: ---------------------------- MPD announces state update: player Sep 15 19:11:30 stream-7 volumio[994]: info: ControllerMpd::getState Sep 15 19:11:30 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand status Sep 15 19:11:30 stream-7 volumio[994]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 15 19:11:30 stream-7 volumio[994]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 15 19:11:30 stream-7 volumio[994]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 15 19:11:30 stream-7 volumio[994]: info: Sep 15 19:11:30 stream-7 volumio[994]: ---------------------------- MPD announces state update: player Sep 15 19:11:30 stream-7 volumio[994]: info: sendMpdCommand status took 30 milliseconds Sep 15 19:11:30 stream-7 volumio[994]: info: ControllerMpd::getState Sep 15 19:11:30 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand status Sep 15 19:11:30 stream-7 volumio[994]: verbose: ControllerMpd::parseState Sep 15 19:11:30 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 19:11:30 stream-7 volumio[994]: info: sendMpdCommand status took 8 milliseconds Sep 15 19:11:30 stream-7 volumio[994]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 15 19:11:30 stream-7 volumio[994]: verbose: ControllerMpd::parseState Sep 15 19:11:30 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 19:11:30 stream-7 volumio[994]: verbose: ControllerMpd::parseTrackInfo Sep 15 19:11:30 stream-7 volumio[994]: info: ControllerMpd::pushState Sep 15 19:11:30 stream-7 volumio[994]: info: CoreCommandRouter::servicePushState Sep 15 19:11:30 stream-7 volumio[994]: info: CorePlayQueue::getTrack 2 Sep 15 19:11:30 stream-7 volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":305,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"🌈 Israel Kamakawiwo'ole ➖ 'Over The Rainbow' & 'What A Wonderful World' Medley ➖ 1993 🌈","artist":"Mario Nicola Misino","album":null,"uri":"USB/8859-1E12/_rainbow_ Israel Kamakawiwo'ole _heavy_minus_sign_ 'Over The Rainbow' & 'What A Wonderful World' Medley _heavy_minus_sign_ 1993.mp3","trackType":"mp3"} Sep 15 19:11:30 stream-7 volumio[994]: verbose: CURRENT POSITION 2 Sep 15 19:11:30 stream-7 volumio[994]: info: CoreStateMachine::syncState stateService play Sep 15 19:11:30 stream-7 volumio[994]: info: CoreStateMachine::syncState currentStatus stop Sep 15 19:11:30 stream-7 volumio[994]: info: ------------------------------ 55ms Sep 15 19:11:30 stream-7 volumio[994]: info: sendMpdCommand playlistinfo took 15 milliseconds Sep 15 19:11:30 stream-7 volumio[994]: verbose: ControllerMpd::parseTrackInfo Sep 15 19:11:30 stream-7 volumio[994]: info: ControllerMpd::pushState Sep 15 19:11:30 stream-7 volumio[994]: info: CoreCommandRouter::servicePushState Sep 15 19:11:30 stream-7 volumio[994]: info: CorePlayQueue::getTrack 2 Sep 15 19:11:30 stream-7 volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":283,"duration":305,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"🌈 Israel Kamakawiwo'ole ➖ 'Over The Rainbow' & 'What A Wonderful World' Medley ➖ 1993 🌈","artist":"Mario Nicola Misino","album":null,"uri":"USB/8859-1E12/_rainbow_ Israel Kamakawiwo'ole _heavy_minus_sign_ 'Over The Rainbow' & 'What A Wonderful World' Medley _heavy_minus_sign_ 1993.mp3","trackType":"mp3"} Sep 15 19:11:30 stream-7 volumio[994]: verbose: CURRENT POSITION 2 Sep 15 19:11:30 stream-7 volumio[994]: info: CoreStateMachine::syncState stateService play Sep 15 19:11:30 stream-7 volumio[994]: info: CoreStateMachine::syncState currentStatus play Sep 15 19:11:30 stream-7 volumio[994]: info: Received an update from plugin. extracting info from payload Sep 15 19:11:30 stream-7 volumio[994]: info: CoreStateMachine::pushState Sep 15 19:11:30 stream-7 volumio[994]: info: CorePlayQueue::getTrack 2 Sep 15 19:11:30 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 19:11:30 stream-7 volumio[994]: info: CoreCommandRouter::volumioPushState Sep 15 19:11:30 stream-7 volumio[994]: info: CoreStateMachine::pushState Sep 15 19:11:30 stream-7 volumio[994]: info: CorePlayQueue::getTrack 2 Sep 15 19:11:30 stream-7 volumio[994]: info: CoreCommandRouter::volumioPushState Sep 15 19:11:30 stream-7 volumio[994]: info: ------------------------------ 118ms Sep 15 19:11:30 stream-7 volumio[994]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 15 19:11:30 stream-7 volumio[994]: info: [GPIO_Control] Status changed: play Sep 15 19:11:30 stream-7 volumio[994]: info: [GPIO_Control] Handling event: musicPlay Sep 15 19:11:30 stream-7 volumio[994]: info: [GPIO_Control] Status changed: play Sep 15 19:11:30 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:30 stream-7 volumio[994]: info: CorePlayQueue::getTrack 2 Sep 15 19:11:30 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:30 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:30 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:31 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:31 stream-7 volumio[994]: info: CorePlayQueue::getTrack 2 Sep 15 19:11:31 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:31 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:31 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:32 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:32 stream-7 volumio[994]: info: CorePlayQueue::getTrack 2 Sep 15 19:11:32 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:32 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:32 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:33 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:33 stream-7 volumio[994]: info: CorePlayQueue::getTrack 2 Sep 15 19:11:33 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:33 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:33 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:34 stream-7 volumio[994]: info: CoreCommandRouter::volumioNext Sep 15 19:11:34 stream-7 volumio[994]: info: CoreStateMachine::next Sep 15 19:11:34 stream-7 volumio[994]: info: CoreStateMachine::stop Sep 15 19:11:34 stream-7 volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 19:11:34 stream-7 volumio[994]: info: CoreStateMachine::stPlaybackTimer Sep 15 19:11:34 stream-7 volumio[994]: info: CoreStateMachine::updateTrackBlock Sep 15 19:11:34 stream-7 volumio[994]: info: CorePlayQueue::getTrackBlock Sep 15 19:11:34 stream-7 volumio[994]: info: CoreStateMachine::pushState Sep 15 19:11:34 stream-7 volumio[994]: info: CorePlayQueue::getTrack 2 Sep 15 19:11:34 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 19:11:34 stream-7 volumio[994]: info: CoreCommandRouter::volumioPushState Sep 15 19:11:34 stream-7 volumio[994]: info: CoreStateMachine::serviceStop Sep 15 19:11:34 stream-7 volumio[994]: info: CorePlayQueue::getTrack 2 Sep 15 19:11:34 stream-7 volumio[994]: info: CoreCommandRouter::serviceStop Sep 15 19:11:34 stream-7 volumio[994]: info: ControllerMpd::stop Sep 15 19:11:34 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Sep 15 19:11:34 stream-7 volumio[994]: info: [GPIO_Control] Status changed: stop Sep 15 19:11:34 stream-7 volumio[994]: info: [GPIO_Control] Handling event: musicStop Sep 15 19:11:34 stream-7 volumio[994]: info: Sep 15 19:11:34 stream-7 volumio[994]: ---------------------------- MPD announces state update: player Sep 15 19:11:34 stream-7 volumio[994]: info: sendMpdCommand stop took 27 milliseconds Sep 15 19:11:34 stream-7 volumio[994]: info: ControllerMpd::getState Sep 15 19:11:34 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand status Sep 15 19:11:34 stream-7 volumio[994]: info: CoreStateMachine::play index undefined Sep 15 19:11:34 stream-7 volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 19:11:34 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:34 stream-7 volumio[994]: info: CoreStateMachine::startPlaybackTimer Sep 15 19:11:34 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:34 stream-7 volumio[994]: verbose: ControllerMpd::clearAddPlayTracks USB/8859-1E12/'Till I Collapse ( 256kbps cbr ).mp3 Sep 15 19:11:34 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Sep 15 19:11:34 stream-7 volumio[994]: info: CoreStateMachine::updateTrackBlock Sep 15 19:11:34 stream-7 volumio[994]: info: CorePlayQueue::getTrackBlock Sep 15 19:11:34 stream-7 volumio[994]: info: sendMpdCommand status took 5 milliseconds Sep 15 19:11:34 stream-7 volumio[994]: info: sendMpdCommand stop took 3 milliseconds Sep 15 19:11:34 stream-7 volumio[994]: verbose: ControllerMpd::parseState Sep 15 19:11:34 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 19:11:34 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand clear Sep 15 19:11:34 stream-7 volumio[994]: info: Sep 15 19:11:34 stream-7 volumio[994]: ---------------------------- MPD announces system playlist update Sep 15 19:11:34 stream-7 volumio[994]: info: Ignoring MPD Status Update Sep 15 19:11:34 stream-7 volumio[994]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 15 19:11:34 stream-7 volumio[994]: info: sendMpdCommand clear took 3 milliseconds Sep 15 19:11:34 stream-7 volumio[994]: verbose: ControllerMpd::parseTrackInfo Sep 15 19:11:34 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand add "USB/8859-1E12/'Till I Collapse ( 256kbps cbr ).mp3" Sep 15 19:11:34 stream-7 volumio[994]: info: ControllerMpd::pushState Sep 15 19:11:34 stream-7 volumio[994]: info: CoreCommandRouter::servicePushState Sep 15 19:11:34 stream-7 volumio[994]: info: CoreStateMachine::pushState Sep 15 19:11:34 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:34 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 19:11:34 stream-7 volumio[994]: info: CoreCommandRouter::volumioPushState Sep 15 19:11:34 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:34 stream-7 volumio[994]: 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":"🌈 Israel Kamakawiwo'ole ➖ 'Over The Rainbow' & 'What A Wonderful World' Medley ➖ 1993 🌈","artist":"Mario Nicola Misino","album":null,"uri":"USB/8859-1E12/_rainbow_ Israel Kamakawiwo'ole _heavy_minus_sign_ 'Over The Rainbow' & 'What A Wonderful World' Medley _heavy_minus_sign_ 1993.mp3","trackType":"mp3"} Sep 15 19:11:34 stream-7 volumio[994]: verbose: CURRENT POSITION 3 Sep 15 19:11:34 stream-7 volumio[994]: info: CoreStateMachine::syncState stateService stop Sep 15 19:11:34 stream-7 volumio[994]: info: CoreStateMachine::syncState currentStatus stop Sep 15 19:11:34 stream-7 volumio[994]: info: CoreStateMachine::pushState Sep 15 19:11:34 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:34 stream-7 volumio[994]: info: CoreCommandRouter::volumioPushState Sep 15 19:11:34 stream-7 volumio[994]: info: No code Sep 15 19:11:34 stream-7 volumio[994]: info: CoreStateMachine::pushState Sep 15 19:11:34 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:34 stream-7 volumio[994]: info: CoreCommandRouter::volumioPushState Sep 15 19:11:34 stream-7 volumio[994]: info: ------------------------------ 71ms Sep 15 19:11:34 stream-7 volumio[994]: error: updateQueue error: null Sep 15 19:11:34 stream-7 volumio[994]: info: Sep 15 19:11:34 stream-7 volumio[994]: ---------------------------- MPD announces system playlist update Sep 15 19:11:34 stream-7 volumio[994]: info: Ignoring MPD Status Update Sep 15 19:11:34 stream-7 volumio[994]: info: ------------------------------ 64ms Sep 15 19:11:34 stream-7 volumio[994]: info: sendMpdCommand add "USB/8859-1E12/'Till I Collapse ( 256kbps cbr ).mp3" took 62 milliseconds Sep 15 19:11:34 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand play Sep 15 19:11:34 stream-7 volumio[994]: info: [GPIO_Control] Status changed: stop Sep 15 19:11:34 stream-7 volumio[994]: info: [GPIO_Control] Status changed: stop Sep 15 19:11:34 stream-7 volumio[994]: info: Sep 15 19:11:34 stream-7 volumio[994]: ---------------------------- MPD announces state update: player Sep 15 19:11:34 stream-7 volumio[994]: info: ------------------------------ 37ms Sep 15 19:11:34 stream-7 volumio[994]: info: sendMpdCommand play took 36 milliseconds Sep 15 19:11:34 stream-7 volumio[994]: info: ControllerMpd::getState Sep 15 19:11:34 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand status Sep 15 19:11:34 stream-7 volumio[994]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 15 19:11:34 stream-7 volumio[994]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 15 19:11:34 stream-7 volumio[994]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 15 19:11:34 stream-7 volumio[994]: info: Sep 15 19:11:34 stream-7 volumio[994]: ---------------------------- MPD announces state update: player Sep 15 19:11:34 stream-7 volumio[994]: info: ControllerMpd::getState Sep 15 19:11:34 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand status Sep 15 19:11:34 stream-7 volumio[994]: info: sendMpdCommand status took 39 milliseconds Sep 15 19:11:34 stream-7 volumio[994]: verbose: ControllerMpd::parseState Sep 15 19:11:34 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 19:11:34 stream-7 volumio[994]: info: sendMpdCommand status took 6 milliseconds Sep 15 19:11:34 stream-7 volumio[994]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 15 19:11:34 stream-7 volumio[994]: verbose: ControllerMpd::parseState Sep 15 19:11:34 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 19:11:34 stream-7 volumio[994]: verbose: ControllerMpd::parseTrackInfo Sep 15 19:11:34 stream-7 volumio[994]: info: ControllerMpd::pushState Sep 15 19:11:34 stream-7 volumio[994]: info: CoreCommandRouter::servicePushState Sep 15 19:11:34 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:34 stream-7 volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"'Till I Collapse","artist":"EminemMusic","album":null,"uri":"USB/8859-1E12/'Till I Collapse ( 256kbps cbr ).mp3","trackType":"mp3"} Sep 15 19:11:34 stream-7 volumio[994]: verbose: CURRENT POSITION 3 Sep 15 19:11:34 stream-7 volumio[994]: info: CoreStateMachine::syncState stateService play Sep 15 19:11:34 stream-7 volumio[994]: info: CoreStateMachine::syncState currentStatus stop Sep 15 19:11:34 stream-7 volumio[994]: info: ------------------------------ 54ms Sep 15 19:11:34 stream-7 volumio[994]: info: sendMpdCommand playlistinfo took 23 milliseconds Sep 15 19:11:34 stream-7 volumio[994]: verbose: ControllerMpd::parseTrackInfo Sep 15 19:11:34 stream-7 volumio[994]: info: ControllerMpd::pushState Sep 15 19:11:34 stream-7 volumio[994]: info: CoreCommandRouter::servicePushState Sep 15 19:11:34 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:34 stream-7 volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":829,"duration":298,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"'Till I Collapse","artist":"EminemMusic","album":null,"uri":"USB/8859-1E12/'Till I Collapse ( 256kbps cbr ).mp3","trackType":"mp3"} Sep 15 19:11:34 stream-7 volumio[994]: verbose: CURRENT POSITION 3 Sep 15 19:11:34 stream-7 volumio[994]: info: CoreStateMachine::syncState stateService play Sep 15 19:11:34 stream-7 volumio[994]: info: CoreStateMachine::syncState currentStatus play Sep 15 19:11:34 stream-7 volumio[994]: info: Received an update from plugin. extracting info from payload Sep 15 19:11:34 stream-7 volumio[994]: info: CoreStateMachine::pushState Sep 15 19:11:34 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:34 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 19:11:34 stream-7 volumio[994]: info: CoreCommandRouter::volumioPushState Sep 15 19:11:34 stream-7 volumio[994]: info: CoreStateMachine::pushState Sep 15 19:11:34 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:34 stream-7 volumio[994]: info: CoreCommandRouter::volumioPushState Sep 15 19:11:34 stream-7 volumio[994]: info: ------------------------------ 87ms Sep 15 19:11:34 stream-7 volumio[994]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 15 19:11:34 stream-7 volumio[994]: info: [GPIO_Control] Status changed: play Sep 15 19:11:34 stream-7 volumio[994]: info: [GPIO_Control] Handling event: musicPlay Sep 15 19:11:34 stream-7 volumio[994]: info: [GPIO_Control] Status changed: play Sep 15 19:11:34 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:34 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:34 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:34 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:34 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:35 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:35 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:35 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:35 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:35 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:36 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:36 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:36 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:36 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:36 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:37 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:37 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:37 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:37 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:37 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:38 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:38 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:38 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:38 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:38 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:39 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:39 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:39 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:39 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:39 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:40 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:40 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:40 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:40 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:40 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:41 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:41 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:41 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:41 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:41 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:42 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:42 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:42 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:42 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:42 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:43 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:43 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:43 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:43 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:43 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:44 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:44 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:44 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:44 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:44 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:45 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:45 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:45 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:45 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:45 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:46 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:46 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:46 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:46 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:46 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:47 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:47 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:47 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:47 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:47 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:48 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:48 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:48 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:48 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:48 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:49 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:49 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:49 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:49 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:49 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:50 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:50 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:50 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:50 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:50 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:51 stream-7 volumio[994]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 19:11:51 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 15 19:11:51 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:11:51 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 15 19:11:51 stream-7 volumio[994]: info: Getting Alsa Cards List without I2S DAC Sep 15 19:11:51 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Sep 15 19:11:51 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:11:51 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 19:11:51 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 19:11:51 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 19:11:51 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 19:11:51 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 19:11:51 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 19:11:51 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 19:11:51 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 15 19:11:51 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 15 19:11:51 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:51 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:51 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:51 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:51 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:52 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:52 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:52 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:52 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:52 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:53 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:53 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:53 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:53 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:53 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:54 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:54 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:54 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:54 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:54 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:54 stream-7 volumiologrotate[653]: ls: cannot access '/var/log/samba/log.wb-STREAM': No such file or directory Sep 15 19:11:54 stream-7 volumiologrotate[653]: ls: cannot access '7': No such file or directory Sep 15 19:11:55 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:55 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:55 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:55 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:55 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:56 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:56 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:56 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:56 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:56 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:57 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:57 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:57 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:57 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:57 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:58 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:58 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:58 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:58 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:58 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:11:59 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:11:59 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:11:59 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:11:59 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:11:59 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:00 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:00 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:00 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:12:00 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:12:00 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:01 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:01 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:01 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:12:01 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:12:01 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:02 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:02 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:02 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:12:02 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:12:02 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:03 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:03 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:03 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:12:03 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:12:03 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:04 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:04 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:04 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:12:04 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:12:04 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:05 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:05 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:05 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:12:05 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:12:05 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:06 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:06 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:06 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:12:06 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:12:06 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:07 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:07 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:07 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:12:07 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:12:07 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:08 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:08 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:08 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:12:08 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:12:08 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:09 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:09 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:09 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:12:09 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:12:09 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:10 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:10 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:10 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:12:10 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:12:10 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:11 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:11 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:11 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:12:11 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:12:11 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:12 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:12 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:12 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:12:12 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:12:12 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:13 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:13 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:13 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:12:13 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:12:13 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:14 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:14 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:14 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:12:14 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:12:14 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:15 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:15 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:15 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:12:15 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:12:15 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:16 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:16 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:16 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:12:16 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:12:16 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:17 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:17 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:17 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:12:17 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:12:17 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:18 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:18 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:18 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:12:18 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:12:18 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:19 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:19 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:19 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:12:19 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:12:19 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:20 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:20 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:20 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:12:20 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:12:20 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:21 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:21 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:21 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:12:21 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:12:21 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:22 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:22 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:22 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:12:22 stream-7 volumio[994]: info: CoreStateMachine::getQueue Sep 15 19:12:22 stream-7 volumio[994]: info: CorePlayQueue::getQueue Sep 15 19:12:22 stream-7 volumio[994]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Sep 15 19:12:22 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Sep 15 19:12:22 stream-7 volumio[994]: info: Preparing to save Alsa Options, stopping services first Sep 15 19:12:22 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:22 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:22 stream-7 volumio[994]: info: CoreCommandRouter::volumioPause Sep 15 19:12:22 stream-7 volumio[994]: info: CoreStateMachine::pause Sep 15 19:12:22 stream-7 volumio[994]: info: CoreStateMachine::stPlaybackTimer Sep 15 19:12:22 stream-7 volumio[994]: info: CoreStateMachine::servicePause Sep 15 19:12:22 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:22 stream-7 volumio[994]: info: CoreCommandRouter::servicePause Sep 15 19:12:22 stream-7 volumio[994]: info: ControllerMpd::pause Sep 15 19:12:22 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand pause Sep 15 19:12:22 stream-7 volumio[994]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"hifiberry-digi-pro","label":"HiFiBerry Digi+ Pro"}} Sep 15 19:12:22 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 15 19:12:22 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Sep 15 19:12:22 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:12:22 stream-7 volumio[994]: info: Enabling I2S DAC: HiFiBerry Digi+ Pro Sep 15 19:12:22 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Sep 15 19:12:22 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:12:22 stream-7 sudo[2072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Sep 15 19:12:22 stream-7 sudo[2072]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:12:22 stream-7 sudo[2072]: pam_unix(sudo:session): session closed for user root Sep 15 19:12:22 stream-7 volumio[994]: info: No Overlays Loaded Sep 15 19:12:22 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:12:22 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Sep 15 19:12:22 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:12:22 stream-7 sudo[2075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-digi-pro Sep 15 19:12:22 stream-7 sudo[2075]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:12:23 stream-7 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Sep 15 19:12:23 stream-7 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status Sep 15 19:12:23 stream-7 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Sep 15 19:12:23 stream-7 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Sep 15 19:12:23 stream-7 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Sep 15 19:12:23 stream-7 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/clock44-gpio Sep 15 19:12:23 stream-7 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/clock48-gpio Sep 15 19:12:23 stream-7 sudo[2075]: pam_unix(sudo:session): session closed for user root Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:12:23 stream-7 kernel: wm8804 1-003b: Failed to read device ID: -121 Sep 15 19:12:23 stream-7 kernel: wm8804: probe of 1-003b failed with error -121 Sep 15 19:12:23 stream-7 volumio[994]: info: Device 2 does not have any Mixer Control Available Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 15 19:12:23 stream-7 volumio[994]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry Digi+ Pro Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 15 19:12:23 stream-7 volumio[994]: info: Disabling external Volume Control Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Sep 15 19:12:23 stream-7 volumio[994]: info: CoreStateMachine::pushState Sep 15 19:12:23 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::volumioPushState Sep 15 19:12:23 stream-7 volumio[994]: info: Preparing to generate the ALSA configuration file Sep 15 19:12:23 stream-7 volumio[994]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 15 19:12:23 stream-7 volumio[994]: info: Reading ALSA contributions from plugins. Sep 15 19:12:23 stream-7 volumio[994]: info: [GPIO_Control] Status changed: pause Sep 15 19:12:23 stream-7 volumio[994]: info: [GPIO_Control] Handling event: musicPause Sep 15 19:12:23 stream-7 volumio[994]: info: Sep 15 19:12:23 stream-7 volumio[994]: ---------------------------- MPD announces state update: player Sep 15 19:12:23 stream-7 volumio[994]: info: sendMpdCommand pause took 575 milliseconds Sep 15 19:12:23 stream-7 volumio[994]: info: ControllerMpd::getState Sep 15 19:12:23 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand status Sep 15 19:12:23 stream-7 volumio[994]: info: I2S Param [object Object] successfully enabled Sep 15 19:12:23 stream-7 volumio[994]: info: sendMpdCommand status took 51 milliseconds Sep 15 19:12:23 stream-7 volumio[994]: verbose: ControllerMpd::parseState Sep 15 19:12:23 stream-7 volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 15 19:12:23 stream-7 volumio[994]: info: Getting Alsa Cards List without I2S DAC Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 15 19:12:23 stream-7 volumio[994]: info: sendMpdCommand playlistinfo took 308 milliseconds Sep 15 19:12:23 stream-7 volumio[994]: verbose: ControllerMpd::parseTrackInfo Sep 15 19:12:23 stream-7 volumio[994]: info: ControllerMpd::pushState Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::servicePushState Sep 15 19:12:23 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:23 stream-7 volumio[994]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":50148,"duration":298,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"'Till I Collapse","artist":"EminemMusic","album":null,"uri":"USB/8859-1E12/'Till I Collapse ( 256kbps cbr ).mp3","trackType":"mp3"} Sep 15 19:12:23 stream-7 volumio[994]: verbose: CURRENT POSITION 3 Sep 15 19:12:23 stream-7 volumio[994]: info: CoreStateMachine::syncState stateService pause Sep 15 19:12:23 stream-7 volumio[994]: info: CoreStateMachine::syncState currentStatus pause Sep 15 19:12:23 stream-7 volumio[994]: info: CoreStateMachine::pushState Sep 15 19:12:23 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::volumioPushState Sep 15 19:12:23 stream-7 volumio[994]: info: CoreStateMachine::stPlaybackTimer Sep 15 19:12:23 stream-7 volumio[994]: info: ------------------------------ 399ms Sep 15 19:12:23 stream-7 volumio[994]: info: [GPIO_Control] Status changed: pause Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:23 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:23 stream-7 volumio[994]: info: mpdhttpout --- Volumio set on pause Sep 15 19:12:23 stream-7 volumio[994]: info: mpdhttpout ---Configuration successfully added to mpd.conf. Sep 15 19:12:23 stream-7 volumio[994]: info: Asound.conf file unchanged, so no further update is needed Sep 15 19:12:23 stream-7 volumio[994]: info: Output device has changed, restarting MPD Sep 15 19:12:23 stream-7 sudo[2111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 19:12:23 stream-7 sudo[2111]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:12:23 stream-7 volumio[994]: info: Output device has changed, restarting Shairport Sync Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:12:23 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:12:23 stream-7 sudo[2111]: pam_unix(sudo:session): session closed for user root Sep 15 19:12:23 stream-7 sudo[2114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 19:12:23 stream-7 sudo[2114]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:12:23 stream-7 systemd[1]: Stopping Music Player Daemon... Sep 15 19:12:23 stream-7 volumio[994]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 19:12:24 stream-7 systemd[1]: mpd.service: Succeeded. Sep 15 19:12:24 stream-7 systemd[1]: Stopped Music Player Daemon. Sep 15 19:12:24 stream-7 volumio[994]: info: MPD Permissions set Sep 15 19:12:24 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:12:24 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:12:24 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:12:24 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:12:24 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:12:24 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:12:24 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:12:24 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:12:24 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:12:24 stream-7 volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:12:24 stream-7 volumio[994]: info: Starting Shairport Sync Sep 15 19:12:24 stream-7 systemd[1]: Starting Music Player Daemon... Sep 15 19:12:24 stream-7 volumio[994]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Sep 15 19:12:24 stream-7 volumio[994]: info: CoreCommandRouter::volumioGetState Sep 15 19:12:24 stream-7 volumio[994]: info: CorePlayQueue::getTrack 3 Sep 15 19:12:24 stream-7 volumio[994]: info: mpdhttpout --- Volumio set on pause Sep 15 19:12:24 stream-7 sudo[2125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 19:12:24 stream-7 sudo[2125]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:12:24 stream-7 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 19:12:24 stream-7 systemd[1]: shairport-sync.service: Succeeded. Sep 15 19:12:24 stream-7 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 19:12:24 stream-7 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 19:12:24 stream-7 sudo[2125]: pam_unix(sudo:session): session closed for user root Sep 15 19:12:24 stream-7 volumio[994]: info: Shairport-Sync Started Sep 15 19:12:24 stream-7 sudo[2119]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 19:12:24 stream-7 sudo[2119]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:12:24 stream-7 sudo[2119]: pam_unix(sudo:session): session closed for user root Sep 15 19:12:24 stream-7 volumio[994]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Sep 15 19:12:24 stream-7 volumio[994]: info: PLUGIN onReboot : networkfs Sep 15 19:12:24 stream-7 volumio[994]: info: PLUGIN onReboot : audiophonicsonoff Sep 15 19:12:24 stream-7 volumio[994]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 19:12:24 stream-7 volumio[994]: TypeError: Cannot read property 'writeSync' of undefined Sep 15 19:12:24 stream-7 volumio[994]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25) Sep 15 19:12:24 stream-7 volumio[994]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Sep 15 19:12:24 stream-7 volumio[994]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Sep 15 19:12:24 stream-7 volumio[994]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Sep 15 19:12:24 stream-7 volumio[994]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Sep 15 19:12:24 stream-7 volumio[994]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Sep 15 19:12:24 stream-7 volumio[994]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) Sep 15 19:12:24 stream-7 volumio[994]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33) Sep 15 19:12:24 stream-7 volumio[994]: at Socket.emit (events.js:315:20) Sep 15 19:12:24 stream-7 volumio[994]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 15 19:12:24 stream-7 volumio[994]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 15 19:12:24 stream-7 volumio[994]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 19:12:25 stream-7 sudo[2148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-15 19:11 Sep 15 19:12:25 stream-7 sudo[2148]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:12:25 stream-7 mpd[2138]: Sep 15 19:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"