-- Logs begin at Mon 2022-08-01 11:24:56 UTC, end at Mon 2022-09-26 13:28:52 UTC. -- Sep 26 13:27:35 raspi02 volumio[748]: info: CorePlayQueue::getTrack 0 Sep 26 13:27:35 raspi02 volumio[748]: info: CorePlayQueue::getTrack 1 Sep 26 13:27:35 raspi02 volumio[748]: info: Prefetching next song Sep 26 13:27:35 raspi02 volumio[748]: info: DOING PREFETCH IN MPD Sep 26 13:27:35 raspi02 volumio[748]: verbose: ControllerMpd::sendMpdCommand add "NAS/Skywalker/Slayer/Reign In Blood [CD]/02 - Piece By Piece.flac" Sep 26 13:27:35 raspi02 volumio[748]: info: Sep 26 13:27:35 raspi02 volumio[748]: ---------------------------- MPD announces system playlist update Sep 26 13:27:35 raspi02 volumio[748]: info: Ignoring MPD Status Update Sep 26 13:27:35 raspi02 volumio[748]: info: sendMpdCommand add "NAS/Skywalker/Slayer/Reign In Blood [CD]/02 - Piece By Piece.flac" took 43 milliseconds Sep 26 13:27:35 raspi02 volumio[748]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 26 13:27:35 raspi02 volumio[748]: info: ------------------------------ 77ms Sep 26 13:27:35 raspi02 volumio[748]: info: sendMpdCommand consume 1 took 54 milliseconds Sep 26 13:27:37 raspi02 volumio[748]: info: VolumeController::SetAlsaVolume50 Sep 26 13:27:37 raspi02 volumio[748]: info: CoreStateMachine::pushState Sep 26 13:27:37 raspi02 volumio[748]: info: CorePlayQueue::getTrack 0 Sep 26 13:27:37 raspi02 volumio[748]: info: CoreCommandRouter::volumioPushState Sep 26 13:27:37 raspi02 volumio[748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 26 13:27:40 raspi02 volumio[748]: info: CoreStateMachine::startPlaybackTimer Sep 26 13:27:40 raspi02 volumio[748]: info: CorePlayQueue::getTrack 1 Sep 26 13:27:40 raspi02 volumio[748]: info: Sep 26 13:27:40 raspi02 volumio[748]: ---------------------------- MPD announces system playlist update Sep 26 13:27:40 raspi02 volumio[748]: info: Ignoring MPD Status Update Sep 26 13:27:40 raspi02 volumio[748]: info: Sep 26 13:27:40 raspi02 volumio[748]: ---------------------------- MPD announces state update: player Sep 26 13:27:40 raspi02 volumio[748]: info: ControllerMpd::getState Sep 26 13:27:40 raspi02 volumio[748]: verbose: ControllerMpd::sendMpdCommand status Sep 26 13:27:40 raspi02 volumio[748]: info: ------------------------------ 29ms Sep 26 13:27:40 raspi02 volumio[748]: info: sendMpdCommand status took 11 milliseconds Sep 26 13:27:40 raspi02 volumio[748]: verbose: ControllerMpd::parseState Sep 26 13:27:40 raspi02 volumio[748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 26 13:27:40 raspi02 volumio[748]: info: sendMpdCommand playlistinfo took 17 milliseconds Sep 26 13:27:40 raspi02 volumio[748]: verbose: ControllerMpd::parseTrackInfo Sep 26 13:27:40 raspi02 volumio[748]: info: ControllerMpd::pushState Sep 26 13:27:40 raspi02 volumio[748]: info: CoreCommandRouter::servicePushState Sep 26 13:27:40 raspi02 volumio[748]: info: CorePlayQueue::getTrack 1 Sep 26 13:27:40 raspi02 volumio[748]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":123,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1033 Kbps","isStreaming":false,"title":"Piece By Piece","artist":"Slayer","album":"Reign In Blood","uri":"NAS/Skywalker/Slayer/Reign In Blood [CD]/02 - Piece By Piece.flac","trackType":"flac"} Sep 26 13:27:40 raspi02 volumio[748]: verbose: CURRENT POSITION 1 Sep 26 13:27:40 raspi02 volumio[748]: info: CoreStateMachine::syncState stateService play Sep 26 13:27:40 raspi02 volumio[748]: info: CoreStateMachine::syncState currentStatus play Sep 26 13:27:40 raspi02 volumio[748]: info: Received an update from plugin. extracting info from payload Sep 26 13:27:40 raspi02 volumio[748]: info: CoreStateMachine::pushState Sep 26 13:27:40 raspi02 volumio[748]: info: CorePlayQueue::getTrack 1 Sep 26 13:27:40 raspi02 volumio[748]: info: CoreCommandRouter::volumioPushState Sep 26 13:27:40 raspi02 volumio[748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 26 13:27:40 raspi02 volumio[748]: info: CoreStateMachine::pushState Sep 26 13:27:40 raspi02 volumio[748]: info: CorePlayQueue::getTrack 1 Sep 26 13:27:40 raspi02 volumio[748]: info: CoreCommandRouter::volumioPushState Sep 26 13:27:40 raspi02 volumio[748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 26 13:27:40 raspi02 volumio[748]: info: ------------------------------ 183ms Sep 26 13:27:41 raspi02 volumio[748]: info: CoreCommandRouter::volumioPause Sep 26 13:27:41 raspi02 volumio[748]: info: CoreStateMachine::pause Sep 26 13:27:41 raspi02 volumio[748]: info: CoreStateMachine::stPlaybackTimer Sep 26 13:27:41 raspi02 volumio[748]: info: CoreStateMachine::servicePause Sep 26 13:27:41 raspi02 volumio[748]: info: CorePlayQueue::getTrack 1 Sep 26 13:27:41 raspi02 volumio[748]: info: CoreCommandRouter::servicePause Sep 26 13:27:41 raspi02 volumio[748]: info: ControllerMpd::pause Sep 26 13:27:41 raspi02 volumio[748]: verbose: ControllerMpd::sendMpdCommand pause Sep 26 13:27:41 raspi02 volumio[748]: info: Sep 26 13:27:41 raspi02 volumio[748]: ---------------------------- MPD announces state update: player Sep 26 13:27:41 raspi02 volumio[748]: info: sendMpdCommand pause took 104 milliseconds Sep 26 13:27:41 raspi02 volumio[748]: info: ControllerMpd::getState Sep 26 13:27:41 raspi02 volumio[748]: verbose: ControllerMpd::sendMpdCommand status Sep 26 13:27:41 raspi02 volumio[748]: info: sendMpdCommand status took 12 milliseconds Sep 26 13:27:41 raspi02 volumio[748]: verbose: ControllerMpd::parseState Sep 26 13:27:41 raspi02 volumio[748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 26 13:27:41 raspi02 volumio[748]: info: sendMpdCommand playlistinfo took 19 milliseconds Sep 26 13:27:41 raspi02 volumio[748]: verbose: ControllerMpd::parseTrackInfo Sep 26 13:27:41 raspi02 volumio[748]: info: ControllerMpd::pushState Sep 26 13:27:41 raspi02 volumio[748]: info: CoreCommandRouter::servicePushState Sep 26 13:27:41 raspi02 volumio[748]: info: CorePlayQueue::getTrack 1 Sep 26 13:27:41 raspi02 volumio[748]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1253,"duration":123,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"921 Kbps","isStreaming":false,"title":"Piece By Piece","artist":"Slayer","album":"Reign In Blood","uri":"NAS/Skywalker/Slayer/Reign In Blood [CD]/02 - Piece By Piece.flac","trackType":"flac"} Sep 26 13:27:41 raspi02 volumio[748]: verbose: CURRENT POSITION 1 Sep 26 13:27:41 raspi02 volumio[748]: info: CoreStateMachine::syncState stateService pause Sep 26 13:27:41 raspi02 volumio[748]: info: CoreStateMachine::syncState currentStatus pause Sep 26 13:27:41 raspi02 volumio[748]: info: CoreStateMachine::pushState Sep 26 13:27:41 raspi02 volumio[748]: info: CorePlayQueue::getTrack 1 Sep 26 13:27:41 raspi02 volumio[748]: info: CoreCommandRouter::volumioPushState Sep 26 13:27:41 raspi02 volumio[748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 26 13:27:41 raspi02 volumio[748]: info: CoreStateMachine::stPlaybackTimer Sep 26 13:27:41 raspi02 volumio[748]: info: ------------------------------ 124ms Sep 26 13:27:45 raspi02 volumio[748]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/A%20Perfect%20Circle/1fe60489-7410-4007-8871-7ee0eb05f4b6.jpg' Sep 26 13:27:46 raspi02 volumio[748]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Silent%20Civilian/a9a7c26e-657c-4d6e-b34f-9a6fb2e869ce.jpg' Sep 26 13:27:46 raspi02 volumio[748]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Arch%20Enemy/5d7969d2-eaff-48b3-bee2-143a770521dc.jpg' Sep 26 13:27:48 raspi02 volumio[748]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 26 13:27:48 raspi02 volumio[748]: info: CURURI: artists://Sentenced Sep 26 13:27:49 raspi02 volumio[748]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Halestorm/19ceea80-d648-48ba-98c6-dc48681a93b4.jpg' Sep 26 13:27:51 raspi02 volumio[748]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ministry/97e0a223-8a95-4008-9cc1-5069e6c73082.jpg' Sep 26 13:27:55 raspi02 volumio[748]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Youssou%20N%27dour%20%26%20Neneh%20Cherry/d1ed6d7c-64d2-4ce1-8536-976223d8a78e.jpg' Sep 26 13:28:03 raspi02 volumio[748]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 26 13:28:03 raspi02 volumio[748]: info: CURURI: artists://Sentenced/The%20Cold%20White%20Light Sep 26 13:28:09 raspi02 volumio[748]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 26 13:28:09 raspi02 volumio[748]: info: CURURI: artists://Sentenced/The%20Funeral%20Album Sep 26 13:28:31 raspi02 volumio[748]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 26 13:28:31 raspi02 volumio[748]: info: CURURI: artists:// Sep 26 13:28:32 raspi02 volumio[748]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/A%20Perfect%20Circle/1fe60489-7410-4007-8871-7ee0eb05f4b6.jpg' Sep 26 13:28:32 raspi02 volumio[748]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Arch%20Enemy/5d7969d2-eaff-48b3-bee2-143a770521dc.jpg' Sep 26 13:28:36 raspi02 volumio[748]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Halestorm/19ceea80-d648-48ba-98c6-dc48681a93b4.jpg' Sep 26 13:28:37 raspi02 volumio[748]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ministry/97e0a223-8a95-4008-9cc1-5069e6c73082.jpg' Sep 26 13:28:39 raspi02 volumio[748]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Silent%20Civilian/a9a7c26e-657c-4d6e-b34f-9a6fb2e869ce.jpg' Sep 26 13:28:40 raspi02 volumio[748]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Youssou%20N%27dour%20%26%20Neneh%20Cherry/d1ed6d7c-64d2-4ce1-8536-976223d8a78e.jpg' Sep 26 13:28:45 raspi02 volumio[748]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 26 13:28:45 raspi02 volumio[748]: info: CURURI: artists://Aborted Sep 26 13:28:46 raspi02 volumio[748]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 26 13:28:46 raspi02 kernel: Under-voltage detected! (0x00050005) Sep 26 13:28:46 raspi02 volumio[748]: { Error: certificate is not yet valid Sep 26 13:28:46 raspi02 volumio[748]: at TLSSocket. (_tls_wrap.js:1105:38) Sep 26 13:28:46 raspi02 volumio[748]: at emitNone (events.js:106:13) Sep 26 13:28:46 raspi02 volumio[748]: at TLSSocket.emit (events.js:208:7) Sep 26 13:28:46 raspi02 volumio[748]: at TLSSocket._finishInit (_tls_wrap.js:639:8) Sep 26 13:28:46 raspi02 volumio[748]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:469:38) code: 'CERT_NOT_YET_VALID' } Sep 26 13:28:46 raspi02 volumio[748]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 26 13:28:52 raspi02 sudo[18321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2022-09-26 13:27 Sep 26 13:28:52 raspi02 sudo[18321]: 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="14acf028cf46278bd3fd3dd02523424a9bc825bf" VOLUMIO_FE_VERSION="74f64fbba9dc8053dc27ed7125863d6ad6533965" VOLUMIO_BE_VERSION="0a6008ded215bf284d5d4cd9c4df3333cb320899" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Apr 24 17:39:58 CEST 2021" VOLUMIO_VERSION="2.882" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="1a98a01eef5aa5927a5874c2d3c1b0dc"