-- 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"