-- Logs begin at Wed 2021-10-06 10:34:49 UTC, end at Sun 2026-05-31 16:42:42 UTC. -- May 31 16:41:41 volumio kernel: usb 1-1.3: new high-speed USB device number 4 using dwc_otg May 31 16:41:41 volumio kernel: usb 1-1.3: New USB device found, idVendor=0781, idProduct=557d, bcdDevice= 1.26 May 31 16:41:41 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 31 16:41:41 volumio kernel: usb 1-1.3: Product: Cruzer Force May 31 16:41:41 volumio kernel: usb 1-1.3: Manufacturer: SanDisk May 31 16:41:41 volumio kernel: usb 1-1.3: SerialNumber: 4C532000030822122344 May 31 16:41:41 volumio kernel: usb-storage 1-1.3:1.0: USB Mass Storage device detected May 31 16:41:41 volumio kernel: scsi host0: usb-storage 1-1.3:1.0 May 31 16:41:41 volumio kernel: usbcore: registered new interface driver uas May 31 16:41:42 volumio kernel: scsi 0:0:0:0: Direct-Access SanDisk Cruzer Force 1.26 PQ: 0 ANSI: 6 May 31 16:41:42 volumio kernel: sd 0:0:0:0: [sda] 31266816 512-byte logical blocks: (16.0 GB/14.9 GiB) May 31 16:41:42 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off May 31 16:41:42 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00 May 31 16:41:42 volumio kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA May 31 16:41:42 volumio kernel: sda: sda4 May 31 16:41:42 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk May 31 16:41:42 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 May 31 16:41:43 volumio udisks-glue[537]: Device file /dev/sda inserted May 31 16:41:43 volumio udisks-glue[537]: Device file /dev/sda4 inserted May 31 16:41:43 volumio udisks-glue[537]: Trying to automount /dev/sda4... May 31 16:41:43 volumio kernel: FAT-fs (sda4): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. May 31 16:41:43 volumio udisks-glue[537]: Successfully automounted /dev/sda4 at /media/DISK May 31 16:41:43 volumio udisks-glue[537]: Device file /dev/sda4 mounted at /media/DISK May 31 16:41:43 volumio volumio[724]: verbose: ControllerMpd::sendMpdCommand status May 31 16:41:43 volumio volumio[724]: info: May 31 16:41:43 volumio volumio[724]: ---------------------------- MPD announces state update: update May 31 16:41:43 volumio udisks-glue[537]: Updating DB (#2) ... May 31 16:41:43 volumio udisks-glue[537]: volume: 40% repeat: off random: off single: off consume: off May 31 16:41:43 volumio volumio[724]: info: ControllerMpd::getState May 31 16:41:43 volumio volumio[724]: verbose: ControllerMpd::sendMpdCommand status May 31 16:41:43 volumio volumio[724]: info: sendMpdCommand status took 65 milliseconds May 31 16:41:43 volumio volumio[724]: info: sendMpdCommand status took 35 milliseconds May 31 16:41:43 volumio volumio[724]: verbose: ControllerMpd::parseState May 31 16:41:43 volumio volumio[724]: info: Command Router : Notfying DB Updatetrue May 31 16:41:44 volumio volumio[724]: verbose: ControllerMpd::parseState May 31 16:41:44 volumio volumio[724]: info: ControllerMpd::pushState May 31 16:41:44 volumio volumio[724]: info: CoreCommandRouter::servicePushState May 31 16:41:44 volumio volumio[724]: info: CoreStateMachine::pushState May 31 16:41:44 volumio volumio[724]: info: CorePlayQueue::getTrack 0 May 31 16:41:44 volumio volumio[724]: info: CoreCommandRouter::volumioPushState May 31 16:41:44 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:41:44 volumio volumio[724]: info: CorePlayQueue::getTrack 0 May 31 16:41:44 volumio volumio[724]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 31 16:41:44 volumio volumio[724]: verbose: CURRENT POSITION 0 May 31 16:41:44 volumio volumio[724]: info: CoreStateMachine::syncState stateService stop May 31 16:41:44 volumio volumio[724]: info: CoreStateMachine::syncState currentStatus stop May 31 16:41:44 volumio volumio[724]: info: CoreStateMachine::pushState May 31 16:41:44 volumio volumio[724]: info: CorePlayQueue::getTrack 0 May 31 16:41:44 volumio volumio[724]: info: CoreCommandRouter::volumioPushState May 31 16:41:44 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:41:44 volumio volumio[724]: info: No code May 31 16:41:44 volumio volumio[724]: info: CoreStateMachine::pushState May 31 16:41:44 volumio volumio[724]: info: CorePlayQueue::getTrack 0 May 31 16:41:44 volumio volumio[724]: info: CoreCommandRouter::volumioPushState May 31 16:41:44 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:41:44 volumio volumio[724]: info: ------------------------------ 1030ms May 31 16:41:48 volumio volumio[724]: info: MPD Database updated - AlbumList cache refreshed May 31 16:41:48 volumio volumio[724]: info: May 31 16:41:48 volumio volumio[724]: ---------------------------- MPD announces state update: database May 31 16:41:48 volumio volumio[724]: verbose: ControllerMpd::sendMpdCommand status May 31 16:41:48 volumio volumio[724]: info: May 31 16:41:48 volumio volumio[724]: ---------------------------- MPD announces state update: update May 31 16:41:48 volumio volumio[724]: info: ControllerMpd::getState May 31 16:41:48 volumio volumio[724]: verbose: ControllerMpd::sendMpdCommand status May 31 16:41:48 volumio volumio[724]: info: ControllerMpd::getState May 31 16:41:48 volumio volumio[724]: verbose: ControllerMpd::sendMpdCommand status May 31 16:41:48 volumio volumio[724]: info: sendMpdCommand status took 140 milliseconds May 31 16:41:48 volumio volumio[724]: info: sendMpdCommand status took 81 milliseconds May 31 16:41:48 volumio volumio[724]: info: sendMpdCommand status took 77 milliseconds May 31 16:41:48 volumio volumio[724]: verbose: ControllerMpd::parseState May 31 16:41:48 volumio volumio[724]: info: Command Router : Notfying DB Updatefalse May 31 16:41:48 volumio volumio[724]: info: CoreCommandRouter::Close All Modals sent May 31 16:41:48 volumio volumio[724]: verbose: ControllerMpd::parseState May 31 16:41:48 volumio volumio[724]: verbose: ControllerMpd::parseState May 31 16:41:48 volumio volumio[724]: info: ControllerMpd::pushState May 31 16:41:48 volumio volumio[724]: info: CoreCommandRouter::servicePushState May 31 16:41:48 volumio volumio[724]: info: CoreStateMachine::pushState May 31 16:41:48 volumio volumio[724]: info: CorePlayQueue::getTrack 0 May 31 16:41:48 volumio volumio[724]: info: CoreCommandRouter::volumioPushState May 31 16:41:48 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:41:48 volumio volumio[724]: info: CorePlayQueue::getTrack 0 May 31 16:41:48 volumio volumio[724]: 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} May 31 16:41:48 volumio volumio[724]: verbose: CURRENT POSITION 0 May 31 16:41:48 volumio volumio[724]: info: CoreStateMachine::syncState stateService stop May 31 16:41:48 volumio volumio[724]: info: CoreStateMachine::syncState currentStatus stop May 31 16:41:48 volumio volumio[724]: info: CoreStateMachine::pushState May 31 16:41:48 volumio volumio[724]: info: CorePlayQueue::getTrack 0 May 31 16:41:48 volumio volumio[724]: info: CoreCommandRouter::volumioPushState May 31 16:41:48 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:41:48 volumio volumio[724]: info: No code May 31 16:41:48 volumio volumio[724]: info: CoreStateMachine::pushState May 31 16:41:48 volumio volumio[724]: info: CorePlayQueue::getTrack 0 May 31 16:41:48 volumio volumio[724]: info: CoreCommandRouter::volumioPushState May 31 16:41:48 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:41:48 volumio volumio[724]: info: ControllerMpd::pushState May 31 16:41:48 volumio volumio[724]: info: CoreCommandRouter::servicePushState May 31 16:41:48 volumio volumio[724]: info: CoreStateMachine::pushState May 31 16:41:48 volumio volumio[724]: info: CorePlayQueue::getTrack 0 May 31 16:41:48 volumio volumio[724]: info: CoreCommandRouter::volumioPushState May 31 16:41:49 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:41:49 volumio volumio[724]: info: CorePlayQueue::getTrack 0 May 31 16:41:49 volumio volumio[724]: 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} May 31 16:41:49 volumio volumio[724]: verbose: CURRENT POSITION 0 May 31 16:41:49 volumio volumio[724]: info: CoreStateMachine::syncState stateService stop May 31 16:41:49 volumio volumio[724]: info: CoreStateMachine::syncState currentStatus stop May 31 16:41:49 volumio volumio[724]: info: CoreStateMachine::pushState May 31 16:41:49 volumio volumio[724]: info: CorePlayQueue::getTrack 0 May 31 16:41:49 volumio volumio[724]: info: CoreCommandRouter::volumioPushState May 31 16:41:49 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:41:49 volumio volumio[724]: info: No code May 31 16:41:49 volumio volumio[724]: info: CoreStateMachine::pushState May 31 16:41:49 volumio volumio[724]: info: CorePlayQueue::getTrack 0 May 31 16:41:49 volumio volumio[724]: info: CoreCommandRouter::volumioPushState May 31 16:41:49 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:41:49 volumio volumio[724]: info: ------------------------------ 638ms May 31 16:41:49 volumio volumio[724]: info: ------------------------------ 641ms May 31 16:41:55 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 16:41:55 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 16:41:55 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 16:41:55 volumio volumio[724]: info: CoreCommandRouter::volumioGetState May 31 16:41:55 volumio volumio[724]: info: CorePlayQueue::getTrack 0 May 31 16:41:55 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 16:41:55 volumio volumio[724]: info: Listing playlists May 31 16:41:55 volumio volumio[724]: info: CoreCommandRouter::volumioGetVisibleSources May 31 16:41:55 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 16:41:55 volumio volumio[724]: info: CoreCommandRouter::volumioGetQueue May 31 16:41:55 volumio volumio[724]: info: CoreStateMachine::getQueue May 31 16:41:55 volumio volumio[724]: info: CorePlayQueue::getQueue May 31 16:41:56 volumio volumio[724]: info: CoreCommandRouter::volumioGetState May 31 16:41:56 volumio volumio[724]: info: CorePlayQueue::getTrack 0 May 31 16:41:56 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 16:41:56 volumio volumio[724]: info: Listing playlists May 31 16:41:56 volumio volumio[724]: info: CoreCommandRouter::volumioGetVisibleSources May 31 16:41:56 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 16:41:56 volumio volumio[724]: info: CoreCommandRouter::volumioGetQueue May 31 16:41:56 volumio volumio[724]: info: CoreStateMachine::getQueue May 31 16:41:56 volumio volumio[724]: info: CorePlayQueue::getQueue May 31 16:41:56 volumio volumio[724]: info: CoreCommandRouter::volumioGetState May 31 16:41:56 volumio volumio[724]: info: CorePlayQueue::getTrack 0 May 31 16:41:56 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 16:41:56 volumio volumio[724]: info: Listing playlists May 31 16:41:56 volumio volumio[724]: info: CoreCommandRouter::volumioGetVisibleSources May 31 16:41:56 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 16:41:56 volumio volumio[724]: info: CoreCommandRouter::volumioGetQueue May 31 16:41:56 volumio volumio[724]: info: CoreStateMachine::getQueue May 31 16:41:56 volumio volumio[724]: info: CorePlayQueue::getQueue May 31 16:41:57 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 16:42:04 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 16:42:04 volumio volumio[724]: info: CURURI: albums:// May 31 16:42:04 volumio volumio[724]: listAlbums - loading Albums from cache May 31 16:42:05 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 16:42:05 volumio volumio[724]: info: CURURI: albums://Charlie%20Puth/Voicenotes May 31 16:42:08 volumio volumio[724]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 16:42:08 volumio volumio[724]: info: CoreStateMachine::ClearQueue May 31 16:42:08 volumio volumio[724]: info: CoreStateMachine::stop May 31 16:42:09 volumio volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 16:42:09 volumio volumio[724]: verbose: UNSET VOLATILE May 31 16:42:09 volumio volumio[724]: info: CorePlayQueue::clearPlayQueue May 31 16:42:09 volumio volumio[724]: info: CorePlayQueue::saveQueue May 31 16:42:09 volumio volumio[724]: info: CoreCommandRouter::volumioPushState May 31 16:42:09 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:42:09 volumio volumio[724]: info: CoreCommandRouter::volumioPushQueue May 31 16:42:09 volumio volumio[724]: info: CoreStateMachine::addQueueItems May 31 16:42:09 volumio volumio[724]: info: CorePlayQueue::addQueueItems May 31 16:42:09 volumio volumio[724]: info: Adding Item to queue: music-library/USB/DISK/【Charlie Puth】Voicenotes/01.Charlie Puth - The Way I Am.flac May 31 16:42:09 volumio volumio[724]: info: Exploding uri music-library/USB/DISK/【Charlie Puth】Voicenotes/01.Charlie Puth - The Way I Am.flac in service mpd May 31 16:42:09 volumio volumio[724]: info: Adding Item to queue: music-library/USB/DISK/【Charlie Puth】Voicenotes/02.Charlie Puth - Attention.flac May 31 16:42:09 volumio volumio[724]: info: Exploding uri music-library/USB/DISK/【Charlie Puth】Voicenotes/02.Charlie Puth - Attention.flac in service mpd May 31 16:42:09 volumio volumio[724]: info: Adding Item to queue: music-library/USB/DISK/【Charlie Puth】Voicenotes/03.Charlie Puth - LA Girls.flac May 31 16:42:09 volumio volumio[724]: info: Exploding uri music-library/USB/DISK/【Charlie Puth】Voicenotes/03.Charlie Puth - LA Girls.flac in service mpd May 31 16:42:09 volumio volumio[724]: info: Adding Item to queue: music-library/USB/DISK/【Charlie Puth】Voicenotes/04.Charlie Puth - How Long.flac May 31 16:42:09 volumio volumio[724]: info: Exploding uri music-library/USB/DISK/【Charlie Puth】Voicenotes/04.Charlie Puth - How Long.flac in service mpd May 31 16:42:09 volumio volumio[724]: info: Adding Item to queue: music-library/USB/DISK/【Charlie Puth】Voicenotes/05.Charlie Puth - Done For Me.flac May 31 16:42:09 volumio volumio[724]: info: Exploding uri music-library/USB/DISK/【Charlie Puth】Voicenotes/05.Charlie Puth - Done For Me.flac in service mpd May 31 16:42:09 volumio volumio[724]: info: Adding Item to queue: music-library/USB/DISK/【Charlie Puth】Voicenotes/06.Charlie Puth - Patient.flac May 31 16:42:09 volumio volumio[724]: info: Exploding uri music-library/USB/DISK/【Charlie Puth】Voicenotes/06.Charlie Puth - Patient.flac in service mpd May 31 16:42:09 volumio volumio[724]: info: Adding Item to queue: music-library/USB/DISK/【Charlie Puth】Voicenotes/07.Charlie Puth - If You Leave Me Now.flac May 31 16:42:09 volumio volumio[724]: info: Exploding uri music-library/USB/DISK/【Charlie Puth】Voicenotes/07.Charlie Puth - If You Leave Me Now.flac in service mpd May 31 16:42:09 volumio volumio[724]: info: Adding Item to queue: music-library/USB/DISK/【Charlie Puth】Voicenotes/08.Charlie Puth - BOY.flac May 31 16:42:09 volumio volumio[724]: info: Exploding uri music-library/USB/DISK/【Charlie Puth】Voicenotes/08.Charlie Puth - BOY.flac in service mpd May 31 16:42:09 volumio volumio[724]: info: Adding Item to queue: music-library/USB/DISK/【Charlie Puth】Voicenotes/09.Charlie Puth - Slow It Down.flac May 31 16:42:09 volumio volumio[724]: info: Exploding uri music-library/USB/DISK/【Charlie Puth】Voicenotes/09.Charlie Puth - Slow It Down.flac in service mpd May 31 16:42:09 volumio volumio[724]: info: Adding Item to queue: music-library/USB/DISK/【Charlie Puth】Voicenotes/10.Charlie Puth - Change.flac May 31 16:42:09 volumio volumio[724]: info: Exploding uri music-library/USB/DISK/【Charlie Puth】Voicenotes/10.Charlie Puth - Change.flac in service mpd May 31 16:42:09 volumio volumio[724]: info: Adding Item to queue: music-library/USB/DISK/【Charlie Puth】Voicenotes/11.Charlie Puth - Somebody Told Me.flac May 31 16:42:09 volumio volumio[724]: info: Exploding uri music-library/USB/DISK/【Charlie Puth】Voicenotes/11.Charlie Puth - Somebody Told Me.flac in service mpd May 31 16:42:09 volumio volumio[724]: info: Adding Item to queue: music-library/USB/DISK/【Charlie Puth】Voicenotes/12.Charlie Puth - Empty Cups.flac May 31 16:42:09 volumio volumio[724]: info: Exploding uri music-library/USB/DISK/【Charlie Puth】Voicenotes/12.Charlie Puth - Empty Cups.flac in service mpd May 31 16:42:09 volumio volumio[724]: info: Adding Item to queue: music-library/USB/DISK/【Charlie Puth】Voicenotes/13.Charlie Puth - Through It All.flac May 31 16:42:09 volumio volumio[724]: info: Exploding uri music-library/USB/DISK/【Charlie Puth】Voicenotes/13.Charlie Puth - Through It All.flac in service mpd May 31 16:42:09 volumio volumio[724]: info: ALBUMART /albumart?cacheid=641&web=Charlie%20Puth/Voicenotes/extralarge&path=%2Fmnt%2FUSB%2FDISK%2F%E3%80%90Charlie%20Puth%E3%80%91Voicenotes%2F01.Charlie%20Puth%20-%20The%20Way%20I%20Am.flac&metadata=false May 31 16:42:09 volumio volumio[724]: info: URI /mnt/USB/DISK/【Charlie Puth】Voicenotes/01.Charlie Puth - The Way I Am.flac May 31 16:42:09 volumio volumio[724]: info: ALBUMART /albumart?cacheid=641&web=Charlie%20Puth/Voicenotes/extralarge&path=%2Fmnt%2FUSB%2FDISK%2F%E3%80%90Charlie%20Puth%E3%80%91Voicenotes%2F02.Charlie%20Puth%20-%20Attention.flac&metadata=false May 31 16:42:09 volumio volumio[724]: info: URI /mnt/USB/DISK/【Charlie Puth】Voicenotes/02.Charlie Puth - Attention.flac May 31 16:42:09 volumio volumio[724]: info: ALBUMART /albumart?cacheid=641&web=Charlie%20Puth/Voicenotes/extralarge&path=%2Fmnt%2FUSB%2FDISK%2F%E3%80%90Charlie%20Puth%E3%80%91Voicenotes%2F03.Charlie%20Puth%20-%20LA%20Girls.flac&metadata=false May 31 16:42:09 volumio volumio[724]: info: URI /mnt/USB/DISK/【Charlie Puth】Voicenotes/03.Charlie Puth - LA Girls.flac May 31 16:42:09 volumio volumio[724]: info: ALBUMART /albumart?cacheid=641&web=Charlie%20Puth/Voicenotes/extralarge&path=%2Fmnt%2FUSB%2FDISK%2F%E3%80%90Charlie%20Puth%E3%80%91Voicenotes%2F04.Charlie%20Puth%20-%20How%20Long.flac&metadata=false May 31 16:42:09 volumio volumio[724]: info: URI /mnt/USB/DISK/【Charlie Puth】Voicenotes/04.Charlie Puth - How Long.flac May 31 16:42:09 volumio volumio[724]: info: ALBUMART /albumart?cacheid=641&web=Charlie%20Puth/Voicenotes/extralarge&path=%2Fmnt%2FUSB%2FDISK%2F%E3%80%90Charlie%20Puth%E3%80%91Voicenotes%2F05.Charlie%20Puth%20-%20Done%20For%20Me.flac&metadata=false May 31 16:42:09 volumio volumio[724]: info: URI /mnt/USB/DISK/【Charlie Puth】Voicenotes/05.Charlie Puth - Done For Me.flac May 31 16:42:09 volumio volumio[724]: info: ALBUMART /albumart?cacheid=641&web=Charlie%20Puth/Voicenotes/extralarge&path=%2Fmnt%2FUSB%2FDISK%2F%E3%80%90Charlie%20Puth%E3%80%91Voicenotes%2F06.Charlie%20Puth%20-%20Patient.flac&metadata=false May 31 16:42:09 volumio volumio[724]: info: URI /mnt/USB/DISK/【Charlie Puth】Voicenotes/06.Charlie Puth - Patient.flac May 31 16:42:09 volumio volumio[724]: info: ALBUMART /albumart?cacheid=641&web=Charlie%20Puth/Voicenotes/extralarge&path=%2Fmnt%2FUSB%2FDISK%2F%E3%80%90Charlie%20Puth%E3%80%91Voicenotes%2F07.Charlie%20Puth%20-%20If%20You%20Leave%20Me%20Now.flac&metadata=false May 31 16:42:09 volumio volumio[724]: info: URI /mnt/USB/DISK/【Charlie Puth】Voicenotes/07.Charlie Puth - If You Leave Me Now.flac May 31 16:42:09 volumio volumio[724]: info: ALBUMART /albumart?cacheid=641&web=Charlie%20Puth/Voicenotes/extralarge&path=%2Fmnt%2FUSB%2FDISK%2F%E3%80%90Charlie%20Puth%E3%80%91Voicenotes%2F08.Charlie%20Puth%20-%20BOY.flac&metadata=false May 31 16:42:09 volumio volumio[724]: info: URI /mnt/USB/DISK/【Charlie Puth】Voicenotes/08.Charlie Puth - BOY.flac May 31 16:42:09 volumio volumio[724]: info: ALBUMART /albumart?cacheid=641&web=Charlie%20Puth/Voicenotes/extralarge&path=%2Fmnt%2FUSB%2FDISK%2F%E3%80%90Charlie%20Puth%E3%80%91Voicenotes%2F09.Charlie%20Puth%20-%20Slow%20It%20Down.flac&metadata=false May 31 16:42:09 volumio volumio[724]: info: URI /mnt/USB/DISK/【Charlie Puth】Voicenotes/09.Charlie Puth - Slow It Down.flac May 31 16:42:09 volumio volumio[724]: info: ALBUMART /albumart?cacheid=641&web=Charlie%20Puth/Voicenotes/extralarge&path=%2Fmnt%2FUSB%2FDISK%2F%E3%80%90Charlie%20Puth%E3%80%91Voicenotes%2F10.Charlie%20Puth%20-%20Change.flac&metadata=false May 31 16:42:09 volumio volumio[724]: info: URI /mnt/USB/DISK/【Charlie Puth】Voicenotes/10.Charlie Puth - Change.flac May 31 16:42:09 volumio volumio[724]: info: ALBUMART /albumart?cacheid=641&web=Charlie%20Puth/Voicenotes/extralarge&path=%2Fmnt%2FUSB%2FDISK%2F%E3%80%90Charlie%20Puth%E3%80%91Voicenotes%2F11.Charlie%20Puth%20-%20Somebody%20Told%20Me.flac&metadata=false May 31 16:42:09 volumio volumio[724]: info: URI /mnt/USB/DISK/【Charlie Puth】Voicenotes/11.Charlie Puth - Somebody Told Me.flac May 31 16:42:09 volumio volumio[724]: info: ALBUMART /albumart?cacheid=641&web=Charlie%20Puth/Voicenotes/extralarge&path=%2Fmnt%2FUSB%2FDISK%2F%E3%80%90Charlie%20Puth%E3%80%91Voicenotes%2F12.Charlie%20Puth%20-%20Empty%20Cups.flac&metadata=false May 31 16:42:09 volumio volumio[724]: info: URI /mnt/USB/DISK/【Charlie Puth】Voicenotes/12.Charlie Puth - Empty Cups.flac May 31 16:42:09 volumio volumio[724]: info: ALBUMART /albumart?cacheid=641&web=Charlie%20Puth/Voicenotes/extralarge&path=%2Fmnt%2FUSB%2FDISK%2F%E3%80%90Charlie%20Puth%E3%80%91Voicenotes%2F13.Charlie%20Puth%20-%20Through%20It%20All.flac&metadata=false May 31 16:42:09 volumio volumio[724]: info: URI /mnt/USB/DISK/【Charlie Puth】Voicenotes/13.Charlie Puth - Through It All.flac May 31 16:42:09 volumio volumio[724]: info: CorePlayQueue::saveQueue May 31 16:42:09 volumio volumio[724]: info: CoreCommandRouter::volumioPushQueue May 31 16:42:09 volumio volumio[724]: info: CoreStateMachine::updateTrackBlock May 31 16:42:09 volumio volumio[724]: info: CorePlayQueue::getTrackBlock May 31 16:42:09 volumio volumio[724]: info: CoreCommandRouter::volumioPlay May 31 16:42:09 volumio volumio[724]: verbose: UNSET VOLATILE May 31 16:42:09 volumio volumio[724]: info: CoreStateMachine::play index 0 May 31 16:42:09 volumio volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 16:42:09 volumio volumio[724]: info: CoreStateMachine::stop May 31 16:42:09 volumio volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 16:42:09 volumio volumio[724]: verbose: UNSET VOLATILE May 31 16:42:09 volumio volumio[724]: info: CoreStateMachine::play index undefined May 31 16:42:09 volumio volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 16:42:09 volumio volumio[724]: info: CorePlayQueue::getTrack 0 May 31 16:42:09 volumio volumio[724]: info: CoreStateMachine::startPlaybackTimer May 31 16:42:09 volumio volumio[724]: info: CorePlayQueue::getTrack 0 May 31 16:42:09 volumio volumio[724]: verbose: ControllerMpd::clearAddPlayTracks USB/DISK/【Charlie Puth】Voicenotes/01.Charlie Puth - The Way I Am.flac May 31 16:42:09 volumio volumio[724]: verbose: ControllerMpd::sendMpdCommand stop May 31 16:42:09 volumio volumio[724]: info: sendMpdCommand stop took 21 milliseconds May 31 16:42:09 volumio volumio[724]: verbose: ControllerMpd::sendMpdCommand clear May 31 16:42:09 volumio volumio[724]: info: May 31 16:42:09 volumio volumio[724]: ---------------------------- MPD announces system playlist update May 31 16:42:09 volumio volumio[724]: info: Ignoring MPD Status Update May 31 16:42:09 volumio volumio[724]: info: sendMpdCommand clear took 40 milliseconds May 31 16:42:09 volumio volumio[724]: verbose: ControllerMpd::sendMpdCommand add "USB/DISK/【Charlie Puth】Voicenotes/01.Charlie Puth - The Way I Am.flac" May 31 16:42:09 volumio volumio[724]: error: updateQueue error: null May 31 16:42:09 volumio volumio[724]: info: May 31 16:42:09 volumio volumio[724]: ---------------------------- MPD announces system playlist update May 31 16:42:09 volumio volumio[724]: info: Ignoring MPD Status Update May 31 16:42:09 volumio volumio[724]: info: ------------------------------ 73ms May 31 16:42:09 volumio volumio[724]: info: sendMpdCommand add "USB/DISK/【Charlie Puth】Voicenotes/01.Charlie Puth - The Way I Am.flac" took 54 milliseconds May 31 16:42:09 volumio volumio[724]: verbose: ControllerMpd::sendMpdCommand play May 31 16:42:09 volumio volumio[724]: info: ------------------------------ 109ms May 31 16:42:09 volumio volumio[724]: info: sendMpdCommand play took 76 milliseconds May 31 16:42:10 volumio volumio[724]: info: May 31 16:42:10 volumio volumio[724]: ---------------------------- MPD announces state update: player May 31 16:42:10 volumio volumio[724]: info: ControllerMpd::getState May 31 16:42:10 volumio volumio[724]: verbose: ControllerMpd::sendMpdCommand status May 31 16:42:10 volumio volumio[724]: info: May 31 16:42:10 volumio volumio[724]: ---------------------------- MPD announces state update: player May 31 16:42:10 volumio volumio[724]: info: ControllerMpd::getState May 31 16:42:10 volumio volumio[724]: verbose: ControllerMpd::sendMpdCommand status May 31 16:42:10 volumio volumio[724]: info: sendMpdCommand status took 46 milliseconds May 31 16:42:10 volumio volumio[724]: info: sendMpdCommand status took 34 milliseconds May 31 16:42:10 volumio volumio[724]: verbose: ControllerMpd::parseState May 31 16:42:10 volumio volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 16:42:10 volumio volumio[724]: verbose: ControllerMpd::parseState May 31 16:42:10 volumio volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 16:42:10 volumio volumio[724]: info: sendMpdCommand playlistinfo took 45 milliseconds May 31 16:42:10 volumio volumio[724]: info: sendMpdCommand playlistinfo took 45 milliseconds May 31 16:42:10 volumio volumio[724]: verbose: ControllerMpd::parseTrackInfo May 31 16:42:10 volumio volumio[724]: verbose: ControllerMpd::parseTrackInfo May 31 16:42:10 volumio volumio[724]: info: ControllerMpd::pushState May 31 16:42:10 volumio volumio[724]: info: CoreCommandRouter::servicePushState May 31 16:42:10 volumio volumio[724]: info: CorePlayQueue::getTrack 0 May 31 16:42:10 volumio volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":186,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1351 Kbps","isStreaming":false,"title":"The Way I Am","artist":"Charlie Puth","album":"Voicenotes","uri":"USB/DISK/【Charlie Puth】Voicenotes/01.Charlie Puth - The Way I Am.flac","trackType":"flac"} May 31 16:42:10 volumio volumio[724]: verbose: CURRENT POSITION 0 May 31 16:42:10 volumio volumio[724]: info: CoreStateMachine::syncState stateService play May 31 16:42:10 volumio volumio[724]: info: CoreStateMachine::syncState currentStatus stop May 31 16:42:10 volumio volumio[724]: info: CoreStateMachine::pushState May 31 16:42:10 volumio volumio[724]: info: CorePlayQueue::getTrack 0 May 31 16:42:10 volumio volumio[724]: info: CoreCommandRouter::volumioPushState May 31 16:42:10 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:42:10 volumio volumio[724]: info: ControllerMpd::pushState May 31 16:42:10 volumio volumio[724]: info: CoreCommandRouter::servicePushState May 31 16:42:10 volumio volumio[724]: info: CorePlayQueue::getTrack 0 May 31 16:42:10 volumio volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":186,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1335 Kbps","isStreaming":false,"title":"The Way I Am","artist":"Charlie Puth","album":"Voicenotes","uri":"USB/DISK/【Charlie Puth】Voicenotes/01.Charlie Puth - The Way I Am.flac","trackType":"flac"} May 31 16:42:10 volumio volumio[724]: verbose: CURRENT POSITION 0 May 31 16:42:10 volumio volumio[724]: info: CoreStateMachine::syncState stateService play May 31 16:42:10 volumio volumio[724]: info: CoreStateMachine::syncState currentStatus play May 31 16:42:10 volumio volumio[724]: info: Received an update from plugin. extracting info from payload May 31 16:42:10 volumio volumio[724]: info: CoreStateMachine::pushState May 31 16:42:10 volumio volumio[724]: info: CorePlayQueue::getTrack 0 May 31 16:42:10 volumio volumio[724]: info: CoreCommandRouter::volumioPushState May 31 16:42:10 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:42:10 volumio volumio[724]: info: CoreStateMachine::pushState May 31 16:42:10 volumio volumio[724]: info: CorePlayQueue::getTrack 0 May 31 16:42:10 volumio volumio[724]: info: CoreCommandRouter::volumioPushState May 31 16:42:10 volumio volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:42:10 volumio volumio[724]: info: ------------------------------ 597ms May 31 16:42:10 volumio volumio[724]: info: ------------------------------ 575ms May 31 16:42:13 volumio volumio[724]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Charlie%20Puth/Voicenotes/6bb68c52-f8fc-4a8a-91ee-18d7e29e39e1.jpg' May 31 16:42:13 volumio volumio[724]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Charlie%20Puth/Voicenotes/6bb68c52-f8fc-4a8a-91ee-18d7e29e39e1.jpg' May 31 16:42:37 volumio volumio[724]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 16:42:37 volumio volumio[724]: { Error: connect ETIMEDOUT 128.121.146.228:443 May 31 16:42:37 volumio volumio[724]: at Object._errnoException (util.js:1022:11) May 31 16:42:37 volumio volumio[724]: at _exceptionWithHostPort (util.js:1044:20) May 31 16:42:37 volumio volumio[724]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) May 31 16:42:37 volumio volumio[724]: code: 'ETIMEDOUT', May 31 16:42:37 volumio volumio[724]: errno: 'ETIMEDOUT', May 31 16:42:37 volumio volumio[724]: syscall: 'connect', May 31 16:42:37 volumio volumio[724]: address: '128.121.146.228', May 31 16:42:37 volumio volumio[724]: port: 443 } May 31 16:42:37 volumio volumio[724]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 16:42:42 volumio sudo[2702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-31 16:41 May 31 16:42:42 volumio sudo[2702]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"