-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Mon 2024-04-22 13:21:57 UTC. -- Apr 22 13:20:02 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:20:11 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:20:13 volumio-papa volumio[634]: Upnp client error: Error: This socket has been ended by the other party Apr 22 13:20:13 volumio-papa volumio[634]: Upnp client error: Error: This socket has been ended by the other party Apr 22 13:20:14 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:20:19 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:20:22 volumio-papa volumio[634]: Upnp client error: Error: This socket has been ended by the other party Apr 22 13:20:23 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:20:24 volumio-papa volumio[634]: Upnp client error: Error: This socket has been ended by the other party Apr 22 13:20:25 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:20:30 volumio-papa volumio[634]: Upnp client error: Error: This socket has been ended by the other party Apr 22 13:20:30 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:20:38 volumio-papa volumio[634]: Cannot compose Albumart path Apr 22 13:20:38 volumio-papa volumio[634]: Cannot compose Albumart path Apr 22 13:20:38 volumio-papa volumio[634]: Cannot compose Albumart path Apr 22 13:20:38 volumio-papa volumio[634]: Cannot compose Albumart path Apr 22 13:20:38 volumio-papa volumio[634]: Cannot compose Albumart path Apr 22 13:20:38 volumio-papa volumio[634]: Cannot compose Albumart path Apr 22 13:20:38 volumio-papa volumio[634]: Cannot compose Albumart path Apr 22 13:20:38 volumio-papa volumio[634]: Cannot compose Albumart path Apr 22 13:20:39 volumio-papa volumio[634]: Cannot compose Albumart path Apr 22 13:20:39 volumio-papa volumio[634]: Cannot compose Albumart path Apr 22 13:20:46 volumio-papa volumio[634]: info: sendMpdCommand status took 65645 milliseconds Apr 22 13:20:46 volumio-papa volumio[634]: info: sendMpdCommand status took 65646 milliseconds Apr 22 13:20:46 volumio-papa volumio[634]: info: sendMpdCommand status took 65645 milliseconds Apr 22 13:20:46 volumio-papa volumio[634]: verbose: ControllerMpd::parseState Apr 22 13:20:46 volumio-papa volumio[634]: info: Command Router : Notfying DB Updatefalse Apr 22 13:20:46 volumio-papa volumio[634]: info: CoreCommandRouter::Close All Modals sent Apr 22 13:20:46 volumio-papa volumio[634]: verbose: ControllerMpd::parseState Apr 22 13:20:46 volumio-papa volumio[634]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 13:20:46 volumio-papa volumio[634]: verbose: ControllerMpd::parseState Apr 22 13:20:46 volumio-papa volumio[634]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 13:20:46 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:20:46 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:20:46 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:20:46 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:20:46 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:20:47 volumio-papa volumio[634]: Upnp client error: Error: This socket has been ended by the other party Apr 22 13:20:47 volumio-papa volumio[634]: Upnp client error: Error: This socket has been ended by the other party Apr 22 13:20:47 volumio-papa volumio[634]: Upnp client error: Error: This socket has been ended by the other party Apr 22 13:20:47 volumio-papa volumio[634]: Upnp client error: Error: This socket has been ended by the other party Apr 22 13:20:47 volumio-papa volumio[634]: Upnp client error: Error: This socket has been ended by the other party Apr 22 13:20:49 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:20:55 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:20:59 volumio-papa volumio[634]: Upnp client error: Error: This socket has been ended by the other party Apr 22 13:20:59 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:21:06 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:21:08 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:21:11 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:21:15 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:21:21 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:21:24 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:21:30 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:21:32 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:21:38 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:21:42 volumio-papa volumio[634]: Cannot compose Albumart path Apr 22 13:21:42 volumio-papa volumio[634]: Cannot compose Albumart path Apr 22 13:21:42 volumio-papa volumio[634]: Cannot compose Albumart path Apr 22 13:21:42 volumio-papa volumio[634]: Cannot compose Albumart path Apr 22 13:21:42 volumio-papa volumio[634]: Cannot compose Albumart path Apr 22 13:21:42 volumio-papa volumio[634]: Cannot compose Albumart path Apr 22 13:21:42 volumio-papa volumio[634]: Cannot compose Albumart path Apr 22 13:21:42 volumio-papa volumio[634]: Cannot compose Albumart path Apr 22 13:21:43 volumio-papa volumio[634]: Cannot compose Albumart path Apr 22 13:21:43 volumio-papa volumio[634]: Cannot compose Albumart path Apr 22 13:21:50 volumio-papa volumio[634]: info: sendMpdCommand status took 128297 milliseconds Apr 22 13:21:50 volumio-papa volumio[634]: info: sendMpdCommand status took 128296 milliseconds Apr 22 13:21:50 volumio-papa volumio[634]: verbose: ControllerMpd::parseState Apr 22 13:21:50 volumio-papa volumio[634]: info: Command Router : Notfying DB Updatefalse Apr 22 13:21:50 volumio-papa volumio[634]: info: CoreCommandRouter::Close All Modals sent Apr 22 13:21:50 volumio-papa volumio[634]: verbose: ControllerMpd::parseState Apr 22 13:21:50 volumio-papa volumio[634]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 13:21:50 volumio-papa volumio[634]: verbose: ControllerMpd::parseState Apr 22 13:21:50 volumio-papa volumio[634]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 13:21:50 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:21:50 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:21:50 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:21:51 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:21:51 volumio-papa volumio[634]: Upnp client error: Error: This socket has been ended by the other party Apr 22 13:21:51 volumio-papa volumio[634]: Upnp client error: Error: This socket has been ended by the other party Apr 22 13:21:51 volumio-papa volumio[634]: info: Apr 22 13:21:51 volumio-papa volumio[634]: ---------------------------- MPD announces system playlist update Apr 22 13:21:51 volumio-papa volumio[634]: info: Ignoring MPD Status Update Apr 22 13:21:51 volumio-papa volumio[634]: info: sendMpdCommand add "NAS/Muziek_papa_backup3/Top 100 Jaaroverzichten 2021-2023 & Hoesjes FLAC/Top 100 Van 2005/088 - Anouk - One Word.flac" took 119793 milliseconds Apr 22 13:21:51 volumio-papa volumio[634]: info: ------------------------------ 119246ms Apr 22 13:21:51 volumio-papa volumio[634]: verbose: ControllerMpd::sendMpdCommand consume 1 Apr 22 13:21:51 volumio-papa volumio[634]: Upnp client error: Error: This socket has been ended by the other party Apr 22 13:21:51 volumio-papa volumio[634]: info: Apr 22 13:21:51 volumio-papa volumio[634]: ---------------------------- MPD announces system playlist update Apr 22 13:21:51 volumio-papa volumio[634]: info: Ignoring MPD Status Update Apr 22 13:21:51 volumio-papa volumio[634]: info: Apr 22 13:21:51 volumio-papa volumio[634]: ---------------------------- MPD announces state update: player Apr 22 13:21:51 volumio-papa volumio[634]: info: ControllerMpd::getState Apr 22 13:21:51 volumio-papa volumio[634]: verbose: ControllerMpd::sendMpdCommand status Apr 22 13:21:51 volumio-papa volumio[634]: info: ------------------------------ 115195ms Apr 22 13:21:51 volumio-papa volumio[634]: info: sendMpdCommand status took 115191 milliseconds Apr 22 13:21:51 volumio-papa volumio[634]: verbose: ControllerMpd::parseState Apr 22 13:21:51 volumio-papa volumio[634]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 13:21:53 volumio-papa volumio[634]: info: sendMpdCommand playlistinfo took 66614 milliseconds Apr 22 13:21:53 volumio-papa volumio[634]: info: sendMpdCommand playlistinfo took 66613 milliseconds Apr 22 13:21:53 volumio-papa volumio[634]: verbose: ControllerMpd::parseTrackInfo Apr 22 13:21:53 volumio-papa volumio[634]: verbose: ControllerMpd::parseTrackInfo Apr 22 13:21:53 volumio-papa volumio[634]: info: ControllerMpd::pushState Apr 22 13:21:53 volumio-papa volumio[634]: info: CoreCommandRouter::servicePushState Apr 22 13:21:53 volumio-papa volumio[634]: info: CorePlayQueue::getTrack 87 Apr 22 13:21:53 volumio-papa volumio[634]: verbose: STATE SERVICE {"status":"play","position":0,"seek":172022,"duration":186,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1066 Kbps","isStreaming":false,"title":"One Word","artist":"Anouk","album":"Top 100 Van 2005","uri":"NAS/Muziek_papa_backup3/Top 100 Jaaroverzichten 2021-2023 & Hoesjes FLAC/Top 100 Van 2005/088 - Anouk - One Word.flac","trackType":"flac"} Apr 22 13:21:53 volumio-papa volumio[634]: verbose: CURRENT POSITION 87 Apr 22 13:21:53 volumio-papa volumio[634]: info: CoreStateMachine::syncState stateService play Apr 22 13:21:53 volumio-papa volumio[634]: info: CoreStateMachine::syncState currentStatus play Apr 22 13:21:53 volumio-papa volumio[634]: info: Received an update from plugin. extracting info from payload Apr 22 13:21:53 volumio-papa volumio[634]: info: CoreStateMachine::pushState Apr 22 13:21:53 volumio-papa volumio[634]: info: CorePlayQueue::getTrack 87 Apr 22 13:21:53 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 13:21:53 volumio-papa volumio[634]: info: CoreCommandRouter::volumioPushState Apr 22 13:21:53 volumio-papa volumio[634]: info: CoreStateMachine::pushState Apr 22 13:21:53 volumio-papa volumio[634]: info: CorePlayQueue::getTrack 87 Apr 22 13:21:53 volumio-papa volumio[634]: info: CoreCommandRouter::volumioPushState Apr 22 13:21:53 volumio-papa volumio[634]: info: ControllerMpd::pushState Apr 22 13:21:53 volumio-papa volumio[634]: info: CoreCommandRouter::servicePushState Apr 22 13:21:53 volumio-papa volumio[634]: info: CorePlayQueue::getTrack 87 Apr 22 13:21:53 volumio-papa volumio[634]: verbose: STATE SERVICE {"status":"play","position":0,"seek":172022,"duration":186,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1066 Kbps","isStreaming":false,"title":"One Word","artist":"Anouk","album":"Top 100 Van 2005","uri":"NAS/Muziek_papa_backup3/Top 100 Jaaroverzichten 2021-2023 & Hoesjes FLAC/Top 100 Van 2005/088 - Anouk - One Word.flac","trackType":"flac"} Apr 22 13:21:53 volumio-papa volumio[634]: verbose: CURRENT POSITION 87 Apr 22 13:21:53 volumio-papa volumio[634]: info: CoreStateMachine::syncState stateService play Apr 22 13:21:53 volumio-papa volumio[634]: info: CoreStateMachine::syncState currentStatus play Apr 22 13:21:53 volumio-papa volumio[634]: info: Received an update from plugin. extracting info from payload Apr 22 13:21:53 volumio-papa volumio[634]: info: CoreStateMachine::pushState Apr 22 13:21:53 volumio-papa volumio[634]: info: CorePlayQueue::getTrack 87 Apr 22 13:21:53 volumio-papa volumio[634]: info: CoreCommandRouter::volumioPushState Apr 22 13:21:53 volumio-papa volumio[634]: info: CoreStateMachine::pushState Apr 22 13:21:53 volumio-papa volumio[634]: info: CorePlayQueue::getTrack 87 Apr 22 13:21:53 volumio-papa volumio[634]: info: CoreCommandRouter::volumioPushState Apr 22 13:21:53 volumio-papa volumio[634]: info: ------------------------------ 132435ms Apr 22 13:21:53 volumio-papa volumio[634]: info: ------------------------------ 132435ms Apr 22 13:21:55 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 13:21:56 volumio-papa volumio[634]: info: sendMpdCommand playlistinfo took 5779 milliseconds Apr 22 13:21:56 volumio-papa volumio[634]: info: sendMpdCommand playlistinfo took 5778 milliseconds Apr 22 13:21:56 volumio-papa volumio[634]: verbose: ControllerMpd::parseTrackInfo Apr 22 13:21:56 volumio-papa volumio[634]: info: ControllerMpd::pushState Apr 22 13:21:56 volumio-papa volumio[634]: info: CoreCommandRouter::servicePushState Apr 22 13:21:56 volumio-papa volumio[634]: info: CorePlayQueue::getTrack 87 Apr 22 13:21:56 volumio-papa volumio[634]: verbose: STATE SERVICE {"status":"play","position":0,"seek":173828,"duration":186,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1192 Kbps","isStreaming":false,"title":"One Word","artist":"Anouk","album":"Top 100 Van 2005","uri":"NAS/Muziek_papa_backup3/Top 100 Jaaroverzichten 2021-2023 & Hoesjes FLAC/Top 100 Van 2005/088 - Anouk - One Word.flac","trackType":"flac"} Apr 22 13:21:56 volumio-papa volumio[634]: verbose: CURRENT POSITION 87 Apr 22 13:21:56 volumio-papa volumio[634]: info: CoreStateMachine::syncState stateService play Apr 22 13:21:56 volumio-papa volumio[634]: info: CoreStateMachine::syncState currentStatus play Apr 22 13:21:56 volumio-papa volumio[634]: info: Received an update from plugin. extracting info from payload Apr 22 13:21:56 volumio-papa volumio[634]: info: CoreStateMachine::pushState Apr 22 13:21:56 volumio-papa volumio[634]: info: CorePlayQueue::getTrack 87 Apr 22 13:21:56 volumio-papa volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 13:21:56 volumio-papa volumio[634]: info: CoreCommandRouter::volumioPushState Apr 22 13:21:56 volumio-papa volumio[634]: info: CoreStateMachine::pushState Apr 22 13:21:56 volumio-papa volumio[634]: info: CorePlayQueue::getTrack 87 Apr 22 13:21:56 volumio-papa volumio[634]: info: CoreCommandRouter::volumioPushState Apr 22 13:21:56 volumio-papa volumio[634]: info: ControllerMpd::pushState Apr 22 13:21:56 volumio-papa volumio[634]: info: CoreCommandRouter::servicePushState Apr 22 13:21:56 volumio-papa volumio[634]: info: CorePlayQueue::getTrack 87 Apr 22 13:21:56 volumio-papa volumio[634]: verbose: STATE SERVICE {"status":"play","position":0,"seek":173828,"duration":186,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1121 Kbps","isStreaming":false,"title":"One Word","artist":"Anouk","album":"Top 100 Van 2005","uri":"NAS/Muziek_papa_backup3/Top 100 Jaaroverzichten 2021-2023 & Hoesjes FLAC/Top 100 Van 2005/088 - Anouk - One Word.flac","trackType":"flac"} Apr 22 13:21:56 volumio-papa volumio[634]: verbose: CURRENT POSITION 87 Apr 22 13:21:56 volumio-papa volumio[634]: info: CoreStateMachine::syncState stateService play Apr 22 13:21:56 volumio-papa volumio[634]: info: CoreStateMachine::syncState currentStatus play Apr 22 13:21:56 volumio-papa volumio[634]: info: Received an update from plugin. extracting info from payload Apr 22 13:21:56 volumio-papa volumio[634]: info: CoreStateMachine::pushState Apr 22 13:21:56 volumio-papa volumio[634]: info: CorePlayQueue::getTrack 87 Apr 22 13:21:56 volumio-papa volumio[634]: info: CoreCommandRouter::volumioPushState Apr 22 13:21:56 volumio-papa volumio[634]: info: CoreStateMachine::pushState Apr 22 13:21:56 volumio-papa volumio[634]: info: CorePlayQueue::getTrack 87 Apr 22 13:21:56 volumio-papa volumio[634]: info: CoreCommandRouter::volumioPushState Apr 22 13:21:56 volumio-papa volumio[634]: info: ------------------------------ 134141ms Apr 22 13:21:56 volumio-papa volumio[634]: info: ------------------------------ 134141ms Apr 22 13:21:57 volumio-papa volumio[634]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 13:21:57 volumio-papa volumio[634]: TypeError: Cannot read property 'substring' of undefined Apr 22 13:21:57 volumio-papa volumio[634]: at /volumio/app/plugins/music_service/mpd/index.js:2800:70 Apr 22 13:21:57 volumio-papa volumio[634]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 22 13:21:57 volumio-papa volumio[634]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 22 13:21:57 volumio-papa volumio[634]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 22 13:21:57 volumio-papa volumio[634]: at Socket.emit (events.js:315:20) Apr 22 13:21:57 volumio-papa volumio[634]: at addChunk (internal/streams/readable.js:309:12) Apr 22 13:21:57 volumio-papa volumio[634]: at readableAddChunk (internal/streams/readable.js:280:11) Apr 22 13:21:57 volumio-papa volumio[634]: at Socket.Readable.push (internal/streams/readable.js:223:10) Apr 22 13:21:57 volumio-papa volumio[634]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 22 13:21:57 volumio-papa volumio[634]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 13:21:57 volumio-papa sudo[1515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-22 13:20 Apr 22 13:21:57 volumio-papa sudo[1515]: 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="682e0b17433cee9f9da31457f545345105e06e63" VOLUMIO_FE_VERSION="fba46add594d630e1b506bc42f1beddaad53fb64" VOLUMIO_FE3_VERSION="c23fef6dba8e02db978bd39bd8d26872e60ada6e" VOLUMIO_BE_VERSION="f7885966dee9f148ddf4f5050103a7d6734e1a10" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 20 Dec 2021 04:57:50 PM CET" VOLUMIO_VERSION="3.179" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6d5d4c489f0b1e608d9cefb791a9102d"