-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Wed 2025-06-11 10:01:55 BST. -- Jun 11 10:00:03 volumio-1 kernel: hwmon hwmon1: Undervoltage detected! Jun 11 10:00:07 volumio-1 kernel: hwmon hwmon1: Voltage normalised Jun 11 10:00:14 volumio-1 kernel: hwmon hwmon1: Undervoltage detected! Jun 11 10:00:18 volumio-1 kernel: hwmon hwmon1: Voltage normalised Jun 11 10:00:19 volumio-1 kernel: usb 1-1.1.3: USB disconnect, device number 11 Jun 11 10:00:19 volumio-1 kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Jun 11 10:00:19 volumio-1 kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x01 driverbyte=DRIVER_OK Jun 11 10:00:20 volumio-1 kernel: hwmon hwmon1: Undervoltage detected! Jun 11 10:00:30 volumio-1 kernel: hwmon hwmon1: Voltage normalised Jun 11 10:00:32 volumio-1 kernel: hwmon hwmon1: Undervoltage detected! Jun 11 10:00:39 volumio-1 kernel: hwmon hwmon1: Voltage normalised Jun 11 10:00:41 volumio-1 kernel: hwmon hwmon1: Undervoltage detected! Jun 11 10:00:43 volumio-1 kernel: usb 1-1.2: new high-speed USB device number 12 using dwc_otg Jun 11 10:00:43 volumio-1 kernel: usb 1-1.2: New USB device found, idVendor=0080, idProduct=a001, bcdDevice= 2.04 Jun 11 10:00:43 volumio-1 kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jun 11 10:00:43 volumio-1 kernel: usb 1-1.2: Product: External USB 3.0 Jun 11 10:00:43 volumio-1 kernel: usb 1-1.2: Manufacturer: TOSHIBA Jun 11 10:00:43 volumio-1 kernel: usb 1-1.2: SerialNumber: 201503310007F Jun 11 10:00:43 volumio-1 kernel: usb 1-1.2: The driver for the USB controller dwc_otg_hcd does not support scatter-gather which is Jun 11 10:00:43 volumio-1 kernel: usb 1-1.2: required by the UAS driver. Please try an other USB controller if you wish to use UAS. Jun 11 10:00:43 volumio-1 kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Jun 11 10:00:43 volumio-1 kernel: scsi host0: usb-storage 1-1.2:1.0 Jun 11 10:00:44 volumio-1 kernel: scsi 0:0:0:0: Direct-Access TO Exter nal USB 3.0 0204 PQ: 0 ANSI: 6 Jun 11 10:00:45 volumio-1 kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Jun 11 10:00:45 volumio-1 kernel: sd 0:0:0:0: [sda] 1953525168 512-byte logical blocks: (1.00 TB/932 GiB) Jun 11 10:00:45 volumio-1 kernel: sd 0:0:0:0: [sda] Write Protect is off Jun 11 10:00:45 volumio-1 kernel: sd 0:0:0:0: [sda] Mode Sense: 47 00 00 08 Jun 11 10:00:45 volumio-1 kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Jun 11 10:00:45 volumio-1 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Jun 11 10:01:08 volumio-1 kernel: hwmon hwmon1: Voltage normalised Jun 11 10:01:09 volumio-1 volumio[958]: verbose: New Socket.io Connection to 192.168.100.191 from 192.168.100.122 UA: Mozilla/5.0 (iPad; CPU OS 18_5_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/137.0.7151.51 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 4 Jun 11 10:01:09 volumio-1 volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 11 10:01:09 volumio-1 volumio[958]: info: CoreCommandRouter::volumioGetVisibleSources Jun 11 10:01:09 volumio-1 volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 10:01:09 volumio-1 volumio[958]: info: CoreCommandRouter::volumioGetState Jun 11 10:01:09 volumio-1 volumio[958]: info: CorePlayQueue::getTrack 0 Jun 11 10:01:09 volumio-1 volumio[958]: info: CoreCommandRouter::volumioGetState Jun 11 10:01:09 volumio-1 volumio[958]: info: CorePlayQueue::getTrack 0 Jun 11 10:01:09 volumio-1 volumio[958]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 11 10:01:09 volumio-1 volumio[958]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 11 10:01:09 volumio-1 volumio[958]: info: Received Get System Info Jun 11 10:01:09 volumio-1 volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 11 10:01:09 volumio-1 volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 11 10:01:09 volumio-1 volumio[958]: info: Discovery: Getting this device information Jun 11 10:01:09 volumio-1 volumio[958]: info: CoreCommandRouter::volumioGetState Jun 11 10:01:09 volumio-1 volumio[958]: info: CorePlayQueue::getTrack 0 Jun 11 10:01:09 volumio-1 volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 11 10:01:09 volumio-1 volumio[958]: info: CoreCommandRouter::volumioGetState Jun 11 10:01:09 volumio-1 volumio[958]: info: CorePlayQueue::getTrack 0 Jun 11 10:01:09 volumio-1 volumio[958]: info: Listing playlists Jun 11 10:01:09 volumio-1 volumio[958]: info: CoreCommandRouter::volumioGetQueue Jun 11 10:01:09 volumio-1 volumio[958]: info: CoreStateMachine::getQueue Jun 11 10:01:09 volumio-1 volumio[958]: info: CorePlayQueue::getQueue Jun 11 10:01:09 volumio-1 volumio[958]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 11 10:01:10 volumio-1 kernel: hwmon hwmon1: Undervoltage detected! Jun 11 10:01:15 volumio-1 volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 11 10:01:15 volumio-1 volumio[958]: info: CURURI: music-library/USB Jun 11 10:01:15 volumio-1 volumio[958]: error: Failed LSINFO: null Jun 11 10:01:15 volumio-1 volumio[958]: info: Preload queue cleared Jun 11 10:01:20 volumio-1 kernel: hwmon hwmon1: Voltage normalised Jun 11 10:01:22 volumio-1 ntpd[782]: 91.109.118.94 local addr 192.168.100.191 -> Jun 11 10:01:22 volumio-1 kernel: hwmon hwmon1: Undervoltage detected! Jun 11 10:01:23 volumio-1 volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jun 11 10:01:23 volumio-1 volumio[958]: verbose: ControllerMpd::sendMpdCommand update Jun 11 10:01:23 volumio-1 volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 11 10:01:23 volumio-1 volumio[958]: info: Jun 11 10:01:23 volumio-1 volumio[958]: ---------------------------- MPD announces state update: update Jun 11 10:01:23 volumio-1 volumio[958]: info: sendMpdCommand update took 6 milliseconds Jun 11 10:01:23 volumio-1 volumio[958]: info: ControllerMpd::getState Jun 11 10:01:23 volumio-1 volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 11 10:01:23 volumio-1 volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 11 10:01:23 volumio-1 volumio[958]: info: Jun 11 10:01:23 volumio-1 volumio[958]: ---------------------------- MPD announces state update: update Jun 11 10:01:23 volumio-1 volumio[958]: info: ControllerMpd::getState Jun 11 10:01:23 volumio-1 volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 11 10:01:23 volumio-1 volumio[958]: info: sendMpdCommand status took 14 milliseconds Jun 11 10:01:23 volumio-1 volumio[958]: info: sendMpdCommand status took 6 milliseconds Jun 11 10:01:23 volumio-1 volumio[958]: verbose: ControllerMpd::parseState Jun 11 10:01:23 volumio-1 volumio[958]: info: Command Router : Notfying DB Updatefalse Jun 11 10:01:23 volumio-1 volumio[958]: info: CoreCommandRouter::Close All Modals sent Jun 11 10:01:23 volumio-1 volumio[958]: verbose: ControllerMpd::parseState Jun 11 10:01:23 volumio-1 volumio[958]: info: ControllerMpd::pushState Jun 11 10:01:23 volumio-1 volumio[958]: info: CoreCommandRouter::servicePushState Jun 11 10:01:23 volumio-1 volumio[958]: info: CoreStateMachine::pushState Jun 11 10:01:23 volumio-1 volumio[958]: info: CorePlayQueue::getTrack 0 Jun 11 10:01:23 volumio-1 volumio[958]: info: CoreCommandRouter::volumioPushState Jun 11 10:01:23 volumio-1 volumio[958]: info: CorePlayQueue::getTrack 0 Jun 11 10:01:23 volumio-1 volumio[958]: 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} Jun 11 10:01:23 volumio-1 volumio[958]: verbose: CURRENT POSITION 0 Jun 11 10:01:23 volumio-1 volumio[958]: info: CoreStateMachine::syncState stateService stop Jun 11 10:01:23 volumio-1 volumio[958]: info: CoreStateMachine::syncState currentStatus stop Jun 11 10:01:23 volumio-1 volumio[958]: info: CoreStateMachine::pushState Jun 11 10:01:23 volumio-1 volumio[958]: info: CorePlayQueue::getTrack 0 Jun 11 10:01:23 volumio-1 volumio[958]: info: CoreCommandRouter::volumioPushState Jun 11 10:01:23 volumio-1 volumio[958]: info: No code Jun 11 10:01:23 volumio-1 volumio[958]: info: CoreStateMachine::pushState Jun 11 10:01:23 volumio-1 volumio[958]: info: CorePlayQueue::getTrack 0 Jun 11 10:01:23 volumio-1 volumio[958]: info: CoreCommandRouter::volumioPushState Jun 11 10:01:23 volumio-1 volumio[958]: info: ------------------------------ 140ms Jun 11 10:01:23 volumio-1 volumio[958]: info: sendMpdCommand status took 134 milliseconds Jun 11 10:01:23 volumio-1 volumio[958]: info: sendMpdCommand status took 131 milliseconds Jun 11 10:01:23 volumio-1 volumio[958]: verbose: ControllerMpd::parseState Jun 11 10:01:23 volumio-1 volumio[958]: info: Command Router : Notfying DB Updatefalse Jun 11 10:01:23 volumio-1 volumio[958]: info: CoreCommandRouter::Close All Modals sent Jun 11 10:01:23 volumio-1 volumio[958]: verbose: ControllerMpd::parseState Jun 11 10:01:23 volumio-1 volumio[958]: info: ControllerMpd::pushState Jun 11 10:01:23 volumio-1 volumio[958]: info: CoreCommandRouter::servicePushState Jun 11 10:01:23 volumio-1 volumio[958]: info: CoreStateMachine::pushState Jun 11 10:01:23 volumio-1 volumio[958]: info: CorePlayQueue::getTrack 0 Jun 11 10:01:23 volumio-1 volumio[958]: info: CoreCommandRouter::volumioPushState Jun 11 10:01:23 volumio-1 volumio[958]: info: CorePlayQueue::getTrack 0 Jun 11 10:01:23 volumio-1 volumio[958]: 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} Jun 11 10:01:23 volumio-1 volumio[958]: verbose: CURRENT POSITION 0 Jun 11 10:01:23 volumio-1 volumio[958]: info: CoreStateMachine::syncState stateService stop Jun 11 10:01:23 volumio-1 volumio[958]: info: CoreStateMachine::syncState currentStatus stop Jun 11 10:01:23 volumio-1 volumio[958]: info: CoreStateMachine::pushState Jun 11 10:01:23 volumio-1 volumio[958]: info: CorePlayQueue::getTrack 0 Jun 11 10:01:23 volumio-1 volumio[958]: info: CoreCommandRouter::volumioPushState Jun 11 10:01:23 volumio-1 volumio[958]: info: No code Jun 11 10:01:23 volumio-1 volumio[958]: info: CoreStateMachine::pushState Jun 11 10:01:23 volumio-1 volumio[958]: info: CorePlayQueue::getTrack 0 Jun 11 10:01:23 volumio-1 volumio[958]: info: CoreCommandRouter::volumioPushState Jun 11 10:01:23 volumio-1 volumio[958]: info: ------------------------------ 255ms Jun 11 10:01:31 volumio-1 volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 11 10:01:31 volumio-1 volumio[958]: info: CURURI: music-library Jun 11 10:01:31 volumio-1 volumio[958]: info: Preload queue cleared Jun 11 10:01:34 volumio-1 volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 11 10:01:34 volumio-1 volumio[958]: info: CURURI: artists:// Jun 11 10:01:34 volumio-1 volumio[958]: info: Preload queue cleared Jun 11 10:01:39 volumio-1 kernel: hwmon hwmon1: Voltage normalised Jun 11 10:01:40 volumio-1 volumio[958]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 11 10:01:40 volumio-1 volumio[958]: info: Preload queue cleared Jun 11 10:01:43 volumio-1 volumio[958]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 11 10:01:43 volumio-1 volumio[958]: info: Preload queue cleared Jun 11 10:01:43 volumio-1 kernel: hwmon hwmon1: Undervoltage detected! Jun 11 10:01:47 volumio-1 volumio[958]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 11 10:01:47 volumio-1 volumio[958]: info: Preload queue cleared Jun 11 10:01:53 volumio-1 ntpd[782]: 77.74.199.184 local addr 192.168.100.191 -> Jun 11 10:01:54 volumio-1 volumio[958]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 11 10:01:54 volumio-1 volumio[958]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 11 10:01:54 volumio-1 volumio[958]: TypeError: Cannot read property 'length' of undefined Jun 11 10:01:54 volumio-1 volumio[958]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 11 10:01:54 volumio-1 volumio[958]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 11 10:01:54 volumio-1 volumio[958]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 11 10:01:54 volumio-1 volumio[958]: at Parser.emit (events.js:315:20) Jun 11 10:01:54 volumio-1 volumio[958]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 11 10:01:54 volumio-1 volumio[958]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 11 10:01:54 volumio-1 volumio[958]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 11 10:01:54 volumio-1 volumio[958]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 11 10:01:54 volumio-1 volumio[958]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 11 10:01:54 volumio-1 volumio[958]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 11 10:01:54 volumio-1 volumio[958]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 11 10:01:54 volumio-1 volumio[958]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 11 10:01:54 volumio-1 volumio[958]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 11 10:01:54 volumio-1 volumio[958]: at IncomingMessage.emit (events.js:327:22) Jun 11 10:01:54 volumio-1 volumio[958]: at endReadableNT (internal/streams/readable.js:1327:12) Jun 11 10:01:54 volumio-1 volumio[958]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 11 10:01:54 volumio-1 volumio[958]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 11 10:01:55 volumio-1 ntpd[782]: 185.103.117.60 local addr 192.168.100.191 -> Jun 11 10:01:55 volumio-1 sudo[5689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-11 10:00 Jun 11 10:01:55 volumio-1 sudo[5689]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"