-- Logs begin at Thu 2019-02-14 21:11:59 AEDT, end at Mon 2025-09-15 19:34:11 AEST. -- Sep 15 19:33:06 stream-7 volumio[961]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Sep 15 19:33:06 stream-7 volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Sep 15 19:33:06 stream-7 volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Sep 15 19:33:06 stream-7 sudo[2233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 19:33:06 stream-7 sudo[2233]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:33:06 stream-7 sudo[2233]: pam_unix(sudo:session): session closed for user root Sep 15 19:33:06 stream-7 sudo[2236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 19:33:06 stream-7 sudo[2236]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:33:06 stream-7 volumio[961]: info: MPD Permissions set Sep 15 19:33:06 stream-7 volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:33:06 stream-7 volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:33:06 stream-7 volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:33:06 stream-7 volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:33:06 stream-7 volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:33:06 stream-7 volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:33:06 stream-7 volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:33:06 stream-7 volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:33:06 stream-7 systemd[1]: Stopping Music Player Daemon... Sep 15 19:33:06 stream-7 volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:33:06 stream-7 volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:33:06 stream-7 systemd[1]: mpd.service: Succeeded. Sep 15 19:33:06 stream-7 systemd[1]: Stopped Music Player Daemon. Sep 15 19:33:06 stream-7 systemd[1]: Starting Music Player Daemon... Sep 15 19:33:06 stream-7 sudo[2242]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 19:33:06 stream-7 sudo[2242]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:33:06 stream-7 sudo[2242]: pam_unix(sudo:session): session closed for user root Sep 15 19:33:07 stream-7 mpd[2244]: Sep 15 19:33 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 15 19:33:07 stream-7 systemd[1]: Started Music Player Daemon. Sep 15 19:33:07 stream-7 sudo[2236]: pam_unix(sudo:session): session closed for user root Sep 15 19:33:07 stream-7 volumio[961]: error: updateQueue error: null Sep 15 19:33:09 stream-7 volumio[961]: info: CoreCommandRouter::volumioPlay Sep 15 19:33:09 stream-7 volumio[961]: info: CoreStateMachine::play index undefined Sep 15 19:33:09 stream-7 volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 19:33:09 stream-7 volumio[961]: info: CorePlayQueue::getTrack 3 Sep 15 19:33:09 stream-7 volumio[961]: info: CoreStateMachine::startPlaybackTimer Sep 15 19:33:09 stream-7 volumio[961]: info: CorePlayQueue::getTrack 3 Sep 15 19:33:09 stream-7 volumio[961]: info: ControllerMpd::resume Sep 15 19:33:09 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand play Sep 15 19:33:09 stream-7 volumio[961]: info: sendMpdCommand play took 2 milliseconds Sep 15 19:33:14 stream-7 volumio[961]: info: CoreCommandRouter::volumioNext Sep 15 19:33:14 stream-7 volumio[961]: info: CoreStateMachine::next Sep 15 19:33:14 stream-7 volumio[961]: info: CoreStateMachine::stop Sep 15 19:33:14 stream-7 volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 19:33:14 stream-7 volumio[961]: info: CoreStateMachine::updateTrackBlock Sep 15 19:33:14 stream-7 volumio[961]: info: CorePlayQueue::getTrackBlock Sep 15 19:33:14 stream-7 volumio[961]: info: CoreStateMachine::stPlaybackTimer Sep 15 19:33:14 stream-7 volumio[961]: info: CoreStateMachine::pushState Sep 15 19:33:14 stream-7 volumio[961]: info: CorePlayQueue::getTrack 3 Sep 15 19:33:14 stream-7 volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 19:33:14 stream-7 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 15 19:33:14 stream-7 volumio[961]: info: CoreStateMachine::serviceStop Sep 15 19:33:14 stream-7 volumio[961]: info: CorePlayQueue::getTrack 3 Sep 15 19:33:14 stream-7 volumio[961]: info: CoreCommandRouter::serviceStop Sep 15 19:33:14 stream-7 volumio[961]: info: ControllerMpd::stop Sep 15 19:33:14 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand stop Sep 15 19:33:14 stream-7 volumio[961]: info: [GPIO_Control] Status changed: stop Sep 15 19:33:14 stream-7 volumio[961]: info: [GPIO_Control] Handling event: musicStop Sep 15 19:33:14 stream-7 volumio[961]: info: sendMpdCommand stop took 21 milliseconds Sep 15 19:33:14 stream-7 volumio[961]: info: CoreStateMachine::play index undefined Sep 15 19:33:14 stream-7 volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 19:33:14 stream-7 volumio[961]: info: CorePlayQueue::getTrack 4 Sep 15 19:33:14 stream-7 volumio[961]: info: CoreStateMachine::startPlaybackTimer Sep 15 19:33:14 stream-7 volumio[961]: info: CorePlayQueue::getTrack 4 Sep 15 19:33:14 stream-7 volumio[961]: verbose: ControllerMpd::clearAddPlayTracks USB/8859-1E12/[MP3DOWNLOAD.TO] Witt Lowry - Into Your Arms (Lyrics) ft. Ava Max - [No Rap]-320k.mp3 Sep 15 19:33:14 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand stop Sep 15 19:33:14 stream-7 volumio[961]: info: CoreStateMachine::updateTrackBlock Sep 15 19:33:14 stream-7 volumio[961]: info: CorePlayQueue::getTrackBlock Sep 15 19:33:14 stream-7 volumio[961]: info: sendMpdCommand stop took 3 milliseconds Sep 15 19:33:14 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand clear Sep 15 19:33:14 stream-7 volumio[961]: info: Sep 15 19:33:14 stream-7 volumio[961]: ---------------------------- MPD announces system playlist update Sep 15 19:33:14 stream-7 volumio[961]: info: Ignoring MPD Status Update Sep 15 19:33:14 stream-7 volumio[961]: info: sendMpdCommand clear took 3 milliseconds Sep 15 19:33:14 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand add "USB/8859-1E12/[MP3DOWNLOAD.TO] Witt Lowry - Into Your Arms (Lyrics) ft. Ava Max - [No Rap]-320k.mp3" Sep 15 19:33:14 stream-7 volumio[961]: error: updateQueue error: null Sep 15 19:33:14 stream-7 volumio[961]: info: Sep 15 19:33:14 stream-7 volumio[961]: ---------------------------- MPD announces system playlist update Sep 15 19:33:14 stream-7 volumio[961]: info: Ignoring MPD Status Update Sep 15 19:33:14 stream-7 volumio[961]: info: ------------------------------ 5ms Sep 15 19:33:14 stream-7 volumio[961]: info: sendMpdCommand add "USB/8859-1E12/[MP3DOWNLOAD.TO] Witt Lowry - Into Your Arms (Lyrics) ft. Ava Max - [No Rap]-320k.mp3" took 4 milliseconds Sep 15 19:33:14 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand play Sep 15 19:33:14 stream-7 volumio[961]: info: ------------------------------ 5ms Sep 15 19:33:14 stream-7 volumio[961]: info: sendMpdCommand play took 4 milliseconds Sep 15 19:33:14 stream-7 volumio[961]: info: Sep 15 19:33:14 stream-7 volumio[961]: ---------------------------- MPD announces state update: player Sep 15 19:33:14 stream-7 volumio[961]: info: ControllerMpd::getState Sep 15 19:33:14 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand status Sep 15 19:33:14 stream-7 volumio[961]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 15 19:33:14 stream-7 volumio[961]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 15 19:33:14 stream-7 volumio[961]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 15 19:33:14 stream-7 volumio[961]: info: FusionDsp - Effects disabled Sep 15 19:33:14 stream-7 volumio[961]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 15 19:33:14 stream-7 volumio[961]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 15 19:33:14 stream-7 volumio[961]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 15 19:33:14 stream-7 volumio[961]: info: FusionDsp - Effects disabled Sep 15 19:33:14 stream-7 volumio[961]: info: Sep 15 19:33:14 stream-7 volumio[961]: ---------------------------- MPD announces state update: player Sep 15 19:33:14 stream-7 volumio[961]: info: sendMpdCommand status took 37 milliseconds Sep 15 19:33:14 stream-7 volumio[961]: info: ControllerMpd::getState Sep 15 19:33:14 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand status Sep 15 19:33:14 stream-7 volumio[961]: verbose: ControllerMpd::parseState Sep 15 19:33:14 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 19:33:14 stream-7 volumio[961]: info: sendMpdCommand status took 5 milliseconds Sep 15 19:33:14 stream-7 volumio[961]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 15 19:33:14 stream-7 volumio[961]: verbose: ControllerMpd::parseState Sep 15 19:33:14 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 19:33:14 stream-7 volumio[961]: verbose: ControllerMpd::parseTrackInfo Sep 15 19:33:14 stream-7 volumio[961]: info: ControllerMpd::pushState Sep 15 19:33:14 stream-7 volumio[961]: info: CoreCommandRouter::servicePushState Sep 15 19:33:14 stream-7 volumio[961]: info: CorePlayQueue::getTrack 4 Sep 15 19:33:14 stream-7 volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":140,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[MP3DOWNLOAD.TO] Witt Lowry - Into Your Arms (Lyrics) ft. Ava Max - [No Rap]-320k.mp3","artist":null,"album":null,"uri":"USB/8859-1E12/[MP3DOWNLOAD.TO] Witt Lowry - Into Your Arms (Lyrics) ft. Ava Max - [No Rap]-320k.mp3","trackType":"mp3"} Sep 15 19:33:14 stream-7 volumio[961]: verbose: CURRENT POSITION 4 Sep 15 19:33:14 stream-7 volumio[961]: info: CoreStateMachine::syncState stateService play Sep 15 19:33:14 stream-7 volumio[961]: info: CoreStateMachine::syncState currentStatus stop Sep 15 19:33:14 stream-7 volumio[961]: info: ------------------------------ 53ms Sep 15 19:33:14 stream-7 volumio[961]: info: sendMpdCommand playlistinfo took 13 milliseconds Sep 15 19:33:14 stream-7 volumio[961]: verbose: ControllerMpd::parseTrackInfo Sep 15 19:33:14 stream-7 volumio[961]: info: ControllerMpd::pushState Sep 15 19:33:14 stream-7 volumio[961]: info: CoreCommandRouter::servicePushState Sep 15 19:33:14 stream-7 volumio[961]: info: CorePlayQueue::getTrack 4 Sep 15 19:33:14 stream-7 volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":367,"duration":140,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"[MP3DOWNLOAD.TO] Witt Lowry - Into Your Arms (Lyrics) ft. Ava Max - [No Rap]-320k.mp3","artist":null,"album":null,"uri":"USB/8859-1E12/[MP3DOWNLOAD.TO] Witt Lowry - Into Your Arms (Lyrics) ft. Ava Max - [No Rap]-320k.mp3","trackType":"mp3"} Sep 15 19:33:14 stream-7 volumio[961]: verbose: CURRENT POSITION 4 Sep 15 19:33:14 stream-7 volumio[961]: info: CoreStateMachine::syncState stateService play Sep 15 19:33:14 stream-7 volumio[961]: info: CoreStateMachine::syncState currentStatus play Sep 15 19:33:14 stream-7 volumio[961]: info: Received an update from plugin. extracting info from payload Sep 15 19:33:14 stream-7 volumio[961]: info: CoreStateMachine::pushState Sep 15 19:33:14 stream-7 volumio[961]: info: CorePlayQueue::getTrack 4 Sep 15 19:33:14 stream-7 volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 19:33:14 stream-7 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 15 19:33:14 stream-7 volumio[961]: info: CoreStateMachine::pushState Sep 15 19:33:14 stream-7 volumio[961]: info: CorePlayQueue::getTrack 4 Sep 15 19:33:14 stream-7 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 15 19:33:14 stream-7 volumio[961]: info: ------------------------------ 82ms Sep 15 19:33:14 stream-7 volumio[961]: info: [GPIO_Control] Status changed: play Sep 15 19:33:14 stream-7 volumio[961]: info: [GPIO_Control] Handling event: musicPlay Sep 15 19:33:14 stream-7 volumio[961]: info: [GPIO_Control] Status changed: play Sep 15 19:33:14 stream-7 volumio[961]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 15 19:33:14 stream-7 volumio[961]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 15 19:33:14 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:14 stream-7 volumio[961]: info: CorePlayQueue::getTrack 4 Sep 15 19:33:14 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:14 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:14 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:15 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:15 stream-7 volumio[961]: info: CorePlayQueue::getTrack 4 Sep 15 19:33:15 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:15 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:15 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:15 stream-7 volumio[961]: info: CoreCommandRouter::volumioPause Sep 15 19:33:15 stream-7 volumio[961]: info: CoreStateMachine::pause Sep 15 19:33:15 stream-7 volumio[961]: info: CoreStateMachine::stPlaybackTimer Sep 15 19:33:15 stream-7 volumio[961]: info: CoreStateMachine::servicePause Sep 15 19:33:15 stream-7 volumio[961]: info: CorePlayQueue::getTrack 4 Sep 15 19:33:15 stream-7 volumio[961]: info: CoreCommandRouter::servicePause Sep 15 19:33:15 stream-7 volumio[961]: info: ControllerMpd::pause Sep 15 19:33:15 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand pause Sep 15 19:33:15 stream-7 volumio[961]: info: Sep 15 19:33:15 stream-7 volumio[961]: ---------------------------- MPD announces state update: player Sep 15 19:33:15 stream-7 volumio[961]: info: sendMpdCommand pause took 3 milliseconds Sep 15 19:33:15 stream-7 volumio[961]: info: ControllerMpd::getState Sep 15 19:33:15 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand status Sep 15 19:33:16 stream-7 volumio[961]: info: sendMpdCommand status took 1 milliseconds Sep 15 19:33:16 stream-7 volumio[961]: verbose: ControllerMpd::parseState Sep 15 19:33:16 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 19:33:16 stream-7 volumio[961]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 15 19:33:16 stream-7 volumio[961]: verbose: ControllerMpd::parseTrackInfo Sep 15 19:33:16 stream-7 volumio[961]: info: ControllerMpd::pushState Sep 15 19:33:16 stream-7 volumio[961]: info: CoreCommandRouter::servicePushState Sep 15 19:33:16 stream-7 volumio[961]: info: CorePlayQueue::getTrack 4 Sep 15 19:33:16 stream-7 volumio[961]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3506,"duration":140,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"[MP3DOWNLOAD.TO] Witt Lowry - Into Your Arms (Lyrics) ft. Ava Max - [No Rap]-320k.mp3","artist":null,"album":null,"uri":"USB/8859-1E12/[MP3DOWNLOAD.TO] Witt Lowry - Into Your Arms (Lyrics) ft. Ava Max - [No Rap]-320k.mp3","trackType":"mp3"} Sep 15 19:33:16 stream-7 volumio[961]: verbose: CURRENT POSITION 4 Sep 15 19:33:16 stream-7 volumio[961]: info: CoreStateMachine::syncState stateService pause Sep 15 19:33:16 stream-7 volumio[961]: info: CoreStateMachine::syncState currentStatus pause Sep 15 19:33:16 stream-7 volumio[961]: info: CoreStateMachine::pushState Sep 15 19:33:16 stream-7 volumio[961]: info: CorePlayQueue::getTrack 4 Sep 15 19:33:16 stream-7 volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 19:33:16 stream-7 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 15 19:33:16 stream-7 volumio[961]: info: CoreStateMachine::stPlaybackTimer Sep 15 19:33:16 stream-7 volumio[961]: info: ------------------------------ 33ms Sep 15 19:33:16 stream-7 volumio[961]: info: [GPIO_Control] Status changed: pause Sep 15 19:33:16 stream-7 volumio[961]: info: [GPIO_Control] Handling event: musicPause Sep 15 19:33:21 stream-7 volumio[961]: info: CoreCommandRouter::volumioPlay Sep 15 19:33:21 stream-7 volumio[961]: info: CoreStateMachine::play index undefined Sep 15 19:33:21 stream-7 volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 19:33:21 stream-7 volumio[961]: info: CorePlayQueue::getTrack 4 Sep 15 19:33:21 stream-7 volumio[961]: info: CoreStateMachine::startPlaybackTimer Sep 15 19:33:21 stream-7 volumio[961]: info: CorePlayQueue::getTrack 4 Sep 15 19:33:21 stream-7 volumio[961]: info: ControllerMpd::resume Sep 15 19:33:21 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand play Sep 15 19:33:21 stream-7 volumio[961]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 15 19:33:21 stream-7 volumio[961]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 15 19:33:21 stream-7 volumio[961]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 15 19:33:21 stream-7 volumio[961]: info: FusionDsp - Effects disabled Sep 15 19:33:21 stream-7 volumio[961]: info: Sep 15 19:33:21 stream-7 volumio[961]: ---------------------------- MPD announces state update: player Sep 15 19:33:21 stream-7 volumio[961]: info: sendMpdCommand play took 24 milliseconds Sep 15 19:33:21 stream-7 volumio[961]: info: ControllerMpd::getState Sep 15 19:33:21 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand status Sep 15 19:33:21 stream-7 volumio[961]: info: sendMpdCommand status took 2 milliseconds Sep 15 19:33:21 stream-7 volumio[961]: verbose: ControllerMpd::parseState Sep 15 19:33:21 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 19:33:21 stream-7 volumio[961]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 15 19:33:21 stream-7 volumio[961]: verbose: ControllerMpd::parseTrackInfo Sep 15 19:33:21 stream-7 volumio[961]: info: ControllerMpd::pushState Sep 15 19:33:21 stream-7 volumio[961]: info: CoreCommandRouter::servicePushState Sep 15 19:33:21 stream-7 volumio[961]: info: CorePlayQueue::getTrack 4 Sep 15 19:33:21 stream-7 volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3716,"duration":140,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"[MP3DOWNLOAD.TO] Witt Lowry - Into Your Arms (Lyrics) ft. Ava Max - [No Rap]-320k.mp3","artist":null,"album":null,"uri":"USB/8859-1E12/[MP3DOWNLOAD.TO] Witt Lowry - Into Your Arms (Lyrics) ft. Ava Max - [No Rap]-320k.mp3","trackType":"mp3"} Sep 15 19:33:21 stream-7 volumio[961]: verbose: CURRENT POSITION 4 Sep 15 19:33:21 stream-7 volumio[961]: info: CoreStateMachine::syncState stateService play Sep 15 19:33:21 stream-7 volumio[961]: info: CoreStateMachine::syncState currentStatus pause Sep 15 19:33:21 stream-7 volumio[961]: info: CoreStateMachine::pushState Sep 15 19:33:21 stream-7 volumio[961]: info: CorePlayQueue::getTrack 4 Sep 15 19:33:21 stream-7 volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 19:33:21 stream-7 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 15 19:33:21 stream-7 volumio[961]: info: ------------------------------ 49ms Sep 15 19:33:21 stream-7 volumio[961]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 15 19:33:21 stream-7 volumio[961]: info: [GPIO_Control] Status changed: play Sep 15 19:33:21 stream-7 volumio[961]: info: [GPIO_Control] Handling event: musicPlay Sep 15 19:33:21 stream-7 volumiologrotate[662]: ls: cannot access '/var/log/samba/log.wb-STREAM': No such file or directory Sep 15 19:33:21 stream-7 volumiologrotate[662]: ls: cannot access '7': No such file or directory Sep 15 19:33:21 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:21 stream-7 volumio[961]: info: CorePlayQueue::getTrack 4 Sep 15 19:33:21 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:21 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:21 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:22 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:22 stream-7 volumio[961]: info: CorePlayQueue::getTrack 4 Sep 15 19:33:22 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:22 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:22 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:23 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:23 stream-7 volumio[961]: info: CorePlayQueue::getTrack 4 Sep 15 19:33:23 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:23 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:23 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:24 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:24 stream-7 volumio[961]: info: CorePlayQueue::getTrack 4 Sep 15 19:33:24 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:24 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:24 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:25 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:25 stream-7 volumio[961]: info: CorePlayQueue::getTrack 4 Sep 15 19:33:25 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:25 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:25 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:26 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:26 stream-7 volumio[961]: info: CorePlayQueue::getTrack 4 Sep 15 19:33:26 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:26 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:26 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:27 stream-7 volumio[961]: info: CoreCommandRouter::volumioNext Sep 15 19:33:27 stream-7 volumio[961]: info: CoreStateMachine::next Sep 15 19:33:27 stream-7 volumio[961]: info: CoreStateMachine::stop Sep 15 19:33:27 stream-7 volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 19:33:27 stream-7 volumio[961]: info: CoreStateMachine::stPlaybackTimer Sep 15 19:33:27 stream-7 volumio[961]: info: CoreStateMachine::updateTrackBlock Sep 15 19:33:27 stream-7 volumio[961]: info: CorePlayQueue::getTrackBlock Sep 15 19:33:27 stream-7 volumio[961]: info: CoreStateMachine::pushState Sep 15 19:33:27 stream-7 volumio[961]: info: CorePlayQueue::getTrack 4 Sep 15 19:33:27 stream-7 volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 19:33:27 stream-7 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 15 19:33:27 stream-7 volumio[961]: info: CoreStateMachine::serviceStop Sep 15 19:33:27 stream-7 volumio[961]: info: CorePlayQueue::getTrack 4 Sep 15 19:33:27 stream-7 volumio[961]: info: CoreCommandRouter::serviceStop Sep 15 19:33:27 stream-7 volumio[961]: info: ControllerMpd::stop Sep 15 19:33:27 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand stop Sep 15 19:33:27 stream-7 volumio[961]: info: [GPIO_Control] Status changed: stop Sep 15 19:33:27 stream-7 volumio[961]: info: [GPIO_Control] Handling event: musicStop Sep 15 19:33:27 stream-7 volumio[961]: info: Sep 15 19:33:27 stream-7 volumio[961]: ---------------------------- MPD announces state update: player Sep 15 19:33:27 stream-7 volumio[961]: info: sendMpdCommand stop took 23 milliseconds Sep 15 19:33:27 stream-7 volumio[961]: info: ControllerMpd::getState Sep 15 19:33:27 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand status Sep 15 19:33:27 stream-7 volumio[961]: info: CoreStateMachine::play index undefined Sep 15 19:33:27 stream-7 volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 19:33:27 stream-7 volumio[961]: info: CorePlayQueue::getTrack 5 Sep 15 19:33:27 stream-7 volumio[961]: info: CoreStateMachine::startPlaybackTimer Sep 15 19:33:27 stream-7 volumio[961]: info: CorePlayQueue::getTrack 5 Sep 15 19:33:27 stream-7 volumio[961]: verbose: ControllerMpd::clearAddPlayTracks USB/8859-1E12/3200 Years Ago (Troy Soundtrack) ( 256kbps cbr ).mp3 Sep 15 19:33:27 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand stop Sep 15 19:33:27 stream-7 volumio[961]: info: CoreStateMachine::updateTrackBlock Sep 15 19:33:27 stream-7 volumio[961]: info: CorePlayQueue::getTrackBlock Sep 15 19:33:27 stream-7 volumio[961]: info: sendMpdCommand status took 5 milliseconds Sep 15 19:33:27 stream-7 volumio[961]: info: sendMpdCommand stop took 3 milliseconds Sep 15 19:33:27 stream-7 volumio[961]: verbose: ControllerMpd::parseState Sep 15 19:33:27 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 19:33:27 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand clear Sep 15 19:33:27 stream-7 volumio[961]: info: Sep 15 19:33:27 stream-7 volumio[961]: ---------------------------- MPD announces system playlist update Sep 15 19:33:27 stream-7 volumio[961]: info: Ignoring MPD Status Update Sep 15 19:33:27 stream-7 volumio[961]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 15 19:33:27 stream-7 volumio[961]: info: sendMpdCommand clear took 5 milliseconds Sep 15 19:33:27 stream-7 volumio[961]: verbose: ControllerMpd::parseTrackInfo Sep 15 19:33:27 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand add "USB/8859-1E12/3200 Years Ago (Troy Soundtrack) ( 256kbps cbr ).mp3" Sep 15 19:33:27 stream-7 volumio[961]: info: ControllerMpd::pushState Sep 15 19:33:27 stream-7 volumio[961]: info: CoreCommandRouter::servicePushState Sep 15 19:33:27 stream-7 volumio[961]: info: CoreStateMachine::pushState Sep 15 19:33:27 stream-7 volumio[961]: info: CorePlayQueue::getTrack 5 Sep 15 19:33:27 stream-7 volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 19:33:27 stream-7 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 15 19:33:27 stream-7 volumio[961]: info: CorePlayQueue::getTrack 5 Sep 15 19:33:27 stream-7 volumio[961]: 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":"[MP3DOWNLOAD.TO] Witt Lowry - Into Your Arms (Lyrics) ft. Ava Max - [No Rap]-320k.mp3","artist":null,"album":null,"uri":"USB/8859-1E12/[MP3DOWNLOAD.TO] Witt Lowry - Into Your Arms (Lyrics) ft. Ava Max - [No Rap]-320k.mp3","trackType":"mp3"} Sep 15 19:33:27 stream-7 volumio[961]: verbose: CURRENT POSITION 5 Sep 15 19:33:27 stream-7 volumio[961]: info: CoreStateMachine::syncState stateService stop Sep 15 19:33:27 stream-7 volumio[961]: info: CoreStateMachine::syncState currentStatus stop Sep 15 19:33:27 stream-7 volumio[961]: info: CoreStateMachine::pushState Sep 15 19:33:27 stream-7 volumio[961]: info: CorePlayQueue::getTrack 5 Sep 15 19:33:27 stream-7 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 15 19:33:27 stream-7 volumio[961]: info: No code Sep 15 19:33:27 stream-7 volumio[961]: info: CoreStateMachine::pushState Sep 15 19:33:27 stream-7 volumio[961]: info: CorePlayQueue::getTrack 5 Sep 15 19:33:27 stream-7 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 15 19:33:27 stream-7 volumio[961]: info: ------------------------------ 72ms Sep 15 19:33:27 stream-7 volumio[961]: error: updateQueue error: null Sep 15 19:33:27 stream-7 volumio[961]: info: Sep 15 19:33:27 stream-7 volumio[961]: ---------------------------- MPD announces system playlist update Sep 15 19:33:27 stream-7 volumio[961]: info: Ignoring MPD Status Update Sep 15 19:33:27 stream-7 volumio[961]: info: ------------------------------ 62ms Sep 15 19:33:27 stream-7 volumio[961]: info: sendMpdCommand add "USB/8859-1E12/3200 Years Ago (Troy Soundtrack) ( 256kbps cbr ).mp3" took 60 milliseconds Sep 15 19:33:27 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand play Sep 15 19:33:27 stream-7 volumio[961]: info: [GPIO_Control] Status changed: stop Sep 15 19:33:27 stream-7 volumio[961]: info: [GPIO_Control] Status changed: stop Sep 15 19:33:27 stream-7 volumio[961]: info: [GPIO_Control] Status changed: stop Sep 15 19:33:27 stream-7 volumio[961]: info: Sep 15 19:33:27 stream-7 volumio[961]: ---------------------------- MPD announces state update: player Sep 15 19:33:27 stream-7 volumio[961]: info: ------------------------------ 31ms Sep 15 19:33:27 stream-7 volumio[961]: info: sendMpdCommand play took 29 milliseconds Sep 15 19:33:27 stream-7 volumio[961]: info: ControllerMpd::getState Sep 15 19:33:27 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand status Sep 15 19:33:27 stream-7 volumio[961]: info: Sep 15 19:33:27 stream-7 volumio[961]: ---------------------------- MPD announces state update: player Sep 15 19:33:27 stream-7 volumio[961]: info: sendMpdCommand status took 5 milliseconds Sep 15 19:33:27 stream-7 volumio[961]: info: ControllerMpd::getState Sep 15 19:33:27 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand status Sep 15 19:33:27 stream-7 volumio[961]: verbose: ControllerMpd::parseState Sep 15 19:33:27 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 19:33:27 stream-7 volumio[961]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 15 19:33:27 stream-7 volumio[961]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 15 19:33:27 stream-7 volumio[961]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 15 19:33:27 stream-7 volumio[961]: info: FusionDsp - Effects disabled Sep 15 19:33:27 stream-7 volumio[961]: info: sendMpdCommand status took 12 milliseconds Sep 15 19:33:27 stream-7 volumio[961]: info: sendMpdCommand playlistinfo took 15 milliseconds Sep 15 19:33:27 stream-7 volumio[961]: verbose: ControllerMpd::parseState Sep 15 19:33:27 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 19:33:27 stream-7 volumio[961]: verbose: ControllerMpd::parseTrackInfo Sep 15 19:33:27 stream-7 volumio[961]: info: ControllerMpd::pushState Sep 15 19:33:27 stream-7 volumio[961]: info: CoreCommandRouter::servicePushState Sep 15 19:33:27 stream-7 volumio[961]: info: CorePlayQueue::getTrack 5 Sep 15 19:33:27 stream-7 volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":214,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"3200 Years Ago (Troy Soundtrack)","artist":"Simply Great Music","album":null,"uri":"USB/8859-1E12/3200 Years Ago (Troy Soundtrack) ( 256kbps cbr ).mp3","trackType":"mp3"} Sep 15 19:33:27 stream-7 volumio[961]: verbose: CURRENT POSITION 5 Sep 15 19:33:27 stream-7 volumio[961]: info: CoreStateMachine::syncState stateService play Sep 15 19:33:27 stream-7 volumio[961]: info: CoreStateMachine::syncState currentStatus stop Sep 15 19:33:27 stream-7 volumio[961]: info: ------------------------------ 41ms Sep 15 19:33:27 stream-7 volumio[961]: info: sendMpdCommand playlistinfo took 39 milliseconds Sep 15 19:33:27 stream-7 volumio[961]: verbose: ControllerMpd::parseTrackInfo Sep 15 19:33:27 stream-7 volumio[961]: info: ControllerMpd::pushState Sep 15 19:33:27 stream-7 volumio[961]: info: CoreCommandRouter::servicePushState Sep 15 19:33:27 stream-7 volumio[961]: info: CorePlayQueue::getTrack 5 Sep 15 19:33:27 stream-7 volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":214,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"3200 Years Ago (Troy Soundtrack)","artist":"Simply Great Music","album":null,"uri":"USB/8859-1E12/3200 Years Ago (Troy Soundtrack) ( 256kbps cbr ).mp3","trackType":"mp3"} Sep 15 19:33:27 stream-7 volumio[961]: verbose: CURRENT POSITION 5 Sep 15 19:33:27 stream-7 volumio[961]: info: CoreStateMachine::syncState stateService play Sep 15 19:33:27 stream-7 volumio[961]: info: CoreStateMachine::syncState currentStatus play Sep 15 19:33:27 stream-7 volumio[961]: info: Received an update from plugin. extracting info from payload Sep 15 19:33:27 stream-7 volumio[961]: info: CoreStateMachine::pushState Sep 15 19:33:27 stream-7 volumio[961]: info: CorePlayQueue::getTrack 5 Sep 15 19:33:27 stream-7 volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 19:33:27 stream-7 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 15 19:33:27 stream-7 volumio[961]: info: CoreStateMachine::pushState Sep 15 19:33:27 stream-7 volumio[961]: info: CorePlayQueue::getTrack 5 Sep 15 19:33:27 stream-7 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 15 19:33:27 stream-7 volumio[961]: info: ------------------------------ 111ms Sep 15 19:33:27 stream-7 volumio[961]: info: [GPIO_Control] Status changed: play Sep 15 19:33:27 stream-7 volumio[961]: info: [GPIO_Control] Handling event: musicPlay Sep 15 19:33:27 stream-7 volumio[961]: info: [GPIO_Control] Status changed: play Sep 15 19:33:27 stream-7 volumio[961]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 15 19:33:27 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:27 stream-7 volumio[961]: info: CorePlayQueue::getTrack 5 Sep 15 19:33:27 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:27 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:27 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:28 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:28 stream-7 volumio[961]: info: CorePlayQueue::getTrack 5 Sep 15 19:33:28 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:28 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:28 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:29 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:29 stream-7 volumio[961]: info: CorePlayQueue::getTrack 5 Sep 15 19:33:29 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:29 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:29 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:30 stream-7 volumio[961]: info: CoreCommandRouter::volumioNext Sep 15 19:33:30 stream-7 volumio[961]: info: CoreStateMachine::next Sep 15 19:33:30 stream-7 volumio[961]: info: CoreStateMachine::stop Sep 15 19:33:30 stream-7 volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 19:33:30 stream-7 volumio[961]: info: CoreStateMachine::stPlaybackTimer Sep 15 19:33:30 stream-7 volumio[961]: info: CoreStateMachine::updateTrackBlock Sep 15 19:33:30 stream-7 volumio[961]: info: CorePlayQueue::getTrackBlock Sep 15 19:33:30 stream-7 volumio[961]: info: CoreStateMachine::pushState Sep 15 19:33:30 stream-7 volumio[961]: info: CorePlayQueue::getTrack 5 Sep 15 19:33:30 stream-7 volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 19:33:30 stream-7 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 15 19:33:30 stream-7 volumio[961]: info: CoreStateMachine::serviceStop Sep 15 19:33:30 stream-7 volumio[961]: info: CorePlayQueue::getTrack 5 Sep 15 19:33:30 stream-7 volumio[961]: info: CoreCommandRouter::serviceStop Sep 15 19:33:30 stream-7 volumio[961]: info: ControllerMpd::stop Sep 15 19:33:30 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand stop Sep 15 19:33:30 stream-7 volumio[961]: info: [GPIO_Control] Status changed: stop Sep 15 19:33:30 stream-7 volumio[961]: info: [GPIO_Control] Handling event: musicStop Sep 15 19:33:30 stream-7 volumio[961]: info: Sep 15 19:33:30 stream-7 volumio[961]: ---------------------------- MPD announces state update: player Sep 15 19:33:30 stream-7 volumio[961]: info: sendMpdCommand stop took 19 milliseconds Sep 15 19:33:30 stream-7 volumio[961]: info: ControllerMpd::getState Sep 15 19:33:30 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand status Sep 15 19:33:30 stream-7 volumio[961]: info: CoreStateMachine::play index undefined Sep 15 19:33:30 stream-7 volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 19:33:30 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:30 stream-7 volumio[961]: info: CoreStateMachine::startPlaybackTimer Sep 15 19:33:30 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:30 stream-7 volumio[961]: verbose: ControllerMpd::clearAddPlayTracks USB/8859-1E12/5_Seconds_of_Summer_Amnesia.mp3 Sep 15 19:33:30 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand stop Sep 15 19:33:30 stream-7 volumio[961]: info: CoreStateMachine::updateTrackBlock Sep 15 19:33:30 stream-7 volumio[961]: info: CorePlayQueue::getTrackBlock Sep 15 19:33:30 stream-7 volumio[961]: info: sendMpdCommand status took 11 milliseconds Sep 15 19:33:30 stream-7 volumio[961]: verbose: ControllerMpd::parseState Sep 15 19:33:30 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 19:33:30 stream-7 volumio[961]: info: sendMpdCommand stop took 9 milliseconds Sep 15 19:33:30 stream-7 volumio[961]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 15 19:33:30 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand clear Sep 15 19:33:30 stream-7 volumio[961]: verbose: ControllerMpd::parseTrackInfo Sep 15 19:33:30 stream-7 volumio[961]: info: ControllerMpd::pushState Sep 15 19:33:30 stream-7 volumio[961]: info: CoreCommandRouter::servicePushState Sep 15 19:33:30 stream-7 volumio[961]: info: CoreStateMachine::pushState Sep 15 19:33:30 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:30 stream-7 volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 19:33:30 stream-7 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 15 19:33:30 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:30 stream-7 volumio[961]: 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":"3200 Years Ago (Troy Soundtrack)","artist":"Simply Great Music","album":null,"uri":"USB/8859-1E12/3200 Years Ago (Troy Soundtrack) ( 256kbps cbr ).mp3","trackType":"mp3"} Sep 15 19:33:30 stream-7 volumio[961]: verbose: CURRENT POSITION 6 Sep 15 19:33:30 stream-7 volumio[961]: info: CoreStateMachine::syncState stateService stop Sep 15 19:33:30 stream-7 volumio[961]: info: CoreStateMachine::syncState currentStatus stop Sep 15 19:33:30 stream-7 volumio[961]: info: CoreStateMachine::pushState Sep 15 19:33:30 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:30 stream-7 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 15 19:33:30 stream-7 volumio[961]: info: No code Sep 15 19:33:30 stream-7 volumio[961]: info: CoreStateMachine::pushState Sep 15 19:33:30 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:30 stream-7 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 15 19:33:30 stream-7 volumio[961]: info: ------------------------------ 83ms Sep 15 19:33:30 stream-7 volumio[961]: info: Sep 15 19:33:30 stream-7 volumio[961]: ---------------------------- MPD announces system playlist update Sep 15 19:33:30 stream-7 volumio[961]: info: Ignoring MPD Status Update Sep 15 19:33:30 stream-7 volumio[961]: info: sendMpdCommand clear took 64 milliseconds Sep 15 19:33:30 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand add "USB/8859-1E12/5_Seconds_of_Summer_Amnesia.mp3" Sep 15 19:33:30 stream-7 volumio[961]: info: [GPIO_Control] Status changed: stop Sep 15 19:33:30 stream-7 volumio[961]: info: [GPIO_Control] Status changed: stop Sep 15 19:33:30 stream-7 volumio[961]: info: [GPIO_Control] Status changed: stop Sep 15 19:33:30 stream-7 volumio[961]: error: updateQueue error: null Sep 15 19:33:30 stream-7 volumio[961]: info: Sep 15 19:33:30 stream-7 volumio[961]: ---------------------------- MPD announces system playlist update Sep 15 19:33:30 stream-7 volumio[961]: info: Ignoring MPD Status Update Sep 15 19:33:30 stream-7 volumio[961]: info: ------------------------------ 25ms Sep 15 19:33:30 stream-7 volumio[961]: info: sendMpdCommand add "USB/8859-1E12/5_Seconds_of_Summer_Amnesia.mp3" took 24 milliseconds Sep 15 19:33:30 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand play Sep 15 19:33:30 stream-7 volumio[961]: info: ------------------------------ 4ms Sep 15 19:33:30 stream-7 volumio[961]: info: sendMpdCommand play took 3 milliseconds Sep 15 19:33:30 stream-7 volumio[961]: info: Sep 15 19:33:30 stream-7 volumio[961]: ---------------------------- MPD announces state update: player Sep 15 19:33:30 stream-7 volumio[961]: info: ControllerMpd::getState Sep 15 19:33:30 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand status Sep 15 19:33:30 stream-7 volumio[961]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 15 19:33:30 stream-7 volumio[961]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 15 19:33:30 stream-7 volumio[961]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 15 19:33:30 stream-7 volumio[961]: info: FusionDsp - Effects disabled Sep 15 19:33:30 stream-7 volumio[961]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 15 19:33:30 stream-7 volumio[961]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 15 19:33:30 stream-7 volumio[961]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 15 19:33:30 stream-7 volumio[961]: info: FusionDsp - Effects disabled Sep 15 19:33:30 stream-7 volumio[961]: info: Sep 15 19:33:30 stream-7 volumio[961]: ---------------------------- MPD announces state update: player Sep 15 19:33:30 stream-7 volumio[961]: info: sendMpdCommand status took 20 milliseconds Sep 15 19:33:30 stream-7 volumio[961]: info: ControllerMpd::getState Sep 15 19:33:30 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand status Sep 15 19:33:30 stream-7 volumio[961]: verbose: ControllerMpd::parseState Sep 15 19:33:30 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 19:33:30 stream-7 volumio[961]: info: sendMpdCommand status took 3 milliseconds Sep 15 19:33:30 stream-7 volumio[961]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 15 19:33:30 stream-7 volumio[961]: verbose: ControllerMpd::parseState Sep 15 19:33:30 stream-7 volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 19:33:30 stream-7 volumio[961]: verbose: ControllerMpd::parseTrackInfo Sep 15 19:33:30 stream-7 volumio[961]: info: ControllerMpd::pushState Sep 15 19:33:30 stream-7 volumio[961]: info: CoreCommandRouter::servicePushState Sep 15 19:33:30 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:30 stream-7 volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5_Seconds_of_Summer_Amnesia.mp3","artist":null,"album":null,"uri":"USB/8859-1E12/5_Seconds_of_Summer_Amnesia.mp3","trackType":"mp3"} Sep 15 19:33:30 stream-7 volumio[961]: verbose: CURRENT POSITION 6 Sep 15 19:33:30 stream-7 volumio[961]: info: CoreStateMachine::syncState stateService play Sep 15 19:33:30 stream-7 volumio[961]: info: CoreStateMachine::syncState currentStatus stop Sep 15 19:33:30 stream-7 volumio[961]: info: ------------------------------ 29ms Sep 15 19:33:30 stream-7 volumio[961]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 15 19:33:30 stream-7 volumio[961]: verbose: ControllerMpd::parseTrackInfo Sep 15 19:33:30 stream-7 volumio[961]: info: ControllerMpd::pushState Sep 15 19:33:30 stream-7 volumio[961]: info: CoreCommandRouter::servicePushState Sep 15 19:33:30 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:30 stream-7 volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":251,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"5_Seconds_of_Summer_Amnesia.mp3","artist":null,"album":null,"uri":"USB/8859-1E12/5_Seconds_of_Summer_Amnesia.mp3","trackType":"mp3"} Sep 15 19:33:30 stream-7 volumio[961]: verbose: CURRENT POSITION 6 Sep 15 19:33:30 stream-7 volumio[961]: info: CoreStateMachine::syncState stateService play Sep 15 19:33:30 stream-7 volumio[961]: info: CoreStateMachine::syncState currentStatus play Sep 15 19:33:30 stream-7 volumio[961]: info: Received an update from plugin. extracting info from payload Sep 15 19:33:30 stream-7 volumio[961]: info: CoreStateMachine::pushState Sep 15 19:33:30 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:30 stream-7 volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 19:33:30 stream-7 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 15 19:33:30 stream-7 volumio[961]: info: CoreStateMachine::pushState Sep 15 19:33:30 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:30 stream-7 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 15 19:33:30 stream-7 volumio[961]: info: ------------------------------ 51ms Sep 15 19:33:30 stream-7 volumio[961]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 15 19:33:30 stream-7 volumio[961]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 15 19:33:30 stream-7 volumio[961]: info: [GPIO_Control] Status changed: play Sep 15 19:33:30 stream-7 volumio[961]: info: [GPIO_Control] Handling event: musicPlay Sep 15 19:33:30 stream-7 volumio[961]: info: [GPIO_Control] Status changed: play Sep 15 19:33:30 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:30 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:30 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:30 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:30 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:31 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:31 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:31 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:31 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:31 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:32 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:32 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:32 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:32 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:32 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:33 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:33 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:33 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:33 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:33 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:34 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:34 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:35 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:35 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:35 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:35 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:35 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:35 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:36 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:36 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:36 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:36 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:37 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:37 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:37 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:37 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:37 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:37 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:38 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:38 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:38 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:38 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:39 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:39 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:39 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:39 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:39 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:40 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:40 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:40 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:40 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:40 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:41 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:41 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:41 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:41 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:41 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:42 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:42 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:42 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:42 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:42 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:43 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:43 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:43 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:43 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:43 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:44 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:44 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:44 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:45 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:45 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:45 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:45 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:45 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:46 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:46 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:46 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:46 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:46 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:47 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:47 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:47 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:47 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:47 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:48 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:48 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:48 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:48 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:48 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:49 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:49 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:49 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:49 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:49 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:50 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:50 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:50 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:50 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:50 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:51 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:51 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:51 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:51 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:51 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:52 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:52 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:52 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:52 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:52 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:53 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:53 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:53 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:53 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:53 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:54 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:54 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:54 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:54 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:54 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:55 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:55 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:55 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:55 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:55 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:56 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:56 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:56 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:56 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:56 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:57 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:57 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:57 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:57 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:57 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:58 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:58 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:58 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:58 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:58 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:33:59 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:33:59 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:33:59 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:33:59 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:33:59 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:34:00 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:34:00 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:34:00 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:34:00 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:34:00 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:34:01 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:34:01 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:34:01 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:34:01 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:34:01 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:34:02 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:34:02 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:34:02 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:34:02 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:34:02 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:34:03 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:34:03 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:34:03 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:34:03 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:34:03 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:34:04 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:34:04 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:34:04 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:34:04 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:34:04 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:34:05 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:34:05 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:34:05 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:34:05 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:34:05 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:34:06 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:34:06 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:34:06 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:34:06 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:34:06 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:34:07 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:34:07 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:34:07 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:34:07 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:34:07 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:34:08 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:34:08 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:34:08 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:34:08 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:34:08 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:34:09 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:34:09 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:34:09 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:34:09 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:34:09 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:34:10 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 15 19:34:10 stream-7 volumio[961]: info: CorePlayQueue::getTrack 6 Sep 15 19:34:10 stream-7 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 15 19:34:10 stream-7 volumio[961]: info: CoreStateMachine::getQueue Sep 15 19:34:10 stream-7 volumio[961]: info: CorePlayQueue::getQueue Sep 15 19:34:10 stream-7 volumio[961]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ Sep 15 19:34:10 stream-7 volumio[961]: info: PLUGIN onShutdown : networkfs Sep 15 19:34:10 stream-7 volumio[961]: info: PLUGIN onShutdown : audiophonicsonoff Sep 15 19:34:10 stream-7 volumio[961]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 19:34:10 stream-7 volumio[961]: TypeError: Cannot read property 'writeSync' of undefined Sep 15 19:34:10 stream-7 volumio[961]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_hardware/audiophonicsonoff/index.js:48:25) Sep 15 19:34:10 stream-7 volumio[961]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) Sep 15 19:34:10 stream-7 volumio[961]: at HashMap. (/volumio/app/pluginmanager.js:621:31) Sep 15 19:34:10 stream-7 volumio[961]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Sep 15 19:34:10 stream-7 volumio[961]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Sep 15 19:34:10 stream-7 volumio[961]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) Sep 15 19:34:10 stream-7 volumio[961]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1328:22) Sep 15 19:34:10 stream-7 volumio[961]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:859:33) Sep 15 19:34:10 stream-7 volumio[961]: at Socket.emit (events.js:315:20) Sep 15 19:34:10 stream-7 volumio[961]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 15 19:34:10 stream-7 volumio[961]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 15 19:34:10 stream-7 volumio[961]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 19:34:11 stream-7 sudo[2424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-15 19:33 Sep 15 19:34:11 stream-7 sudo[2424]: 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="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"