-- Logs begin at Sat 2021-10-09 11:18:42 UTC, end at Sat 2021-10-09 11:56:22 UTC. -- Oct 09 11:55:51 volumio sudo[6090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 09 11:55:51 volumio sudo[6090]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 11:55:51 volumio sudo[6092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 09 11:55:51 volumio sudo[6092]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 11:55:51 volumio sudo[6090]: pam_unix(sudo:session): session closed for user root Oct 09 11:55:51 volumio sudo[6092]: pam_unix(sudo:session): session closed for user root Oct 09 11:55:52 volumio sudo[6114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 09 11:55:52 volumio sudo[6116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 09 11:55:52 volumio sudo[6114]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 11:55:52 volumio sudo[6116]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 11:55:52 volumio sudo[6114]: pam_unix(sudo:session): session closed for user root Oct 09 11:55:52 volumio sudo[6116]: pam_unix(sudo:session): session closed for user root Oct 09 11:55:52 volumio volumio[933]: info: CoreCommandRouter::volumioGetState Oct 09 11:55:52 volumio volumio[933]: info: CorePlayQueue::getTrack 0 Oct 09 11:55:52 volumio volumio[933]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 09 11:55:52 volumio volumio[933]: info: CoreCommandRouter::volumioGetVisibleSources Oct 09 11:55:52 volumio volumio[933]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 09 11:55:52 volumio volumio[933]: info: Listing playlists Oct 09 11:55:52 volumio volumio[933]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 09 11:55:52 volumio volumio[933]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 09 11:55:52 volumio volumio[933]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 09 11:55:52 volumio volumio[933]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 09 11:55:53 volumio volumio[933]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Casiopea/Work%20out/fa9979cf-6725-4068-b5f8-7dcb020c8392.jpg' Oct 09 11:55:55 volumio volumio[933]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 09 11:55:58 volumio volumio[933]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 09 11:55:58 volumio volumio[933]: info: CoreCommandRouter::volumioGetQueue Oct 09 11:55:58 volumio volumio[933]: info: CoreStateMachine::getQueue Oct 09 11:55:58 volumio volumio[933]: info: CorePlayQueue::getQueue Oct 09 11:56:04 volumio volumio[933]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 09 11:56:04 volumio volumio[933]: info: CURURI: music-library Oct 09 11:56:06 volumio volumio[933]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 09 11:56:06 volumio volumio[933]: info: CURURI: music-library/USB Oct 09 11:56:07 volumio volumio[933]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 09 11:56:07 volumio volumio[933]: info: CURURI: music-library/USB/nas_music Oct 09 11:56:11 volumio volumio[933]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 09 11:56:11 volumio volumio[933]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 09 11:56:11 volumio volumio[933]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 09 11:56:11 volumio volumio[933]: info: Received Get System Version Oct 09 11:56:11 volumio volumio[933]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 09 11:56:12 volumio volumio[933]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 09 11:56:12 volumio volumio[933]: info: CURURI: music-library/USB/nas_music/George Winston Oct 09 11:56:16 volumio volumio[933]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 09 11:56:16 volumio volumio[933]: info: CoreStateMachine::ClearQueue Oct 09 11:56:16 volumio volumio[933]: info: CoreStateMachine::stop Oct 09 11:56:16 volumio volumio[933]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 11:56:16 volumio volumio[933]: verbose: UNSET VOLATILE Oct 09 11:56:16 volumio volumio[933]: info: CorePlayQueue::clearPlayQueue Oct 09 11:56:16 volumio volumio[933]: info: CorePlayQueue::saveQueue Oct 09 11:56:16 volumio volumio[933]: info: CoreCommandRouter::volumioPushState Oct 09 11:56:16 volumio volumio[933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 11:56:16 volumio volumio[933]: info: CoreCommandRouter::volumioPushQueue Oct 09 11:56:16 volumio volumio[933]: info: CoreStateMachine::addQueueItems Oct 09 11:56:16 volumio volumio[933]: info: CorePlayQueue::addQueueItems Oct 09 11:56:16 volumio volumio[933]: info: Adding Item to queue: music-library/USB/nas_music/George Winston/Autumn Oct 09 11:56:16 volumio volumio[933]: info: Exploding uri music-library/USB/nas_music/George Winston/Autumn in service mpd Oct 09 11:56:16 volumio volumio[933]: info: ALBUMART /albumart?cacheid=815&web=George%20Winston/Autumn/extralarge&path=%2Fmnt%2FUSB%2Fnas_music%2FGeorge%20Winston%2FAutumn%2F01%20Colors%EF%BC%8FDance.flac&metadata=false Oct 09 11:56:16 volumio volumio[933]: info: URI /mnt/USB/nas_music/George Winston/Autumn/01 Colors／Dance.flac Oct 09 11:56:16 volumio volumio[933]: info: ALBUMART /albumart?cacheid=815&web=George%20Winston/Autumn/extralarge&path=%2Fmnt%2FUSB%2Fnas_music%2FGeorge%20Winston%2FAutumn%2F02%20Woods.flac&metadata=false Oct 09 11:56:16 volumio volumio[933]: info: URI /mnt/USB/nas_music/George Winston/Autumn/02 Woods.flac Oct 09 11:56:16 volumio volumio[933]: info: ALBUMART /albumart?cacheid=815&web=George%20Winston/Autumn/extralarge&path=%2Fmnt%2FUSB%2Fnas_music%2FGeorge%20Winston%2FAutumn%2F03%20Longing%EF%BC%8FLove.flac&metadata=false Oct 09 11:56:16 volumio volumio[933]: info: URI /mnt/USB/nas_music/George Winston/Autumn/03 Longing／Love.flac Oct 09 11:56:16 volumio volumio[933]: info: ALBUMART /albumart?cacheid=815&web=George%20Winston/Autumn/extralarge&path=%2Fmnt%2FUSB%2Fnas_music%2FGeorge%20Winston%2FAutumn%2F04%20Road.flac&metadata=false Oct 09 11:56:16 volumio volumio[933]: info: URI /mnt/USB/nas_music/George Winston/Autumn/04 Road.flac Oct 09 11:56:16 volumio volumio[933]: info: ALBUMART /albumart?cacheid=815&web=George%20Winston/Autumn/extralarge&path=%2Fmnt%2FUSB%2Fnas_music%2FGeorge%20Winston%2FAutumn%2F05%20Moon.flac&metadata=false Oct 09 11:56:16 volumio volumio[933]: info: URI /mnt/USB/nas_music/George Winston/Autumn/05 Moon.flac Oct 09 11:56:16 volumio volumio[933]: info: ALBUMART /albumart?cacheid=815&web=George%20Winston/Autumn/extralarge&path=%2Fmnt%2FUSB%2Fnas_music%2FGeorge%20Winston%2FAutumn%2F06%20Sea.flac&metadata=false Oct 09 11:56:16 volumio volumio[933]: info: URI /mnt/USB/nas_music/George Winston/Autumn/06 Sea.flac Oct 09 11:56:16 volumio volumio[933]: info: ALBUMART /albumart?cacheid=815&web=George%20Winston/Autumn/extralarge&path=%2Fmnt%2FUSB%2Fnas_music%2FGeorge%20Winston%2FAutumn%2F07%20Stars.flac&metadata=false Oct 09 11:56:16 volumio volumio[933]: info: URI /mnt/USB/nas_music/George Winston/Autumn/07 Stars.flac Oct 09 11:56:16 volumio volumio[933]: info: CorePlayQueue::saveQueue Oct 09 11:56:16 volumio volumio[933]: info: CoreCommandRouter::volumioPushQueue Oct 09 11:56:16 volumio volumio[933]: info: CoreStateMachine::updateTrackBlock Oct 09 11:56:16 volumio volumio[933]: info: CorePlayQueue::getTrackBlock Oct 09 11:56:16 volumio volumio[933]: info: CoreCommandRouter::volumioPlay Oct 09 11:56:16 volumio volumio[933]: verbose: UNSET VOLATILE Oct 09 11:56:16 volumio volumio[933]: info: CoreStateMachine::play index 0 Oct 09 11:56:16 volumio volumio[933]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 11:56:16 volumio volumio[933]: info: CoreStateMachine::stop Oct 09 11:56:16 volumio volumio[933]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 11:56:16 volumio volumio[933]: verbose: UNSET VOLATILE Oct 09 11:56:16 volumio volumio[933]: info: CoreStateMachine::play index undefined Oct 09 11:56:16 volumio volumio[933]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 11:56:16 volumio volumio[933]: info: CorePlayQueue::getTrack 0 Oct 09 11:56:16 volumio volumio[933]: info: CoreStateMachine::startPlaybackTimer Oct 09 11:56:16 volumio volumio[933]: info: CorePlayQueue::getTrack 0 Oct 09 11:56:16 volumio volumio[933]: verbose: ControllerMpd::clearAddPlayTracks USB/nas_music/George Winston/Autumn/01 Colors／Dance.flac Oct 09 11:56:16 volumio volumio[933]: verbose: ControllerMpd::sendMpdCommand stop Oct 09 11:56:16 volumio volumio[933]: info: sendMpdCommand stop took 27 milliseconds Oct 09 11:56:16 volumio volumio[933]: verbose: ControllerMpd::sendMpdCommand clear Oct 09 11:56:16 volumio volumio[933]: info: Oct 09 11:56:16 volumio volumio[933]: ---------------------------- MPD announces system playlist update Oct 09 11:56:16 volumio volumio[933]: info: Ignoring MPD Status Update Oct 09 11:56:16 volumio volumio[933]: info: sendMpdCommand clear took 32 milliseconds Oct 09 11:56:16 volumio volumio[933]: verbose: ControllerMpd::sendMpdCommand add "USB/nas_music/George Winston/Autumn/01 Colors／Dance.flac" Oct 09 11:56:16 volumio volumio[933]: error: updateQueue error: null Oct 09 11:56:16 volumio volumio[933]: info: Oct 09 11:56:16 volumio volumio[933]: ---------------------------- MPD announces system playlist update Oct 09 11:56:16 volumio volumio[933]: info: Ignoring MPD Status Update Oct 09 11:56:16 volumio volumio[933]: info: ------------------------------ 52ms Oct 09 11:56:16 volumio volumio[933]: info: sendMpdCommand add "USB/nas_music/George Winston/Autumn/01 Colors／Dance.flac" took 33 milliseconds Oct 09 11:56:16 volumio volumio[933]: verbose: ControllerMpd::sendMpdCommand play Oct 09 11:56:16 volumio volumio[933]: info: ------------------------------ 78ms Oct 09 11:56:16 volumio volumio[933]: info: sendMpdCommand play took 60 milliseconds Oct 09 11:56:17 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Oct 09 11:56:17 volumio volumio[933]: info: Oct 09 11:56:17 volumio volumio[933]: ---------------------------- MPD announces state update: player Oct 09 11:56:17 volumio volumio[933]: info: ControllerMpd::getState Oct 09 11:56:17 volumio volumio[933]: verbose: ControllerMpd::sendMpdCommand status Oct 09 11:56:17 volumio volumio[933]: info: Oct 09 11:56:17 volumio volumio[933]: ---------------------------- MPD announces state update: player Oct 09 11:56:17 volumio volumio[933]: info: ControllerMpd::getState Oct 09 11:56:17 volumio volumio[933]: verbose: ControllerMpd::sendMpdCommand status Oct 09 11:56:17 volumio volumio[933]: info: sendMpdCommand status took 49 milliseconds Oct 09 11:56:17 volumio volumio[933]: verbose: ControllerMpd::parseState Oct 09 11:56:17 volumio volumio[933]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 09 11:56:17 volumio volumio[933]: info: sendMpdCommand status took 69 milliseconds Oct 09 11:56:17 volumio volumio[933]: info: sendMpdCommand playlistinfo took 23 milliseconds Oct 09 11:56:17 volumio volumio[933]: verbose: ControllerMpd::parseState Oct 09 11:56:17 volumio volumio[933]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 09 11:56:17 volumio volumio[933]: verbose: ControllerMpd::parseTrackInfo Oct 09 11:56:17 volumio volumio[933]: info: ControllerMpd::pushState Oct 09 11:56:17 volumio volumio[933]: info: CoreCommandRouter::servicePushState Oct 09 11:56:17 volumio volumio[933]: info: CorePlayQueue::getTrack 0 Oct 09 11:56:17 volumio volumio[933]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":623,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Colors/Dance","artist":"George Winston","album":"Autumn","uri":"USB/nas_music/George Winston/Autumn/01 Colors／Dance.flac","trackType":"flac"} Oct 09 11:56:17 volumio volumio[933]: verbose: CURRENT POSITION 0 Oct 09 11:56:17 volumio volumio[933]: info: CoreStateMachine::syncState stateService play Oct 09 11:56:17 volumio volumio[933]: info: CoreStateMachine::syncState currentStatus stop Oct 09 11:56:17 volumio volumio[933]: info: CoreStateMachine::pushState Oct 09 11:56:17 volumio volumio[933]: info: CorePlayQueue::getTrack 0 Oct 09 11:56:17 volumio volumio[933]: info: CoreCommandRouter::volumioPushState Oct 09 11:56:17 volumio volumio[933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 11:56:17 volumio volumio[933]: info: ------------------------------ 327ms Oct 09 11:56:17 volumio volumio[933]: info: sendMpdCommand playlistinfo took 194 milliseconds Oct 09 11:56:17 volumio volumio[933]: verbose: ControllerMpd::parseTrackInfo Oct 09 11:56:17 volumio volumio[933]: info: ControllerMpd::pushState Oct 09 11:56:17 volumio volumio[933]: info: CoreCommandRouter::servicePushState Oct 09 11:56:17 volumio volumio[933]: info: CorePlayQueue::getTrack 0 Oct 09 11:56:17 volumio volumio[933]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":623,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Colors/Dance","artist":"George Winston","album":"Autumn","uri":"USB/nas_music/George Winston/Autumn/01 Colors／Dance.flac","trackType":"flac"} Oct 09 11:56:17 volumio volumio[933]: verbose: CURRENT POSITION 0 Oct 09 11:56:17 volumio volumio[933]: info: CoreStateMachine::syncState stateService play Oct 09 11:56:17 volumio volumio[933]: info: CoreStateMachine::syncState currentStatus play Oct 09 11:56:17 volumio volumio[933]: info: Received an update from plugin. extracting info from payload Oct 09 11:56:17 volumio volumio[933]: info: CoreStateMachine::pushState Oct 09 11:56:17 volumio volumio[933]: info: CorePlayQueue::getTrack 0 Oct 09 11:56:17 volumio volumio[933]: info: CoreCommandRouter::volumioPushState Oct 09 11:56:17 volumio volumio[933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 11:56:17 volumio volumio[933]: info: CoreStateMachine::pushState Oct 09 11:56:17 volumio volumio[933]: info: CorePlayQueue::getTrack 0 Oct 09 11:56:17 volumio volumio[933]: info: CoreCommandRouter::volumioPushState Oct 09 11:56:17 volumio volumio[933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 11:56:17 volumio volumio[933]: info: ------------------------------ 547ms Oct 09 11:56:18 volumio volumio[933]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 09 11:56:18 volumio volumio[933]: { Error: certificate is not yet valid Oct 09 11:56:18 volumio volumio[933]: at TLSSocket. (_tls_wrap.js:1105:38) Oct 09 11:56:18 volumio volumio[933]: at emitNone (events.js:106:13) Oct 09 11:56:18 volumio volumio[933]: at TLSSocket.emit (events.js:208:7) Oct 09 11:56:18 volumio volumio[933]: at TLSSocket._finishInit (_tls_wrap.js:639:8) Oct 09 11:56:18 volumio volumio[933]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:469:38) code: 'CERT_NOT_YET_VALID' } Oct 09 11:56:18 volumio volumio[933]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 09 11:56:22 volumio sudo[6216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2021-10-09 11:55 Oct 09 11:56:22 volumio sudo[6216]: 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"