-- Logs begin at Sat 2025-09-13 16:45:34 UTC, end at Sat 2025-09-13 22:55:31 UTC. -- Sep 13 22:54:00 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 13 22:54:04 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 13 22:54:06 musical-fidelity volumio[1007]: info: CALLMETHOD: music_service mpd saveMusicLibraryOptions [object Object] Sep 13 22:54:06 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , saveMusicLibraryOptions Sep 13 22:54:08 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 13 22:54:12 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 13 22:54:16 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 13 22:54:17 musical-fidelity volumio[1007]: info: CoreCommandRouter::volumioVolatilePlay Sep 13 22:54:17 musical-fidelity volumio[1007]: info: CoreStateMachine::volatilePlay Sep 13 22:54:17 musical-fidelity volumio[1007]: info: ControllerMpd::play undefined Sep 13 22:54:17 musical-fidelity volumio[1007]: verbose: ControllerMpd::sendMpdCommand play Sep 13 22:54:17 musical-fidelity volumio[1007]: error: MPD error: Cannot read property 'toString' of undefined Sep 13 22:54:20 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 13 22:54:20 musical-fidelity volumio[1007]: info: sendMpdCommand play took 3233 milliseconds Sep 13 22:54:26 musical-fidelity volumio[1007]: info: CoreCommandRouter::volumioVolatilePlay Sep 13 22:54:26 musical-fidelity volumio[1007]: info: CoreStateMachine::volatilePlay Sep 13 22:54:26 musical-fidelity volumio[1007]: info: ControllerMpd::play undefined Sep 13 22:54:26 musical-fidelity volumio[1007]: verbose: ControllerMpd::sendMpdCommand play Sep 13 22:54:26 musical-fidelity volumio[1007]: error: MPD error: Cannot read property 'toString' of undefined Sep 13 22:55:09 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 13 22:55:09 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 13 22:55:09 musical-fidelity volumio[1007]: info: Discovery: Getting this device information Sep 13 22:55:09 musical-fidelity volumio[1007]: info: CoreCommandRouter::volumioGetState Sep 13 22:55:09 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Sep 13 22:55:09 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConf Sep 13 22:55:09 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConf Sep 13 22:55:09 musical-fidelity volumio[1007]: info: CoreCommandRouter::volumioGetState Sep 13 22:55:09 musical-fidelity volumio[1007]: info: CoreCommandRouter::volumioGetVisibleSources Sep 13 22:55:09 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 22:55:09 musical-fidelity volumio[1007]: info: CoreCommandRouter::volumioGetState Sep 13 22:55:09 musical-fidelity volumio[1007]: info: CoreCommandRouter::volumioGetQueue Sep 13 22:55:09 musical-fidelity volumio[1007]: info: CoreStateMachine::getQueue Sep 13 22:55:09 musical-fidelity volumio[1007]: info: CorePlayQueue::getQueue Sep 13 22:55:09 musical-fidelity volumio[1007]: info: Listing playlists Sep 13 22:55:09 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 13 22:55:09 musical-fidelity volumio[1007]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 13 22:55:11 musical-fidelity volumio[1007]: info: CoreCommandRouter::volumioVolatilePlay Sep 13 22:55:11 musical-fidelity volumio[1007]: info: CoreStateMachine::volatilePlay Sep 13 22:55:11 musical-fidelity volumio[1007]: info: ControllerMpd::play undefined Sep 13 22:55:11 musical-fidelity volumio[1007]: verbose: ControllerMpd::sendMpdCommand play Sep 13 22:55:11 musical-fidelity volumio[1007]: error: MPD error: Cannot read property 'toString' of undefined Sep 13 22:55:22 musical-fidelity volumio[1007]: info: CoreCommandRouter::volumioVolatilePlay Sep 13 22:55:22 musical-fidelity volumio[1007]: info: CoreStateMachine::volatilePlay Sep 13 22:55:22 musical-fidelity volumio[1007]: info: ControllerMpd::play undefined Sep 13 22:55:22 musical-fidelity volumio[1007]: verbose: ControllerMpd::sendMpdCommand play Sep 13 22:55:22 musical-fidelity volumio[1007]: error: MPD error: Cannot read property 'toString' of undefined Sep 13 22:55:29 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Sep 13 22:55:29 musical-fidelity volumio[1007]: info: Received Push USB{"activeRoute":"0","powerShutdown":"0"} Sep 13 22:55:29 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: inputs , setPCInputReturn Sep 13 22:55:29 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: inputs , registerLastUsbStatus Sep 13 22:55:29 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: inputs , disablePCInput Sep 13 22:55:29 musical-fidelity volumio[1007]: info: CoreCommandRouter::servicePushState Sep 13 22:55:29 musical-fidelity volumio[1007]: info: CoreStateMachine::pushState Sep 13 22:55:29 musical-fidelity volumio[1007]: info: CoreCommandRouter::volumioPushState Sep 13 22:55:29 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 22:55:29 musical-fidelity volumio[1007]: info: CorePlayQueue::getTrack 1 Sep 13 22:55:29 musical-fidelity volumio[1007]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received inputs Sep 13 22:55:29 musical-fidelity volumio[1007]: info: CoreCommandRouter::volumiosetSourceActivempd Sep 13 22:55:29 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 22:55:29 musical-fidelity volumio[1007]: info: Received Push USB{"activeRoute":"0","powerShutdown":"0"} Sep 13 22:55:29 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: inputs , setPCInputReturn Sep 13 22:55:29 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: inputs , registerLastUsbStatus Sep 13 22:55:29 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: inputs , disablePCInput Sep 13 22:55:30 musical-fidelity kernel: usb 1-1.4: new high-speed USB device number 13 using dwc_otg Sep 13 22:55:30 musical-fidelity kernel: usb 1-1.4: New USB device found, idVendor=0424, idProduct=2412 Sep 13 22:55:30 musical-fidelity kernel: usb 1-1.4: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Sep 13 22:55:30 musical-fidelity kernel: hub 1-1.4:1.0: USB hub found Sep 13 22:55:30 musical-fidelity kernel: hub 1-1.4:1.0: 2 ports detected Sep 13 22:55:30 musical-fidelity kernel: usb 1-1.4.2: new high-speed USB device number 14 using dwc_otg Sep 13 22:55:30 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Sep 13 22:55:30 musical-fidelity volumio[1007]: info: Received Push USB{"activeRoute":"1","powerShutdown":"0"} Sep 13 22:55:30 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: inputs , registerLastUsbStatus Sep 13 22:55:30 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: inputs , enablePCInput Sep 13 22:55:30 musical-fidelity volumio[1007]: info: Unsetting Volatile before setting PC Input Sep 13 22:55:30 musical-fidelity volumio[1007]: verbose: UNSET VOLATILE Sep 13 22:55:30 musical-fidelity volumio[1007]: info: CoreCommandRouter::volumioStop Sep 13 22:55:30 musical-fidelity volumio[1007]: info: CoreStateMachine::stop Sep 13 22:55:30 musical-fidelity volumio[1007]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 22:55:30 musical-fidelity volumio[1007]: verbose: UNSET VOLATILE Sep 13 22:55:30 musical-fidelity volumio[1007]: info: CoreStateMachine::updateTrackBlock Sep 13 22:55:30 musical-fidelity volumio[1007]: info: CorePlayQueue::getTrackBlock Sep 13 22:55:30 musical-fidelity volumio[1007]: info: CoreStateMachine::stPlaybackTimer Sep 13 22:55:30 musical-fidelity volumio[1007]: info: CoreStateMachine::pushState Sep 13 22:55:30 musical-fidelity volumio[1007]: info: CorePlayQueue::getTrack 1 Sep 13 22:55:30 musical-fidelity volumio[1007]: info: CoreCommandRouter::volumioPushState Sep 13 22:55:30 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 22:55:30 musical-fidelity volumio[1007]: info: CoreStateMachine::serviceStop Sep 13 22:55:30 musical-fidelity volumio[1007]: info: CorePlayQueue::getTrack 1 Sep 13 22:55:30 musical-fidelity volumio[1007]: info: CoreCommandRouter::serviceStop Sep 13 22:55:30 musical-fidelity volumio[1007]: info: ControllerMpd::stop Sep 13 22:55:30 musical-fidelity volumio[1007]: verbose: ControllerMpd::sendMpdCommand stop Sep 13 22:55:30 musical-fidelity volumio[1007]: info: Updating push state because input changed Sep 13 22:55:30 musical-fidelity volumio[1007]: info: CoreCommandRouter::volumiosetSourceActivempd Sep 13 22:55:30 musical-fidelity volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 22:55:30 musical-fidelity volumio[1007]: info: Sep 13 22:55:30 musical-fidelity volumio[1007]: ---------------------------- MPD announces state update: player Sep 13 22:55:30 musical-fidelity volumio[1007]: info: sendMpdCommand stop took 25 milliseconds Sep 13 22:55:30 musical-fidelity volumio[1007]: info: ControllerMpd::getState Sep 13 22:55:30 musical-fidelity volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Sep 13 22:55:30 musical-fidelity volumio[1007]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 13 22:55:30 musical-fidelity volumio[1007]: TypeError: Cannot read property 'substring' of undefined Sep 13 22:55:30 musical-fidelity volumio[1007]: at /volumio/app/plugins/music_service/mpd/index.js:2779:70 Sep 13 22:55:30 musical-fidelity volumio[1007]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Sep 13 22:55:30 musical-fidelity volumio[1007]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Sep 13 22:55:30 musical-fidelity volumio[1007]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Sep 13 22:55:30 musical-fidelity volumio[1007]: at emitOne (events.js:116:13) Sep 13 22:55:30 musical-fidelity volumio[1007]: at Socket.emit (events.js:211:7) Sep 13 22:55:30 musical-fidelity volumio[1007]: at addChunk (_stream_readable.js:263:12) Sep 13 22:55:30 musical-fidelity volumio[1007]: at readableAddChunk (_stream_readable.js:246:13) Sep 13 22:55:30 musical-fidelity volumio[1007]: at Socket.Readable.push (_stream_readable.js:208:10) Sep 13 22:55:30 musical-fidelity volumio[1007]: at Pipe.onread (net.js:607:20) Sep 13 22:55:30 musical-fidelity volumio[1007]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 13 22:55:30 musical-fidelity kernel: usb 1-1.4: USB disconnect, device number 13 Sep 13 22:55:31 musical-fidelity kernel: usb 1-1.4.2: device not accepting address 14, error -71 Sep 13 22:55:31 musical-fidelity sudo[5150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-13 22:54 Sep 13 22:55:31 musical-fidelity sudo[5150]: 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="6d9442f761618503268d0366db805a252514dec1" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="mf" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Sep 6 12:01:32 CEST 2022" VOLUMIO_VERSION="1.081" VOLUMIO_HARDWARE="pi" VOLUMIO_VENDOR="Musical Fidelity" VOLUMIO_MODEL="MX-Stream" VOLUMIO_VENDOR_MODEL="Musical Fidelity MX-Stream" VOLUMIO_HASH="fcc4f08af4feb5d7558230fb59bf1e14"