-- 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"