-- Logs begin at Thu 2019-02-14 10:12:00 GMT, end at Sun 2025-12-07 15:29:54 GMT. -- Dec 07 15:28:08 volumio-3 go-librespot[1279]: time="2025-12-07T15:28:08Z" level=trace msg="sent dealer ping" Dec 07 15:28:08 volumio-3 go-librespot[1279]: time="2025-12-07T15:28:08Z" level=trace msg="received dealer pong" Dec 07 15:28:28 volumio-3 volumio[845]: info: MPD Database updated - AlbumList cache refreshed Dec 07 15:28:28 volumio-3 volumio[845]: info: Dec 07 15:28:28 volumio-3 volumio[845]: ---------------------------- MPD announces state update: database Dec 07 15:28:28 volumio-3 volumio[845]: verbose: ControllerMpd::sendMpdCommand status Dec 07 15:28:28 volumio-3 volumio[845]: info: Dec 07 15:28:28 volumio-3 volumio[845]: ---------------------------- MPD announces state update: update Dec 07 15:28:28 volumio-3 volumio[845]: info: ControllerMpd::getState Dec 07 15:28:28 volumio-3 volumio[845]: verbose: ControllerMpd::sendMpdCommand status Dec 07 15:28:28 volumio-3 volumio[845]: info: ControllerMpd::getState Dec 07 15:28:28 volumio-3 volumio[845]: verbose: ControllerMpd::sendMpdCommand status Dec 07 15:28:28 volumio-3 volumio[845]: info: MPD Database updated - AlbumList cache refreshed Dec 07 15:28:28 volumio-3 volumio[845]: info: Dec 07 15:28:28 volumio-3 volumio[845]: ---------------------------- MPD announces state update: database Dec 07 15:28:28 volumio-3 volumio[845]: verbose: ControllerMpd::sendMpdCommand status Dec 07 15:28:28 volumio-3 volumio[845]: info: Dec 07 15:28:28 volumio-3 volumio[845]: ---------------------------- MPD announces state update: update Dec 07 15:28:28 volumio-3 volumio[845]: info: ControllerMpd::getState Dec 07 15:28:28 volumio-3 volumio[845]: verbose: ControllerMpd::sendMpdCommand status Dec 07 15:28:28 volumio-3 volumio[845]: info: ControllerMpd::getState Dec 07 15:28:28 volumio-3 volumio[845]: verbose: ControllerMpd::sendMpdCommand status Dec 07 15:28:28 volumio-3 volumio[845]: info: MPD Database updated - AlbumList cache refreshed Dec 07 15:28:28 volumio-3 volumio[845]: info: Dec 07 15:28:28 volumio-3 volumio[845]: ---------------------------- MPD announces state update: database Dec 07 15:28:28 volumio-3 volumio[845]: verbose: ControllerMpd::sendMpdCommand status Dec 07 15:28:28 volumio-3 volumio[845]: info: Dec 07 15:28:28 volumio-3 volumio[845]: ---------------------------- MPD announces state update: update Dec 07 15:28:28 volumio-3 volumio[845]: info: ControllerMpd::getState Dec 07 15:28:28 volumio-3 volumio[845]: verbose: ControllerMpd::sendMpdCommand status Dec 07 15:28:28 volumio-3 volumio[845]: info: ControllerMpd::getState Dec 07 15:28:28 volumio-3 volumio[845]: verbose: ControllerMpd::sendMpdCommand status Dec 07 15:28:34 volumio-3 volumio[845]: info: sendMpdCommand status took 6064 milliseconds Dec 07 15:28:34 volumio-3 volumio[845]: info: sendMpdCommand status took 6078 milliseconds Dec 07 15:28:34 volumio-3 volumio[845]: info: sendMpdCommand status took 6077 milliseconds Dec 07 15:28:34 volumio-3 volumio[845]: verbose: ControllerMpd::parseState Dec 07 15:28:35 volumio-3 volumio[845]: info: Command Router : Notfying DB Updatefalse Dec 07 15:28:35 volumio-3 volumio[845]: info: CoreCommandRouter::Close All Modals sent Dec 07 15:28:35 volumio-3 volumio[845]: verbose: ControllerMpd::parseState Dec 07 15:28:35 volumio-3 volumio[845]: verbose: ControllerMpd::parseState Dec 07 15:28:35 volumio-3 volumio[845]: info: ControllerMpd::pushState Dec 07 15:28:35 volumio-3 volumio[845]: info: CoreCommandRouter::servicePushState Dec 07 15:28:35 volumio-3 volumio[845]: info: CoreStateMachine::pushState Dec 07 15:28:35 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:35 volumio-3 volumio[845]: info: CoreCommandRouter::volumioPushState Dec 07 15:28:35 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:35 volumio-3 volumio[845]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 07 15:28:35 volumio-3 volumio[845]: verbose: CURRENT POSITION 0 Dec 07 15:28:35 volumio-3 volumio[845]: info: CoreStateMachine::syncState stateService stop Dec 07 15:28:35 volumio-3 volumio[845]: info: CoreStateMachine::syncState currentStatus stop Dec 07 15:28:35 volumio-3 volumio[845]: info: CoreStateMachine::pushState Dec 07 15:28:35 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:35 volumio-3 volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 15:28:35 volumio-3 volumio[845]: info: CoreCommandRouter::volumioPushState Dec 07 15:28:35 volumio-3 volumio[845]: info: No code Dec 07 15:28:35 volumio-3 volumio[845]: info: CoreStateMachine::pushState Dec 07 15:28:35 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:35 volumio-3 volumio[845]: info: CoreCommandRouter::volumioPushState Dec 07 15:28:35 volumio-3 volumio[845]: info: ControllerMpd::pushState Dec 07 15:28:35 volumio-3 volumio[845]: info: CoreCommandRouter::servicePushState Dec 07 15:28:35 volumio-3 volumio[845]: info: CoreStateMachine::pushState Dec 07 15:28:35 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:35 volumio-3 volumio[845]: info: CoreCommandRouter::volumioPushState Dec 07 15:28:35 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:35 volumio-3 volumio[845]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 07 15:28:35 volumio-3 volumio[845]: verbose: CURRENT POSITION 0 Dec 07 15:28:35 volumio-3 volumio[845]: info: CoreStateMachine::syncState stateService stop Dec 07 15:28:35 volumio-3 volumio[845]: info: CoreStateMachine::syncState currentStatus stop Dec 07 15:28:35 volumio-3 volumio[845]: info: CoreStateMachine::pushState Dec 07 15:28:35 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:35 volumio-3 volumio[845]: info: CoreCommandRouter::volumioPushState Dec 07 15:28:35 volumio-3 volumio[845]: info: No code Dec 07 15:28:35 volumio-3 volumio[845]: info: CoreStateMachine::pushState Dec 07 15:28:35 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:35 volumio-3 volumio[845]: info: CoreCommandRouter::volumioPushState Dec 07 15:28:35 volumio-3 volumio[845]: info: ------------------------------ 6381ms Dec 07 15:28:35 volumio-3 volumio[845]: info: ------------------------------ 6382ms Dec 07 15:28:35 volumio-3 volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 07 15:28:35 volumio-3 volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 07 15:28:35 volumio-3 volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 07 15:28:35 volumio-3 volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 07 15:28:35 volumio-3 volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 07 15:28:35 volumio-3 volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 07 15:28:38 volumio-3 go-librespot[1279]: time="2025-12-07T15:28:38Z" level=trace msg="sent dealer ping" Dec 07 15:28:38 volumio-3 go-librespot[1279]: time="2025-12-07T15:28:38Z" level=trace msg="received dealer pong" Dec 07 15:28:39 volumio-3 volumio[845]: info: sendMpdCommand status took 10526 milliseconds Dec 07 15:28:39 volumio-3 volumio[845]: info: sendMpdCommand status took 10541 milliseconds Dec 07 15:28:39 volumio-3 volumio[845]: info: sendMpdCommand status took 10540 milliseconds Dec 07 15:28:39 volumio-3 volumio[845]: verbose: ControllerMpd::parseState Dec 07 15:28:39 volumio-3 volumio[845]: info: Command Router : Notfying DB Updatefalse Dec 07 15:28:39 volumio-3 volumio[845]: info: CoreCommandRouter::Close All Modals sent Dec 07 15:28:39 volumio-3 volumio[845]: verbose: ControllerMpd::parseState Dec 07 15:28:39 volumio-3 volumio[845]: verbose: ControllerMpd::parseState Dec 07 15:28:39 volumio-3 volumio[845]: info: ControllerMpd::pushState Dec 07 15:28:39 volumio-3 volumio[845]: info: CoreCommandRouter::servicePushState Dec 07 15:28:39 volumio-3 volumio[845]: info: CoreStateMachine::pushState Dec 07 15:28:39 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:39 volumio-3 volumio[845]: info: CoreCommandRouter::volumioPushState Dec 07 15:28:39 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:39 volumio-3 volumio[845]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 07 15:28:39 volumio-3 volumio[845]: verbose: CURRENT POSITION 0 Dec 07 15:28:39 volumio-3 volumio[845]: info: CoreStateMachine::syncState stateService stop Dec 07 15:28:39 volumio-3 volumio[845]: info: CoreStateMachine::syncState currentStatus stop Dec 07 15:28:39 volumio-3 volumio[845]: info: CoreStateMachine::pushState Dec 07 15:28:39 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:39 volumio-3 volumio[845]: info: CoreCommandRouter::volumioPushState Dec 07 15:28:39 volumio-3 volumio[845]: info: No code Dec 07 15:28:39 volumio-3 volumio[845]: info: CoreStateMachine::pushState Dec 07 15:28:39 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:39 volumio-3 volumio[845]: info: CoreCommandRouter::volumioPushState Dec 07 15:28:39 volumio-3 volumio[845]: info: ControllerMpd::pushState Dec 07 15:28:39 volumio-3 volumio[845]: info: CoreCommandRouter::servicePushState Dec 07 15:28:39 volumio-3 volumio[845]: info: CoreStateMachine::pushState Dec 07 15:28:39 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:39 volumio-3 volumio[845]: info: CoreCommandRouter::volumioPushState Dec 07 15:28:39 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:39 volumio-3 volumio[845]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 07 15:28:39 volumio-3 volumio[845]: verbose: CURRENT POSITION 0 Dec 07 15:28:39 volumio-3 volumio[845]: info: CoreStateMachine::syncState stateService stop Dec 07 15:28:39 volumio-3 volumio[845]: info: CoreStateMachine::syncState currentStatus stop Dec 07 15:28:39 volumio-3 volumio[845]: info: CoreStateMachine::pushState Dec 07 15:28:39 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:39 volumio-3 volumio[845]: info: CoreCommandRouter::volumioPushState Dec 07 15:28:39 volumio-3 volumio[845]: info: No code Dec 07 15:28:39 volumio-3 volumio[845]: info: CoreStateMachine::pushState Dec 07 15:28:39 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:39 volumio-3 volumio[845]: info: CoreCommandRouter::volumioPushState Dec 07 15:28:39 volumio-3 volumio[845]: info: ------------------------------ 10796ms Dec 07 15:28:39 volumio-3 volumio[845]: info: ------------------------------ 10797ms Dec 07 15:28:39 volumio-3 volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 07 15:28:39 volumio-3 volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 07 15:28:39 volumio-3 volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 07 15:28:39 volumio-3 volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 07 15:28:39 volumio-3 volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 07 15:28:44 volumio-3 volumio[845]: info: sendMpdCommand status took 15329 milliseconds Dec 07 15:28:44 volumio-3 volumio[845]: info: sendMpdCommand status took 15345 milliseconds Dec 07 15:28:44 volumio-3 volumio[845]: info: sendMpdCommand status took 15344 milliseconds Dec 07 15:28:44 volumio-3 volumio[845]: verbose: ControllerMpd::parseState Dec 07 15:28:44 volumio-3 volumio[845]: info: Command Router : Notfying DB Updatefalse Dec 07 15:28:44 volumio-3 volumio[845]: info: CoreCommandRouter::Close All Modals sent Dec 07 15:28:44 volumio-3 volumio[845]: verbose: ControllerMpd::parseState Dec 07 15:28:44 volumio-3 volumio[845]: verbose: ControllerMpd::parseState Dec 07 15:28:44 volumio-3 volumio[845]: info: ControllerMpd::pushState Dec 07 15:28:44 volumio-3 volumio[845]: info: CoreCommandRouter::servicePushState Dec 07 15:28:44 volumio-3 volumio[845]: info: CoreStateMachine::pushState Dec 07 15:28:44 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:44 volumio-3 volumio[845]: info: CoreCommandRouter::volumioPushState Dec 07 15:28:44 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:44 volumio-3 volumio[845]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 07 15:28:44 volumio-3 volumio[845]: verbose: CURRENT POSITION 0 Dec 07 15:28:44 volumio-3 volumio[845]: info: CoreStateMachine::syncState stateService stop Dec 07 15:28:44 volumio-3 volumio[845]: info: CoreStateMachine::syncState currentStatus stop Dec 07 15:28:44 volumio-3 volumio[845]: info: CoreStateMachine::pushState Dec 07 15:28:44 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:44 volumio-3 volumio[845]: info: CoreCommandRouter::volumioPushState Dec 07 15:28:44 volumio-3 volumio[845]: info: No code Dec 07 15:28:44 volumio-3 volumio[845]: info: CoreStateMachine::pushState Dec 07 15:28:44 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:44 volumio-3 volumio[845]: info: CoreCommandRouter::volumioPushState Dec 07 15:28:44 volumio-3 volumio[845]: info: ControllerMpd::pushState Dec 07 15:28:44 volumio-3 volumio[845]: info: CoreCommandRouter::servicePushState Dec 07 15:28:44 volumio-3 volumio[845]: info: CoreStateMachine::pushState Dec 07 15:28:44 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:44 volumio-3 volumio[845]: info: CoreCommandRouter::volumioPushState Dec 07 15:28:44 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:44 volumio-3 volumio[845]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 07 15:28:44 volumio-3 volumio[845]: verbose: CURRENT POSITION 0 Dec 07 15:28:44 volumio-3 volumio[845]: info: CoreStateMachine::syncState stateService stop Dec 07 15:28:44 volumio-3 volumio[845]: info: CoreStateMachine::syncState currentStatus stop Dec 07 15:28:44 volumio-3 volumio[845]: info: CoreStateMachine::pushState Dec 07 15:28:44 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:44 volumio-3 volumio[845]: info: CoreCommandRouter::volumioPushState Dec 07 15:28:44 volumio-3 volumio[845]: info: No code Dec 07 15:28:44 volumio-3 volumio[845]: info: CoreStateMachine::pushState Dec 07 15:28:44 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:28:44 volumio-3 volumio[845]: info: CoreCommandRouter::volumioPushState Dec 07 15:28:44 volumio-3 volumio[845]: info: ------------------------------ 15603ms Dec 07 15:28:44 volumio-3 volumio[845]: info: ------------------------------ 15604ms Dec 07 15:28:44 volumio-3 volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 07 15:28:44 volumio-3 volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 07 15:28:44 volumio-3 volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 07 15:28:44 volumio-3 volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 07 15:28:44 volumio-3 volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 07 15:28:44 volumio-3 volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 07 15:28:58 volumio-3 volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 07 15:28:58 volumio-3 volumio[845]: info: CURURI: music-library/USB Dec 07 15:28:58 volumio-3 volumio[845]: info: Preload queue cleared Dec 07 15:28:59 volumio-3 volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 07 15:28:59 volumio-3 volumio[845]: info: CURURI: music-library/USB/TOSHIBA Dec 07 15:28:59 volumio-3 volumio[845]: info: Preload queue cleared Dec 07 15:28:59 volumio-3 volumio[845]: info: Preloading song: music-library/USB/TOSHIBA/DSCF8786.AVI Dec 07 15:28:59 volumio-3 volumio[845]: info: Exploding uri music-library/USB/TOSHIBA/DSCF8786.AVI in service mpd Dec 07 15:28:59 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=//extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDSCF8786.AVI&metadata=false Dec 07 15:28:59 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/DSCF8786.AVI Dec 07 15:29:02 volumio-3 volumio[845]: info: Preload queue cleared Dec 07 15:29:02 volumio-3 volumio[845]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 07 15:29:02 volumio-3 volumio[845]: info: CoreStateMachine::ClearQueue Dec 07 15:29:02 volumio-3 volumio[845]: info: CoreStateMachine::stop Dec 07 15:29:02 volumio-3 volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 15:29:02 volumio-3 volumio[845]: info: CorePlayQueue::clearPlayQueue Dec 07 15:29:02 volumio-3 volumio[845]: info: CorePlayQueue::saveQueue Dec 07 15:29:02 volumio-3 volumio[845]: info: CoreCommandRouter::volumioPushQueue Dec 07 15:29:02 volumio-3 volumio[845]: info: CoreStateMachine::addQueueItems Dec 07 15:29:02 volumio-3 volumio[845]: info: CorePlayQueue::addQueueItems Dec 07 15:29:02 volumio-3 volumio[845]: info: Preload queue cleared Dec 07 15:29:02 volumio-3 volumio[845]: info: Adding Item to queue: music-library/USB/TOSHIBA/DSCF8786.AVI Dec 07 15:29:02 volumio-3 volumio[845]: info: Using cached record of: music-library/USB/TOSHIBA/DSCF8786.AVI Dec 07 15:29:02 volumio-3 volumio[845]: info: CoreCommandRouter::volumioPushQueue Dec 07 15:29:02 volumio-3 volumio[845]: info: CorePlayQueue::saveQueue Dec 07 15:29:02 volumio-3 volumio[845]: info: CoreStateMachine::updateTrackBlock Dec 07 15:29:02 volumio-3 volumio[845]: info: CorePlayQueue::getTrackBlock Dec 07 15:29:02 volumio-3 volumio[845]: info: CoreCommandRouter::volumioPlay Dec 07 15:29:02 volumio-3 volumio[845]: info: CoreStateMachine::play index 0 Dec 07 15:29:02 volumio-3 volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 15:29:02 volumio-3 volumio[845]: info: CoreStateMachine::addQueueItems Dec 07 15:29:02 volumio-3 volumio[845]: info: CorePlayQueue::addQueueItems Dec 07 15:29:02 volumio-3 volumio[845]: info: Preload queue cleared Dec 07 15:29:02 volumio-3 volumio[845]: info: Adding Item to queue: music-library/USB/TOSHIBA/Documents Dec 07 15:29:02 volumio-3 volumio[845]: info: Exploding uri music-library/USB/TOSHIBA/Documents in service mpd Dec 07 15:29:02 volumio-3 volumio[845]: info: Adding Item to queue: music-library/USB/TOSHIBA/Downloads A Dec 07 15:29:02 volumio-3 volumio[845]: info: Exploding uri music-library/USB/TOSHIBA/Downloads A in service mpd Dec 07 15:29:08 volumio-3 go-librespot[1279]: time="2025-12-07T15:29:08Z" level=trace msg="received accesspoint ping" Dec 07 15:29:08 volumio-3 go-librespot[1279]: time="2025-12-07T15:29:08Z" level=trace msg="received accesspoint pong ack" Dec 07 15:29:08 volumio-3 nmbd[806]: [2025/12/07 15:29:08.432857, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 07 15:29:08 volumio-3 nmbd[806]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.25 for name WORKGROUP<1d>. Dec 07 15:29:08 volumio-3 nmbd[806]: This response was from IP 192.168.1.250, reporting an IP address of 192.168.1.250. Dec 07 15:29:08 volumio-3 go-librespot[1279]: time="2025-12-07T15:29:08Z" level=trace msg="sent dealer ping" Dec 07 15:29:08 volumio-3 go-librespot[1279]: time="2025-12-07T15:29:08Z" level=trace msg="received dealer pong" Dec 07 15:29:11 volumio-3 volumio-remote-updater[567]: [2025-12-07 15:29:11] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Dec 07 15:29:16 volumio-3 volumio-remote-updater[567]: [2025-12-07 15:29:16] [connect] Successful connection Dec 07 15:29:28 volumio-3 volumio-remote-updater[567]: [2025-12-07 15:29:28] [connect] Successful connection Dec 07 15:29:38 volumio-3 go-librespot[1279]: time="2025-12-07T15:29:38Z" level=trace msg="sent dealer ping" Dec 07 15:29:38 volumio-3 go-librespot[1279]: time="2025-12-07T15:29:38Z" level=trace msg="received dealer pong" Dec 07 15:29:43 volumio-3 volumio-remote-updater[567]: [2025-12-07 15:29:43] [connect] Successful connection Dec 07 15:29:47 volumio-3 volumio[845]: info: Adding Item to queue: music-library/USB/TOSHIBA/Goldfinches Dec 07 15:29:47 volumio-3 volumio[845]: info: Exploding uri music-library/USB/TOSHIBA/Goldfinches in service mpd Dec 07 15:29:47 volumio-3 volumio[845]: info: Adding Item to queue: music-library/USB/TOSHIBA/Holiday 2017 Dec 07 15:29:47 volumio-3 volumio[845]: info: Exploding uri music-library/USB/TOSHIBA/Holiday 2017 in service mpd Dec 07 15:29:47 volumio-3 volumio[845]: info: Adding Item to queue: music-library/USB/TOSHIBA/Music downloads Dec 07 15:29:47 volumio-3 volumio[845]: info: Exploding uri music-library/USB/TOSHIBA/Music downloads in service mpd Dec 07 15:29:47 volumio-3 volumio[845]: info: Adding Item to queue: music-library/USB/TOSHIBA/Our Friends In The North Dec 07 15:29:47 volumio-3 volumio[845]: info: Exploding uri music-library/USB/TOSHIBA/Our Friends In The North in service mpd Dec 07 15:29:47 volumio-3 volumio[845]: info: Adding Item to queue: music-library/USB/TOSHIBA/Wendys' Music Dec 07 15:29:47 volumio-3 volumio[845]: info: Exploding uri music-library/USB/TOSHIBA/Wendys' Music in service mpd Dec 07 15:29:47 volumio-3 volumio[845]: info: CoreStateMachine::stop Dec 07 15:29:47 volumio-3 volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 15:29:49 volumio-3 volumio[845]: info: CoreStateMachine::play index undefined Dec 07 15:29:49 volumio-3 volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 15:29:49 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:29:49 volumio-3 volumio[845]: info: CoreStateMachine::startPlaybackTimer Dec 07 15:29:49 volumio-3 volumio[845]: info: CorePlayQueue::getTrack 0 Dec 07 15:29:49 volumio-3 volumio[845]: verbose: ControllerMpd::clearAddPlayTracks USB/TOSHIBA/DSCF8786.AVI Dec 07 15:29:49 volumio-3 volumio[845]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 15:29:51 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=//extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDocuments%2FAudio%2FPilgrim2.wma&metadata=false Dec 07 15:29:51 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Documents/Audio/Pilgrim2.wma Dec 07 15:29:51 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=BBC%20Radio%204/BBC%20National%20DAB/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2F.Trash-1000%2Ffiles%2FMark%20Steel's%20In%20Town%20Melton%20Mobray.MP2&metadata=false Dec 07 15:29:51 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/.Trash-1000/files/Mark Steel's In Town Melton Mobray.MP2 Dec 07 15:29:51 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=BBC%20Radio%204/BBC%20National%20DAB/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2F.Trash-1000%2Ffiles%2FMark%20Steel's%20in%20town%20Barnard%20Castle.MP2&metadata=false Dec 07 15:29:51 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/.Trash-1000/files/Mark Steel's in town Barnard Castle.MP2 Dec 07 15:29:51 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=BBC%20Radio%204/BBC%20National%20DAB/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2F.Trash-1000%2Ffiles%2FMark%20Steel's%20in%20town%20Paisley.MP2&metadata=false Dec 07 15:29:51 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/.Trash-1000/files/Mark Steel's in town Paisley.MP2 Dec 07 15:29:51 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=BBC%20Radio%204/BBC%20National%20DAB/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2F.Trash-1000%2Ffiles%2FMark%20Steel's%20in%20town%20Shrewsbury.MP2&metadata=false Dec 07 15:29:51 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/.Trash-1000/files/Mark Steel's in town Shrewsbury.MP2 Dec 07 15:29:51 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=BBC%20Radio%204/BBC%20National%20DAB/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2F.Trash-1000%2Ffiles%2FMark%20steel's%20in%20town%20Fleetwood.MP2&metadata=false Dec 07 15:29:51 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/.Trash-1000/files/Mark steel's in town Fleetwood.MP2 Dec 07 15:29:51 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=Andrew%20Lawrence//extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2FAndrew%20Lawrence%2FAndrew%20Lawrence%20mp3%2FAndrew%20Lawrence%20-%20Secret%20London%20Show.mp3&metadata=false Dec 07 15:29:51 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/Andrew Lawrence/Andrew Lawrence mp3/Andrew Lawrence - Secret London Show.mp3 Dec 07 15:29:51 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=Andrew%20Lawrence//extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2FAndrew%20Lawrence%2FAndrewLawrence.m4a&metadata=false Dec 07 15:29:51 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/Andrew Lawrence/AndrewLawrence.m4a Dec 07 15:29:51 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=Phoenix/Glassnote%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2FGlassnote%2F01-01-%20Oblique%20City.mp3&metadata=false Dec 07 15:29:51 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/Glassnote/01-01- Oblique City.mp3 Dec 07 15:29:51 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=Half%20Moon%20Run/Glassnote%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2FGlassnote%2F01-02-%20Judgement.mp3&metadata=false Dec 07 15:29:51 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/Glassnote/01-02- Judgement.mp3 Dec 07 15:29:51 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=Childish%20Gambino/Glassnote%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2FGlassnote%2F01-03-%203005%20%5BExplicit%5D.mp3&metadata=false Dec 07 15:29:51 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/Glassnote/01-03- 3005 [Explicit].mp3 Dec 07 15:29:51 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=Justin%20Nozuka/Glassnote%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2FGlassnote%2F01-04-%20Right%20By%20You.mp3&metadata=false Dec 07 15:29:51 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/Glassnote/01-04- Right By You.mp3 Dec 07 15:29:51 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=Little%20Green%20Cars/Glassnote%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2FGlassnote%2F01-05-%20The%20Consequences%20Of%20Not%20Sleeping.mp3&metadata=false Dec 07 15:29:51 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/Glassnote/01-05- The Consequences Of Not Sleeping.mp3 Dec 07 15:29:51 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=Jeremy%20Messersmith/Glassnote%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2FGlassnote%2F01-06-%20Ghost.mp3&metadata=false Dec 07 15:29:51 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/Glassnote/01-06- Ghost.mp3 Dec 07 15:29:51 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=Givers/Glassnote%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2FGlassnote%2F01-07-%20Up%20Up%20Up.mp3&metadata=false Dec 07 15:29:51 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/Glassnote/01-07- Up Up Up.mp3 Dec 07 15:29:51 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=Oberhofer/Glassnote%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2FGlassnote%2F01-08-%20Away%20Frm%20U.mp3&metadata=false Dec 07 15:29:51 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/Glassnote/01-08- Away Frm U.mp3 Dec 07 15:29:51 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=Foy%20Vance/Glassnote%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2FGlassnote%2F01-09-%20Joy%20Of%20Nothing%20(Album%20Version).mp3&metadata=false Dec 07 15:29:51 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/Glassnote/01-09- Joy Of Nothing (Album Version).mp3 Dec 07 15:29:51 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=Jeremy%20Messersmith/Glassnote%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2FGlassnote%2F01-10-%20I%20Want%20To%20Be%20Your%20One%20Night%20Stand.mp3&metadata=false Dec 07 15:29:51 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/Glassnote/01-10- I Want To Be Your One Night Stand.mp3 Dec 07 15:29:51 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=//extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2FGreece%202014%2FGoing%20To%20Spartahori%20n.AVI&metadata=false Dec 07 15:29:51 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/Greece 2014/Going To Spartahori n.AVI Dec 07 15:29:52 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=//extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2FGreece%202014%2FLevkas%20c.AVI&metadata=false Dec 07 15:29:52 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/Greece 2014/Levkas c.AVI Dec 07 15:29:52 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=Golden%20Earring/The%20Long%20Versions%20-%20Part%20One/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2FHp%20Backup%2FDownload%2F10%20-%20Long%20Blond%20Animal%20(Live).mp3&metadata=false Dec 07 15:29:52 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/Hp Backup/Download/10 - Long Blond Animal (Live).mp3 Dec 07 15:29:52 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=Joe%20Bonamassa/An%20Acoustic%20Evening%20(Live%20at%20the%20Vienna%20Opera%20House)/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2FHp%20Backup%2FDownload%2F2-02%2BJockey%2BFull%2Bof%2BBourbon.mp3&metadata=false Dec 07 15:29:52 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/Hp Backup/Download/2-02+Jockey+Full+of+Bourbon.mp3 Dec 07 15:29:52 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=BBC%20Radio%204/Drama%20of%20the%20Week/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2FHp%20Backup%2FDownload%2FA%20christmas%20carol%20ptw_20141222-0800a.mp3&metadata=false Dec 07 15:29:52 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/Hp Backup/Download/A christmas carol ptw_20141222-0800a.mp3 Dec 07 15:29:52 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=Andrew%20Lawrence//extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2FHp%20Backup%2FDownload%2FAndrewLawrence.m4a&metadata=false Dec 07 15:29:52 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/Hp Backup/Download/AndrewLawrence.m4a Dec 07 15:29:52 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=//extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2FHp%20Backup%2FDownload%2FBeautiful-Hopeful-Free.mp3&metadata=false Dec 07 15:29:52 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/Hp Backup/Download/Beautiful-Hopeful-Free.mp3 Dec 07 15:29:52 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=//extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2FHp%20Backup%2FDownload%2FBeth_Hart-and-Joe_Bonamassa-Seesaw.mp3&metadata=false Dec 07 15:29:52 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/Hp Backup/Download/Beth_Hart-and-Joe_Bonamassa-Seesaw.mp3 Dec 07 15:29:52 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=Black%20Country%20Communion/Black%20Country%20Communion%20Disc%201/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2FHp%20Backup%2FDownload%2FBlack-Country-Communion.zip&metadata=false Dec 07 15:29:52 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/Hp Backup/Download/Black-Country-Communion.zip Dec 07 15:29:52 volumio-3 volumio[845]: info: ALBUMART /albumart?cacheid=90&web=Black%20Country%20Communion/Black%20Country%20Communion%20Disc%201/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA%2FDownloads%20A%2FHp%20Backup%2FDownload%2FBlack-Country-Communion.zip&metadata=false Dec 07 15:29:52 volumio-3 volumio[845]: info: URI /mnt/USB/TOSHIBA/Downloads A/Hp Backup/Download/Black-Country-Communion.zip Dec 07 15:29:52 volumio-3 volumio[845]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 15:29:52 volumio-3 volumio[845]: Error: Unable to resolve or reject the same promise twice Dec 07 15:29:52 volumio-3 volumio[845]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 07 15:29:52 volumio-3 volumio[845]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Dec 07 15:29:52 volumio-3 volumio[845]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Dec 07 15:29:52 volumio-3 volumio[845]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Dec 07 15:29:52 volumio-3 volumio[845]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Dec 07 15:29:52 volumio-3 volumio[845]: at Socket.emit (events.js:315:20) Dec 07 15:29:52 volumio-3 volumio[845]: at addChunk (internal/streams/readable.js:309:12) Dec 07 15:29:52 volumio-3 volumio[845]: at readableAddChunk (internal/streams/readable.js:280:11) Dec 07 15:29:52 volumio-3 volumio[845]: at Socket.Readable.push (internal/streams/readable.js:223:10) Dec 07 15:29:52 volumio-3 volumio[845]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Dec 07 15:29:52 volumio-3 volumio[845]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 15:29:54 volumio-3 sudo[5600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-07 15:28 Dec 07 15:29:54 volumio-3 sudo[5600]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"