-- Logs begin at Sat 2021-04-24 15:39:58 UTC, end at Tue 2021-04-27 21:38:43 UTC. -- Apr 27 21:38:36 steves-volumio volumio[1116]: info: CorePlayQueue::getTrack 4 Apr 27 21:38:36 steves-volumio volumio[1116]: info: CorePlayQueue::getTrack 5 Apr 27 21:38:36 steves-volumio volumio[1116]: info: Prefetching next song Apr 27 21:38:36 steves-volumio volumio[1116]: info: DOING PREFETCH IN MPD Apr 27 21:38:36 steves-volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand add "USB/Classic 2/Choral and Opera/Opera/Emma Kirkby Songs and Arias/01 tornami a vagheggiar.wma" Apr 27 21:38:36 steves-volumio volumio[1116]: info: sendMpdCommand add "USB/Classic 2/Choral and Opera/Opera/Emma Kirkby Songs and Arias/01 tornami a vagheggiar.wma" took 1 milliseconds Apr 27 21:38:36 steves-volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand consume 1 Apr 27 21:38:36 steves-volumio volumio[1116]: info: Apr 27 21:38:36 steves-volumio volumio[1116]: ---------------------------- MPD announces system playlist update Apr 27 21:38:36 steves-volumio volumio[1116]: info: Ignoring MPD Status Update Apr 27 21:38:36 steves-volumio volumio[1116]: info: sendMpdCommand consume 1 took 1 milliseconds Apr 27 21:38:36 steves-volumio volumio[1116]: info: ------------------------------ 2ms Apr 27 21:38:40 steves-volumio volumio[1116]: info: CoreStateMachine::startPlaybackTimer Apr 27 21:38:40 steves-volumio volumio[1116]: info: CorePlayQueue::getTrack 5 Apr 27 21:38:41 steves-volumio volumio[1116]: info: Apr 27 21:38:41 steves-volumio volumio[1116]: ---------------------------- MPD announces system playlist update Apr 27 21:38:41 steves-volumio volumio[1116]: info: Ignoring MPD Status Update Apr 27 21:38:41 steves-volumio volumio[1116]: info: Apr 27 21:38:41 steves-volumio volumio[1116]: ---------------------------- MPD announces state update: player Apr 27 21:38:41 steves-volumio volumio[1116]: info: ControllerMpd::getState Apr 27 21:38:41 steves-volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status Apr 27 21:38:42 steves-volumio volumio[1116]: info: ------------------------------ 504ms Apr 27 21:38:42 steves-volumio volumio[1116]: info: sendMpdCommand status took 503 milliseconds Apr 27 21:38:42 steves-volumio volumio[1116]: verbose: ControllerMpd::parseState Apr 27 21:38:42 steves-volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 21:38:42 steves-volumio volumio[1116]: info: Apr 27 21:38:42 steves-volumio volumio[1116]: ---------------------------- MPD announces state update: player Apr 27 21:38:42 steves-volumio volumio[1116]: info: ControllerMpd::getState Apr 27 21:38:42 steves-volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status Apr 27 21:38:42 steves-volumio volumio[1116]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 27 21:38:42 steves-volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo Apr 27 21:38:42 steves-volumio volumio[1116]: info: ControllerMpd::pushState Apr 27 21:38:42 steves-volumio volumio[1116]: info: CoreCommandRouter::servicePushState Apr 27 21:38:42 steves-volumio volumio[1116]: info: CorePlayQueue::getTrack 5 Apr 27 21:38:42 steves-volumio volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tornami a vagheggiar","artist":"Emma Kirkby","album":"The sweet sound of Emma Kirkby","uri":"USB/Classic 2/Choral and Opera/Opera/Emma Kirkby Songs and Arias/01 tornami a vagheggiar.wma","trackType":"wma"} Apr 27 21:38:42 steves-volumio volumio[1116]: verbose: CURRENT POSITION 5 Apr 27 21:38:42 steves-volumio volumio[1116]: info: CoreStateMachine::syncState stateService play Apr 27 21:38:42 steves-volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus play Apr 27 21:38:42 steves-volumio volumio[1116]: info: Received an update from plugin. extracting info from payload Apr 27 21:38:42 steves-volumio volumio[1116]: info: CoreStateMachine::pushState Apr 27 21:38:42 steves-volumio volumio[1116]: info: CorePlayQueue::getTrack 5 Apr 27 21:38:42 steves-volumio volumio[1116]: info: CoreCommandRouter::volumioPushState Apr 27 21:38:42 steves-volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:38:42 steves-volumio volumio[1116]: info: CoreStateMachine::pushState Apr 27 21:38:42 steves-volumio volumio[1116]: info: CorePlayQueue::getTrack 5 Apr 27 21:38:42 steves-volumio volumio[1116]: info: CoreCommandRouter::volumioPushState Apr 27 21:38:42 steves-volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:38:42 steves-volumio volumio[1116]: info: ------------------------------ 524ms Apr 27 21:38:42 steves-volumio volumio[1116]: info: sendMpdCommand status took 19 milliseconds Apr 27 21:38:42 steves-volumio volumio[1116]: verbose: ControllerMpd::parseState Apr 27 21:38:42 steves-volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 21:38:42 steves-volumio volumio[1116]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 27 21:38:42 steves-volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo Apr 27 21:38:42 steves-volumio volumio[1116]: info: ControllerMpd::pushState Apr 27 21:38:42 steves-volumio volumio[1116]: info: CoreCommandRouter::servicePushState Apr 27 21:38:42 steves-volumio volumio[1116]: info: CorePlayQueue::getTrack 5 Apr 27 21:38:42 steves-volumio volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":440,"duration":268,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tornami a vagheggiar","artist":"Emma Kirkby","album":"The sweet sound of Emma Kirkby","uri":"USB/Classic 2/Choral and Opera/Opera/Emma Kirkby Songs and Arias/01 tornami a vagheggiar.wma","trackType":"wma"} Apr 27 21:38:42 steves-volumio volumio[1116]: verbose: CURRENT POSITION 5 Apr 27 21:38:42 steves-volumio volumio[1116]: info: CoreStateMachine::syncState stateService play Apr 27 21:38:42 steves-volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus play Apr 27 21:38:42 steves-volumio volumio[1116]: info: Received an update from plugin. extracting info from payload Apr 27 21:38:42 steves-volumio volumio[1116]: info: CoreStateMachine::pushState Apr 27 21:38:42 steves-volumio volumio[1116]: info: CorePlayQueue::getTrack 5 Apr 27 21:38:42 steves-volumio volumio[1116]: info: CoreCommandRouter::volumioPushState Apr 27 21:38:42 steves-volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:38:42 steves-volumio volumio[1116]: info: CoreStateMachine::pushState Apr 27 21:38:42 steves-volumio volumio[1116]: info: CorePlayQueue::getTrack 5 Apr 27 21:38:42 steves-volumio volumio[1116]: info: CoreCommandRouter::volumioPushState Apr 27 21:38:42 steves-volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:38:42 steves-volumio volumio[1116]: info: ------------------------------ 55ms Apr 27 21:38:42 steves-volumio volumio[1116]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 21:38:42 steves-volumio volumio[1116]: { Error: certificate is not yet valid Apr 27 21:38:42 steves-volumio volumio[1116]: at TLSSocket. (_tls_wrap.js:1105:38) Apr 27 21:38:42 steves-volumio volumio[1116]: at emitNone (events.js:106:13) Apr 27 21:38:42 steves-volumio volumio[1116]: at TLSSocket.emit (events.js:208:7) Apr 27 21:38:42 steves-volumio volumio[1116]: at TLSSocket._finishInit (_tls_wrap.js:639:8) Apr 27 21:38:42 steves-volumio volumio[1116]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:469:38) code: 'CERT_NOT_YET_VALID' } Apr 27 21:38:42 steves-volumio volumio[1116]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 21:38:43 steves-volumio sudo[12141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2021-04-27 21:37 Apr 27 21:38:43 steves-volumio sudo[12141]: 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"