-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Wed 2024-04-17 16:39:10 EDT. -- Apr 17 16:38:11 volumio kernel: usb 2-1: new SuperSpeed USB device number 2 using xhci_hcd Apr 17 16:38:11 volumio kernel: usb 2-1: New USB device found, idVendor=31db, idProduct=9210, bcdDevice=f0.01 Apr 17 16:38:11 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Apr 17 16:38:11 volumio kernel: usb 2-1: Product: DockCase SSD Enclosure C1P Apr 17 16:38:11 volumio kernel: usb 2-1: Manufacturer: DockCase Inc. Apr 17 16:38:11 volumio kernel: usb 2-1: SerialNumber: 202308102320 Apr 17 16:38:11 volumio kernel: scsi host0: uas Apr 17 16:38:13 volumio kernel: scsi 0:0:0:0: Direct-Access DockCase DSWC1P 1.00 PQ: 0 ANSI: 6 Apr 17 16:38:13 volumio kernel: sd 0:0:0:0: [sda] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB) Apr 17 16:38:13 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Apr 17 16:38:13 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 37 00 00 08 Apr 17 16:38:13 volumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Apr 17 16:38:13 volumio kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 512 bytes Apr 17 16:38:13 volumio kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes Apr 17 16:38:13 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Apr 17 16:38:13 volumio kernel: sda: sda1 Apr 17 16:38:13 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Apr 17 16:38:13 volumio volumio[881]: info: Mounting Device Music Apr 17 16:38:13 volumio sudo[1944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Music -o noatime Apr 17 16:38:13 volumio sudo[1944]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:38:13 volumio sudo[1944]: pam_unix(sudo:session): session closed for user root Apr 17 16:38:13 volumio kernel: fuse: init (API version 7.37) Apr 17 16:38:13 volumio systemd[1]: Mounting FUSE Control File System... Apr 17 16:38:13 volumio volumio[881]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 17 16:38:13 volumio systemd[1]: Mounted FUSE Control File System. Apr 17 16:38:17 volumio volumio[881]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 17 16:38:17 volumio volumio[881]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 17 16:38:17 volumio volumio[881]: info: Retrieving Cloud Streaming UI Apr 17 16:38:17 volumio volumio[881]: info: Getting Tidal Cloud Configuration Apr 17 16:38:17 volumio volumio[881]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 17 16:38:17 volumio volumio[881]: info: Getting Qobuz Cloud Configuration Apr 17 16:38:17 volumio volumio[881]: info: Asking plugin for UI Config Apr 17 16:38:17 volumio volumio[881]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 17 16:38:18 volumio volumio[881]: info: Getting Spotify Cloud Configuration Apr 17 16:38:18 volumio volumio[881]: info: Asking plugin for UI Config Apr 17 16:38:18 volumio volumio[881]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 17 16:38:18 volumio volumio[881]: info: Saving Spotify Acccount Apr 17 16:38:18 volumio volumio[881]: info: Got it Apr 17 16:38:18 volumio volumio[881]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 17 16:38:18 volumio volumio[881]: info: Got Tidal Cloud Configuration Apr 17 16:38:18 volumio volumio[881]: info: Got it Apr 17 16:38:18 volumio volumio[881]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 17 16:38:18 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 17 16:38:18 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 17 16:38:18 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 17 16:38:18 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 17 16:38:18 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 16:38:18 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 16:38:18 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 16:38:18 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 16:38:18 volumio volumio[881]: info: CoreCommandRouter::volumioGetBrowseSources Apr 17 16:38:18 volumio volumio[881]: info: CoreCommandRouter::volumioGetBrowseSources Apr 17 16:38:18 volumio volumio[881]: info: CoreCommandRouter::volumioGetBrowseSources Apr 17 16:38:18 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 17 16:38:18 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 17 16:38:19 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 17 16:38:19 volumio volumio[881]: verbose: ControllerMpd::sendMpdCommand rescan Apr 17 16:38:19 volumio volumio[881]: verbose: ControllerMpd::sendMpdCommand status Apr 17 16:38:19 volumio volumio[881]: info: Apr 17 16:38:19 volumio volumio[881]: ---------------------------- MPD announces state update: update Apr 17 16:38:19 volumio volumio[881]: info: sendMpdCommand rescan took 13 milliseconds Apr 17 16:38:19 volumio volumio[881]: info: ControllerMpd::getState Apr 17 16:38:19 volumio volumio[881]: verbose: ControllerMpd::sendMpdCommand status Apr 17 16:38:19 volumio volumio[881]: info: sendMpdCommand status took 13 milliseconds Apr 17 16:38:19 volumio volumio[881]: info: sendMpdCommand status took 4 milliseconds Apr 17 16:38:19 volumio volumio[881]: verbose: ControllerMpd::parseState Apr 17 16:38:20 volumio volumio[881]: info: Command Router : Notfying DB Updatetrue Apr 17 16:38:20 volumio volumio[881]: verbose: ControllerMpd::parseState Apr 17 16:38:20 volumio volumio[881]: info: ControllerMpd::pushState Apr 17 16:38:20 volumio volumio[881]: info: CoreCommandRouter::servicePushState Apr 17 16:38:20 volumio volumio[881]: info: CoreStateMachine::pushState Apr 17 16:38:20 volumio volumio[881]: info: CorePlayQueue::getTrack 0 Apr 17 16:38:20 volumio volumio[881]: info: CoreCommandRouter::volumioPushState Apr 17 16:38:20 volumio volumio[881]: info: MRS: Pushing multiroomSync output update for this device Apr 17 16:38:20 volumio volumio[881]: info: MRS: Pushing multiroomSync output Apr 17 16:38:20 volumio volumio[881]: info: CorePlayQueue::getTrack 0 Apr 17 16:38:20 volumio volumio[881]: 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} Apr 17 16:38:20 volumio volumio[881]: verbose: CURRENT POSITION 0 Apr 17 16:38:20 volumio volumio[881]: info: CoreStateMachine::syncState stateService stop Apr 17 16:38:20 volumio volumio[881]: info: CoreStateMachine::syncState currentStatus stop Apr 17 16:38:20 volumio volumio[881]: info: CoreStateMachine::pushState Apr 17 16:38:20 volumio volumio[881]: info: CorePlayQueue::getTrack 0 Apr 17 16:38:20 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 17 16:38:20 volumio volumio[881]: info: CoreCommandRouter::volumioPushState Apr 17 16:38:20 volumio volumio[881]: info: MRS: Pushing multiroomSync output update for this device Apr 17 16:38:20 volumio volumio[881]: info: MRS: Pushing multiroomSync output Apr 17 16:38:20 volumio volumio[881]: info: No code Apr 17 16:38:20 volumio volumio[881]: info: CoreStateMachine::pushState Apr 17 16:38:20 volumio volumio[881]: info: CorePlayQueue::getTrack 0 Apr 17 16:38:20 volumio volumio[881]: info: CoreCommandRouter::volumioPushState Apr 17 16:38:20 volumio volumio[881]: info: MRS: Pushing multiroomSync output update for this device Apr 17 16:38:20 volumio volumio[881]: info: MRS: Pushing multiroomSync output Apr 17 16:38:20 volumio volumio[881]: info: ------------------------------ 252ms Apr 17 16:38:20 volumio volumio[881]: verbose: ControllerMpd::sendMpdCommand status Apr 17 16:38:20 volumio volumio[881]: info: Apr 17 16:38:20 volumio volumio[881]: ---------------------------- MPD announces state update: update Apr 17 16:38:20 volumio volumio[881]: info: ControllerMpd::getState Apr 17 16:38:20 volumio volumio[881]: verbose: ControllerMpd::sendMpdCommand status Apr 17 16:38:20 volumio volumio[881]: verbose: ControllerMpd::sendMpdCommand status Apr 17 16:38:20 volumio volumio[881]: info: Apr 17 16:38:20 volumio volumio[881]: ---------------------------- MPD announces state update: update Apr 17 16:38:20 volumio volumio[881]: info: ControllerMpd::getState Apr 17 16:38:20 volumio volumio[881]: verbose: ControllerMpd::sendMpdCommand status Apr 17 16:38:20 volumio volumio[881]: info: sendMpdCommand status took 5 milliseconds Apr 17 16:38:20 volumio volumio[881]: info: sendMpdCommand status took 4 milliseconds Apr 17 16:38:20 volumio volumio[881]: info: sendMpdCommand status took 3 milliseconds Apr 17 16:38:20 volumio volumio[881]: info: sendMpdCommand status took 3 milliseconds Apr 17 16:38:20 volumio volumio[881]: verbose: ControllerMpd::parseState Apr 17 16:38:20 volumio volumio[881]: info: Command Router : Notfying DB Updatetrue Apr 17 16:38:20 volumio volumio[881]: info: CoreCommandRouter::Close All Modals sent Apr 17 16:38:20 volumio volumio[881]: verbose: ControllerMpd::parseState Apr 17 16:38:20 volumio volumio[881]: verbose: ControllerMpd::parseState Apr 17 16:38:20 volumio volumio[881]: info: Command Router : Notfying DB Updatetrue Apr 17 16:38:20 volumio volumio[881]: info: CoreCommandRouter::Close All Modals sent Apr 17 16:38:20 volumio volumio[881]: verbose: ControllerMpd::parseState Apr 17 16:38:20 volumio volumio[881]: info: ControllerMpd::pushState Apr 17 16:38:20 volumio volumio[881]: info: CoreCommandRouter::servicePushState Apr 17 16:38:20 volumio volumio[881]: info: CoreStateMachine::pushState Apr 17 16:38:20 volumio volumio[881]: info: CorePlayQueue::getTrack 0 Apr 17 16:38:20 volumio volumio[881]: info: CoreCommandRouter::volumioPushState Apr 17 16:38:20 volumio volumio[881]: info: MRS: Pushing multiroomSync output update for this device Apr 17 16:38:20 volumio volumio[881]: info: MRS: Pushing multiroomSync output Apr 17 16:38:20 volumio volumio[881]: info: CorePlayQueue::getTrack 0 Apr 17 16:38:20 volumio volumio[881]: 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} Apr 17 16:38:20 volumio volumio[881]: verbose: CURRENT POSITION 0 Apr 17 16:38:20 volumio volumio[881]: info: CoreStateMachine::syncState stateService stop Apr 17 16:38:20 volumio volumio[881]: info: CoreStateMachine::syncState currentStatus stop Apr 17 16:38:20 volumio volumio[881]: info: CoreStateMachine::pushState Apr 17 16:38:20 volumio volumio[881]: info: CorePlayQueue::getTrack 0 Apr 17 16:38:20 volumio volumio[881]: info: CoreCommandRouter::volumioPushState Apr 17 16:38:20 volumio volumio[881]: info: MRS: Pushing multiroomSync output update for this device Apr 17 16:38:20 volumio volumio[881]: info: MRS: Pushing multiroomSync output Apr 17 16:38:20 volumio volumio[881]: info: No code Apr 17 16:38:20 volumio volumio[881]: info: CoreStateMachine::pushState Apr 17 16:38:20 volumio volumio[881]: info: CorePlayQueue::getTrack 0 Apr 17 16:38:20 volumio volumio[881]: info: CoreCommandRouter::volumioPushState Apr 17 16:38:20 volumio volumio[881]: info: MRS: Pushing multiroomSync output update for this device Apr 17 16:38:20 volumio volumio[881]: info: MRS: Pushing multiroomSync output Apr 17 16:38:20 volumio volumio[881]: info: ControllerMpd::pushState Apr 17 16:38:20 volumio volumio[881]: info: CoreCommandRouter::servicePushState Apr 17 16:38:20 volumio volumio[881]: info: CoreStateMachine::pushState Apr 17 16:38:20 volumio volumio[881]: info: CorePlayQueue::getTrack 0 Apr 17 16:38:20 volumio volumio[881]: info: CoreCommandRouter::volumioPushState Apr 17 16:38:20 volumio volumio[881]: info: MRS: Pushing multiroomSync output update for this device Apr 17 16:38:20 volumio volumio[881]: info: MRS: Pushing multiroomSync output Apr 17 16:38:20 volumio volumio[881]: info: CorePlayQueue::getTrack 0 Apr 17 16:38:20 volumio volumio[881]: 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} Apr 17 16:38:20 volumio volumio[881]: verbose: CURRENT POSITION 0 Apr 17 16:38:20 volumio volumio[881]: info: CoreStateMachine::syncState stateService stop Apr 17 16:38:20 volumio volumio[881]: info: CoreStateMachine::syncState currentStatus stop Apr 17 16:38:20 volumio volumio[881]: info: CoreStateMachine::pushState Apr 17 16:38:20 volumio volumio[881]: info: CorePlayQueue::getTrack 0 Apr 17 16:38:20 volumio volumio[881]: info: CoreCommandRouter::volumioPushState Apr 17 16:38:20 volumio volumio[881]: info: MRS: Pushing multiroomSync output update for this device Apr 17 16:38:20 volumio volumio[881]: info: MRS: Pushing multiroomSync output Apr 17 16:38:20 volumio volumio[881]: info: No code Apr 17 16:38:20 volumio volumio[881]: info: CoreStateMachine::pushState Apr 17 16:38:20 volumio volumio[881]: info: CorePlayQueue::getTrack 0 Apr 17 16:38:20 volumio volumio[881]: info: CoreCommandRouter::volumioPushState Apr 17 16:38:20 volumio volumio[881]: info: MRS: Pushing multiroomSync output update for this device Apr 17 16:38:20 volumio volumio[881]: info: MRS: Pushing multiroomSync output Apr 17 16:38:20 volumio volumio[881]: info: ------------------------------ 137ms Apr 17 16:38:20 volumio volumio[881]: info: ------------------------------ 135ms Apr 17 16:38:22 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 17 16:38:22 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 17 16:38:22 volumio volumio[881]: verbose: ControllerMpd::sendMpdCommand rescan Apr 17 16:38:22 volumio volumio[881]: info: sendMpdCommand rescan took 7 milliseconds Apr 17 16:38:23 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Apr 17 16:38:23 volumio volumio[881]: verbose: ControllerMpd::sendMpdCommand update Apr 17 16:38:23 volumio volumio[881]: info: sendMpdCommand update took 5 milliseconds Apr 17 16:38:43 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 17 16:38:43 volumio volumio[881]: info: CURURI: music-library Apr 17 16:38:44 volumio volumio[881]: info: Preload queue cleared Apr 17 16:38:46 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 17 16:38:46 volumio volumio[881]: info: CURURI: music-library/USB Apr 17 16:39:01 volumio kernel: sd 0:0:0:0: [sda] tag#23 uas_eh_abort_handler 0 uas-tag 8 inflight: CMD IN Apr 17 16:39:01 volumio kernel: sd 0:0:0:0: [sda] tag#23 CDB: opcode=0x28 28 00 0a 4f 42 a8 00 04 00 00 Apr 17 16:39:01 volumio kernel: sd 0:0:0:0: [sda] tag#22 uas_eh_abort_handler 0 uas-tag 7 inflight: CMD IN Apr 17 16:39:01 volumio kernel: sd 0:0:0:0: [sda] tag#22 CDB: opcode=0x28 28 00 0a 4f 3e a8 00 04 00 00 Apr 17 16:39:01 volumio kernel: sd 0:0:0:0: [sda] tag#21 uas_eh_abort_handler 0 uas-tag 6 inflight: CMD IN Apr 17 16:39:01 volumio kernel: sd 0:0:0:0: [sda] tag#21 CDB: opcode=0x28 28 00 0a 4f 3a a8 00 04 00 00 Apr 17 16:39:01 volumio kernel: sd 0:0:0:0: [sda] tag#20 uas_eh_abort_handler 0 uas-tag 5 inflight: CMD IN Apr 17 16:39:01 volumio kernel: sd 0:0:0:0: [sda] tag#20 CDB: opcode=0x28 28 00 0a 4f 36 a8 00 04 00 00 Apr 17 16:39:01 volumio kernel: sd 0:0:0:0: [sda] tag#18 uas_eh_abort_handler 0 uas-tag 4 inflight: CMD IN Apr 17 16:39:01 volumio kernel: sd 0:0:0:0: [sda] tag#18 CDB: opcode=0x28 28 00 0a 4f 32 a8 00 04 00 00 Apr 17 16:39:01 volumio kernel: sd 0:0:0:0: [sda] tag#17 uas_eh_abort_handler 0 uas-tag 3 inflight: CMD IN Apr 17 16:39:01 volumio kernel: sd 0:0:0:0: [sda] tag#17 CDB: opcode=0x28 28 00 0a 4f 2e a8 00 04 00 00 Apr 17 16:39:01 volumio kernel: scsi host0: uas_eh_device_reset_handler start Apr 17 16:39:02 volumio kernel: usb 2-1: reset SuperSpeed USB device number 2 using xhci_hcd Apr 17 16:39:02 volumio kernel: scsi host0: uas_eh_device_reset_handler success Apr 17 16:39:05 volumio volumio[881]: info: Preload queue cleared Apr 17 16:39:05 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 17 16:39:05 volumio volumio[881]: info: CURURI: music-library/USB/Music Apr 17 16:39:05 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 17 16:39:05 volumio volumio[881]: info: CURURI: music-library/USB/Music Apr 17 16:39:05 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 17 16:39:05 volumio volumio[881]: info: CURURI: music-library/USB/Music Apr 17 16:39:05 volumio volumio[881]: info: Preload queue cleared Apr 17 16:39:05 volumio volumio[881]: info: Preload queue cleared Apr 17 16:39:05 volumio volumio[881]: info: Preload queue cleared Apr 17 16:39:05 volumio volumio[881]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Apr 17 16:39:05 volumio volumio[881]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Apr 17 16:39:05 volumio volumio[881]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Apr 17 16:39:05 volumio volumio[881]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Apr 17 16:39:05 volumio volumio[881]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Apr 17 16:39:05 volumio volumio[881]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:440:9) Apr 17 16:39:05 volumio volumio[881]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 17 16:39:05 volumio volumio[881]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 17 16:39:05 volumio volumio[881]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Apr 17 16:39:05 volumio volumio[881]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Apr 17 16:39:05 volumio volumio[881]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Apr 17 16:39:05 volumio volumio[881]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Apr 17 16:39:05 volumio volumio[881]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Apr 17 16:39:05 volumio volumio[881]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:440:9) Apr 17 16:39:05 volumio volumio[881]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 17 16:39:05 volumio volumio[881]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 17 16:39:06 volumio volumio[881]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Apr 17 16:39:06 volumio volumio[881]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Apr 17 16:39:06 volumio volumio[881]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Apr 17 16:39:06 volumio volumio[881]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Apr 17 16:39:06 volumio volumio[881]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Apr 17 16:39:06 volumio volumio[881]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:440:9) Apr 17 16:39:06 volumio volumio[881]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 17 16:39:06 volumio volumio[881]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 17 16:39:09 volumio volumio[881]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Apr 17 16:39:09 volumio volumio[881]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Apr 17 16:39:09 volumio volumio[881]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Apr 17 16:39:09 volumio volumio[881]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Apr 17 16:39:09 volumio volumio[881]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Apr 17 16:39:09 volumio volumio[881]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:440:9) Apr 17 16:39:09 volumio volumio[881]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 17 16:39:09 volumio volumio[881]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 17 16:39:09 volumio volumio[881]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Apr 17 16:39:09 volumio volumio[881]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Apr 17 16:39:09 volumio volumio[881]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Apr 17 16:39:09 volumio volumio[881]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Apr 17 16:39:09 volumio volumio[881]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Apr 17 16:39:09 volumio volumio[881]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:440:9) Apr 17 16:39:09 volumio volumio[881]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 17 16:39:09 volumio volumio[881]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 17 16:39:09 volumio volumio[881]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Apr 17 16:39:09 volumio volumio[881]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Apr 17 16:39:09 volumio volumio[881]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Apr 17 16:39:09 volumio volumio[881]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Apr 17 16:39:09 volumio volumio[881]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Apr 17 16:39:09 volumio volumio[881]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:440:9) Apr 17 16:39:09 volumio volumio[881]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 17 16:39:09 volumio volumio[881]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 17 16:39:09 volumio volumio[881]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Apr 17 16:39:09 volumio volumio[881]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Apr 17 16:39:09 volumio volumio[881]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Apr 17 16:39:09 volumio volumio[881]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Apr 17 16:39:09 volumio volumio[881]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Apr 17 16:39:09 volumio volumio[881]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:440:9) Apr 17 16:39:09 volumio volumio[881]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 17 16:39:09 volumio volumio[881]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 17 16:39:09 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 17 16:39:09 volumio volumio[881]: info: CURURI: music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264} Apr 17 16:39:09 volumio volumio[881]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Apr 17 16:39:09 volumio volumio[881]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Apr 17 16:39:09 volumio volumio[881]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Apr 17 16:39:09 volumio volumio[881]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Apr 17 16:39:09 volumio volumio[881]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Apr 17 16:39:09 volumio volumio[881]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:440:9) Apr 17 16:39:09 volumio volumio[881]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 17 16:39:09 volumio volumio[881]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 17 16:39:09 volumio volumio[881]: info: Preload queue cleared Apr 17 16:39:09 volumio volumio[881]: info: Preloading song: music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/01 The Guests.flac Apr 17 16:39:09 volumio volumio[881]: info: Preloading song: music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/02 Humbled in Love.flac Apr 17 16:39:09 volumio volumio[881]: info: Preloading song: music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/03 The Window.flac Apr 17 16:39:09 volumio volumio[881]: info: Preloading song: music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/04 Came So Far for Beauty.flac Apr 17 16:39:09 volumio volumio[881]: info: Preloading song: music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/05 The Lost Canadian (Un Canadien Errant).flac Apr 17 16:39:09 volumio volumio[881]: info: Preloading song: music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/06 The Traitor.flac Apr 17 16:39:09 volumio volumio[881]: info: Preloading song: music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/07 Our Lady of Solitude.flac Apr 17 16:39:09 volumio volumio[881]: info: Preloading song: music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/08 The Gypsy's Wife.flac Apr 17 16:39:09 volumio volumio[881]: info: Preloading song: music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/09 The Smokey Life.flac Apr 17 16:39:09 volumio volumio[881]: info: Preloading song: music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/10 Ballad of the Absent Mare.flac Apr 17 16:39:09 volumio volumio[881]: info: Preloading song: music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/Cohen recent songs.CUE Apr 17 16:39:09 volumio volumio[881]: info: Preloading song: music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/Cohen recent songs.m3u Apr 17 16:39:09 volumio volumio[881]: info: Exploding uri music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/01 The Guests.flac in service mpd Apr 17 16:39:09 volumio volumio[881]: info: ALBUMART /albumart?cacheid=530&web=Leonard%20Cohen/Recent%20Songs/extralarge&path=%2Fmnt%2FUSB%2FMusic%2F06.%20Leonard%20Cohen%20-%20Recent%20Songs%20-%201979%20%7BColumbia%20CK%2036264%7D%2F01%20The%20Guests.flac&metadata=false Apr 17 16:39:09 volumio volumio[881]: info: URI /mnt/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/01 The Guests.flac Apr 17 16:39:10 volumio volumio[881]: info: Exploding uri music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/02 Humbled in Love.flac in service mpd Apr 17 16:39:10 volumio volumio[881]: info: ALBUMART /albumart?cacheid=530&web=Leonard%20Cohen/Recent%20Songs/extralarge&path=%2Fmnt%2FUSB%2FMusic%2F06.%20Leonard%20Cohen%20-%20Recent%20Songs%20-%201979%20%7BColumbia%20CK%2036264%7D%2F02%20Humbled%20in%20Love.flac&metadata=false Apr 17 16:39:10 volumio volumio[881]: info: URI /mnt/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/02 Humbled in Love.flac Apr 17 16:39:10 volumio volumio[881]: info: Exploding uri music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/03 The Window.flac in service mpd Apr 17 16:39:10 volumio volumio[881]: info: Exploding uri music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/04 Came So Far for Beauty.flac in service mpd Apr 17 16:39:10 volumio volumio[881]: info: ALBUMART /albumart?cacheid=530&web=Leonard%20Cohen/Recent%20Songs/extralarge&path=%2Fmnt%2FUSB%2FMusic%2F06.%20Leonard%20Cohen%20-%20Recent%20Songs%20-%201979%20%7BColumbia%20CK%2036264%7D%2F03%20The%20Window.flac&metadata=false Apr 17 16:39:10 volumio volumio[881]: info: URI /mnt/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/03 The Window.flac Apr 17 16:39:10 volumio volumio[881]: info: ALBUMART /albumart?cacheid=530&web=Leonard%20Cohen/Recent%20Songs/extralarge&path=%2Fmnt%2FUSB%2FMusic%2F06.%20Leonard%20Cohen%20-%20Recent%20Songs%20-%201979%20%7BColumbia%20CK%2036264%7D%2F04%20Came%20So%20Far%20for%20Beauty.flac&metadata=false Apr 17 16:39:10 volumio volumio[881]: info: URI /mnt/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/04 Came So Far for Beauty.flac Apr 17 16:39:10 volumio volumio[881]: info: Exploding uri music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/05 The Lost Canadian (Un Canadien Errant).flac in service mpd Apr 17 16:39:10 volumio volumio[881]: info: ALBUMART /albumart?cacheid=530&web=Leonard%20Cohen/Recent%20Songs/extralarge&path=%2Fmnt%2FUSB%2FMusic%2F06.%20Leonard%20Cohen%20-%20Recent%20Songs%20-%201979%20%7BColumbia%20CK%2036264%7D%2F05%20The%20Lost%20Canadian%20(Un%20Canadien%20Errant).flac&metadata=false Apr 17 16:39:10 volumio volumio[881]: info: URI /mnt/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/05 The Lost Canadian (Un Canadien Errant).flac Apr 17 16:39:10 volumio volumio[881]: info: Exploding uri music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/06 The Traitor.flac in service mpd Apr 17 16:39:10 volumio volumio[881]: info: ALBUMART /albumart?cacheid=530&web=Leonard%20Cohen/Recent%20Songs/extralarge&path=%2Fmnt%2FUSB%2FMusic%2F06.%20Leonard%20Cohen%20-%20Recent%20Songs%20-%201979%20%7BColumbia%20CK%2036264%7D%2F06%20The%20Traitor.flac&metadata=false Apr 17 16:39:10 volumio volumio[881]: info: URI /mnt/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/06 The Traitor.flac Apr 17 16:39:10 volumio volumio[881]: info: Exploding uri music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/07 Our Lady of Solitude.flac in service mpd Apr 17 16:39:10 volumio volumio[881]: info: ALBUMART /albumart?cacheid=530&web=Leonard%20Cohen/Recent%20Songs/extralarge&path=%2Fmnt%2FUSB%2FMusic%2F06.%20Leonard%20Cohen%20-%20Recent%20Songs%20-%201979%20%7BColumbia%20CK%2036264%7D%2F07%20Our%20Lady%20of%20Solitude.flac&metadata=false Apr 17 16:39:10 volumio volumio[881]: info: URI /mnt/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/07 Our Lady of Solitude.flac Apr 17 16:39:10 volumio volumio[881]: info: Exploding uri music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/08 The Gypsy's Wife.flac in service mpd Apr 17 16:39:10 volumio volumio[881]: info: ALBUMART /albumart?cacheid=530&web=Leonard%20Cohen/Recent%20Songs/extralarge&path=%2Fmnt%2FUSB%2FMusic%2F06.%20Leonard%20Cohen%20-%20Recent%20Songs%20-%201979%20%7BColumbia%20CK%2036264%7D%2F08%20The%20Gypsy's%20Wife.flac&metadata=false Apr 17 16:39:10 volumio volumio[881]: info: URI /mnt/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/08 The Gypsy's Wife.flac Apr 17 16:39:10 volumio volumio[881]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Apr 17 16:39:10 volumio volumio[881]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Apr 17 16:39:10 volumio volumio[881]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Apr 17 16:39:10 volumio volumio[881]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Apr 17 16:39:10 volumio volumio[881]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Apr 17 16:39:10 volumio volumio[881]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:440:9) Apr 17 16:39:10 volumio volumio[881]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 17 16:39:10 volumio volumio[881]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 17 16:39:10 volumio volumio[881]: info: Exploding uri music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/09 The Smokey Life.flac in service mpd Apr 17 16:39:10 volumio volumio[881]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Apr 17 16:39:10 volumio volumio[881]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Apr 17 16:39:10 volumio volumio[881]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Apr 17 16:39:10 volumio volumio[881]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Apr 17 16:39:10 volumio volumio[881]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Apr 17 16:39:10 volumio volumio[881]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:440:9) Apr 17 16:39:10 volumio volumio[881]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 17 16:39:10 volumio volumio[881]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 17 16:39:10 volumio volumio[881]: info: ALBUMART /albumart?cacheid=530&web=Leonard%20Cohen/Recent%20Songs/extralarge&path=%2Fmnt%2FUSB%2FMusic%2F06.%20Leonard%20Cohen%20-%20Recent%20Songs%20-%201979%20%7BColumbia%20CK%2036264%7D%2F09%20The%20Smokey%20Life.flac&metadata=false Apr 17 16:39:10 volumio volumio[881]: info: URI /mnt/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/09 The Smokey Life.flac Apr 17 16:39:10 volumio volumio[881]: info: Exploding uri music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/10 Ballad of the Absent Mare.flac in service mpd Apr 17 16:39:10 volumio volumio[881]: info: ALBUMART /albumart?cacheid=530&web=Leonard%20Cohen/Recent%20Songs/extralarge&path=%2Fmnt%2FUSB%2FMusic%2F06.%20Leonard%20Cohen%20-%20Recent%20Songs%20-%201979%20%7BColumbia%20CK%2036264%7D%2F10%20Ballad%20of%20the%20Absent%20Mare.flac&metadata=false Apr 17 16:39:10 volumio volumio[881]: info: URI /mnt/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/10 Ballad of the Absent Mare.flac Apr 17 16:39:10 volumio volumio[881]: info: Exploding uri music-library/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/Cohen recent songs.CUE in service mpd Apr 17 16:39:10 volumio volumio[881]: info: ALBUMART /albumart?cacheid=530&web=Leonard%20Cohen/Recent%20Songs/extralarge&path=%2Fmnt%2FUSB%2FMusic%2F06.%20Leonard%20Cohen%20-%20Recent%20Songs%20-%201979%20%7BColumbia%20CK%2036264%7D%2FCohen%20recent%20songs.CUE&metadata=false Apr 17 16:39:10 volumio volumio[881]: info: URI /mnt/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/Cohen recent songs.CUE Apr 17 16:39:10 volumio volumio[881]: info: ALBUMART /albumart?cacheid=530&web=Leonard%20Cohen/Recent%20Songs/extralarge&path=%2Fmnt%2FUSB%2FMusic%2F06.%20Leonard%20Cohen%20-%20Recent%20Songs%20-%201979%20%7BColumbia%20CK%2036264%7D%2FCohen%20recent%20songs.CUE&metadata=false Apr 17 16:39:10 volumio volumio[881]: info: URI /mnt/USB/Music/06. Leonard Cohen - Recent Songs - 1979 {Columbia CK 36264}/Cohen recent songs.CUE Apr 17 16:39:10 volumio volumio[881]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 17 16:39:10 volumio volumio[881]: Error: Unable to resolve or reject the same promise twice Apr 17 16:39:10 volumio volumio[881]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 17 16:39:10 volumio volumio[881]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Apr 17 16:39:10 volumio volumio[881]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 17 16:39:10 volumio volumio[881]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 17 16:39:10 volumio volumio[881]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 17 16:39:10 volumio volumio[881]: at Socket.emit (events.js:315:20) Apr 17 16:39:10 volumio volumio[881]: at addChunk (internal/streams/readable.js:309:12) Apr 17 16:39:10 volumio volumio[881]: at readableAddChunk (internal/streams/readable.js:280:11) Apr 17 16:39:10 volumio volumio[881]: at Socket.Readable.push (internal/streams/readable.js:223:10) Apr 17 16:39:10 volumio volumio[881]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 17 16:39:10 volumio volumio[881]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 17 16:39:10 volumio sudo[2014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-17 16:38 Apr 17 16:39:10 volumio sudo[2014]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"