-- Logs begin at Sat 2021-10-09 13:53:32 UTC, end at Sat 2021-10-09 13:57:37 UTC. -- Oct 09 13:56:45 volumio sudo[1299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 09 13:56:45 volumio sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 09 13:56:45 volumio sudo[1299]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 13:56:45 volumio sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 13:56:46 volumio sudo[1299]: pam_unix(sudo:session): session closed for user root Oct 09 13:56:46 volumio sudo[1301]: pam_unix(sudo:session): session closed for user root Oct 09 13:56:46 volumio sudo[1321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 09 13:56:46 volumio sudo[1321]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 13:56:46 volumio sudo[1323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 09 13:56:47 volumio sudo[1323]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 13:56:47 volumio sudo[1321]: pam_unix(sudo:session): session closed for user root Oct 09 13:56:47 volumio sudo[1323]: pam_unix(sudo:session): session closed for user root Oct 09 13:56:48 volumio volumio[946]: info: CoreCommandRouter::volumioGetState Oct 09 13:56:48 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 09 13:56:48 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 09 13:56:48 volumio volumio[946]: info: Listing playlists Oct 09 13:56:48 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 09 13:56:48 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 09 13:56:48 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 09 13:56:48 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 09 13:57:00 volumio sudo[1388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 09 13:57:00 volumio sudo[1388]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 13:57:00 volumio sudo[1390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 09 13:57:00 volumio sudo[1390]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 13:57:00 volumio sudo[1388]: pam_unix(sudo:session): session closed for user root Oct 09 13:57:00 volumio sudo[1390]: pam_unix(sudo:session): session closed for user root Oct 09 13:57:00 volumio volumio[946]: info: CoreCommandRouter::volumioGetState Oct 09 13:57:00 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 09 13:57:00 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 09 13:57:00 volumio volumio[946]: info: Listing playlists Oct 09 13:57:00 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 09 13:57:00 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 09 13:57:00 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 09 13:57:00 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 09 13:57:09 volumio volumio[946]: info: CoreCommandRouter::volumioGetQueue Oct 09 13:57:09 volumio volumio[946]: info: CoreStateMachine::getQueue Oct 09 13:57:09 volumio volumio[946]: info: CorePlayQueue::getQueue Oct 09 13:57:14 volumio volumio[946]: info: CoreCommandRouter::volumioGetVisibleSources Oct 09 13:57:14 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 09 13:57:16 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 09 13:57:16 volumio volumio[946]: info: CURURI: playlists Oct 09 13:57:16 volumio volumio[946]: info: Listing playlists Oct 09 13:57:18 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 09 13:57:18 volumio volumio[946]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 09 13:57:18 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 09 13:57:18 volumio volumio[946]: info: Received Get System Version Oct 09 13:57:18 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 09 13:57:20 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 09 13:57:20 volumio volumio[946]: info: CURURI: music-library Oct 09 13:57:21 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 09 13:57:21 volumio volumio[946]: info: CURURI: music-library/USB Oct 09 13:57:23 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 09 13:57:23 volumio volumio[946]: info: CURURI: music-library/USB/nas_music Oct 09 13:57:27 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 09 13:57:27 volumio volumio[946]: info: CURURI: music-library/USB/nas_music/George Winston Oct 09 13:57:30 volumio volumio[946]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 09 13:57:30 volumio volumio[946]: info: CoreStateMachine::ClearQueue Oct 09 13:57:31 volumio volumio[946]: info: CoreStateMachine::stop Oct 09 13:57:31 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 13:57:31 volumio volumio[946]: verbose: UNSET VOLATILE Oct 09 13:57:31 volumio volumio[946]: info: CorePlayQueue::clearPlayQueue Oct 09 13:57:31 volumio volumio[946]: info: CorePlayQueue::saveQueue Oct 09 13:57:31 volumio volumio[946]: info: CoreCommandRouter::volumioPushState Oct 09 13:57:31 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 13:57:31 volumio volumio[946]: info: CoreCommandRouter::volumioPushQueue Oct 09 13:57:31 volumio volumio[946]: info: CoreStateMachine::addQueueItems Oct 09 13:57:31 volumio volumio[946]: info: CorePlayQueue::addQueueItems Oct 09 13:57:31 volumio volumio[946]: info: Adding Item to queue: music-library/USB/nas_music/George Winston/Winter Into Spring Oct 09 13:57:31 volumio volumio[946]: info: Exploding uri music-library/USB/nas_music/George Winston/Winter Into Spring in service mpd Oct 09 13:57:31 volumio volumio[946]: info: ALBUMART /albumart?cacheid=815&web=George%20Winston/Winter%20Into%20Spring/extralarge&path=%2Fmnt%2FUSB%2Fnas_music%2FGeorge%20Winston%2FWinter%20Into%20Spring%2F01%2001_January%20Stars.flac&metadata=false Oct 09 13:57:31 volumio volumio[946]: info: URI /mnt/USB/nas_music/George Winston/Winter Into Spring/01 01_January Stars.flac Oct 09 13:57:31 volumio volumio[946]: info: ALBUMART /albumart?cacheid=815&web=George%20Winston/Winter%20Into%20Spring/extralarge&path=%2Fmnt%2FUSB%2Fnas_music%2FGeorge%20Winston%2FWinter%20Into%20Spring%2F02%2002_February%20Sea.flac&metadata=false Oct 09 13:57:31 volumio volumio[946]: info: URI /mnt/USB/nas_music/George Winston/Winter Into Spring/02 02_February Sea.flac Oct 09 13:57:31 volumio volumio[946]: info: ALBUMART /albumart?cacheid=815&web=George%20Winston/Winter%20Into%20Spring/extralarge&path=%2Fmnt%2FUSB%2Fnas_music%2FGeorge%20Winston%2FWinter%20Into%20Spring%2F03%2003_Ocean%20Waves%20(O%20Mar).flac&metadata=false Oct 09 13:57:31 volumio volumio[946]: info: URI /mnt/USB/nas_music/George Winston/Winter Into Spring/03 03_Ocean Waves (O Mar).flac Oct 09 13:57:31 volumio volumio[946]: info: ALBUMART /albumart?cacheid=815&web=George%20Winston/Winter%20Into%20Spring/extralarge&path=%2Fmnt%2FUSB%2Fnas_music%2FGeorge%20Winston%2FWinter%20Into%20Spring%2F04%2004_Reflection.flac&metadata=false Oct 09 13:57:31 volumio volumio[946]: info: URI /mnt/USB/nas_music/George Winston/Winter Into Spring/04 04_Reflection.flac Oct 09 13:57:31 volumio volumio[946]: info: ALBUMART /albumart?cacheid=815&web=George%20Winston/Winter%20Into%20Spring/extralarge&path=%2Fmnt%2FUSB%2Fnas_music%2FGeorge%20Winston%2FWinter%20Into%20Spring%2F05%2005_Rain.flac&metadata=false Oct 09 13:57:31 volumio volumio[946]: info: URI /mnt/USB/nas_music/George Winston/Winter Into Spring/05 05_Rain.flac Oct 09 13:57:31 volumio volumio[946]: info: ALBUMART /albumart?cacheid=815&web=George%20Winston/Winter%20Into%20Spring/extralarge&path=%2Fmnt%2FUSB%2Fnas_music%2FGeorge%20Winston%2FWinter%20Into%20Spring%2F06%2006_Blossom%EF%BC%8FMeadow.flac&metadata=false Oct 09 13:57:31 volumio volumio[946]: info: URI /mnt/USB/nas_music/George Winston/Winter Into Spring/06 06_Blossom／Meadow.flac Oct 09 13:57:31 volumio volumio[946]: info: ALBUMART /albumart?cacheid=815&web=George%20Winston/Winter%20Into%20Spring/extralarge&path=%2Fmnt%2FUSB%2Fnas_music%2FGeorge%20Winston%2FWinter%20Into%20Spring%2F07%2007_The%20Venice%20Dreamer%EF%BC%9APart%20one%20-%20Introduction.flac&metadata=false Oct 09 13:57:31 volumio volumio[946]: info: URI /mnt/USB/nas_music/George Winston/Winter Into Spring/07 07_The Venice Dreamer：Part one - Introduction.flac Oct 09 13:57:31 volumio volumio[946]: info: ALBUMART /albumart?cacheid=815&web=George%20Winston/Winter%20Into%20Spring/extralarge&path=%2Fmnt%2FUSB%2Fnas_music%2FGeorge%20Winston%2FWinter%20Into%20Spring%2F08%2008_The%20Venice%20Dreamer%EF%BC%9APart%20two.flac&metadata=false Oct 09 13:57:31 volumio volumio[946]: info: URI /mnt/USB/nas_music/George Winston/Winter Into Spring/08 08_The Venice Dreamer：Part two.flac Oct 09 13:57:31 volumio volumio[946]: info: ALBUMART /albumart?cacheid=815&web=George%20Winston/Winter%20Into%20Spring/extralarge&path=%2Fmnt%2FUSB%2Fnas_music%2FGeorge%20Winston%2FWinter%20Into%20Spring%2F09%2009_(Love%20Echoes%20in%20the)%20Pine%20Hills.flac&metadata=false Oct 09 13:57:31 volumio volumio[946]: info: URI /mnt/USB/nas_music/George Winston/Winter Into Spring/09 09_(Love Echoes in the) Pine Hills.flac Oct 09 13:57:31 volumio volumio[946]: info: CorePlayQueue::saveQueue Oct 09 13:57:31 volumio volumio[946]: info: CoreCommandRouter::volumioPushQueue Oct 09 13:57:31 volumio volumio[946]: info: CoreStateMachine::updateTrackBlock Oct 09 13:57:31 volumio volumio[946]: info: CorePlayQueue::getTrackBlock Oct 09 13:57:31 volumio volumio[946]: info: CoreCommandRouter::volumioPlay Oct 09 13:57:31 volumio volumio[946]: verbose: UNSET VOLATILE Oct 09 13:57:31 volumio volumio[946]: info: CoreStateMachine::play index 0 Oct 09 13:57:31 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 13:57:31 volumio volumio[946]: info: CoreStateMachine::stop Oct 09 13:57:31 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 13:57:31 volumio volumio[946]: verbose: UNSET VOLATILE Oct 09 13:57:31 volumio volumio[946]: info: CoreStateMachine::play index undefined Oct 09 13:57:31 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 13:57:31 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 09 13:57:31 volumio volumio[946]: info: CoreStateMachine::startPlaybackTimer Oct 09 13:57:31 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 09 13:57:31 volumio volumio[946]: verbose: ControllerMpd::clearAddPlayTracks USB/nas_music/George Winston/Winter Into Spring/01 01_January Stars.flac Oct 09 13:57:31 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Oct 09 13:57:31 volumio volumio[946]: info: sendMpdCommand stop took 29 milliseconds Oct 09 13:57:31 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand clear Oct 09 13:57:31 volumio volumio[946]: info: Oct 09 13:57:31 volumio volumio[946]: ---------------------------- MPD announces system playlist update Oct 09 13:57:31 volumio volumio[946]: info: Ignoring MPD Status Update Oct 09 13:57:31 volumio volumio[946]: info: sendMpdCommand clear took 45 milliseconds Oct 09 13:57:31 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand add "USB/nas_music/George Winston/Winter Into Spring/01 01_January Stars.flac" Oct 09 13:57:31 volumio volumio[946]: error: updateQueue error: null Oct 09 13:57:31 volumio volumio[946]: info: Oct 09 13:57:31 volumio volumio[946]: ---------------------------- MPD announces system playlist update Oct 09 13:57:31 volumio volumio[946]: info: Ignoring MPD Status Update Oct 09 13:57:31 volumio volumio[946]: info: ------------------------------ 57ms Oct 09 13:57:31 volumio volumio[946]: info: sendMpdCommand add "USB/nas_music/George Winston/Winter Into Spring/01 01_January Stars.flac" took 35 milliseconds Oct 09 13:57:31 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand play Oct 09 13:57:31 volumio volumio[946]: info: ------------------------------ 110ms Oct 09 13:57:31 volumio volumio[946]: info: sendMpdCommand play took 89 milliseconds Oct 09 13:57:31 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Oct 09 13:57:31 volumio volumio[946]: info: Oct 09 13:57:31 volumio volumio[946]: ---------------------------- MPD announces state update: player Oct 09 13:57:31 volumio volumio[946]: info: ControllerMpd::getState Oct 09 13:57:31 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status Oct 09 13:57:31 volumio volumio[946]: info: Oct 09 13:57:31 volumio volumio[946]: ---------------------------- MPD announces state update: player Oct 09 13:57:31 volumio volumio[946]: info: ControllerMpd::getState Oct 09 13:57:31 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status Oct 09 13:57:31 volumio volumio[946]: info: sendMpdCommand status took 96 milliseconds Oct 09 13:57:32 volumio volumio[946]: verbose: ControllerMpd::parseState Oct 09 13:57:32 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 09 13:57:32 volumio volumio[946]: info: sendMpdCommand status took 84 milliseconds Oct 09 13:57:32 volumio volumio[946]: info: sendMpdCommand playlistinfo took 30 milliseconds Oct 09 13:57:32 volumio volumio[946]: verbose: ControllerMpd::parseState Oct 09 13:57:32 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 09 13:57:32 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo Oct 09 13:57:32 volumio volumio[946]: info: ControllerMpd::pushState Oct 09 13:57:32 volumio volumio[946]: info: CoreCommandRouter::servicePushState Oct 09 13:57:32 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 09 13:57:32 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":397,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"01_January Stars","artist":"George Winston","album":"Winter Into Spring","uri":"USB/nas_music/George Winston/Winter Into Spring/01 01_January Stars.flac","trackType":"flac"} Oct 09 13:57:32 volumio volumio[946]: verbose: CURRENT POSITION 0 Oct 09 13:57:32 volumio volumio[946]: info: CoreStateMachine::syncState stateService play Oct 09 13:57:32 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus stop Oct 09 13:57:32 volumio volumio[946]: info: CoreStateMachine::pushState Oct 09 13:57:32 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 09 13:57:32 volumio volumio[946]: info: CoreCommandRouter::volumioPushState Oct 09 13:57:32 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 13:57:32 volumio volumio[946]: info: ------------------------------ 418ms Oct 09 13:57:32 volumio volumio[946]: info: sendMpdCommand playlistinfo took 201 milliseconds Oct 09 13:57:32 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo Oct 09 13:57:32 volumio volumio[946]: info: ControllerMpd::pushState Oct 09 13:57:32 volumio volumio[946]: info: CoreCommandRouter::servicePushState Oct 09 13:57:32 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 09 13:57:32 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":557,"duration":397,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"01_January Stars","artist":"George Winston","album":"Winter Into Spring","uri":"USB/nas_music/George Winston/Winter Into Spring/01 01_January Stars.flac","trackType":"flac"} Oct 09 13:57:32 volumio volumio[946]: verbose: CURRENT POSITION 0 Oct 09 13:57:32 volumio volumio[946]: info: CoreStateMachine::syncState stateService play Oct 09 13:57:32 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play Oct 09 13:57:32 volumio volumio[946]: info: Received an update from plugin. extracting info from payload Oct 09 13:57:32 volumio volumio[946]: info: CoreStateMachine::pushState Oct 09 13:57:32 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 09 13:57:32 volumio volumio[946]: info: CoreCommandRouter::volumioPushState Oct 09 13:57:32 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 13:57:32 volumio volumio[946]: info: CoreStateMachine::pushState Oct 09 13:57:32 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 09 13:57:32 volumio volumio[946]: info: CoreCommandRouter::volumioPushState Oct 09 13:57:32 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 13:57:32 volumio volumio[946]: info: ------------------------------ 590ms Oct 09 13:57:33 volumio volumio[946]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 09 13:57:33 volumio volumio[946]: { Error: certificate is not yet valid Oct 09 13:57:33 volumio volumio[946]: at TLSSocket. (_tls_wrap.js:1105:38) Oct 09 13:57:33 volumio volumio[946]: at emitNone (events.js:106:13) Oct 09 13:57:33 volumio volumio[946]: at TLSSocket.emit (events.js:208:7) Oct 09 13:57:33 volumio volumio[946]: at TLSSocket._finishInit (_tls_wrap.js:639:8) Oct 09 13:57:33 volumio volumio[946]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:469:38) code: 'CERT_NOT_YET_VALID' } Oct 09 13:57:33 volumio volumio[946]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 09 13:57:37 volumio sudo[1489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2021-10-09 13:56 Oct 09 13:57:37 volumio sudo[1489]: 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"