-- Logs begin at Sun 2023-08-06 07:23:51 UTC, end at Sun 2023-08-06 09:22:52 UTC. -- Aug 06 09:21:00 volumio volumio[924]: info: Listing playlists Aug 06 09:21:00 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 06 09:21:00 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 06 09:21:00 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 06 09:21:00 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 06 09:21:01 volumio volumio[924]: info: CoreCommandRouter::volumioGetQueue Aug 06 09:21:01 volumio volumio[924]: info: CoreStateMachine::getQueue Aug 06 09:21:01 volumio volumio[924]: info: CorePlayQueue::getQueue Aug 06 09:21:01 volumio volumio[924]: info: CoreCommandRouter::volumioGetVisibleSources Aug 06 09:21:01 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 06 09:21:03 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 06 09:21:03 volumio volumio[924]: info: CURURI: artists:// Aug 06 09:21:07 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 06 09:21:07 volumio volumio[924]: info: CURURI: artists://B'z Aug 06 09:21:18 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 06 09:21:18 volumio volumio[924]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 06 09:21:18 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 06 09:21:18 volumio volumio[924]: info: Received Get System Version Aug 06 09:21:18 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 06 09:21:20 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 06 09:21:20 volumio volumio[924]: info: CURURI: artists://B'z/BREAK%20THROUGH Aug 06 09:21:32 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 06 09:21:32 volumio volumio[924]: info: CURURI: artists://B'z/RISKY Aug 06 09:21:42 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 06 09:21:42 volumio volumio[924]: info: CURURI: artists://B'z/IN%20THE%20LIFE Aug 06 09:21:49 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 06 09:21:49 volumio volumio[924]: info: CURURI: artists://B'z/B'z%20The%20Best%20%22ULTRA%20Pleasure%22 Aug 06 09:22:11 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 06 09:22:11 volumio volumio[924]: info: CURURI: artists://B'z/B'z%20The%20Best%20%22Pleasure%22 Aug 06 09:22:28 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 06 09:22:28 volumio volumio[924]: info: CURURI: artists:///B'z%20The%20Best%20%22Treasure%22 Aug 06 09:22:36 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 06 09:22:36 volumio volumio[924]: info: CURURI: artists://B'z/RUN Aug 06 09:22:45 volumio volumio[924]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 06 09:22:45 volumio volumio[924]: info: CoreStateMachine::ClearQueue Aug 06 09:22:45 volumio volumio[924]: info: CoreStateMachine::stop Aug 06 09:22:45 volumio volumio[924]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 06 09:22:45 volumio volumio[924]: verbose: UNSET VOLATILE Aug 06 09:22:45 volumio volumio[924]: info: CorePlayQueue::clearPlayQueue Aug 06 09:22:45 volumio volumio[924]: info: CorePlayQueue::saveQueue Aug 06 09:22:45 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Aug 06 09:22:45 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 06 09:22:45 volumio volumio[924]: info: CoreCommandRouter::volumioPushQueue Aug 06 09:22:45 volumio volumio[924]: info: CoreStateMachine::addQueueItems Aug 06 09:22:45 volumio volumio[924]: info: CorePlayQueue::addQueueItems Aug 06 09:22:45 volumio volumio[924]: info: Adding Item to queue: artists://B'z/RUN Aug 06 09:22:45 volumio volumio[924]: info: Exploding uri artists://B'z/RUN in service mpd Aug 06 09:22:45 volumio volumio[924]: info: CorePlayQueue::saveQueue Aug 06 09:22:45 volumio volumio[924]: info: CoreCommandRouter::volumioPushQueue Aug 06 09:22:45 volumio volumio[924]: info: CoreStateMachine::updateTrackBlock Aug 06 09:22:45 volumio volumio[924]: info: CorePlayQueue::getTrackBlock Aug 06 09:22:45 volumio volumio[924]: info: CoreCommandRouter::volumioPlay Aug 06 09:22:45 volumio volumio[924]: verbose: UNSET VOLATILE Aug 06 09:22:45 volumio volumio[924]: info: CoreStateMachine::play index 0 Aug 06 09:22:45 volumio volumio[924]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 06 09:22:45 volumio volumio[924]: info: CoreStateMachine::stop Aug 06 09:22:45 volumio volumio[924]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 06 09:22:45 volumio volumio[924]: verbose: UNSET VOLATILE Aug 06 09:22:45 volumio volumio[924]: info: CoreStateMachine::play index undefined Aug 06 09:22:45 volumio volumio[924]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 06 09:22:45 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Aug 06 09:22:45 volumio volumio[924]: info: CoreStateMachine::startPlaybackTimer Aug 06 09:22:45 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Aug 06 09:22:45 volumio volumio[924]: verbose: ControllerMpd::clearAddPlayTracks USB/10AC-160D/B'z/RUN/01 THE GAMBLER.flac Aug 06 09:22:45 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand stop Aug 06 09:22:45 volumio volumio[924]: info: sendMpdCommand stop took 19 milliseconds Aug 06 09:22:45 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand clear Aug 06 09:22:45 volumio volumio[924]: info: Aug 06 09:22:45 volumio volumio[924]: ---------------------------- MPD announces system playlist update Aug 06 09:22:45 volumio volumio[924]: info: Ignoring MPD Status Update Aug 06 09:22:45 volumio volumio[924]: info: sendMpdCommand clear took 40 milliseconds Aug 06 09:22:45 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand add "USB/10AC-160D/B'z/RUN/01 THE GAMBLER.flac" Aug 06 09:22:45 volumio volumio[924]: error: updateQueue error: null Aug 06 09:22:45 volumio volumio[924]: info: Aug 06 09:22:45 volumio volumio[924]: ---------------------------- MPD announces system playlist update Aug 06 09:22:45 volumio volumio[924]: info: Ignoring MPD Status Update Aug 06 09:22:45 volumio volumio[924]: info: ------------------------------ 107ms Aug 06 09:22:45 volumio volumio[924]: info: sendMpdCommand add "USB/10AC-160D/B'z/RUN/01 THE GAMBLER.flac" took 86 milliseconds Aug 06 09:22:45 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand play Aug 06 09:22:45 volumio volumio[924]: info: ------------------------------ 105ms Aug 06 09:22:45 volumio volumio[924]: info: sendMpdCommand play took 90 milliseconds Aug 06 09:22:46 volumio volumio[924]: info: Aug 06 09:22:46 volumio volumio[924]: ---------------------------- MPD announces state update: player Aug 06 09:22:46 volumio volumio[924]: info: ControllerMpd::getState Aug 06 09:22:46 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Aug 06 09:22:46 volumio volumio[924]: info: sendMpdCommand status took 169 milliseconds Aug 06 09:22:46 volumio volumio[924]: verbose: ControllerMpd::parseState Aug 06 09:22:46 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 06 09:22:46 volumio volumio[924]: info: Aug 06 09:22:46 volumio volumio[924]: ---------------------------- MPD announces state update: player Aug 06 09:22:46 volumio volumio[924]: info: ControllerMpd::getState Aug 06 09:22:46 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Aug 06 09:22:46 volumio volumio[924]: info: sendMpdCommand playlistinfo took 43 milliseconds Aug 06 09:22:46 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Aug 06 09:22:46 volumio volumio[924]: info: ControllerMpd::pushState Aug 06 09:22:46 volumio volumio[924]: info: CoreCommandRouter::servicePushState Aug 06 09:22:46 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Aug 06 09:22:46 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":327,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"THE GAMBLER","artist":"B'z","album":"RUN","uri":"USB/10AC-160D/B'z/RUN/01 THE GAMBLER.flac","trackType":"flac"} Aug 06 09:22:46 volumio volumio[924]: verbose: CURRENT POSITION 0 Aug 06 09:22:46 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Aug 06 09:22:46 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus stop Aug 06 09:22:46 volumio volumio[924]: info: CoreStateMachine::pushState Aug 06 09:22:46 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Aug 06 09:22:46 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Aug 06 09:22:46 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 06 09:22:46 volumio volumio[924]: info: ------------------------------ 495ms Aug 06 09:22:46 volumio volumio[924]: info: sendMpdCommand status took 202 milliseconds Aug 06 09:22:46 volumio volumio[924]: verbose: ControllerMpd::parseState Aug 06 09:22:46 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 06 09:22:46 volumio volumio[924]: info: sendMpdCommand playlistinfo took 197 milliseconds Aug 06 09:22:46 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Aug 06 09:22:46 volumio volumio[924]: info: ControllerMpd::pushState Aug 06 09:22:46 volumio volumio[924]: info: CoreCommandRouter::servicePushState Aug 06 09:22:46 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Aug 06 09:22:46 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":327,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"570 Kbps","isStreaming":false,"title":"THE GAMBLER","artist":"B'z","album":"RUN","uri":"USB/10AC-160D/B'z/RUN/01 THE GAMBLER.flac","trackType":"flac"} Aug 06 09:22:46 volumio volumio[924]: verbose: CURRENT POSITION 0 Aug 06 09:22:46 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Aug 06 09:22:46 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Aug 06 09:22:46 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Aug 06 09:22:46 volumio volumio[924]: info: CoreStateMachine::pushState Aug 06 09:22:46 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Aug 06 09:22:46 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Aug 06 09:22:46 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 06 09:22:46 volumio volumio[924]: info: CoreStateMachine::pushState Aug 06 09:22:46 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Aug 06 09:22:46 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Aug 06 09:22:46 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 06 09:22:47 volumio volumio[924]: info: ------------------------------ 676ms Aug 06 09:22:48 volumio volumio[924]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 06 09:22:48 volumio volumio[924]: { Error: certificate is not yet valid Aug 06 09:22:48 volumio volumio[924]: at TLSSocket. (_tls_wrap.js:1105:38) Aug 06 09:22:48 volumio volumio[924]: at emitNone (events.js:106:13) Aug 06 09:22:48 volumio volumio[924]: at TLSSocket.emit (events.js:208:7) Aug 06 09:22:48 volumio volumio[924]: at TLSSocket._finishInit (_tls_wrap.js:639:8) Aug 06 09:22:48 volumio volumio[924]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:469:38) code: 'CERT_NOT_YET_VALID' } Aug 06 09:22:48 volumio volumio[924]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 06 09:22:52 volumio sudo[18228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-08-06 09:21 Aug 06 09:22:52 volumio sudo[18228]: 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"