-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2024-05-08 16:30:48 CEST. -- May 08 16:30:38 volumio volumio[853]: info: CoreCommandRouter::volumioPause May 08 16:30:38 volumio volumio[853]: info: CoreStateMachine::pause May 08 16:30:38 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer May 08 16:30:38 volumio volumio[853]: info: CoreStateMachine::servicePause May 08 16:30:38 volumio volumio[853]: info: CorePlayQueue::getTrack 0 May 08 16:30:38 volumio volumio[853]: info: CoreCommandRouter::servicePause May 08 16:30:38 volumio volumio[853]: info: ControllerMpd::pause May 08 16:30:38 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand pause May 08 16:30:38 volumio volumio[853]: info: May 08 16:30:38 volumio volumio[853]: ---------------------------- MPD announces state update: player May 08 16:30:38 volumio volumio[853]: info: sendMpdCommand pause took 3 milliseconds May 08 16:30:38 volumio volumio[853]: info: ControllerMpd::getState May 08 16:30:38 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status May 08 16:30:38 volumio volumio[853]: info: May 08 16:30:38 volumio volumio[853]: ---------------------------- MPD announces state update: player May 08 16:30:38 volumio volumio[853]: info: ControllerMpd::getState May 08 16:30:38 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status May 08 16:30:38 volumio volumio[853]: info: May 08 16:30:38 volumio volumio[853]: ---------------------------- MPD announces state update: player May 08 16:30:38 volumio volumio[853]: info: ControllerMpd::getState May 08 16:30:38 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status May 08 16:30:38 volumio volumio[853]: info: sendMpdCommand status took 5 milliseconds May 08 16:30:38 volumio volumio[853]: info: sendMpdCommand status took 3 milliseconds May 08 16:30:38 volumio volumio[853]: info: sendMpdCommand status took 3 milliseconds May 08 16:30:38 volumio volumio[853]: verbose: ControllerMpd::parseState May 08 16:30:38 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 16:30:39 volumio volumio[853]: verbose: ControllerMpd::parseState May 08 16:30:39 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 16:30:39 volumio volumio[853]: verbose: ControllerMpd::parseState May 08 16:30:39 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 16:30:39 volumio volumio[853]: info: sendMpdCommand playlistinfo took 3 milliseconds May 08 16:30:39 volumio volumio[853]: info: sendMpdCommand playlistinfo took 3 milliseconds May 08 16:30:39 volumio volumio[853]: info: sendMpdCommand playlistinfo took 3 milliseconds May 08 16:30:39 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo May 08 16:30:39 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo May 08 16:30:39 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo May 08 16:30:39 volumio volumio[853]: info: ControllerMpd::pushState May 08 16:30:39 volumio volumio[853]: info: CoreCommandRouter::servicePushState May 08 16:30:39 volumio volumio[853]: info: CorePlayQueue::getTrack 0 May 08 16:30:39 volumio volumio[853]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":144463,"duration":481,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"War Pigs","artist":"Black Sabbath","album":"Paranoid","uri":"NAS/FLAC/Black Sabbath - Paranoid/01 - War Pigs.dsf","trackType":"dsf"} May 08 16:30:39 volumio volumio[853]: verbose: CURRENT POSITION 0 May 08 16:30:39 volumio volumio[853]: info: CoreStateMachine::syncState stateService pause May 08 16:30:39 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus pause May 08 16:30:39 volumio volumio[853]: info: CoreStateMachine::pushState May 08 16:30:39 volumio volumio[853]: info: CorePlayQueue::getTrack 0 May 08 16:30:39 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 16:30:39 volumio volumio[853]: info: CoreCommandRouter::volumioPushState May 08 16:30:39 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer May 08 16:30:39 volumio volumio[853]: info: ControllerMpd::pushState May 08 16:30:39 volumio volumio[853]: info: CoreCommandRouter::servicePushState May 08 16:30:39 volumio volumio[853]: info: CorePlayQueue::getTrack 0 May 08 16:30:39 volumio volumio[853]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":144463,"duration":481,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"War Pigs","artist":"Black Sabbath","album":"Paranoid","uri":"NAS/FLAC/Black Sabbath - Paranoid/01 - War Pigs.dsf","trackType":"dsf"} May 08 16:30:39 volumio volumio[853]: verbose: CURRENT POSITION 0 May 08 16:30:39 volumio volumio[853]: info: CoreStateMachine::syncState stateService pause May 08 16:30:39 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus pause May 08 16:30:39 volumio volumio[853]: info: CoreStateMachine::pushState May 08 16:30:39 volumio volumio[853]: info: CorePlayQueue::getTrack 0 May 08 16:30:39 volumio volumio[853]: info: CoreCommandRouter::volumioPushState May 08 16:30:39 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer May 08 16:30:39 volumio volumio[853]: info: ControllerMpd::pushState May 08 16:30:39 volumio volumio[853]: info: CoreCommandRouter::servicePushState May 08 16:30:39 volumio volumio[853]: info: CorePlayQueue::getTrack 0 May 08 16:30:39 volumio volumio[853]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":144463,"duration":481,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"War Pigs","artist":"Black Sabbath","album":"Paranoid","uri":"NAS/FLAC/Black Sabbath - Paranoid/01 - War Pigs.dsf","trackType":"dsf"} May 08 16:30:39 volumio volumio[853]: verbose: CURRENT POSITION 0 May 08 16:30:39 volumio volumio[853]: info: CoreStateMachine::syncState stateService pause May 08 16:30:39 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus pause May 08 16:30:39 volumio volumio[853]: info: CoreStateMachine::pushState May 08 16:30:39 volumio volumio[853]: info: CorePlayQueue::getTrack 0 May 08 16:30:39 volumio volumio[853]: info: CoreCommandRouter::volumioPushState May 08 16:30:39 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer May 08 16:30:39 volumio volumio[853]: info: ------------------------------ 44ms May 08 16:30:39 volumio volumio[853]: info: ------------------------------ 42ms May 08 16:30:39 volumio volumio[853]: info: ------------------------------ 41ms May 08 16:30:41 volumio volumio[853]: info: CoreCommandRouter::volumioClearQueue May 08 16:30:41 volumio volumio[853]: info: CoreStateMachine::ClearQueue May 08 16:30:41 volumio volumio[853]: info: CoreStateMachine::stop May 08 16:30:41 volumio volumio[853]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 16:30:41 volumio volumio[853]: info: CoreStateMachine::updateTrackBlock May 08 16:30:41 volumio volumio[853]: info: CorePlayQueue::getTrackBlock May 08 16:30:41 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer May 08 16:30:41 volumio volumio[853]: info: CoreStateMachine::pushState May 08 16:30:41 volumio volumio[853]: info: CorePlayQueue::getTrack 0 May 08 16:30:41 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 16:30:41 volumio volumio[853]: info: CoreCommandRouter::volumioPushState May 08 16:30:41 volumio volumio[853]: info: CoreStateMachine::serviceStop May 08 16:30:41 volumio volumio[853]: info: CorePlayQueue::getTrack 0 May 08 16:30:41 volumio volumio[853]: info: CoreCommandRouter::serviceStop May 08 16:30:41 volumio volumio[853]: info: ControllerMpd::stop May 08 16:30:41 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand stop May 08 16:30:41 volumio volumio[853]: info: CorePlayQueue::clearPlayQueue May 08 16:30:41 volumio volumio[853]: info: CorePlayQueue::saveQueue May 08 16:30:41 volumio volumio[853]: info: CoreCommandRouter::volumioPushState May 08 16:30:41 volumio volumio[853]: info: CoreCommandRouter::volumioPushQueue May 08 16:30:41 volumio volumio[853]: info: May 08 16:30:41 volumio volumio[853]: ---------------------------- MPD announces state update: player May 08 16:30:41 volumio volumio[853]: info: sendMpdCommand stop took 26 milliseconds May 08 16:30:41 volumio volumio[853]: info: ControllerMpd::getState May 08 16:30:41 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status May 08 16:30:41 volumio volumio[853]: info: sendMpdCommand status took 1 milliseconds May 08 16:30:41 volumio volumio[853]: verbose: ControllerMpd::parseState May 08 16:30:41 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 16:30:41 volumio volumio[853]: info: May 08 16:30:41 volumio volumio[853]: ---------------------------- MPD announces state update: player May 08 16:30:41 volumio volumio[853]: info: ControllerMpd::getState May 08 16:30:41 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status May 08 16:30:41 volumio volumio[853]: info: May 08 16:30:41 volumio volumio[853]: ---------------------------- MPD announces state update: player May 08 16:30:41 volumio volumio[853]: info: ControllerMpd::getState May 08 16:30:41 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status May 08 16:30:41 volumio volumio[853]: info: sendMpdCommand playlistinfo took 4 milliseconds May 08 16:30:41 volumio volumio[853]: info: sendMpdCommand status took 3 milliseconds May 08 16:30:41 volumio volumio[853]: info: sendMpdCommand status took 2 milliseconds May 08 16:30:41 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo May 08 16:30:41 volumio volumio[853]: verbose: ControllerMpd::parseState May 08 16:30:41 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 16:30:41 volumio volumio[853]: verbose: ControllerMpd::parseState May 08 16:30:41 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 16:30:41 volumio volumio[853]: info: ControllerMpd::pushState May 08 16:30:41 volumio volumio[853]: info: CoreCommandRouter::servicePushState May 08 16:30:41 volumio volumio[853]: info: CoreStateMachine::pushState May 08 16:30:41 volumio volumio[853]: info: CorePlayQueue::getTrack 0 May 08 16:30:41 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 16:30:41 volumio volumio[853]: info: CoreCommandRouter::volumioPushState May 08 16:30:41 volumio volumio[853]: info: CorePlayQueue::getTrack 0 May 08 16:30:41 volumio volumio[853]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"War Pigs","artist":"Black Sabbath","album":"Paranoid","uri":"NAS/FLAC/Black Sabbath - Paranoid/01 - War Pigs.dsf","trackType":"dsf"} May 08 16:30:41 volumio volumio[853]: verbose: CURRENT POSITION 0 May 08 16:30:41 volumio volumio[853]: info: CoreStateMachine::syncState stateService stop May 08 16:30:41 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus stop May 08 16:30:41 volumio volumio[853]: info: CoreStateMachine::pushState May 08 16:30:41 volumio volumio[853]: info: CorePlayQueue::getTrack 0 May 08 16:30:41 volumio volumio[853]: info: CoreCommandRouter::volumioPushState May 08 16:30:41 volumio volumio[853]: info: No code May 08 16:30:41 volumio volumio[853]: info: CoreStateMachine::pushState May 08 16:30:41 volumio volumio[853]: info: CorePlayQueue::getTrack 0 May 08 16:30:41 volumio volumio[853]: info: CoreCommandRouter::volumioPushState May 08 16:30:41 volumio volumio[853]: info: ------------------------------ 36ms May 08 16:30:41 volumio volumio[853]: info: sendMpdCommand playlistinfo took 28 milliseconds May 08 16:30:41 volumio volumio[853]: info: sendMpdCommand playlistinfo took 28 milliseconds May 08 16:30:41 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo May 08 16:30:41 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo May 08 16:30:41 volumio volumio[853]: info: ControllerMpd::pushState May 08 16:30:41 volumio volumio[853]: info: CoreCommandRouter::servicePushState May 08 16:30:41 volumio volumio[853]: info: CoreStateMachine::pushState May 08 16:30:41 volumio volumio[853]: info: CorePlayQueue::getTrack 0 May 08 16:30:41 volumio volumio[853]: info: CoreCommandRouter::volumioPushState May 08 16:30:41 volumio volumio[853]: info: CorePlayQueue::getTrack 0 May 08 16:30:41 volumio volumio[853]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"War Pigs","artist":"Black Sabbath","album":"Paranoid","uri":"NAS/FLAC/Black Sabbath - Paranoid/01 - War Pigs.dsf","trackType":"dsf"} May 08 16:30:41 volumio volumio[853]: verbose: CURRENT POSITION 0 May 08 16:30:41 volumio volumio[853]: info: CoreStateMachine::syncState stateService stop May 08 16:30:41 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus stop May 08 16:30:41 volumio volumio[853]: info: CoreStateMachine::pushState May 08 16:30:41 volumio volumio[853]: info: CorePlayQueue::getTrack 0 May 08 16:30:41 volumio volumio[853]: info: CoreCommandRouter::volumioPushState May 08 16:30:41 volumio volumio[853]: info: No code May 08 16:30:41 volumio volumio[853]: info: CoreStateMachine::pushState May 08 16:30:41 volumio volumio[853]: info: CorePlayQueue::getTrack 0 May 08 16:30:41 volumio volumio[853]: info: CoreCommandRouter::volumioPushState May 08 16:30:41 volumio volumio[853]: info: ControllerMpd::pushState May 08 16:30:41 volumio volumio[853]: info: CoreCommandRouter::servicePushState May 08 16:30:41 volumio volumio[853]: info: CoreStateMachine::pushState May 08 16:30:41 volumio volumio[853]: info: CorePlayQueue::getTrack 0 May 08 16:30:41 volumio volumio[853]: info: CoreCommandRouter::volumioPushState May 08 16:30:41 volumio volumio[853]: info: CorePlayQueue::getTrack 0 May 08 16:30:41 volumio volumio[853]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"War Pigs","artist":"Black Sabbath","album":"Paranoid","uri":"NAS/FLAC/Black Sabbath - Paranoid/01 - War Pigs.dsf","trackType":"dsf"} May 08 16:30:41 volumio volumio[853]: verbose: CURRENT POSITION 0 May 08 16:30:41 volumio volumio[853]: info: CoreStateMachine::syncState stateService stop May 08 16:30:41 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus stop May 08 16:30:41 volumio volumio[853]: info: CoreStateMachine::pushState May 08 16:30:41 volumio volumio[853]: info: CorePlayQueue::getTrack 0 May 08 16:30:41 volumio volumio[853]: info: CoreCommandRouter::volumioPushState May 08 16:30:41 volumio volumio[853]: info: No code May 08 16:30:41 volumio volumio[853]: info: CoreStateMachine::pushState May 08 16:30:41 volumio volumio[853]: info: CorePlayQueue::getTrack 0 May 08 16:30:41 volumio volumio[853]: info: CoreCommandRouter::volumioPushState May 08 16:30:41 volumio volumio[853]: info: ------------------------------ 83ms May 08 16:30:41 volumio volumio[853]: info: ------------------------------ 83ms May 08 16:30:47 volumio volumio[853]: info: Preload queue cleared May 08 16:30:47 volumio volumio[853]: info: CoreCommandRouter::volumioReplaceandPlayItems May 08 16:30:47 volumio volumio[853]: info: CoreStateMachine::ClearQueue May 08 16:30:47 volumio volumio[853]: info: CoreStateMachine::stop May 08 16:30:47 volumio volumio[853]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 16:30:47 volumio volumio[853]: info: CorePlayQueue::clearPlayQueue May 08 16:30:47 volumio volumio[853]: info: CorePlayQueue::saveQueue May 08 16:30:47 volumio volumio[853]: info: CoreCommandRouter::volumioPushQueue May 08 16:30:47 volumio volumio[853]: info: CoreStateMachine::addQueueItems May 08 16:30:47 volumio volumio[853]: info: CorePlayQueue::addQueueItems May 08 16:30:47 volumio volumio[853]: info: Preload queue cleared May 08 16:30:47 volumio volumio[853]: info: Adding Item to queue: music-library/NAS/FLAC/Blur - The Great Escape [2CD Special Japanese Edition 2012] May 08 16:30:47 volumio volumio[853]: info: Exploding uri music-library/NAS/FLAC/Blur - The Great Escape [2CD Special Japanese Edition 2012] in service mpd May 08 16:30:47 volumio volumio[853]: info: ALBUMART /albumart?cacheid=771&web=Blur/The%20Great%20Escape%20(Disc%20One)/extralarge&path=%2Fmnt%2FNAS%2FFLAC%2FBlur%20-%20The%20Great%20Escape%20%5B2CD%20Special%20Japanese%20Edition%202012%5D%2FBlur%20-%20The%20Great%20Escape%20(Disc%20One).cue&metadata=false May 08 16:30:47 volumio volumio[853]: info: URI /mnt/NAS/FLAC/Blur - The Great Escape [2CD Special Japanese Edition 2012]/Blur - The Great Escape (Disc One).cue May 08 16:30:47 volumio volumio[853]: info: ALBUMART /albumart?cacheid=771&web=Blur/The%20Great%20Escape%20(Disc%20One)/extralarge&path=%2Fmnt%2FNAS%2FFLAC%2FBlur%20-%20The%20Great%20Escape%20%5B2CD%20Special%20Japanese%20Edition%202012%5D%2FBlur%20-%20The%20Great%20Escape%20(Disc%20One).cue&metadata=false May 08 16:30:47 volumio volumio[853]: info: URI /mnt/NAS/FLAC/Blur - The Great Escape [2CD Special Japanese Edition 2012]/Blur - The Great Escape (Disc One).cue May 08 16:30:47 volumio volumio[853]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 16:30:47 volumio volumio[853]: Error: Unable to resolve or reject the same promise twice May 08 16:30:47 volumio volumio[853]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 08 16:30:47 volumio volumio[853]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 May 08 16:30:47 volumio volumio[853]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) May 08 16:30:47 volumio volumio[853]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) May 08 16:30:47 volumio volumio[853]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) May 08 16:30:47 volumio volumio[853]: at Socket.emit (events.js:315:20) May 08 16:30:47 volumio volumio[853]: at addChunk (internal/streams/readable.js:309:12) May 08 16:30:47 volumio volumio[853]: at readableAddChunk (internal/streams/readable.js:280:11) May 08 16:30:47 volumio volumio[853]: at Socket.Readable.push (internal/streams/readable.js:223:10) May 08 16:30:47 volumio volumio[853]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) May 08 16:30:47 volumio volumio[853]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 16:30:48 volumio sudo[1769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-08 16:29 May 08 16:30:48 volumio sudo[1769]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"