Jan 27 20:31:01 musicserver volumio[31520]: Searching all installed plugins Jan 27 20:31:01 musicserver volumio[31520]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 20:31:01 musicserver volumio[31520]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jan 27 20:31:01 musicserver volumio[31520]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jan 27 20:31:02 musicserver volumio[31520]: info: All search sources collected, pushing search results Jan 27 20:31:05 musicserver volumio[31520]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 27 20:31:05 musicserver volumio[31520]: info: CURURI: artists://The%20Alan%20Parsons%20Project Jan 27 20:31:05 musicserver volumio[31520]: info: Preload queue cleared Jan 27 20:31:05 musicserver volumio[31520]: info: Preload queue cleared Jan 27 20:31:05 musicserver volumio[31520]: info: Preloading song: music-library/NAS/Truenas/Classic Rock/The Alan Parsons Project/Eye In The Sky/1-01 Sirius.m4a Jan 27 20:31:05 musicserver volumio[31520]: info: Preloading song: music-library/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/1. I Robot.m4a Jan 27 20:31:05 musicserver volumio[31520]: info: Preloading song: music-library/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/2. I Wouldn’t Want to Be Like You.m4a Jan 27 20:31:05 musicserver volumio[31520]: info: Preloading song: music-library/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/3. Some Other Time.m4a Jan 27 20:31:05 musicserver volumio[31520]: info: Preloading song: music-library/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/4. Breakdown.m4a Jan 27 20:31:05 musicserver volumio[31520]: info: Preloading song: music-library/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/5. Don’t Let It Show.m4a Jan 27 20:31:05 musicserver volumio[31520]: info: Preloading song: music-library/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/6. The Voice.m4a Jan 27 20:31:05 musicserver volumio[31520]: info: Preloading song: music-library/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/7. Nucleus.m4a Jan 27 20:31:05 musicserver volumio[31520]: info: Preloading song: music-library/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/8. Day After Day (The Show Must Go On).m4a Jan 27 20:31:05 musicserver volumio[31520]: info: Preloading song: music-library/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/9. Total Eclipse.m4a Jan 27 20:31:05 musicserver volumio[31520]: info: Preloading song: music-library/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/10. Genesis Ch. 1 V. 32.m4a Jan 27 20:31:05 musicserver volumio[31520]: info: Exploding uri music-library/NAS/Truenas/Classic Rock/The Alan Parsons Project/Eye In The Sky/1-01 Sirius.m4a in service mpd Jan 27 20:31:05 musicserver volumio[31520]: info: ALBUMART /albumart?cacheid=313&web=The%20Alan%20Parsons%20Project/Eye%20In%20The%20Sky/large&path=%2Fmnt%2FNAS%2FTruenas%2FClassic%20Rock%2FThe%20Alan%20Parsons%20Project%2FEye%20In%20The%20Sky%2F1-01%20Sirius.m4a&metadata=false Jan 27 20:31:05 musicserver volumio[31520]: info: URI /mnt/NAS/Truenas/Classic Rock/The Alan Parsons Project/Eye In The Sky/1-01 Sirius.m4a Jan 27 20:31:05 musicserver volumio[31520]: info: Exploding uri music-library/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/1. I Robot.m4a in service mpd Jan 27 20:31:05 musicserver volumio[31520]: info: ALBUMART /albumart?cacheid=313&web=The%20Alan%20Parsons%20Project/I%20Robot/large&path=%2Fmnt%2FNAS%2FTruenas%2FClassic%20Rock%2FThe%20Alan%20Parsons%20Project%2FI%20Robot%2F1.%20I%20Robot.m4a&metadata=false Jan 27 20:31:05 musicserver volumio[31520]: info: URI /mnt/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/1. I Robot.m4a Jan 27 20:31:05 musicserver volumio[31520]: info: Exploding uri music-library/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/2. I Wouldn’t Want to Be Like You.m4a in service mpd Jan 27 20:31:05 musicserver volumio[31520]: info: ALBUMART /albumart?cacheid=313&web=The%20Alan%20Parsons%20Project/I%20Robot/large&path=%2Fmnt%2FNAS%2FTruenas%2FClassic%20Rock%2FThe%20Alan%20Parsons%20Project%2FI%20Robot%2F2.%20I%20Wouldn%E2%80%99t%20Want%20to%20Be%20Like%20You.m4a&metadata=false Jan 27 20:31:05 musicserver volumio[31520]: info: URI /mnt/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/2. I Wouldn’t Want to Be Like You.m4a Jan 27 20:31:05 musicserver volumio[31520]: info: Exploding uri music-library/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/3. Some Other Time.m4a in service mpd Jan 27 20:31:05 musicserver volumio[31520]: info: ALBUMART /albumart?cacheid=313&web=The%20Alan%20Parsons%20Project/I%20Robot/large&path=%2Fmnt%2FNAS%2FTruenas%2FClassic%20Rock%2FThe%20Alan%20Parsons%20Project%2FI%20Robot%2F3.%20Some%20Other%20Time.m4a&metadata=false Jan 27 20:31:05 musicserver volumio[31520]: info: URI /mnt/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/3. Some Other Time.m4a Jan 27 20:31:05 musicserver volumio[31520]: info: Exploding uri music-library/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/4. Breakdown.m4a in service mpd Jan 27 20:31:05 musicserver volumio[31520]: info: ALBUMART /albumart?cacheid=313&web=The%20Alan%20Parsons%20Project/I%20Robot/large&path=%2Fmnt%2FNAS%2FTruenas%2FClassic%20Rock%2FThe%20Alan%20Parsons%20Project%2FI%20Robot%2F4.%20Breakdown.m4a&metadata=false Jan 27 20:31:05 musicserver volumio[31520]: info: URI /mnt/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/4. Breakdown.m4a Jan 27 20:31:05 musicserver volumio[31520]: info: Exploding uri music-library/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/5. Don’t Let It Show.m4a in service mpd Jan 27 20:31:05 musicserver volumio[31520]: info: ALBUMART /albumart?cacheid=313&web=The%20Alan%20Parsons%20Project/I%20Robot/large&path=%2Fmnt%2FNAS%2FTruenas%2FClassic%20Rock%2FThe%20Alan%20Parsons%20Project%2FI%20Robot%2F5.%20Don%E2%80%99t%20Let%20It%20Show.m4a&metadata=false Jan 27 20:31:05 musicserver volumio[31520]: info: URI /mnt/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/5. Don’t Let It Show.m4a Jan 27 20:31:05 musicserver volumio[31520]: info: Exploding uri music-library/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/6. The Voice.m4a in service mpd Jan 27 20:31:05 musicserver volumio[31520]: info: ALBUMART /albumart?cacheid=313&web=The%20Alan%20Parsons%20Project/I%20Robot/large&path=%2Fmnt%2FNAS%2FTruenas%2FClassic%20Rock%2FThe%20Alan%20Parsons%20Project%2FI%20Robot%2F6.%20The%20Voice.m4a&metadata=false Jan 27 20:31:05 musicserver volumio[31520]: info: URI /mnt/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/6. The Voice.m4a Jan 27 20:31:05 musicserver volumio[31520]: info: Exploding uri music-library/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/7. Nucleus.m4a in service mpd Jan 27 20:31:05 musicserver volumio[31520]: info: ALBUMART /albumart?cacheid=313&web=The%20Alan%20Parsons%20Project/I%20Robot/large&path=%2Fmnt%2FNAS%2FTruenas%2FClassic%20Rock%2FThe%20Alan%20Parsons%20Project%2FI%20Robot%2F7.%20Nucleus.m4a&metadata=false Jan 27 20:31:05 musicserver volumio[31520]: info: URI /mnt/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/7. Nucleus.m4a Jan 27 20:31:05 musicserver volumio[31520]: info: Exploding uri music-library/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/8. Day After Day (The Show Must Go On).m4a in service mpd Jan 27 20:31:05 musicserver volumio[31520]: info: Exploding uri music-library/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/9. Total Eclipse.m4a in service mpd Jan 27 20:31:05 musicserver volumio[31520]: info: ALBUMART /albumart?cacheid=313&web=The%20Alan%20Parsons%20Project/I%20Robot/large&path=%2Fmnt%2FNAS%2FTruenas%2FClassic%20Rock%2FThe%20Alan%20Parsons%20Project%2FI%20Robot%2F8.%20Day%20After%20Day%20(The%20Show%20Must%20Go%20On).m4a&metadata=false Jan 27 20:31:05 musicserver volumio[31520]: info: URI /mnt/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/8. Day After Day (The Show Must Go On).m4a Jan 27 20:31:05 musicserver volumio[31520]: info: ALBUMART /albumart?cacheid=313&web=The%20Alan%20Parsons%20Project/I%20Robot/large&path=%2Fmnt%2FNAS%2FTruenas%2FClassic%20Rock%2FThe%20Alan%20Parsons%20Project%2FI%20Robot%2F9.%20Total%20Eclipse.m4a&metadata=false Jan 27 20:31:05 musicserver volumio[31520]: info: URI /mnt/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/9. Total Eclipse.m4a Jan 27 20:31:05 musicserver volumio[31520]: info: Exploding uri music-library/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/10. Genesis Ch. 1 V. 32.m4a in service mpd Jan 27 20:31:05 musicserver volumio[31520]: info: ALBUMART /albumart?cacheid=313&web=The%20Alan%20Parsons%20Project/I%20Robot/large&path=%2Fmnt%2FNAS%2FTruenas%2FClassic%20Rock%2FThe%20Alan%20Parsons%20Project%2FI%20Robot%2F10.%20Genesis%20Ch.%201%20V.%2032.m4a&metadata=false Jan 27 20:31:05 musicserver volumio[31520]: info: URI /mnt/NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/10. Genesis Ch. 1 V. 32.m4a Jan 27 20:31:31 musicserver volumio[31520]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Uriah%20Heep/Greatest%20Hits/692ef5a3-12f1-4965-b9f7-f4ec3ff23d43.jpg' Jan 27 20:31:46 musicserver volumio[31520]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/311/From%20Chaos/554faeb0-b8b3-4167-b656-df3becb30321.jpg' Jan 27 20:31:47 musicserver volumio[31520]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Balanescu%20Quartet/Possessed/7a4647a6-7bf9-4f3c-91e0-ced8a88ca70c.jpg' Jan 27 20:32:12 musicserver volumio[31520]: info: Preload queue cleared Jan 27 20:32:12 musicserver volumio[31520]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 27 20:32:12 musicserver volumio[31520]: info: CoreStateMachine::ClearQueue Jan 27 20:32:12 musicserver volumio[31520]: info: CoreStateMachine::stop Jan 27 20:32:12 musicserver volumio[31520]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 20:32:12 musicserver volumio[31520]: info: CorePlayQueue::clearPlayQueue Jan 27 20:32:12 musicserver volumio[31520]: info: CorePlayQueue::saveQueue Jan 27 20:32:12 musicserver volumio[31520]: info: CoreCommandRouter::volumioPushQueue Jan 27 20:32:12 musicserver volumio[31520]: info: CoreStateMachine::addQueueItems Jan 27 20:32:12 musicserver volumio[31520]: info: CorePlayQueue::addQueueItems Jan 27 20:32:12 musicserver volumio[31520]: info: Preload queue cleared Jan 27 20:32:12 musicserver volumio[31520]: info: Adding Item to queue: artists://The%20Alan%20Parsons%20Project/I%20Robot Jan 27 20:32:12 musicserver volumio[31520]: info: Exploding uri artists://The%20Alan%20Parsons%20Project/I%20Robot in service mpd Jan 27 20:32:12 musicserver volumio[31520]: info: CoreCommandRouter::volumioPushQueue Jan 27 20:32:12 musicserver volumio[31520]: info: CorePlayQueue::saveQueue Jan 27 20:32:12 musicserver volumio[31520]: info: CoreStateMachine::updateTrackBlock Jan 27 20:32:12 musicserver volumio[31520]: info: CorePlayQueue::getTrackBlock Jan 27 20:32:12 musicserver volumio[31520]: info: CoreCommandRouter::volumioPlay Jan 27 20:32:12 musicserver volumio[31520]: info: CoreStateMachine::play index 0 Jan 27 20:32:12 musicserver volumio[31520]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 20:32:12 musicserver volumio[31520]: info: CoreStateMachine::stop Jan 27 20:32:12 musicserver volumio[31520]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 20:32:12 musicserver volumio[31520]: info: CoreStateMachine::play index undefined Jan 27 20:32:12 musicserver volumio[31520]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 20:32:12 musicserver volumio[31520]: info: CorePlayQueue::getTrack 0 Jan 27 20:32:12 musicserver volumio[31520]: info: CoreStateMachine::startPlaybackTimer Jan 27 20:32:12 musicserver volumio[31520]: info: CorePlayQueue::getTrack 0 Jan 27 20:32:12 musicserver volumio[31520]: verbose: ControllerMpd::clearAddPlayTracks NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/1. I Robot.m4a Jan 27 20:32:12 musicserver volumio[31520]: verbose: ControllerMpd::sendMpdCommand stop Jan 27 20:32:12 musicserver volumio[31520]: info: sendMpdCommand stop took 0 milliseconds Jan 27 20:32:12 musicserver volumio[31520]: verbose: ControllerMpd::sendMpdCommand clear Jan 27 20:32:12 musicserver volumio[31520]: info: Jan 27 20:32:12 musicserver volumio[31520]: ---------------------------- MPD announces system playlist update Jan 27 20:32:12 musicserver volumio[31520]: info: Ignoring MPD Status Update Jan 27 20:32:12 musicserver volumio[31520]: info: sendMpdCommand clear took 1 milliseconds Jan 27 20:32:12 musicserver volumio[31520]: verbose: ControllerMpd::sendMpdCommand add "NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/1. I Robot.m4a" Jan 27 20:32:12 musicserver volumio[31520]: info: Jan 27 20:32:12 musicserver volumio[31520]: ---------------------------- MPD announces system playlist update Jan 27 20:32:12 musicserver volumio[31520]: info: Ignoring MPD Status Update Jan 27 20:32:12 musicserver volumio[31520]: info: Jan 27 20:32:12 musicserver volumio[31520]: ---------------------------- MPD announces system playlist update Jan 27 20:32:12 musicserver volumio[31520]: info: Ignoring MPD Status Update Jan 27 20:32:12 musicserver volumio[31520]: error: updateQueue error: null Jan 27 20:32:12 musicserver volumio[31520]: info: Jan 27 20:32:12 musicserver volumio[31520]: ---------------------------- MPD announces system playlist update Jan 27 20:32:12 musicserver volumio[31520]: info: Ignoring MPD Status Update Jan 27 20:32:12 musicserver volumio[31520]: info: ------------------------------ 2ms Jan 27 20:32:12 musicserver volumio[31520]: info: sendMpdCommand add "NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/1. I Robot.m4a" took 2 milliseconds Jan 27 20:32:12 musicserver volumio[31520]: info: ------------------------------ 1ms Jan 27 20:32:12 musicserver volumio[31520]: info: ------------------------------ 1ms Jan 27 20:32:12 musicserver volumio[31520]: verbose: ControllerMpd::sendMpdCommand play Jan 27 20:32:12 musicserver volumio[31520]: info: Jan 27 20:32:12 musicserver volumio[31520]: ---------------------------- MPD announces system playlist update Jan 27 20:32:12 musicserver volumio[31520]: info: Ignoring MPD Status Update Jan 27 20:32:12 musicserver volumio[31520]: info: Jan 27 20:32:12 musicserver volumio[31520]: ---------------------------- MPD announces system playlist update Jan 27 20:32:12 musicserver volumio[31520]: info: Ignoring MPD Status Update Jan 27 20:32:12 musicserver volumio[31520]: info: ------------------------------ 2ms Jan 27 20:32:12 musicserver volumio[31520]: info: sendMpdCommand play took 2 milliseconds Jan 27 20:32:12 musicserver volumio[31520]: info: ------------------------------ 1ms Jan 27 20:32:12 musicserver volumio[31520]: info: ------------------------------ 1ms Jan 27 20:32:13 musicserver volumio[31520]: info: Jan 27 20:32:13 musicserver volumio[31520]: ---------------------------- MPD announces state update: player Jan 27 20:32:13 musicserver volumio[31520]: info: ControllerMpd::getState Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::sendMpdCommand status Jan 27 20:32:13 musicserver volumio[31520]: info: Jan 27 20:32:13 musicserver volumio[31520]: ---------------------------- MPD announces state update: player Jan 27 20:32:13 musicserver volumio[31520]: info: ControllerMpd::getState Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::sendMpdCommand status Jan 27 20:32:13 musicserver volumio[31520]: info: Jan 27 20:32:13 musicserver volumio[31520]: ---------------------------- MPD announces state update: player Jan 27 20:32:13 musicserver volumio[31520]: info: Jan 27 20:32:13 musicserver volumio[31520]: ---------------------------- MPD announces state update: player Jan 27 20:32:13 musicserver volumio[31520]: info: ControllerMpd::getState Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::sendMpdCommand status Jan 27 20:32:13 musicserver volumio[31520]: info: sendMpdCommand status took 3 milliseconds Jan 27 20:32:13 musicserver volumio[31520]: info: ControllerMpd::getState Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::sendMpdCommand status Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::parseState Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 20:32:13 musicserver volumio[31520]: info: Jan 27 20:32:13 musicserver volumio[31520]: ---------------------------- MPD announces state update: player Jan 27 20:32:13 musicserver volumio[31520]: info: ControllerMpd::getState Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::sendMpdCommand status Jan 27 20:32:13 musicserver volumio[31520]: info: Jan 27 20:32:13 musicserver volumio[31520]: ---------------------------- MPD announces state update: player Jan 27 20:32:13 musicserver volumio[31520]: info: ControllerMpd::getState Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::sendMpdCommand status Jan 27 20:32:13 musicserver volumio[31520]: info: sendMpdCommand status took 6 milliseconds Jan 27 20:32:13 musicserver volumio[31520]: info: sendMpdCommand status took 5 milliseconds Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::parseState Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::parseState Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 20:32:13 musicserver volumio[31520]: info: sendMpdCommand status took 9 milliseconds Jan 27 20:32:13 musicserver volumio[31520]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 27 20:32:13 musicserver volumio[31520]: info: sendMpdCommand status took 7 milliseconds Jan 27 20:32:13 musicserver volumio[31520]: info: sendMpdCommand status took 7 milliseconds Jan 27 20:32:13 musicserver volumio[31520]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 27 20:32:13 musicserver volumio[31520]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::parseState Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::parseTrackInfo Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::parseState Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::parseState Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::parseTrackInfo Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::parseTrackInfo Jan 27 20:32:13 musicserver volumio[31520]: info: ControllerMpd::pushState Jan 27 20:32:13 musicserver volumio[31520]: info: CoreCommandRouter::servicePushState Jan 27 20:32:13 musicserver volumio[31520]: info: CorePlayQueue::getTrack 0 Jan 27 20:32:13 musicserver volumio[31520]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":362,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"724 Kbps","isStreaming":false,"title":"I Robot","artist":"The Alan Parsons Project","album":"I Robot","uri":"NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/1. I Robot.m4a","trackType":"m4a"} Jan 27 20:32:13 musicserver volumio[31520]: verbose: CURRENT POSITION 0 Jan 27 20:32:13 musicserver volumio[31520]: info: CoreStateMachine::syncState stateService play Jan 27 20:32:13 musicserver volumio[31520]: info: CoreStateMachine::syncState currentStatus stop Jan 27 20:32:13 musicserver volumio[31520]: info: ControllerMpd::pushState Jan 27 20:32:13 musicserver volumio[31520]: info: CoreCommandRouter::servicePushState Jan 27 20:32:13 musicserver volumio[31520]: info: CorePlayQueue::getTrack 0 Jan 27 20:32:13 musicserver volumio[31520]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":362,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"724 Kbps","isStreaming":false,"title":"I Robot","artist":"The Alan Parsons Project","album":"I Robot","uri":"NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/1. I Robot.m4a","trackType":"m4a"} Jan 27 20:32:13 musicserver volumio[31520]: verbose: CURRENT POSITION 0 Jan 27 20:32:13 musicserver volumio[31520]: info: CoreStateMachine::syncState stateService play Jan 27 20:32:13 musicserver volumio[31520]: info: CoreStateMachine::syncState currentStatus play Jan 27 20:32:13 musicserver volumio[31520]: info: Received an update from plugin. extracting info from payload Jan 27 20:32:13 musicserver volumio[31520]: info: CoreStateMachine::pushState Jan 27 20:32:13 musicserver volumio[31520]: info: CorePlayQueue::getTrack 0 Jan 27 20:32:13 musicserver volumio[31520]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 20:32:13 musicserver volumio[31520]: info: CoreCommandRouter::volumioPushState Jan 27 20:32:13 musicserver volumio[31520]: info: CoreStateMachine::pushState Jan 27 20:32:13 musicserver volumio[31520]: info: CorePlayQueue::getTrack 0 Jan 27 20:32:13 musicserver volumio[31520]: info: CoreCommandRouter::volumioPushState Jan 27 20:32:13 musicserver volumio[31520]: info: ControllerMpd::pushState Jan 27 20:32:13 musicserver volumio[31520]: info: CoreCommandRouter::servicePushState Jan 27 20:32:13 musicserver volumio[31520]: info: CorePlayQueue::getTrack 0 Jan 27 20:32:13 musicserver volumio[31520]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":362,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"724 Kbps","isStreaming":false,"title":"I Robot","artist":"The Alan Parsons Project","album":"I Robot","uri":"NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/1. I Robot.m4a","trackType":"m4a"} Jan 27 20:32:13 musicserver volumio[31520]: verbose: CURRENT POSITION 0 Jan 27 20:32:13 musicserver volumio[31520]: info: CoreStateMachine::syncState stateService play Jan 27 20:32:13 musicserver volumio[31520]: info: CoreStateMachine::syncState currentStatus play Jan 27 20:32:13 musicserver volumio[31520]: info: Received an update from plugin. extracting info from payload Jan 27 20:32:13 musicserver volumio[31520]: info: CoreStateMachine::pushState Jan 27 20:32:13 musicserver volumio[31520]: info: CorePlayQueue::getTrack 0 Jan 27 20:32:13 musicserver volumio[31520]: info: CoreCommandRouter::volumioPushState Jan 27 20:32:13 musicserver volumio[31520]: info: CoreStateMachine::pushState Jan 27 20:32:13 musicserver volumio[31520]: info: CorePlayQueue::getTrack 0 Jan 27 20:32:13 musicserver volumio[31520]: info: CoreCommandRouter::volumioPushState Jan 27 20:32:13 musicserver volumio[31520]: info: ------------------------------ 23ms Jan 27 20:32:13 musicserver volumio[31520]: info: ------------------------------ 27ms Jan 27 20:32:13 musicserver volumio[31520]: info: ------------------------------ 26ms Jan 27 20:32:13 musicserver volumio[31520]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 27 20:32:13 musicserver volumio[31520]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 27 20:32:13 musicserver volumio[31520]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::parseTrackInfo Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::parseTrackInfo Jan 27 20:32:13 musicserver volumio[31520]: verbose: ControllerMpd::parseTrackInfo Jan 27 20:32:13 musicserver volumio[31520]: info: ControllerMpd::pushState Jan 27 20:32:13 musicserver volumio[31520]: info: CoreCommandRouter::servicePushState Jan 27 20:32:13 musicserver volumio[31520]: info: CorePlayQueue::getTrack 0 Jan 27 20:32:13 musicserver volumio[31520]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":362,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"724 Kbps","isStreaming":false,"title":"I Robot","artist":"The Alan Parsons Project","album":"I Robot","uri":"NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/1. I Robot.m4a","trackType":"m4a"} Jan 27 20:32:13 musicserver volumio[31520]: verbose: CURRENT POSITION 0 Jan 27 20:32:13 musicserver volumio[31520]: info: CoreStateMachine::syncState stateService play Jan 27 20:32:13 musicserver volumio[31520]: info: CoreStateMachine::syncState currentStatus play Jan 27 20:32:13 musicserver volumio[31520]: info: Received an update from plugin. extracting info from payload Jan 27 20:32:13 musicserver volumio[31520]: info: CoreStateMachine::pushState Jan 27 20:32:13 musicserver volumio[31520]: info: CorePlayQueue::getTrack 0 Jan 27 20:32:13 musicserver volumio[31520]: info: CoreCommandRouter::volumioPushState Jan 27 20:32:13 musicserver volumio[31520]: info: CoreStateMachine::pushState Jan 27 20:32:13 musicserver volumio[31520]: info: CorePlayQueue::getTrack 0 Jan 27 20:32:13 musicserver volumio[31520]: info: CoreCommandRouter::volumioPushState Jan 27 20:32:13 musicserver volumio[31520]: info: ControllerMpd::pushState Jan 27 20:32:13 musicserver volumio[31520]: info: CoreCommandRouter::servicePushState Jan 27 20:32:13 musicserver volumio[31520]: info: CorePlayQueue::getTrack 0 Jan 27 20:32:13 musicserver volumio[31520]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":362,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"724 Kbps","isStreaming":false,"title":"I Robot","artist":"The Alan Parsons Project","album":"I Robot","uri":"NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/1. I Robot.m4a","trackType":"m4a"} Jan 27 20:32:13 musicserver volumio[31520]: verbose: CURRENT POSITION 0 Jan 27 20:32:13 musicserver volumio[31520]: info: CoreStateMachine::syncState stateService play Jan 27 20:32:13 musicserver volumio[31520]: info: CoreStateMachine::syncState currentStatus play Jan 27 20:32:13 musicserver volumio[31520]: info: Received an update from plugin. extracting info from payload Jan 27 20:32:13 musicserver volumio[31520]: info: CoreStateMachine::pushState Jan 27 20:32:13 musicserver volumio[31520]: info: CorePlayQueue::getTrack 0 Jan 27 20:32:13 musicserver volumio[31520]: info: CoreCommandRouter::volumioPushState Jan 27 20:32:13 musicserver volumio[31520]: info: CoreStateMachine::pushState Jan 27 20:32:13 musicserver volumio[31520]: info: CorePlayQueue::getTrack 0 Jan 27 20:32:13 musicserver volumio[31520]: info: CoreCommandRouter::volumioPushState Jan 27 20:32:13 musicserver volumio[31520]: info: ControllerMpd::pushState Jan 27 20:32:13 musicserver volumio[31520]: info: CoreCommandRouter::servicePushState Jan 27 20:32:13 musicserver volumio[31520]: info: CorePlayQueue::getTrack 0 Jan 27 20:32:13 musicserver volumio[31520]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":362,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"724 Kbps","isStreaming":false,"title":"I Robot","artist":"The Alan Parsons Project","album":"I Robot","uri":"NAS/Truenas/Classic Rock/The Alan Parsons Project/I Robot/1. I Robot.m4a","trackType":"m4a"} Jan 27 20:32:13 musicserver volumio[31520]: verbose: CURRENT POSITION 0 Jan 27 20:32:13 musicserver volumio[31520]: info: CoreStateMachine::syncState stateService play Jan 27 20:32:13 musicserver volumio[31520]: info: CoreStateMachine::syncState currentStatus play Jan 27 20:32:13 musicserver volumio[31520]: info: Received an update from plugin. extracting info from payload Jan 27 20:32:13 musicserver volumio[31520]: info: CoreStateMachine::pushState Jan 27 20:32:13 musicserver volumio[31520]: info: CorePlayQueue::getTrack 0 Jan 27 20:32:13 musicserver volumio[31520]: info: CoreCommandRouter::volumioPushState Jan 27 20:32:13 musicserver volumio[31520]: info: CoreStateMachine::pushState Jan 27 20:32:13 musicserver volumio[31520]: info: CorePlayQueue::getTrack 0 Jan 27 20:32:13 musicserver volumio[31520]: info: CoreCommandRouter::volumioPushState Jan 27 20:32:13 musicserver volumio[31520]: info: ------------------------------ 42ms Jan 27 20:32:13 musicserver volumio[31520]: info: ------------------------------ 38ms Jan 27 20:32:13 musicserver volumio[31520]: info: ------------------------------ 39ms Jan 27 20:32:23 musicserver volumio[31520]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 20:32:23 musicserver volumio[31520]: AggregateError Jan 27 20:32:23 musicserver volumio[31520]: at internalConnectMultiple (node:net:1114:18) Jan 27 20:32:23 musicserver volumio[31520]: at afterConnectMultiple (node:net:1667:5) { Jan 27 20:32:23 musicserver volumio[31520]: code: 'ECONNREFUSED', Jan 27 20:32:23 musicserver volumio[31520]: [errors]: [ Jan 27 20:32:23 musicserver volumio[31520]: Error: connect ECONNREFUSED 158.69.209.125:443 Jan 27 20:32:23 musicserver volumio[31520]: at createConnectionError (node:net:1634:14) Jan 27 20:32:23 musicserver volumio[31520]: at afterConnectMultiple (node:net:1664:40) { Jan 27 20:32:23 musicserver volumio[31520]: errno: -111, Jan 27 20:32:23 musicserver volumio[31520]: code: 'ECONNREFUSED', Jan 27 20:32:23 musicserver volumio[31520]: syscall: 'connect', Jan 27 20:32:23 musicserver volumio[31520]: address: '158.69.209.125', Jan 27 20:32:23 musicserver volumio[31520]: port: 443 Jan 27 20:32:23 musicserver volumio[31520]: }, Jan 27 20:32:23 musicserver volumio[31520]: Error: connect ECONNREFUSED 158.69.210.98:443 Jan 27 20:32:23 musicserver volumio[31520]: at createConnectionError (node:net:1634:14) Jan 27 20:32:23 musicserver volumio[31520]: at afterConnectMultiple (node:net:1664:40) { Jan 27 20:32:23 musicserver volumio[31520]: errno: -111, Jan 27 20:32:23 musicserver volumio[31520]: code: 'ECONNREFUSED', Jan 27 20:32:23 musicserver volumio[31520]: syscall: 'connect', Jan 27 20:32:23 musicserver volumio[31520]: address: '158.69.210.98', Jan 27 20:32:23 musicserver volumio[31520]: port: 443 Jan 27 20:32:23 musicserver volumio[31520]: } Jan 27 20:32:23 musicserver volumio[31520]: ] Jan 27 20:32:23 musicserver volumio[31520]: } Jan 27 20:32:23 musicserver volumio[31520]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 20:32:23 musicserver sudo[4663]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-27 20:31' Jan 27 20:32:23 musicserver sudo[4663]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"