Sep 01 14:16:01 volumio volumio[1264]: info: VolumeController::SetAlsaVolume68 Sep 01 14:16:01 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:01 volumio volumio[1264]: info: CorePlayQueue::getTrack 1 Sep 01 14:16:01 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:16:01 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:02 volumio volumio[1264]: info: CoreCommandRouter::volumioSeek Sep 01 14:16:02 volumio volumio[1264]: info: CoreStateMachine::seek Sep 01 14:16:02 volumio volumio[1264]: info: CorePlayQueue::getTrack 1 Sep 01 14:16:02 volumio volumio[1264]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/02. Celine Dion - If Love Is Out of the Question.flac","service":"mpd","name":"If Love Is Out of the Question","artist":"Céline Dion","album":"Unison","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=232&web=C%C3%A9line%20Dion/Unison/extralarge&path=%2FINTERNAL%2FCeline%20Dion%20-%201990%20-%20Unison%20(24bit-96kHz)&metadata=false","duration":234,"samplerate":"96 kHz","bitdepth":"24 bit","trackType":"flac","channels":2} Sep 01 14:16:02 volumio volumio[1264]: info: CoreStateMachine::startPlaybackTimer Sep 01 14:16:02 volumio volumio[1264]: info: CorePlayQueue::getTrack 1 Sep 01 14:16:02 volumio volumio[1264]: info: ControllerMpd::seek Sep 01 14:16:02 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:02 volumio volumio[1264]: info: CorePlayQueue::getTrack 1 Sep 01 14:16:02 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:16:02 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:02 volumio volumio[1264]: error: null Sep 01 14:16:02 volumio volumio[1264]: info: Sep 01 14:16:02 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:16:02 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:16:02 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:16:02 volumio volumio[1264]: info: Sep 01 14:16:02 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:16:02 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:16:02 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:16:02 volumio volumio[1264]: info: Sep 01 14:16:02 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:16:02 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:16:02 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:16:02 volumio volumio[1264]: info: sendMpdCommand status took 0 milliseconds Sep 01 14:16:02 volumio volumio[1264]: info: sendMpdCommand status took 1 milliseconds Sep 01 14:16:02 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:16:02 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:16:02 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:16:02 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:16:02 volumio volumio[1264]: info: sendMpdCommand status took 1 milliseconds Sep 01 14:16:02 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:16:02 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:16:02 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 01 14:16:02 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 01 14:16:02 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 01 14:16:02 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:16:02 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:16:02 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:16:02 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:16:02 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:16:02 volumio volumio[1264]: info: CorePlayQueue::getTrack 1 Sep 01 14:16:02 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":120000,"duration":234,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3177 Kbps","isStreaming":false,"title":"If Love Is Out of the Question","artist":"Céline Dion","album":"Unison","uri":"INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/02. Celine Dion - If Love Is Out of the Question.flac","trackType":"flac"} Sep 01 14:16:02 volumio volumio[1264]: verbose: CURRENT POSITION 1 Sep 01 14:16:02 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:16:02 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:16:02 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:16:02 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:02 volumio volumio[1264]: info: CorePlayQueue::getTrack 1 Sep 01 14:16:02 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:02 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:02 volumio volumio[1264]: info: CorePlayQueue::getTrack 1 Sep 01 14:16:02 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:02 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:16:02 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:16:02 volumio volumio[1264]: info: CorePlayQueue::getTrack 1 Sep 01 14:16:02 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":120000,"duration":234,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3177 Kbps","isStreaming":false,"title":"If Love Is Out of the Question","artist":"Céline Dion","album":"Unison","uri":"INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/02. Celine Dion - If Love Is Out of the Question.flac","trackType":"flac"} Sep 01 14:16:02 volumio volumio[1264]: verbose: CURRENT POSITION 1 Sep 01 14:16:02 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:16:02 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:16:02 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:16:02 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:02 volumio volumio[1264]: info: CorePlayQueue::getTrack 1 Sep 01 14:16:02 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:02 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:02 volumio volumio[1264]: info: CorePlayQueue::getTrack 1 Sep 01 14:16:02 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:02 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:16:02 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:16:02 volumio volumio[1264]: info: CorePlayQueue::getTrack 1 Sep 01 14:16:02 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":120000,"duration":234,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3177 Kbps","isStreaming":false,"title":"If Love Is Out of the Question","artist":"Céline Dion","album":"Unison","uri":"INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/02. Celine Dion - If Love Is Out of the Question.flac","trackType":"flac"} Sep 01 14:16:02 volumio volumio[1264]: verbose: CURRENT POSITION 1 Sep 01 14:16:02 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:16:02 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:16:02 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:16:02 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:02 volumio volumio[1264]: info: CorePlayQueue::getTrack 1 Sep 01 14:16:02 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:02 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:02 volumio volumio[1264]: info: CorePlayQueue::getTrack 1 Sep 01 14:16:02 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:02 volumio volumio[1264]: info: ------------------------------ 13ms Sep 01 14:16:02 volumio volumio[1264]: info: ------------------------------ 12ms Sep 01 14:16:02 volumio volumio[1264]: info: ------------------------------ 12ms Sep 01 14:16:03 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 01 14:16:03 volumio systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories... Sep 01 14:16:03 volumio dbus-daemon[965]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.44' (uid=0 pid=5412 comm="timedatectl show --property=NTPSynchronized --valu") Sep 01 14:16:03 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 01 14:16:03 volumio systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully. Sep 01 14:16:03 volumio systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories. Sep 01 14:16:03 volumio systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dclean.service.mount: Deactivated successfully. Sep 01 14:16:03 volumio dbus-daemon[965]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 01 14:16:03 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 01 14:16:03 volumio setdatetime-helper.sh[5410]: Time is not synchronized. Attempting to sync... Sep 01 14:16:03 volumio sudo[5421]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Mon, 01 Sep 2025 11:16:04 GMT#015' Sep 01 14:16:03 volumio sudo[5421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 01 14:16:04 volumio setdatetime-helper.sh[5422]: Mon Sep 1 14:16:04 MSK 2025 Sep 01 14:16:04 volumio sudo[5421]: pam_unix(sudo:session): session closed for user root Sep 01 14:16:04 volumio setdatetime-helper.sh[5410]: Time synchronized successfully. Sep 01 14:16:04 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 01 14:16:04 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::volumioNext Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::next Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::stop Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::stPlaybackTimer Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::updateTrackBlock Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrackBlock Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 1 Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::serviceStop Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 1 Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::serviceStop Sep 01 14:16:04 volumio volumio[1264]: info: ControllerMpd::stop Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 14:16:04 volumio volumio[1264]: info: Sep 01 14:16:04 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:16:04 volumio volumio[1264]: info: sendMpdCommand stop took 14 milliseconds Sep 01 14:16:04 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::play index undefined Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::startPlaybackTimer Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/03. Celine Dion - Where Does My Heart Beat Now.flac Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::updateTrackBlock Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrackBlock Sep 01 14:16:04 volumio volumio[1264]: info: Sep 01 14:16:04 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:16:04 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:16:04 volumio volumio[1264]: info: Sep 01 14:16:04 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:16:04 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:16:04 volumio volumio[1264]: info: sendMpdCommand status took 1 milliseconds Sep 01 14:16:04 volumio volumio[1264]: info: sendMpdCommand stop took 1 milliseconds Sep 01 14:16:04 volumio volumio[1264]: info: sendMpdCommand status took 1 milliseconds Sep 01 14:16:04 volumio volumio[1264]: info: sendMpdCommand status took 1 milliseconds Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:16:04 volumio volumio[1264]: info: touch_display: Setting screensaver timeout to 300 seconds. Sep 01 14:16:04 volumio volumio[1264]: info: Sep 01 14:16:04 volumio volumio[1264]: ---------------------------- MPD announces system playlist update Sep 01 14:16:04 volumio volumio[1264]: info: Ignoring MPD Status Update Sep 01 14:16:04 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 14:16:04 volumio volumio[1264]: info: sendMpdCommand clear took 1 milliseconds Sep 01 14:16:04 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 14:16:04 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/03. Celine Dion - Where Does My Heart Beat Now.flac" Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:16:04 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:04 volumio volumio[1264]: 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":"If Love Is Out of the Question","artist":"Céline Dion","album":"Unison","uri":"INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/02. Celine Dion - If Love Is Out of the Question.flac","trackType":"flac"} Sep 01 14:16:04 volumio volumio[1264]: verbose: CURRENT POSITION 2 Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::syncState stateService stop Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:04 volumio volumio[1264]: info: No code Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:04 volumio volumio[1264]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Sep 01 14:16:04 volumio volumio[1264]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Sep 01 14:16:04 volumio volumio[1264]: info: ------------------------------ 13ms Sep 01 14:16:04 volumio volumio[1264]: info: ------------------------------ 10ms Sep 01 14:16:04 volumio volumio[1264]: info: ------------------------------ 11ms Sep 01 14:16:04 volumio volumio[1264]: info: Sep 01 14:16:04 volumio volumio[1264]: ---------------------------- MPD announces system playlist update Sep 01 14:16:04 volumio volumio[1264]: info: Ignoring MPD Status Update Sep 01 14:16:04 volumio volumio[1264]: info: Sep 01 14:16:04 volumio volumio[1264]: ---------------------------- MPD announces system playlist update Sep 01 14:16:04 volumio volumio[1264]: info: Ignoring MPD Status Update Sep 01 14:16:04 volumio volumio[1264]: error: updateQueue error: null Sep 01 14:16:04 volumio volumio[1264]: info: Sep 01 14:16:04 volumio volumio[1264]: ---------------------------- MPD announces system playlist update Sep 01 14:16:04 volumio volumio[1264]: info: Ignoring MPD Status Update Sep 01 14:16:04 volumio volumio[1264]: info: ------------------------------ 12ms Sep 01 14:16:04 volumio volumio[1264]: info: sendMpdCommand add "INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/03. Celine Dion - Where Does My Heart Beat Now.flac" took 11 milliseconds Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand play Sep 01 14:16:04 volumio volumio[1264]: info: ------------------------------ 5ms Sep 01 14:16:04 volumio volumio[1264]: info: ------------------------------ 5ms Sep 01 14:16:04 volumio volumio[1264]: info: ------------------------------ 4ms Sep 01 14:16:04 volumio volumio[1264]: info: sendMpdCommand play took 3 milliseconds Sep 01 14:16:04 volumio volumio[1264]: info: Sep 01 14:16:04 volumio volumio[1264]: ---------------------------- MPD announces system playlist update Sep 01 14:16:04 volumio volumio[1264]: info: Ignoring MPD Status Update Sep 01 14:16:04 volumio volumio[1264]: info: Sep 01 14:16:04 volumio volumio[1264]: ---------------------------- MPD announces system playlist update Sep 01 14:16:04 volumio volumio[1264]: info: Ignoring MPD Status Update Sep 01 14:16:04 volumio volumio[1264]: info: ------------------------------ 0ms Sep 01 14:16:04 volumio volumio[1264]: info: ------------------------------ 0ms Sep 01 14:16:04 volumio volumio[1264]: info: Sep 01 14:16:04 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:16:04 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:16:04 volumio volumio[1264]: info: Sep 01 14:16:04 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:16:04 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:16:04 volumio volumio[1264]: info: Sep 01 14:16:04 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:16:04 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:16:04 volumio volumio[1264]: info: Sep 01 14:16:04 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:16:04 volumio volumio[1264]: info: sendMpdCommand status took 7 milliseconds Sep 01 14:16:04 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:16:04 volumio volumio[1264]: info: sendMpdCommand status took 4 milliseconds Sep 01 14:16:04 volumio volumio[1264]: info: sendMpdCommand status took 2 milliseconds Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:16:04 volumio volumio[1264]: info: Sep 01 14:16:04 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:16:04 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:16:04 volumio volumio[1264]: info: Sep 01 14:16:04 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:16:04 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:16:04 volumio volumio[1264]: info: sendMpdCommand status took 3 milliseconds Sep 01 14:16:04 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 14:16:04 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 14:16:04 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 01 14:16:04 volumio volumio[1264]: info: sendMpdCommand status took 3 milliseconds Sep 01 14:16:04 volumio volumio[1264]: info: sendMpdCommand status took 3 milliseconds Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:16:04 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:04 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Where Does My Heart Beat Now","artist":"Céline Dion","album":"Unison","uri":"INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/03. Celine Dion - Where Does My Heart Beat Now.flac","trackType":"flac"} Sep 01 14:16:04 volumio volumio[1264]: verbose: CURRENT POSITION 2 Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:16:04 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:04 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Where Does My Heart Beat Now","artist":"Céline Dion","album":"Unison","uri":"INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/03. Celine Dion - Where Does My Heart Beat Now.flac","trackType":"flac"} Sep 01 14:16:04 volumio volumio[1264]: verbose: CURRENT POSITION 2 Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:16:04 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:04 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:04 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Where Does My Heart Beat Now","artist":"Céline Dion","album":"Unison","uri":"INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/03. Celine Dion - Where Does My Heart Beat Now.flac","trackType":"flac"} Sep 01 14:16:04 volumio volumio[1264]: verbose: CURRENT POSITION 2 Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:16:04 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:04 volumio volumio[1264]: info: ------------------------------ 18ms Sep 01 14:16:04 volumio volumio[1264]: info: ------------------------------ 23ms Sep 01 14:16:04 volumio volumio[1264]: info: ------------------------------ 20ms Sep 01 14:16:04 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 01 14:16:04 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 15 milliseconds Sep 01 14:16:04 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:16:04 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:16:04 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:04 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Where Does My Heart Beat Now","artist":"Céline Dion","album":"Unison","uri":"INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/03. Celine Dion - Where Does My Heart Beat Now.flac","trackType":"flac"} Sep 01 14:16:04 volumio volumio[1264]: verbose: CURRENT POSITION 2 Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:16:04 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:04 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:04 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Where Does My Heart Beat Now","artist":"Céline Dion","album":"Unison","uri":"INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/03. Celine Dion - Where Does My Heart Beat Now.flac","trackType":"flac"} Sep 01 14:16:04 volumio volumio[1264]: verbose: CURRENT POSITION 2 Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:16:04 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:04 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:04 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Where Does My Heart Beat Now","artist":"Céline Dion","album":"Unison","uri":"INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/03. Celine Dion - Where Does My Heart Beat Now.flac","trackType":"flac"} Sep 01 14:16:04 volumio volumio[1264]: verbose: CURRENT POSITION 2 Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:16:04 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:04 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:04 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:04 volumio volumio[1264]: info: ------------------------------ 37ms Sep 01 14:16:04 volumio volumio[1264]: info: ------------------------------ 35ms Sep 01 14:16:04 volumio volumio[1264]: info: ------------------------------ 35ms Sep 01 14:16:09 volumio volumio[1264]: info: VolumeController::SetAlsaVolume88 Sep 01 14:16:09 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:09 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:09 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:16:09 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:15 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 01 14:16:15 volumio volumio[1264]: info: CURURI: music-library/INTERNAL Sep 01 14:16:16 volumio volumio[1264]: info: Preload queue cleared Sep 01 14:16:19 volumio volumio[1264]: info: VolumeController::SetAlsaVolume100 Sep 01 14:16:19 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:19 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:19 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:16:19 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:21 volumio volumio[1264]: info: VolumeController::SetAlsaVolume92 Sep 01 14:16:21 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:21 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:21 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:16:21 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:21 volumio volumio[1264]: info: VolumeController::SetAlsaVolume85 Sep 01 14:16:21 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:21 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:21 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:16:21 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:22 volumio volumio[1264]: info: VolumeController::SetAlsaVolume88 Sep 01 14:16:22 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:16:22 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:22 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:16:22 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:16:24 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 14:16:24 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 14:16:24 volumio volumio[1264]: info: Discovery: Getting this device information Sep 01 14:16:24 volumio volumio[1264]: info: CoreCommandRouter::volumioGetState Sep 01 14:16:24 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:24 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 14:16:28 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 14:16:28 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 14:16:28 volumio volumio[1264]: info: Discovery: Getting this device information Sep 01 14:16:28 volumio volumio[1264]: info: CoreCommandRouter::volumioGetState Sep 01 14:16:28 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:28 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 14:16:33 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 01 14:16:58 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 14:16:58 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 14:16:58 volumio volumio[1264]: info: Discovery: Getting this device information Sep 01 14:16:58 volumio volumio[1264]: info: CoreCommandRouter::volumioGetState Sep 01 14:16:58 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:16:58 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::volumioNext Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::next Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::stop Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::stPlaybackTimer Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::updateTrackBlock Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrackBlock Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::serviceStop Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 2 Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::serviceStop Sep 01 14:17:07 volumio volumio[1264]: info: ControllerMpd::stop Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 14:17:07 volumio volumio[1264]: info: Sep 01 14:17:07 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:07 volumio volumio[1264]: info: sendMpdCommand stop took 8 milliseconds Sep 01 14:17:07 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::play index undefined Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::startPlaybackTimer Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/04. Celine Dion - The Last to Know.flac Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::updateTrackBlock Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrackBlock Sep 01 14:17:07 volumio volumio[1264]: info: Sep 01 14:17:07 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:07 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:07 volumio volumio[1264]: info: Sep 01 14:17:07 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:07 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:07 volumio volumio[1264]: info: sendMpdCommand status took 4 milliseconds Sep 01 14:17:07 volumio volumio[1264]: info: sendMpdCommand stop took 4 milliseconds Sep 01 14:17:07 volumio volumio[1264]: info: sendMpdCommand status took 4 milliseconds Sep 01 14:17:07 volumio volumio[1264]: info: sendMpdCommand status took 3 milliseconds Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:07 volumio volumio[1264]: info: Sep 01 14:17:07 volumio volumio[1264]: ---------------------------- MPD announces system playlist update Sep 01 14:17:07 volumio volumio[1264]: info: Ignoring MPD Status Update Sep 01 14:17:07 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 14:17:07 volumio volumio[1264]: info: sendMpdCommand clear took 1 milliseconds Sep 01 14:17:07 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 14:17:07 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/04. Celine Dion - The Last to Know.flac" Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:07 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:07 volumio volumio[1264]: 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":"Where Does My Heart Beat Now","artist":"Céline Dion","album":"Unison","uri":"INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/03. Celine Dion - Where Does My Heart Beat Now.flac","trackType":"flac"} Sep 01 14:17:07 volumio volumio[1264]: verbose: CURRENT POSITION 3 Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::syncState stateService stop Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:07 volumio volumio[1264]: info: No code Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:07 volumio volumio[1264]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Sep 01 14:17:07 volumio volumio[1264]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Sep 01 14:17:07 volumio volumio[1264]: info: ------------------------------ 15ms Sep 01 14:17:07 volumio volumio[1264]: info: ------------------------------ 14ms Sep 01 14:17:07 volumio volumio[1264]: info: ------------------------------ 14ms Sep 01 14:17:07 volumio volumio[1264]: info: Sep 01 14:17:07 volumio volumio[1264]: ---------------------------- MPD announces system playlist update Sep 01 14:17:07 volumio volumio[1264]: info: Ignoring MPD Status Update Sep 01 14:17:07 volumio volumio[1264]: info: Sep 01 14:17:07 volumio volumio[1264]: ---------------------------- MPD announces system playlist update Sep 01 14:17:07 volumio volumio[1264]: info: Ignoring MPD Status Update Sep 01 14:17:07 volumio volumio[1264]: error: updateQueue error: null Sep 01 14:17:07 volumio volumio[1264]: info: Sep 01 14:17:07 volumio volumio[1264]: ---------------------------- MPD announces system playlist update Sep 01 14:17:07 volumio volumio[1264]: info: Ignoring MPD Status Update Sep 01 14:17:07 volumio volumio[1264]: info: ------------------------------ 10ms Sep 01 14:17:07 volumio volumio[1264]: info: sendMpdCommand add "INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/04. Celine Dion - The Last to Know.flac" took 9 milliseconds Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand play Sep 01 14:17:07 volumio volumio[1264]: info: Sep 01 14:17:07 volumio volumio[1264]: ---------------------------- MPD announces system playlist update Sep 01 14:17:07 volumio volumio[1264]: info: Ignoring MPD Status Update Sep 01 14:17:07 volumio volumio[1264]: info: touch_display: Setting screensaver timeout to 300 seconds. Sep 01 14:17:07 volumio volumio[1264]: info: ------------------------------ 8ms Sep 01 14:17:07 volumio volumio[1264]: info: ------------------------------ 7ms Sep 01 14:17:07 volumio volumio[1264]: info: ------------------------------ 7ms Sep 01 14:17:07 volumio volumio[1264]: info: sendMpdCommand play took 7 milliseconds Sep 01 14:17:07 volumio volumio[1264]: info: ------------------------------ 7ms Sep 01 14:17:07 volumio volumio[1264]: info: Sep 01 14:17:07 volumio volumio[1264]: ---------------------------- MPD announces system playlist update Sep 01 14:17:07 volumio volumio[1264]: info: Ignoring MPD Status Update Sep 01 14:17:07 volumio volumio[1264]: info: Sep 01 14:17:07 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:07 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:07 volumio volumio[1264]: info: ------------------------------ 2ms Sep 01 14:17:07 volumio volumio[1264]: info: Sep 01 14:17:07 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:07 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:07 volumio volumio[1264]: info: Sep 01 14:17:07 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:07 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:07 volumio volumio[1264]: info: Sep 01 14:17:07 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:07 volumio volumio[1264]: info: sendMpdCommand status took 2 milliseconds Sep 01 14:17:07 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:07 volumio volumio[1264]: info: sendMpdCommand status took 2 milliseconds Sep 01 14:17:07 volumio volumio[1264]: info: sendMpdCommand status took 1 milliseconds Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:07 volumio volumio[1264]: info: Sep 01 14:17:07 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:07 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:07 volumio volumio[1264]: info: Sep 01 14:17:07 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:07 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:07 volumio volumio[1264]: info: sendMpdCommand status took 4 milliseconds Sep 01 14:17:07 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 01 14:17:07 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 01 14:17:07 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 01 14:17:07 volumio volumio[1264]: info: sendMpdCommand status took 2 milliseconds Sep 01 14:17:07 volumio volumio[1264]: info: sendMpdCommand status took 2 milliseconds Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:07 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:07 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Last to Know","artist":"Céline Dion","album":"Unison","uri":"INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/04. Celine Dion - The Last to Know.flac","trackType":"flac"} Sep 01 14:17:07 volumio volumio[1264]: verbose: CURRENT POSITION 3 Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:17:07 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:07 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Last to Know","artist":"Céline Dion","album":"Unison","uri":"INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/04. Celine Dion - The Last to Know.flac","trackType":"flac"} Sep 01 14:17:07 volumio volumio[1264]: verbose: CURRENT POSITION 3 Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:17:07 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:07 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:07 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Last to Know","artist":"Céline Dion","album":"Unison","uri":"INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/04. Celine Dion - The Last to Know.flac","trackType":"flac"} Sep 01 14:17:07 volumio volumio[1264]: verbose: CURRENT POSITION 3 Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:17:07 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:07 volumio volumio[1264]: info: ------------------------------ 11ms Sep 01 14:17:07 volumio volumio[1264]: info: ------------------------------ 15ms Sep 01 14:17:07 volumio volumio[1264]: info: ------------------------------ 14ms Sep 01 14:17:07 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 01 14:17:07 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 01 14:17:07 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:07 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:07 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:07 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Last to Know","artist":"Céline Dion","album":"Unison","uri":"INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/04. Celine Dion - The Last to Know.flac","trackType":"flac"} Sep 01 14:17:07 volumio volumio[1264]: verbose: CURRENT POSITION 3 Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:17:07 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:07 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:07 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Last to Know","artist":"Céline Dion","album":"Unison","uri":"INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/04. Celine Dion - The Last to Know.flac","trackType":"flac"} Sep 01 14:17:07 volumio volumio[1264]: verbose: CURRENT POSITION 3 Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:17:07 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:07 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:07 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Last to Know","artist":"Céline Dion","album":"Unison","uri":"INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/04. Celine Dion - The Last to Know.flac","trackType":"flac"} Sep 01 14:17:07 volumio volumio[1264]: verbose: CURRENT POSITION 3 Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:17:07 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:07 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:07 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:07 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:07 volumio volumio[1264]: info: ------------------------------ 28ms Sep 01 14:17:07 volumio volumio[1264]: info: ------------------------------ 28ms Sep 01 14:17:07 volumio volumio[1264]: info: ------------------------------ 27ms Sep 01 14:17:08 volumio volumio[1264]: info: CoreCommandRouter::volumioSeek Sep 01 14:17:08 volumio volumio[1264]: info: CoreStateMachine::seek Sep 01 14:17:08 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:08 volumio volumio[1264]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/04. Celine Dion - The Last to Know.flac","service":"mpd","name":"The Last to Know","artist":"Céline Dion","album":"Unison","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=232&web=C%C3%A9line%20Dion/Unison/extralarge&path=%2FINTERNAL%2FCeline%20Dion%20-%201990%20-%20Unison%20(24bit-96kHz)&metadata=false","duration":276,"samplerate":"96 kHz","bitdepth":"24 bit","trackType":"flac","channels":2} Sep 01 14:17:08 volumio volumio[1264]: info: CoreStateMachine::startPlaybackTimer Sep 01 14:17:08 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:08 volumio volumio[1264]: info: ControllerMpd::seek Sep 01 14:17:08 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:08 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:08 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:17:08 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:08 volumio volumio[1264]: error: null Sep 01 14:17:08 volumio volumio[1264]: info: Sep 01 14:17:08 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:08 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:08 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:08 volumio volumio[1264]: info: Sep 01 14:17:08 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:08 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:08 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:08 volumio volumio[1264]: info: Sep 01 14:17:08 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:08 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:08 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:08 volumio volumio[1264]: info: sendMpdCommand status took 1 milliseconds Sep 01 14:17:08 volumio volumio[1264]: info: sendMpdCommand status took 1 milliseconds Sep 01 14:17:08 volumio volumio[1264]: info: sendMpdCommand status took 1 milliseconds Sep 01 14:17:08 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:08 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:08 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:08 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:08 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:08 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:08 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 14:17:08 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 14:17:08 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 14:17:08 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:08 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:08 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:08 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:17:08 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:17:08 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:08 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":172000,"duration":276,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2949 Kbps","isStreaming":false,"title":"The Last to Know","artist":"Céline Dion","album":"Unison","uri":"INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/04. Celine Dion - The Last to Know.flac","trackType":"flac"} Sep 01 14:17:08 volumio volumio[1264]: verbose: CURRENT POSITION 3 Sep 01 14:17:08 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:17:08 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:17:08 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:17:08 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:08 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:08 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:08 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:08 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:08 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:08 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:17:08 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:17:08 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:08 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":172000,"duration":276,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2949 Kbps","isStreaming":false,"title":"The Last to Know","artist":"Céline Dion","album":"Unison","uri":"INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/04. Celine Dion - The Last to Know.flac","trackType":"flac"} Sep 01 14:17:08 volumio volumio[1264]: verbose: CURRENT POSITION 3 Sep 01 14:17:08 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:17:08 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:17:08 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:17:08 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:08 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:08 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:08 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:08 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:08 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:08 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:17:08 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:17:08 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:08 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":172000,"duration":276,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2949 Kbps","isStreaming":false,"title":"The Last to Know","artist":"Céline Dion","album":"Unison","uri":"INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/04. Celine Dion - The Last to Know.flac","trackType":"flac"} Sep 01 14:17:08 volumio volumio[1264]: verbose: CURRENT POSITION 3 Sep 01 14:17:08 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:17:08 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:17:08 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:17:08 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:08 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:08 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:08 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:08 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:08 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:08 volumio volumio[1264]: info: ------------------------------ 17ms Sep 01 14:17:08 volumio volumio[1264]: info: ------------------------------ 17ms Sep 01 14:17:08 volumio volumio[1264]: info: ------------------------------ 17ms Sep 01 14:17:10 volumio volumio[1264]: info: VolumeController::SetAlsaVolume66 Sep 01 14:17:10 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:10 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:10 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:17:10 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:13 volumio volumio[1264]: info: CoreCommandRouter::volumioGetState Sep 01 14:17:13 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:13 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 14:17:13 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 14:17:13 volumio volumio[1264]: info: Discovery: Getting this device information Sep 01 14:17:13 volumio volumio[1264]: info: CoreCommandRouter::volumioGetState Sep 01 14:17:13 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:13 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 14:17:13 volumio sudo[5704]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 14:17:13 volumio sudo[5704]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 14:17:13 volumio sudo[5704]: pam_unix(sudo:session): session closed for user root Sep 01 14:17:13 volumio sudo[5706]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 14:17:13 volumio sudo[5706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 14:17:13 volumio sudo[5706]: pam_unix(sudo:session): session closed for user root Sep 01 14:17:13 volumio volumio[1264]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.56 UA: Mozilla/5.0 (Linux; Android 15; 23013PC75G Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.143 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Sep 01 14:17:13 volumio sudo[5711]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 14:17:13 volumio sudo[5711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 14:17:13 volumio sudo[5711]: pam_unix(sudo:session): session closed for user root Sep 01 14:17:13 volumio sudo[5713]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 14:17:13 volumio sudo[5713]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 14:17:13 volumio sudo[5713]: pam_unix(sudo:session): session closed for user root Sep 01 14:17:13 volumio volumio[1264]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.56 UA: Mozilla/5.0 (Linux; Android 15; 23013PC75G Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.143 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Sep 01 14:17:13 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 01 14:17:13 volumio volumio[1264]: info: CoreCommandRouter::volumioGetVisibleSources Sep 01 14:17:13 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 14:17:13 volumio volumio[1264]: info: CoreCommandRouter::volumioGetState Sep 01 14:17:13 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:13 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 01 14:17:13 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 01 14:17:13 volumio volumio[1264]: info: Received Get System Info Sep 01 14:17:13 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 14:17:13 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 14:17:13 volumio volumio[1264]: info: Discovery: Getting this device information Sep 01 14:17:13 volumio volumio[1264]: info: CoreCommandRouter::volumioGetState Sep 01 14:17:13 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:13 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 14:17:13 volumio volumio[1264]: info: CoreCommandRouter::volumioGetState Sep 01 14:17:13 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:13 volumio volumio[1264]: info: Listing playlists Sep 01 14:17:13 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 01 14:17:13 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 01 14:17:13 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 01 14:17:14 volumio volumio[1264]: info: CoreCommandRouter::volumioGetQueue Sep 01 14:17:14 volumio volumio[1264]: info: CoreStateMachine::getQueue Sep 01 14:17:14 volumio volumio[1264]: info: CorePlayQueue::getQueue Sep 01 14:17:15 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 01 14:17:15 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 14:17:15 volumio volumio[1264]: info: Received Get System Info Sep 01 14:17:15 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 14:17:15 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 14:17:15 volumio volumio[1264]: info: Discovery: Getting this device information Sep 01 14:17:15 volumio volumio[1264]: info: CoreCommandRouter::volumioGetState Sep 01 14:17:15 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:15 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 14:17:16 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 14:17:16 volumio volumio[1264]: info: Received Get System Info Sep 01 14:17:16 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 14:17:16 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 14:17:16 volumio volumio[1264]: info: Discovery: Getting this device information Sep 01 14:17:16 volumio volumio[1264]: info: CoreCommandRouter::volumioGetState Sep 01 14:17:16 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:16 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 14:17:17 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 01 14:17:17 volumio dbus-daemon[965]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.46' (uid=0 pid=5723 comm="timedatectl show --property=NTPSynchronized --valu") Sep 01 14:17:17 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 01 14:17:17 volumio dbus-daemon[965]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 01 14:17:17 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 01 14:17:17 volumio setdatetime-helper.sh[5722]: Time is not synchronized. Attempting to sync... Sep 01 14:17:17 volumio sudo[5733]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Mon, 01 Sep 2025 11:17:17 GMT#015' Sep 01 14:17:17 volumio sudo[5733]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 01 14:17:17 volumio setdatetime-helper.sh[5734]: Mon Sep 1 14:17:17 MSK 2025 Sep 01 14:17:17 volumio setdatetime-helper.sh[5722]: Time synchronized successfully. Sep 01 14:17:17 volumio sudo[5733]: pam_unix(sudo:session): session closed for user root Sep 01 14:17:17 volumio systemd-journald[428]: Time jumped backwards, rotating. Sep 01 14:17:17 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 01 14:17:17 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 01 14:17:17 volumio volumio[1264]: info: Preload queue cleared Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::ClearQueue Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::stop Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::stPlaybackTimer Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::updateTrackBlock Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrackBlock Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::serviceStop Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 3 Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::serviceStop Sep 01 14:17:17 volumio volumio[1264]: info: ControllerMpd::stop Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::clearPlayQueue Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::saveQueue Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::volumioPushQueue Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::addQueueItems Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::addQueueItems Sep 01 14:17:17 volumio volumio[1264]: info: Preload queue cleared Sep 01 14:17:17 volumio volumio[1264]: info: Adding Item to queue: music-library/INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44) Sep 01 14:17:17 volumio volumio[1264]: info: Exploding uri music-library/INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44) in service mpd Sep 01 14:17:17 volumio volumio[1264]: info: Sep 01 14:17:17 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:17 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Celine%20Dion/The%20Very%20Best%20of%20Celine%20Dion/extralarge&path=%2Fmnt%2FINTERNAL%2FCeline%20Dion%20-%201999%20-%20The%20Very%20Best%20of%20Celine%20Dion%20(16-44)(24-44)%2F01%20-%20The%20Power%20of%20Love%20(Radio%20Edit).flac&metadata=false Sep 01 14:17:17 volumio volumio[1264]: info: URI /mnt/INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/01 - The Power of Love (Radio Edit).flac Sep 01 14:17:17 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Celine%20Dion/The%20Very%20Best%20of%20Celine%20Dion/extralarge&path=%2Fmnt%2FINTERNAL%2FCeline%20Dion%20-%201999%20-%20The%20Very%20Best%20of%20Celine%20Dion%20(16-44)(24-44)%2F02%20-%20If%20You%20Asked%20Me%20To.flac&metadata=false Sep 01 14:17:17 volumio volumio[1264]: info: URI /mnt/INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/02 - If You Asked Me To.flac Sep 01 14:17:17 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Celine%20Dion/The%20Very%20Best%20of%20Celine%20Dion/extralarge&path=%2Fmnt%2FINTERNAL%2FCeline%20Dion%20-%201999%20-%20The%20Very%20Best%20of%20Celine%20Dion%20(16-44)(24-44)%2F03%20-%20Because%20You%20Loved%20Me%20(Theme%20from%20Up%20Close%20and%20Personal).flac&metadata=false Sep 01 14:17:17 volumio volumio[1264]: info: URI /mnt/INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/03 - Because You Loved Me (Theme from Up Close and Personal).flac Sep 01 14:17:17 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Celine%20Dion/The%20Very%20Best%20of%20Celine%20Dion/extralarge&path=%2Fmnt%2FINTERNAL%2FCeline%20Dion%20-%201999%20-%20The%20Very%20Best%20of%20Celine%20Dion%20(16-44)(24-44)%2F04%20-%20It's%20All%20Coming%20Back%20to%20Me%20Now%20(Radio%20Version).flac&metadata=false Sep 01 14:17:17 volumio volumio[1264]: info: URI /mnt/INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/04 - It's All Coming Back to Me Now (Radio Version).flac Sep 01 14:17:17 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Celine%20Dion/The%20Very%20Best%20of%20Celine%20Dion/extralarge&path=%2Fmnt%2FINTERNAL%2FCeline%20Dion%20-%201999%20-%20The%20Very%20Best%20of%20Celine%20Dion%20(16-44)(24-44)%2F05%20-%20Love%20Can%20Move%20Mountains.flac&metadata=false Sep 01 14:17:17 volumio volumio[1264]: info: URI /mnt/INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/05 - Love Can Move Mountains.flac Sep 01 14:17:17 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Celine%20Dion/The%20Very%20Best%20of%20Celine%20Dion/extralarge&path=%2Fmnt%2FINTERNAL%2FCeline%20Dion%20-%201999%20-%20The%20Very%20Best%20of%20Celine%20Dion%20(16-44)(24-44)%2F06%20-%20To%20Love%20You%20More%20(Radio%20Edit).flac&metadata=false Sep 01 14:17:17 volumio volumio[1264]: info: URI /mnt/INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/06 - To Love You More (Radio Edit).flac Sep 01 14:17:17 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Celine%20Dion/The%20Very%20Best%20of%20Celine%20Dion/extralarge&path=%2Fmnt%2FINTERNAL%2FCeline%20Dion%20-%201999%20-%20The%20Very%20Best%20of%20Celine%20Dion%20(16-44)(24-44)%2F07%20-%20My%20Heart%20Will%20Go%20On%20(Love%20Theme%20from%20Titanic).flac&metadata=false Sep 01 14:17:17 volumio volumio[1264]: info: URI /mnt/INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/07 - My Heart Will Go On (Love Theme from Titanic).flac Sep 01 14:17:17 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Celine%20Dion/The%20Very%20Best%20of%20Celine%20Dion/extralarge&path=%2Fmnt%2FINTERNAL%2FCeline%20Dion%20-%201999%20-%20The%20Very%20Best%20of%20Celine%20Dion%20(16-44)(24-44)%2F08%20-%20That's%20the%20Way%20It%20Is.flac&metadata=false Sep 01 14:17:17 volumio volumio[1264]: info: URI /mnt/INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/08 - That's the Way It Is.flac Sep 01 14:17:17 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Celine%20Dion/The%20Very%20Best%20of%20Celine%20Dion/extralarge&path=%2Fmnt%2FINTERNAL%2FCeline%20Dion%20-%201999%20-%20The%20Very%20Best%20of%20Celine%20Dion%20(16-44)(24-44)%2F09%20-%20If%20Walls%20Could%20Talk.flac&metadata=false Sep 01 14:17:17 volumio volumio[1264]: info: URI /mnt/INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/09 - If Walls Could Talk.flac Sep 01 14:17:17 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Celine%20Dion/The%20Very%20Best%20of%20Celine%20Dion/extralarge&path=%2Fmnt%2FINTERNAL%2FCeline%20Dion%20-%201999%20-%20The%20Very%20Best%20of%20Celine%20Dion%20(16-44)(24-44)%2F10%20-%20The%20First%20Time%20Ever%20I%20Saw%20Your%20Face.flac&metadata=false Sep 01 14:17:17 volumio volumio[1264]: info: URI /mnt/INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/10 - The First Time Ever I Saw Your Face.flac Sep 01 14:17:17 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Celine%20Dion/The%20Very%20Best%20of%20Celine%20Dion/extralarge&path=%2Fmnt%2FINTERNAL%2FCeline%20Dion%20-%201999%20-%20The%20Very%20Best%20of%20Celine%20Dion%20(16-44)(24-44)%2F11%20-%20Then%20You%20Look%20at%20Me.flac&metadata=false Sep 01 14:17:17 volumio volumio[1264]: info: URI /mnt/INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/11 - Then You Look at Me.flac Sep 01 14:17:17 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Celine%20Dion/The%20Very%20Best%20of%20Celine%20Dion/extralarge&path=%2Fmnt%2FINTERNAL%2FCeline%20Dion%20-%201999%20-%20The%20Very%20Best%20of%20Celine%20Dion%20(16-44)(24-44)%2F12%20-%20I%20Want%20You%20to%20Need%20Me.flac&metadata=false Sep 01 14:17:17 volumio volumio[1264]: info: URI /mnt/INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/12 - I Want You to Need Me.flac Sep 01 14:17:17 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Celine%20Dion/The%20Very%20Best%20of%20Celine%20Dion/extralarge&path=%2Fmnt%2FINTERNAL%2FCeline%20Dion%20-%201999%20-%20The%20Very%20Best%20of%20Celine%20Dion%20(16-44)(24-44)%2F13%20-%20Live%20for%20the%20One%20I%20Love.flac&metadata=false Sep 01 14:17:17 volumio volumio[1264]: info: URI /mnt/INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/13 - Live for the One I Love.flac Sep 01 14:17:17 volumio volumio[1264]: info: sendMpdCommand stop took 21 milliseconds Sep 01 14:17:17 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::volumioPushQueue Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::saveQueue Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::updateTrackBlock Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrackBlock Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::volumioPlay Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::play index 0 Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::stop Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::play index undefined Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::startPlaybackTimer Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/01 - The Power of Love (Radio Edit).flac Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 14:17:17 volumio volumio[1264]: info: Sep 01 14:17:17 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:17 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:17 volumio volumio[1264]: info: Sep 01 14:17:17 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:17 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:17 volumio volumio[1264]: info: sendMpdCommand status took 6 milliseconds Sep 01 14:17:17 volumio volumio[1264]: info: sendMpdCommand stop took 1 milliseconds Sep 01 14:17:17 volumio volumio[1264]: info: sendMpdCommand status took 1 milliseconds Sep 01 14:17:17 volumio volumio[1264]: info: sendMpdCommand status took 0 milliseconds Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:17 volumio volumio[1264]: info: touch_display: Setting screensaver timeout to 300 seconds. Sep 01 14:17:17 volumio volumio[1264]: info: Sep 01 14:17:17 volumio volumio[1264]: ---------------------------- MPD announces system playlist update Sep 01 14:17:17 volumio volumio[1264]: info: Ignoring MPD Status Update Sep 01 14:17:17 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 01 14:17:17 volumio volumio[1264]: info: sendMpdCommand clear took 3 milliseconds Sep 01 14:17:17 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 01 14:17:17 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/01 - The Power of Love (Radio Edit).flac" Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:17 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:17 volumio volumio[1264]: 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":"The Last to Know","artist":"Céline Dion","album":"Unison","uri":"INTERNAL/Celine Dion - 1990 - Unison (24bit-96kHz)/04. Celine Dion - The Last to Know.flac","trackType":"flac"} Sep 01 14:17:17 volumio volumio[1264]: verbose: CURRENT POSITION 0 Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::syncState stateService stop Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:17 volumio volumio[1264]: info: No code Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:17 volumio volumio[1264]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Sep 01 14:17:17 volumio volumio[1264]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Sep 01 14:17:17 volumio volumio[1264]: info: ------------------------------ 20ms Sep 01 14:17:17 volumio volumio[1264]: info: ------------------------------ 13ms Sep 01 14:17:17 volumio volumio[1264]: info: ------------------------------ 12ms Sep 01 14:17:17 volumio volumio[1264]: info: Sep 01 14:17:17 volumio volumio[1264]: ---------------------------- MPD announces system playlist update Sep 01 14:17:17 volumio volumio[1264]: info: Ignoring MPD Status Update Sep 01 14:17:17 volumio volumio[1264]: info: Sep 01 14:17:17 volumio volumio[1264]: ---------------------------- MPD announces system playlist update Sep 01 14:17:17 volumio volumio[1264]: info: Ignoring MPD Status Update Sep 01 14:17:17 volumio volumio[1264]: error: updateQueue error: null Sep 01 14:17:17 volumio volumio[1264]: info: Sep 01 14:17:17 volumio volumio[1264]: ---------------------------- MPD announces system playlist update Sep 01 14:17:17 volumio volumio[1264]: info: Ignoring MPD Status Update Sep 01 14:17:17 volumio volumio[1264]: info: ------------------------------ 8ms Sep 01 14:17:17 volumio volumio[1264]: info: sendMpdCommand add "INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/01 - The Power of Love (Radio Edit).flac" took 7 milliseconds Sep 01 14:17:17 volumio volumio[1264]: info: ------------------------------ 1ms Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand play Sep 01 14:17:17 volumio volumio[1264]: info: Sep 01 14:17:17 volumio volumio[1264]: ---------------------------- MPD announces system playlist update Sep 01 14:17:17 volumio volumio[1264]: info: Ignoring MPD Status Update Sep 01 14:17:17 volumio volumio[1264]: info: ------------------------------ 5ms Sep 01 14:17:17 volumio volumio[1264]: info: ------------------------------ 4ms Sep 01 14:17:17 volumio volumio[1264]: info: sendMpdCommand play took 4 milliseconds Sep 01 14:17:17 volumio volumio[1264]: info: ------------------------------ 4ms Sep 01 14:17:17 volumio volumio[1264]: info: Sep 01 14:17:17 volumio volumio[1264]: ---------------------------- MPD announces system playlist update Sep 01 14:17:17 volumio volumio[1264]: info: Ignoring MPD Status Update Sep 01 14:17:17 volumio volumio[1264]: info: Sep 01 14:17:17 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:17 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:17 volumio volumio[1264]: info: Sep 01 14:17:17 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:17 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:17 volumio volumio[1264]: info: ------------------------------ 8ms Sep 01 14:17:17 volumio volumio[1264]: info: Sep 01 14:17:17 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:17 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:17 volumio volumio[1264]: info: Sep 01 14:17:17 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:17 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:17 volumio volumio[1264]: info: Sep 01 14:17:17 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:17 volumio volumio[1264]: info: sendMpdCommand status took 2 milliseconds Sep 01 14:17:17 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:17 volumio volumio[1264]: info: sendMpdCommand status took 1 milliseconds Sep 01 14:17:17 volumio volumio[1264]: info: sendMpdCommand status took 1 milliseconds Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:17 volumio volumio[1264]: info: Sep 01 14:17:17 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:17 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:17 volumio volumio[1264]: info: sendMpdCommand status took 2 milliseconds Sep 01 14:17:17 volumio volumio[1264]: info: sendMpdCommand status took 2 milliseconds Sep 01 14:17:17 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 14:17:17 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 14:17:17 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 14:17:17 volumio volumio[1264]: info: sendMpdCommand status took 2 milliseconds Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:17 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:17 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":289,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Power of Love (Radio Edit)","artist":"Celine Dion","album":"The Very Best of Celine Dion","uri":"INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/01 - The Power of Love (Radio Edit).flac","trackType":"flac"} Sep 01 14:17:17 volumio volumio[1264]: verbose: CURRENT POSITION 0 Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:17:17 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:17 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":229,"duration":289,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"232 Kbps","isStreaming":false,"title":"The Power of Love (Radio Edit)","artist":"Celine Dion","album":"The Very Best of Celine Dion","uri":"INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/01 - The Power of Love (Radio Edit).flac","trackType":"flac"} Sep 01 14:17:17 volumio volumio[1264]: verbose: CURRENT POSITION 0 Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:17:17 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:17 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:17 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":400,"duration":289,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"217 Kbps","isStreaming":false,"title":"The Power of Love (Radio Edit)","artist":"Celine Dion","album":"The Very Best of Celine Dion","uri":"INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/01 - The Power of Love (Radio Edit).flac","trackType":"flac"} Sep 01 14:17:17 volumio volumio[1264]: verbose: CURRENT POSITION 0 Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:17:17 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:17 volumio volumio[1264]: info: ------------------------------ 8ms Sep 01 14:17:17 volumio volumio[1264]: info: ------------------------------ 12ms Sep 01 14:17:17 volumio volumio[1264]: info: ------------------------------ 12ms Sep 01 14:17:17 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 10 milliseconds Sep 01 14:17:17 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 01 14:17:17 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:17 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:17 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:17 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":289,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1438 Kbps","isStreaming":false,"title":"The Power of Love (Radio Edit)","artist":"Celine Dion","album":"The Very Best of Celine Dion","uri":"INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/01 - The Power of Love (Radio Edit).flac","trackType":"flac"} Sep 01 14:17:17 volumio volumio[1264]: verbose: CURRENT POSITION 0 Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:17:17 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:17 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:17 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":289,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1438 Kbps","isStreaming":false,"title":"The Power of Love (Radio Edit)","artist":"Celine Dion","album":"The Very Best of Celine Dion","uri":"INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/01 - The Power of Love (Radio Edit).flac","trackType":"flac"} Sep 01 14:17:17 volumio volumio[1264]: verbose: CURRENT POSITION 0 Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:17:17 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:17 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:17 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":289,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1438 Kbps","isStreaming":false,"title":"The Power of Love (Radio Edit)","artist":"Celine Dion","album":"The Very Best of Celine Dion","uri":"INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/01 - The Power of Love (Radio Edit).flac","trackType":"flac"} Sep 01 14:17:17 volumio volumio[1264]: verbose: CURRENT POSITION 0 Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:17:17 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:17 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:17 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:17 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:17 volumio volumio[1264]: info: ------------------------------ 27ms Sep 01 14:17:17 volumio volumio[1264]: info: ------------------------------ 27ms Sep 01 14:17:17 volumio volumio[1264]: info: ------------------------------ 26ms Sep 01 14:17:20 volumio volumio[1264]: info: CoreCommandRouter::volumioSeek Sep 01 14:17:20 volumio volumio[1264]: info: CoreStateMachine::seek Sep 01 14:17:20 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:20 volumio volumio[1264]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/01 - The Power of Love (Radio Edit).flac","service":"mpd","name":"The Power of Love (Radio Edit)","artist":"Celine Dion","album":"The Very Best of Celine Dion","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=232&web=Celine%20Dion/The%20Very%20Best%20of%20Celine%20Dion/extralarge&path=%2FINTERNAL%2FCeline%20Dion%20-%201999%20-%20The%20Very%20Best%20of%20Celine%20Dion%20(16-44)(24-44)&metadata=false","duration":289,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"flac","channels":2} Sep 01 14:17:20 volumio volumio[1264]: info: CoreStateMachine::startPlaybackTimer Sep 01 14:17:20 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:20 volumio volumio[1264]: info: ControllerMpd::seek Sep 01 14:17:20 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:20 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:20 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:17:20 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:20 volumio volumio[1264]: error: null Sep 01 14:17:20 volumio volumio[1264]: info: Sep 01 14:17:20 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:20 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:20 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:20 volumio volumio[1264]: info: Sep 01 14:17:20 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:20 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:20 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:20 volumio volumio[1264]: info: Sep 01 14:17:20 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:20 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:20 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:20 volumio volumio[1264]: info: sendMpdCommand status took 1 milliseconds Sep 01 14:17:20 volumio volumio[1264]: info: sendMpdCommand status took 1 milliseconds Sep 01 14:17:20 volumio volumio[1264]: info: sendMpdCommand status took 2 milliseconds Sep 01 14:17:20 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:20 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:20 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:20 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:20 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:20 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:20 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 14:17:20 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 14:17:20 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 14:17:20 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:20 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:20 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:20 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:17:20 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:17:20 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:20 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":150000,"duration":289,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1455 Kbps","isStreaming":false,"title":"The Power of Love (Radio Edit)","artist":"Celine Dion","album":"The Very Best of Celine Dion","uri":"INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/01 - The Power of Love (Radio Edit).flac","trackType":"flac"} Sep 01 14:17:20 volumio volumio[1264]: verbose: CURRENT POSITION 0 Sep 01 14:17:20 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:17:20 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:17:20 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:17:20 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:20 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:20 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:20 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:20 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:20 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:20 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:17:20 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:17:20 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:20 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":150000,"duration":289,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1455 Kbps","isStreaming":false,"title":"The Power of Love (Radio Edit)","artist":"Celine Dion","album":"The Very Best of Celine Dion","uri":"INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/01 - The Power of Love (Radio Edit).flac","trackType":"flac"} Sep 01 14:17:20 volumio volumio[1264]: verbose: CURRENT POSITION 0 Sep 01 14:17:20 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:17:20 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:17:20 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:17:20 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:20 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:20 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:20 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:20 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:20 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:20 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:17:20 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:17:20 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:20 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":150000,"duration":289,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1455 Kbps","isStreaming":false,"title":"The Power of Love (Radio Edit)","artist":"Celine Dion","album":"The Very Best of Celine Dion","uri":"INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/01 - The Power of Love (Radio Edit).flac","trackType":"flac"} Sep 01 14:17:20 volumio volumio[1264]: verbose: CURRENT POSITION 0 Sep 01 14:17:20 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:17:20 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:17:20 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:17:20 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:20 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:20 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:20 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:20 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:20 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:20 volumio volumio[1264]: info: ------------------------------ 26ms Sep 01 14:17:20 volumio volumio[1264]: info: ------------------------------ 25ms Sep 01 14:17:20 volumio volumio[1264]: info: ------------------------------ 25ms Sep 01 14:17:22 volumio volumio[1264]: info: CoreCommandRouter::volumioSeek Sep 01 14:17:22 volumio volumio[1264]: info: CoreStateMachine::seek Sep 01 14:17:22 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:22 volumio volumio[1264]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/01 - The Power of Love (Radio Edit).flac","service":"mpd","name":"The Power of Love (Radio Edit)","artist":"Celine Dion","album":"The Very Best of Celine Dion","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=232&web=Celine%20Dion/The%20Very%20Best%20of%20Celine%20Dion/extralarge&path=%2FINTERNAL%2FCeline%20Dion%20-%201999%20-%20The%20Very%20Best%20of%20Celine%20Dion%20(16-44)(24-44)&metadata=false","duration":289,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"flac","channels":2} Sep 01 14:17:22 volumio volumio[1264]: info: CoreStateMachine::startPlaybackTimer Sep 01 14:17:22 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:22 volumio volumio[1264]: info: ControllerMpd::seek Sep 01 14:17:22 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:22 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:22 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:17:22 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:22 volumio volumio[1264]: error: null Sep 01 14:17:22 volumio volumio[1264]: info: Sep 01 14:17:22 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:22 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:22 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:22 volumio volumio[1264]: info: Sep 01 14:17:22 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:22 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:22 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:22 volumio volumio[1264]: info: Sep 01 14:17:22 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:22 volumio volumio[1264]: info: ControllerMpd::getState Sep 01 14:17:22 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:17:22 volumio volumio[1264]: info: sendMpdCommand status took 2 milliseconds Sep 01 14:17:22 volumio volumio[1264]: info: sendMpdCommand status took 1 milliseconds Sep 01 14:17:22 volumio volumio[1264]: info: sendMpdCommand status took 1 milliseconds Sep 01 14:17:22 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:22 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:22 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:22 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:22 volumio volumio[1264]: verbose: ControllerMpd::parseState Sep 01 14:17:22 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:17:22 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 14:17:22 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 14:17:22 volumio volumio[1264]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 14:17:22 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:22 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:22 volumio volumio[1264]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:17:22 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:17:22 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:17:22 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:22 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":219000,"duration":289,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1771 Kbps","isStreaming":false,"title":"The Power of Love (Radio Edit)","artist":"Celine Dion","album":"The Very Best of Celine Dion","uri":"INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/01 - The Power of Love (Radio Edit).flac","trackType":"flac"} Sep 01 14:17:22 volumio volumio[1264]: verbose: CURRENT POSITION 0 Sep 01 14:17:22 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:17:22 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:17:22 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:17:22 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:22 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:22 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:22 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:22 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:22 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:22 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:17:22 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:17:22 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:22 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":219000,"duration":289,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1771 Kbps","isStreaming":false,"title":"The Power of Love (Radio Edit)","artist":"Celine Dion","album":"The Very Best of Celine Dion","uri":"INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/01 - The Power of Love (Radio Edit).flac","trackType":"flac"} Sep 01 14:17:22 volumio volumio[1264]: verbose: CURRENT POSITION 0 Sep 01 14:17:22 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:17:22 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:17:22 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:17:22 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:22 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:22 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:22 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:22 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:22 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:22 volumio volumio[1264]: info: ControllerMpd::pushState Sep 01 14:17:22 volumio volumio[1264]: info: CoreCommandRouter::servicePushState Sep 01 14:17:22 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:22 volumio volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":219000,"duration":289,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1771 Kbps","isStreaming":false,"title":"The Power of Love (Radio Edit)","artist":"Celine Dion","album":"The Very Best of Celine Dion","uri":"INTERNAL/Celine Dion - 1999 - The Very Best of Celine Dion (16-44)(24-44)/01 - The Power of Love (Radio Edit).flac","trackType":"flac"} Sep 01 14:17:22 volumio volumio[1264]: verbose: CURRENT POSITION 0 Sep 01 14:17:22 volumio volumio[1264]: info: CoreStateMachine::syncState stateService play Sep 01 14:17:22 volumio volumio[1264]: info: CoreStateMachine::syncState currentStatus play Sep 01 14:17:22 volumio volumio[1264]: info: Received an update from plugin. extracting info from payload Sep 01 14:17:22 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:22 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:22 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:22 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:22 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:22 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:22 volumio volumio[1264]: info: ------------------------------ 15ms Sep 01 14:17:22 volumio volumio[1264]: info: ------------------------------ 15ms Sep 01 14:17:22 volumio volumio[1264]: info: ------------------------------ 15ms Sep 01 14:17:23 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 01 14:17:27 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 14:17:27 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 14:17:27 volumio volumio[1264]: info: Discovery: Getting this device information Sep 01 14:17:27 volumio volumio[1264]: info: CoreCommandRouter::volumioGetState Sep 01 14:17:27 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:27 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 14:17:28 volumio volumio[1264]: info: Preload queue cleared Sep 01 14:17:28 volumio volumio[1264]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 01 14:17:28 volumio volumio[1264]: info: CoreStateMachine::ClearQueue Sep 01 14:17:28 volumio volumio[1264]: info: CoreStateMachine::stop Sep 01 14:17:28 volumio volumio[1264]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:17:28 volumio volumio[1264]: info: CoreStateMachine::stPlaybackTimer Sep 01 14:17:28 volumio volumio[1264]: info: CoreStateMachine::updateTrackBlock Sep 01 14:17:28 volumio volumio[1264]: info: CorePlayQueue::getTrackBlock Sep 01 14:17:28 volumio volumio[1264]: info: CoreStateMachine::pushState Sep 01 14:17:28 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:28 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:17:28 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState Sep 01 14:17:28 volumio volumio[1264]: info: CoreStateMachine::serviceStop Sep 01 14:17:28 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 Sep 01 14:17:28 volumio volumio[1264]: info: CoreCommandRouter::serviceStop Sep 01 14:17:28 volumio volumio[1264]: info: ControllerMpd::stop Sep 01 14:17:28 volumio volumio[1264]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 14:17:28 volumio volumio[1264]: info: CorePlayQueue::clearPlayQueue Sep 01 14:17:28 volumio volumio[1264]: info: CorePlayQueue::saveQueue Sep 01 14:17:28 volumio volumio[1264]: info: CoreCommandRouter::volumioPushQueue Sep 01 14:17:28 volumio volumio[1264]: info: CoreStateMachine::addQueueItems Sep 01 14:17:28 volumio volumio[1264]: info: CorePlayQueue::addQueueItems Sep 01 14:17:28 volumio volumio[1264]: info: Preload queue cleared Sep 01 14:17:28 volumio volumio[1264]: info: Adding Item to queue: music-library/INTERNAL/Madonna - The Immaculate Collection (1990) {FLAC} [vtwin88cube] Sep 01 14:17:28 volumio volumio[1264]: info: Exploding uri music-library/INTERNAL/Madonna - The Immaculate Collection (1990) {FLAC} [vtwin88cube] in service mpd Sep 01 14:17:28 volumio volumio[1264]: info: Sep 01 14:17:28 volumio volumio[1264]: ---------------------------- MPD announces state update: player Sep 01 14:17:28 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Madonna/The%20Immaculate%20Collection/extralarge&path=%2Fmnt%2FINTERNAL%2FMadonna%20-%20The%20Immaculate%20Collection%20(1990)%20%7BFLAC%7D%20%5Bvtwin88cube%5D%2F01.Holiday.flac&metadata=false Sep 01 14:17:28 volumio volumio[1264]: info: URI /mnt/INTERNAL/Madonna - The Immaculate Collection (1990) {FLAC} [vtwin88cube]/01.Holiday.flac Sep 01 14:17:28 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Madonna/The%20Immaculate%20Collection/extralarge&path=%2Fmnt%2FINTERNAL%2FMadonna%20-%20The%20Immaculate%20Collection%20(1990)%20%7BFLAC%7D%20%5Bvtwin88cube%5D%2F02.Lucky%20Star.flac&metadata=false Sep 01 14:17:28 volumio volumio[1264]: info: URI /mnt/INTERNAL/Madonna - The Immaculate Collection (1990) {FLAC} [vtwin88cube]/02.Lucky Star.flac Sep 01 14:17:28 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Madonna/The%20Immaculate%20Collection/extralarge&path=%2Fmnt%2FINTERNAL%2FMadonna%20-%20The%20Immaculate%20Collection%20(1990)%20%7BFLAC%7D%20%5Bvtwin88cube%5D%2F03.Borderline.flac&metadata=false Sep 01 14:17:28 volumio volumio[1264]: info: URI /mnt/INTERNAL/Madonna - The Immaculate Collection (1990) {FLAC} [vtwin88cube]/03.Borderline.flac Sep 01 14:17:28 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Madonna/The%20Immaculate%20Collection/extralarge&path=%2Fmnt%2FINTERNAL%2FMadonna%20-%20The%20Immaculate%20Collection%20(1990)%20%7BFLAC%7D%20%5Bvtwin88cube%5D%2F04.Like%20A%20Virgin.flac&metadata=false Sep 01 14:17:28 volumio volumio[1264]: info: URI /mnt/INTERNAL/Madonna - The Immaculate Collection (1990) {FLAC} [vtwin88cube]/04.Like A Virgin.flac Sep 01 14:17:28 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Madonna/The%20Immaculate%20Collection/extralarge&path=%2Fmnt%2FINTERNAL%2FMadonna%20-%20The%20Immaculate%20Collection%20(1990)%20%7BFLAC%7D%20%5Bvtwin88cube%5D%2F05.Material%20Girl.flac&metadata=false Sep 01 14:17:28 volumio volumio[1264]: info: URI /mnt/INTERNAL/Madonna - The Immaculate Collection (1990) {FLAC} [vtwin88cube]/05.Material Girl.flac Sep 01 14:17:28 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Madonna/The%20Immaculate%20Collection/extralarge&path=%2Fmnt%2FINTERNAL%2FMadonna%20-%20The%20Immaculate%20Collection%20(1990)%20%7BFLAC%7D%20%5Bvtwin88cube%5D%2F06.Crazy%20For%20You.flac&metadata=false Sep 01 14:17:28 volumio volumio[1264]: info: URI /mnt/INTERNAL/Madonna - The Immaculate Collection (1990) {FLAC} [vtwin88cube]/06.Crazy For You.flac Sep 01 14:17:28 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Madonna/The%20Immaculate%20Collection/extralarge&path=%2Fmnt%2FINTERNAL%2FMadonna%20-%20The%20Immaculate%20Collection%20(1990)%20%7BFLAC%7D%20%5Bvtwin88cube%5D%2F07.Into%20The%20Groove.flac&metadata=false Sep 01 14:17:28 volumio volumio[1264]: info: URI /mnt/INTERNAL/Madonna - The Immaculate Collection (1990) {FLAC} [vtwin88cube]/07.Into The Groove.flac Sep 01 14:17:28 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Madonna/The%20Immaculate%20Collection/extralarge&path=%2Fmnt%2FINTERNAL%2FMadonna%20-%20The%20Immaculate%20Collection%20(1990)%20%7BFLAC%7D%20%5Bvtwin88cube%5D%2F08.Live%20To%20Tell.flac&metadata=false Sep 01 14:17:28 volumio volumio[1264]: info: URI /mnt/INTERNAL/Madonna - The Immaculate Collection (1990) {FLAC} [vtwin88cube]/08.Live To Tell.flac Sep 01 14:17:28 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Madonna/The%20Immaculate%20Collection/extralarge&path=%2Fmnt%2FINTERNAL%2FMadonna%20-%20The%20Immaculate%20Collection%20(1990)%20%7BFLAC%7D%20%5Bvtwin88cube%5D%2F09.Papa%20Don't%20Preach.flac&metadata=false Sep 01 14:17:28 volumio volumio[1264]: info: URI /mnt/INTERNAL/Madonna - The Immaculate Collection (1990) {FLAC} [vtwin88cube]/09.Papa Don't Preach.flac Sep 01 14:17:28 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Madonna/The%20Immaculate%20Collection/extralarge&path=%2Fmnt%2FINTERNAL%2FMadonna%20-%20The%20Immaculate%20Collection%20(1990)%20%7BFLAC%7D%20%5Bvtwin88cube%5D%2F10.Open%20Your%20Heart.flac&metadata=false Sep 01 14:17:28 volumio volumio[1264]: info: URI /mnt/INTERNAL/Madonna - The Immaculate Collection (1990) {FLAC} [vtwin88cube]/10.Open Your Heart.flac Sep 01 14:17:28 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Madonna/The%20Immaculate%20Collection/extralarge&path=%2Fmnt%2FINTERNAL%2FMadonna%20-%20The%20Immaculate%20Collection%20(1990)%20%7BFLAC%7D%20%5Bvtwin88cube%5D%2F11.La%20Isla%20Bonita.flac&metadata=false Sep 01 14:17:28 volumio volumio[1264]: info: URI /mnt/INTERNAL/Madonna - The Immaculate Collection (1990) {FLAC} [vtwin88cube]/11.La Isla Bonita.flac Sep 01 14:17:28 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Madonna/The%20Immaculate%20Collection/extralarge&path=%2Fmnt%2FINTERNAL%2FMadonna%20-%20The%20Immaculate%20Collection%20(1990)%20%7BFLAC%7D%20%5Bvtwin88cube%5D%2F12.Like%20A%20Prayer.flac&metadata=false Sep 01 14:17:28 volumio volumio[1264]: info: URI /mnt/INTERNAL/Madonna - The Immaculate Collection (1990) {FLAC} [vtwin88cube]/12.Like A Prayer.flac Sep 01 14:17:28 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Madonna/The%20Immaculate%20Collection/extralarge&path=%2Fmnt%2FINTERNAL%2FMadonna%20-%20The%20Immaculate%20Collection%20(1990)%20%7BFLAC%7D%20%5Bvtwin88cube%5D%2F13.Express%20Yourself.flac&metadata=false Sep 01 14:17:28 volumio volumio[1264]: info: URI /mnt/INTERNAL/Madonna - The Immaculate Collection (1990) {FLAC} [vtwin88cube]/13.Express Yourself.flac Sep 01 14:17:28 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Madonna/The%20Immaculate%20Collection/extralarge&path=%2Fmnt%2FINTERNAL%2FMadonna%20-%20The%20Immaculate%20Collection%20(1990)%20%7BFLAC%7D%20%5Bvtwin88cube%5D%2F14.Cherish.flac&metadata=false Sep 01 14:17:28 volumio volumio[1264]: info: URI /mnt/INTERNAL/Madonna - The Immaculate Collection (1990) {FLAC} [vtwin88cube]/14.Cherish.flac Sep 01 14:17:28 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Madonna/The%20Immaculate%20Collection/extralarge&path=%2Fmnt%2FINTERNAL%2FMadonna%20-%20The%20Immaculate%20Collection%20(1990)%20%7BFLAC%7D%20%5Bvtwin88cube%5D%2F15.Vogue.flac&metadata=false Sep 01 14:17:28 volumio volumio[1264]: info: URI /mnt/INTERNAL/Madonna - The Immaculate Collection (1990) {FLAC} [vtwin88cube]/15.Vogue.flac Sep 01 14:17:28 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Madonna/The%20Immaculate%20Collection/extralarge&path=%2Fmnt%2FINTERNAL%2FMadonna%20-%20The%20Immaculate%20Collection%20(1990)%20%7BFLAC%7D%20%5Bvtwin88cube%5D%2F16.Justify%20My%20Love.flac&metadata=false Sep 01 14:17:28 volumio volumio[1264]: info: URI /mnt/INTERNAL/Madonna - The Immaculate Collection (1990) {FLAC} [vtwin88cube]/16.Justify My Love.flac Sep 01 14:17:28 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Madonna/The%20Immaculate%20Collection/extralarge&path=%2Fmnt%2FINTERNAL%2FMadonna%20-%20The%20Immaculate%20Collection%20(1990)%20%7BFLAC%7D%20%5Bvtwin88cube%5D%2F17.Rescue%20Me.flac&metadata=false Sep 01 14:17:28 volumio volumio[1264]: info: URI /mnt/INTERNAL/Madonna - The Immaculate Collection (1990) {FLAC} [vtwin88cube]/17.Rescue Me.flac Sep 01 14:17:28 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Madonna/The%20Immaculate%20Collection/extralarge&path=%2Fmnt%2FINTERNAL%2FMadonna%20-%20The%20Immaculate%20Collection%20(1990)%20%7BFLAC%7D%20%5Bvtwin88cube%5D%2FMadonna%20-%20The%20Immaculate%20Collection.cue&metadata=false Sep 01 14:17:28 volumio volumio[1264]: info: URI /mnt/INTERNAL/Madonna - The Immaculate Collection (1990) {FLAC} [vtwin88cube]/Madonna - The Immaculate Collection.cue Sep 01 14:17:28 volumio volumio[1264]: info: ALBUMART /albumart?cacheid=232&web=Madonna/The%20Immaculate%20Collection/extralarge&path=%2Fmnt%2FINTERNAL%2FMadonna%20-%20The%20Immaculate%20Collection%20(1990)%20%7BFLAC%7D%20%5Bvtwin88cube%5D%2FMadonna%20-%20The%20Immaculate%20Collection.cue&metadata=false Sep 01 14:17:28 volumio volumio[1264]: info: URI /mnt/INTERNAL/Madonna - The Immaculate Collection (1990) {FLAC} [vtwin88cube]/Madonna - The Immaculate Collection.cue Sep 01 14:17:28 volumio volumio[1264]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 14:17:28 volumio volumio[1264]: Error: Unable to resolve or reject the same promise twice Sep 01 14:17:28 volumio volumio[1264]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Sep 01 14:17:28 volumio volumio[1264]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Sep 01 14:17:28 volumio volumio[1264]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Sep 01 14:17:28 volumio volumio[1264]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Sep 01 14:17:28 volumio volumio[1264]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Sep 01 14:17:28 volumio volumio[1264]: at Socket.emit (node:events:514:28) Sep 01 14:17:28 volumio volumio[1264]: at addChunk (node:internal/streams/readable:343:12) Sep 01 14:17:28 volumio volumio[1264]: at readableAddChunk (node:internal/streams/readable:312:11) Sep 01 14:17:28 volumio volumio[1264]: at Readable.push (node:internal/streams/readable:253:10) Sep 01 14:17:28 volumio volumio[1264]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Sep 01 14:17:28 volumio volumio[1264]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 14:17:29 volumio sudo[5804]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-01 14:16' Sep 01 14:17:29 volumio sudo[5804]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dfc7bb04e09bf2260691765a2340814af1fa2971" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="30d63a8c53d2826bd7f262e22b4e4511a4cc9d01" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Aug 30 06:39:32 UTC 2025" VOLUMIO_VERSION="4.022" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e7b0bd0cb5661ea77402e5bd121ec64a"