-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2024-07-25 09:41:03 CEST. -- Jul 25 09:40:19 minidsp-shd ntpd[679]: receive: Unexpected origin timestamp 0xea4c7f98.f6dd8f2b does not match aorg 0000000000.00000000 from server@45.87.76.3 xmt 0xea4c7fe3.2c890cc0 Jul 25 09:40:19 minidsp-shd ntpd[679]: receive: Unexpected origin timestamp 0xea4c7f98.f6e4cb9d does not match aorg 0000000000.00000000 from server@193.104.37.238 xmt 0xea4c7fe3.2c7d5212 Jul 25 09:40:19 minidsp-shd ntpd[679]: receive: Unexpected origin timestamp 0xea4c7f98.f6e1ec04 does not match aorg 0000000000.00000000 from server@84.199.86.248 xmt 0xea4c7fe3.2cfeb80c Jul 25 09:40:19 minidsp-shd ntpd[679]: receive: Unexpected origin timestamp 0xea4c7f98.f6dbed5e does not match aorg 0000000000.00000000 from server@81.82.227.219 xmt 0xea4c7fe3.2e01536a Jul 25 09:40:19 minidsp-shd ntpd[679]: receive: Unexpected origin timestamp 0xea4c7f98.f6e3559b does not match aorg 0000000000.00000000 from server@213.213.212.103 xmt 0xea4c7fe3.2ec3b878 Jul 25 09:40:23 minidsp-shd volumio[699]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 25 09:40:27 minidsp-shd volumio[699]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 25 09:40:27 minidsp-shd volumio[699]: info: CoreCommandRouter::volumioPause Jul 25 09:40:27 minidsp-shd volumio[699]: info: CoreStateMachine::pause Jul 25 09:40:27 minidsp-shd volumio[699]: info: CoreStateMachine::stPlaybackTimer Jul 25 09:40:27 minidsp-shd volumio[699]: info: CoreStateMachine::servicePause Jul 25 09:40:27 minidsp-shd volumio[699]: info: CoreCommandRouter::servicePause Jul 25 09:40:27 minidsp-shd volumio[699]: info: ControllerMpd::pause Jul 25 09:40:27 minidsp-shd volumio[699]: verbose: ControllerMpd::sendMpdCommand pause Jul 25 09:40:27 minidsp-shd volumio[699]: info: Jul 25 09:40:27 minidsp-shd volumio[699]: ---------------------------- MPD announces state update: player Jul 25 09:40:27 minidsp-shd volumio[699]: info: sendMpdCommand pause took 4 milliseconds Jul 25 09:40:27 minidsp-shd volumio[699]: info: ControllerMpd::getState Jul 25 09:40:27 minidsp-shd volumio[699]: verbose: ControllerMpd::sendMpdCommand status Jul 25 09:40:27 minidsp-shd volumio[699]: info: Jul 25 09:40:27 minidsp-shd volumio[699]: ---------------------------- MPD announces state update: player Jul 25 09:40:27 minidsp-shd volumio[699]: info: ControllerMpd::getState Jul 25 09:40:27 minidsp-shd volumio[699]: verbose: ControllerMpd::sendMpdCommand status Jul 25 09:40:27 minidsp-shd volumio[699]: info: Jul 25 09:40:27 minidsp-shd volumio[699]: ---------------------------- MPD announces state update: player Jul 25 09:40:27 minidsp-shd volumio[699]: info: ControllerMpd::getState Jul 25 09:40:27 minidsp-shd volumio[699]: verbose: ControllerMpd::sendMpdCommand status Jul 25 09:40:27 minidsp-shd volumio[699]: info: sendMpdCommand status took 11 milliseconds Jul 25 09:40:27 minidsp-shd volumio[699]: info: sendMpdCommand status took 9 milliseconds Jul 25 09:40:27 minidsp-shd volumio[699]: info: sendMpdCommand status took 5 milliseconds Jul 25 09:40:27 minidsp-shd volumio[699]: verbose: ControllerMpd::parseState Jul 25 09:40:27 minidsp-shd volumio[699]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 09:40:27 minidsp-shd volumio[699]: verbose: ControllerMpd::parseState Jul 25 09:40:27 minidsp-shd volumio[699]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 09:40:27 minidsp-shd volumio[699]: verbose: ControllerMpd::parseState Jul 25 09:40:27 minidsp-shd volumio[699]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 09:40:27 minidsp-shd volumio[699]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 25 09:40:27 minidsp-shd volumio[699]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 25 09:40:27 minidsp-shd volumio[699]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 25 09:40:27 minidsp-shd volumio[699]: verbose: ControllerMpd::parseTrackInfo Jul 25 09:40:27 minidsp-shd volumio[699]: verbose: ControllerMpd::parseTrackInfo Jul 25 09:40:27 minidsp-shd volumio[699]: verbose: ControllerMpd::parseTrackInfo Jul 25 09:40:27 minidsp-shd volumio[699]: info: ControllerMpd::pushState Jul 25 09:40:27 minidsp-shd volumio[699]: info: CoreCommandRouter::servicePushState Jul 25 09:40:27 minidsp-shd volumio[699]: info: CoreStateMachine::pushState Jul 25 09:40:27 minidsp-shd volumio[699]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 09:40:27 minidsp-shd volumio[699]: info: CoreCommandRouter::volumioPushState Jul 25 09:40:27 minidsp-shd volumio[699]: info: ControllerMpd::pushState Jul 25 09:40:27 minidsp-shd volumio[699]: info: CoreCommandRouter::servicePushState Jul 25 09:40:27 minidsp-shd volumio[699]: info: CoreStateMachine::pushState Jul 25 09:40:27 minidsp-shd volumio[699]: info: CoreCommandRouter::volumioPushState Jul 25 09:40:27 minidsp-shd volumio[699]: info: ControllerMpd::pushState Jul 25 09:40:27 minidsp-shd volumio[699]: info: CoreCommandRouter::servicePushState Jul 25 09:40:27 minidsp-shd volumio[699]: info: CoreStateMachine::pushState Jul 25 09:40:27 minidsp-shd volumio[699]: info: CoreCommandRouter::volumioPushState Jul 25 09:40:27 minidsp-shd volumio[699]: info: ------------------------------ 98ms Jul 25 09:40:27 minidsp-shd volumio[699]: info: ------------------------------ 95ms Jul 25 09:40:27 minidsp-shd volumio[699]: info: ------------------------------ 92ms Jul 25 09:40:28 minidsp-shd volumio[699]: info: CoreCommandRouter::volumioVolatilePlay Jul 25 09:40:28 minidsp-shd volumio[699]: info: CoreStateMachine::volatilePlay Jul 25 09:40:28 minidsp-shd volumio[699]: info: ControllerMpd::play undefined Jul 25 09:40:28 minidsp-shd volumio[699]: verbose: ControllerMpd::sendMpdCommand play Jul 25 09:40:28 minidsp-shd volumio[699]: error: MPD error: Cannot read property 'toString' of undefined Jul 25 09:40:28 minidsp-shd volumio[699]: error: Cannot read property 'toString' of undefined Jul 25 09:40:29 minidsp-shd volumio[699]: info: CoreCommandRouter::volumioVolatilePlay Jul 25 09:40:29 minidsp-shd volumio[699]: info: CoreStateMachine::volatilePlay Jul 25 09:40:29 minidsp-shd volumio[699]: info: ControllerMpd::play undefined Jul 25 09:40:29 minidsp-shd volumio[699]: verbose: ControllerMpd::sendMpdCommand play Jul 25 09:40:29 minidsp-shd volumio[699]: error: MPD error: Cannot read property 'toString' of undefined Jul 25 09:40:29 minidsp-shd volumio[699]: error: Cannot read property 'toString' of undefined Jul 25 09:40:31 minidsp-shd volumio[699]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 25 09:40:31 minidsp-shd volumio[699]: info: sendMpdCommand play took 2703 milliseconds Jul 25 09:41:02 minidsp-shd volumio[699]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 25 09:41:02 minidsp-shd volumio[699]: info: CURURI: music-library Jul 25 09:41:02 minidsp-shd volumio[699]: info: sendMpdCommand play took 32183 milliseconds Jul 25 09:41:02 minidsp-shd volumio[699]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 25 09:41:02 minidsp-shd volumio[699]: TypeError: Cannot read property 'substring' of undefined Jul 25 09:41:02 minidsp-shd volumio[699]: at /volumio/app/plugins/music_service/mpd/index.js:2826:70 Jul 25 09:41:02 minidsp-shd volumio[699]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jul 25 09:41:02 minidsp-shd volumio[699]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jul 25 09:41:02 minidsp-shd volumio[699]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jul 25 09:41:02 minidsp-shd volumio[699]: at Socket.emit (events.js:400:28) Jul 25 09:41:02 minidsp-shd volumio[699]: at addChunk (internal/streams/readable.js:293:12) Jul 25 09:41:02 minidsp-shd volumio[699]: at readableAddChunk (internal/streams/readable.js:263:11) Jul 25 09:41:02 minidsp-shd volumio[699]: at Socket.Readable.push (internal/streams/readable.js:206:10) Jul 25 09:41:02 minidsp-shd volumio[699]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Jul 25 09:41:02 minidsp-shd volumio[699]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 25 09:41:03 minidsp-shd sudo[1111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-25 09:40 Jul 25 09:41:03 minidsp-shd sudo[1111]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:00:28 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo2" VOLUMIO_DEVICENAME="NanoPi Neo2" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="29ccfe8a9cff19911db4b736879ac117"