-- Logs begin at Mon 2022-11-28 15:08:23 UTC, end at Mon 2022-11-28 15:24:27 UTC. -- Nov 28 15:23:17 volumio systemd[1]: Starting Cleanup of Temporary Directories... Nov 28 15:23:17 volumio systemd[1]: Started Cleanup of Temporary Directories. Nov 28 15:24:01 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Nov 28 15:24:02 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Nov 28 15:24:02 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Nov 28 15:24:04 volumio volumio[827]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 15:24:04 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 28 15:24:04 volumio volumio[827]: info: CoreCommandRouter::volumioGetQueue Nov 28 15:24:04 volumio volumio[827]: info: CoreStateMachine::getQueue Nov 28 15:24:04 volumio volumio[827]: info: CorePlayQueue::getQueue Nov 28 15:24:04 volumio volumio[827]: info: CoreCommandRouter::volumioGetState Nov 28 15:24:04 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Nov 28 15:24:04 volumio volumio[827]: info: CoreCommandRouter::volumioGetState Nov 28 15:24:04 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Nov 28 15:24:04 volumio volumio[827]: info: CoreCommandRouter::volumioGetQueue Nov 28 15:24:04 volumio volumio[827]: info: CoreStateMachine::getQueue Nov 28 15:24:04 volumio volumio[827]: info: CorePlayQueue::getQueue Nov 28 15:24:04 volumio volumio[827]: info: CoreCommandRouter::volumioGetVisibleSources Nov 28 15:24:04 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 15:24:04 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 28 15:24:04 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 15:24:04 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 28 15:24:04 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 15:24:04 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 15:24:04 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 15:24:04 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 15:24:04 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 15:24:04 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 15:24:04 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 15:24:04 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 28 15:24:04 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 28 15:24:19 volumio volumio[827]: info: Clearing queue after UPNP request Nov 28 15:24:19 volumio volumio[827]: info: CoreStateMachine::ClearQueue Nov 28 15:24:19 volumio volumio[827]: info: CoreStateMachine::stop Nov 28 15:24:19 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 15:24:19 volumio volumio[827]: UNSET VOLATILE Nov 28 15:24:19 volumio volumio[827]: info: CorePlayQueue::clearPlayQueue Nov 28 15:24:19 volumio volumio[827]: info: CorePlayQueue::saveQueue Nov 28 15:24:19 volumio volumio[827]: info: CoreCommandRouter::volumioPushState Nov 28 15:24:19 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 15:24:19 volumio volumio[827]: info: CoreCommandRouter::volumioPushQueue Nov 28 15:24:19 volumio volumio[827]: info: Nov 28 15:24:19 volumio volumio[827]: ---------------------------- MPD announces system playlist update Nov 28 15:24:19 volumio volumio[827]: info: Ignoring MPD Status Update Nov 28 15:24:19 volumio volumio[827]: error: updateQueue error: null Nov 28 15:24:19 volumio volumio[827]: info: ------------------------------ 20ms Nov 28 15:24:19 volumio volumio[827]: info: Starting UPNP Playback Nov 28 15:24:19 volumio volumio[827]: info: Preparing playback through UPNP Nov 28 15:24:19 volumio volumio[827]: info: CoreCommandRouter::volumioGetState Nov 28 15:24:19 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Nov 28 15:24:19 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 28 15:24:20 volumio volumio[827]: info: Nov 28 15:24:20 volumio volumio[827]: ---------------------------- MPD announces system playlist update Nov 28 15:24:20 volumio volumio[827]: info: Ignoring MPD Status Update Nov 28 15:24:20 volumio volumio[827]: info: ------------------------------ 15ms Nov 28 15:24:20 volumio volumio[827]: info: Nov 28 15:24:20 volumio volumio[827]: ---------------------------- MPD announces system playlist update Nov 28 15:24:20 volumio volumio[827]: info: Ignoring MPD Status Update Nov 28 15:24:20 volumio volumio[827]: info: ------------------------------ 13ms Nov 28 15:24:20 volumio volumio[827]: info: Nov 28 15:24:20 volumio volumio[827]: ---------------------------- MPD announces system playlist update Nov 28 15:24:20 volumio volumio[827]: info: Ignoring MPD Status Update Nov 28 15:24:20 volumio volumio[827]: info: ------------------------------ 12ms Nov 28 15:24:20 volumio volumio[827]: info: Nov 28 15:24:20 volumio volumio[827]: ---------------------------- MPD announces system playlist update Nov 28 15:24:20 volumio volumio[827]: info: Ignoring MPD Status Update Nov 28 15:24:20 volumio volumio[827]: info: ------------------------------ 12ms Nov 28 15:24:20 volumio volumio[827]: info: Nov 28 15:24:20 volumio volumio[827]: ---------------------------- MPD announces system playlist update Nov 28 15:24:20 volumio volumio[827]: info: Ignoring MPD Status Update Nov 28 15:24:20 volumio volumio[827]: info: ------------------------------ 12ms Nov 28 15:24:20 volumio volumio[827]: info: Nov 28 15:24:20 volumio volumio[827]: ---------------------------- MPD announces system playlist update Nov 28 15:24:20 volumio volumio[827]: info: Ignoring MPD Status Update Nov 28 15:24:20 volumio volumio[827]: info: ------------------------------ 14ms Nov 28 15:24:22 volumio volumio[827]: info: Starting UPNP Playback Nov 28 15:24:22 volumio volumio[827]: info: Preparing playback through UPNP Nov 28 15:24:22 volumio volumio[827]: info: Consume mode Nov 28 15:24:22 volumio volumio[827]: info: CoreCommandRouter::volumioGetState Nov 28 15:24:22 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 28 15:24:23 volumio volumio[827]: info: Nov 28 15:24:23 volumio volumio[827]: ---------------------------- MPD announces system playlist update Nov 28 15:24:23 volumio volumio[827]: info: Ignoring MPD Status Update Nov 28 15:24:23 volumio volumio[827]: info: ------------------------------ 12ms Nov 28 15:24:23 volumio volumio[827]: info: Nov 28 15:24:23 volumio volumio[827]: ---------------------------- MPD announces system playlist update Nov 28 15:24:23 volumio volumio[827]: info: Ignoring MPD Status Update Nov 28 15:24:23 volumio volumio[827]: info: ------------------------------ 12ms Nov 28 15:24:23 volumio volumio[827]: info: Nov 28 15:24:23 volumio volumio[827]: ---------------------------- MPD announces system playlist update Nov 28 15:24:23 volumio volumio[827]: info: Ignoring MPD Status Update Nov 28 15:24:23 volumio volumio[827]: info: ------------------------------ 12ms Nov 28 15:24:23 volumio volumio[827]: info: Nov 28 15:24:23 volumio volumio[827]: ---------------------------- MPD announces system playlist update Nov 28 15:24:23 volumio volumio[827]: info: Ignoring MPD Status Update Nov 28 15:24:23 volumio volumio[827]: info: ------------------------------ 12ms Nov 28 15:24:23 volumio volumio[827]: info: Nov 28 15:24:23 volumio volumio[827]: ---------------------------- MPD announces system playlist update Nov 28 15:24:23 volumio volumio[827]: info: Ignoring MPD Status Update Nov 28 15:24:23 volumio volumio[827]: info: ------------------------------ 13ms Nov 28 15:24:23 volumio volumio[827]: info: Nov 28 15:24:23 volumio volumio[827]: ---------------------------- MPD announces system playlist update Nov 28 15:24:23 volumio volumio[827]: info: Ignoring MPD Status Update Nov 28 15:24:23 volumio volumio[827]: info: ------------------------------ 12ms Nov 28 15:24:23 volumio volumio[827]: info: Nov 28 15:24:23 volumio volumio[827]: ---------------------------- MPD announces state update: player Nov 28 15:24:23 volumio volumio[827]: info: ControllerMpd::getState Nov 28 15:24:23 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status Nov 28 15:24:23 volumio volumio[827]: info: Nov 28 15:24:23 volumio volumio[827]: ---------------------------- MPD announces state update: player Nov 28 15:24:23 volumio volumio[827]: info: ControllerMpd::getState Nov 28 15:24:23 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status Nov 28 15:24:23 volumio volumio[827]: info: Nov 28 15:24:23 volumio volumio[827]: ---------------------------- MPD announces system playlist update Nov 28 15:24:23 volumio volumio[827]: info: Ignoring MPD Status Update Nov 28 15:24:23 volumio volumio[827]: info: Nov 28 15:24:23 volumio volumio[827]: ---------------------------- MPD announces state update: player Nov 28 15:24:23 volumio volumio[827]: info: ControllerMpd::getState Nov 28 15:24:23 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status Nov 28 15:24:23 volumio volumio[827]: verbose: ControllerMpd::parseState Nov 28 15:24:23 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 15:24:23 volumio volumio[827]: verbose: ControllerMpd::parseState Nov 28 15:24:23 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 15:24:23 volumio volumio[827]: info: ------------------------------ 87ms Nov 28 15:24:23 volumio volumio[827]: verbose: ControllerMpd::parseState Nov 28 15:24:23 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 15:24:23 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo Nov 28 15:24:23 volumio volumio[827]: info: ControllerMpd::pushState Nov 28 15:24:23 volumio volumio[827]: info: CoreCommandRouter::servicePushState Nov 28 15:24:23 volumio volumio[827]: In UPNP mode Nov 28 15:24:23 volumio volumio[827]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":221,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"426 Kbps","isStreaming":false,"title":"Go Down, Moses","artist":"Louis Armstrong","album":"Louis And The Good Book","uri":"http://192.168.1.5:57645/external/audio/media/195680.flac","trackType":"flac"} Nov 28 15:24:23 volumio volumio[827]: verbose: CURRENT POSITION 0 Nov 28 15:24:23 volumio volumio[827]: info: CoreStateMachine::syncState stateService play Nov 28 15:24:23 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus stop Nov 28 15:24:23 volumio volumio[827]: info: CoreStateMachine::pushState Nov 28 15:24:23 volumio volumio[827]: info: CoreCommandRouter::volumioPushState Nov 28 15:24:23 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 15:24:23 volumio volumio[827]: info: ------------------------------ 326ms Nov 28 15:24:23 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo Nov 28 15:24:23 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo Nov 28 15:24:23 volumio volumio[827]: info: ControllerMpd::pushState Nov 28 15:24:23 volumio volumio[827]: info: CoreCommandRouter::servicePushState Nov 28 15:24:23 volumio volumio[827]: In UPNP mode Nov 28 15:24:23 volumio volumio[827]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":221,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"402 Kbps","isStreaming":false,"title":"Go Down, Moses","artist":"Louis Armstrong","album":"Louis And The Good Book","uri":"http://192.168.1.5:57645/external/audio/media/195680.flac","trackType":"flac"} Nov 28 15:24:23 volumio volumio[827]: verbose: CURRENT POSITION 0 Nov 28 15:24:23 volumio volumio[827]: info: CoreStateMachine::syncState stateService play Nov 28 15:24:23 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play Nov 28 15:24:23 volumio volumio[827]: info: Received an update from plugin. extracting info from payload Nov 28 15:24:23 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 28 15:24:23 volumio volumio[827]: info: CoreStateMachine::pushState Nov 28 15:24:23 volumio volumio[827]: info: CoreCommandRouter::volumioPushState Nov 28 15:24:23 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 15:24:23 volumio volumio[827]: info: CoreStateMachine::pushState Nov 28 15:24:23 volumio volumio[827]: info: CoreCommandRouter::volumioPushState Nov 28 15:24:23 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 15:24:23 volumio volumio[827]: info: ControllerMpd::pushState Nov 28 15:24:23 volumio volumio[827]: info: CoreCommandRouter::servicePushState Nov 28 15:24:23 volumio volumio[827]: In UPNP mode Nov 28 15:24:23 volumio volumio[827]: verbose: STATE SERVICE {"status":"play","position":0,"seek":557,"duration":221,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"400 Kbps","isStreaming":false,"title":"Go Down, Moses","artist":"Louis Armstrong","album":"Louis And The Good Book","uri":"http://192.168.1.5:57645/external/audio/media/195680.flac","trackType":"flac"} Nov 28 15:24:23 volumio volumio[827]: verbose: CURRENT POSITION 0 Nov 28 15:24:24 volumio volumio[827]: info: CoreStateMachine::syncState stateService play Nov 28 15:24:24 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play Nov 28 15:24:24 volumio volumio[827]: info: Received an update from plugin. extracting info from payload Nov 28 15:24:24 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 28 15:24:24 volumio volumio[827]: info: CoreStateMachine::pushState Nov 28 15:24:24 volumio volumio[827]: info: CoreCommandRouter::volumioPushState Nov 28 15:24:24 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 15:24:24 volumio volumio[827]: info: CoreStateMachine::pushState Nov 28 15:24:24 volumio volumio[827]: info: CoreCommandRouter::volumioPushState Nov 28 15:24:24 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 15:24:24 volumio volumio[827]: info: ------------------------------ 468ms Nov 28 15:24:24 volumio volumio[827]: info: ------------------------------ 423ms Nov 28 15:24:24 volumio volumio[827]: info: CoreCommandRouter::volumioGetQueue Nov 28 15:24:24 volumio volumio[827]: info: CoreStateMachine::getQueue Nov 28 15:24:24 volumio volumio[827]: info: CorePlayQueue::getQueue Nov 28 15:24:24 volumio volumio[827]: info: CoreCommandRouter::volumioGetQueue Nov 28 15:24:24 volumio volumio[827]: info: CoreStateMachine::getQueue Nov 28 15:24:24 volumio volumio[827]: info: CorePlayQueue::getQueue Nov 28 15:24:24 volumio volumio[827]: info: CoreCommandRouter::volumioGetQueue Nov 28 15:24:24 volumio volumio[827]: info: CoreStateMachine::getQueue Nov 28 15:24:24 volumio volumio[827]: info: CorePlayQueue::getQueue Nov 28 15:24:24 volumio volumio[827]: info: CoreCommandRouter::volumioGetQueue Nov 28 15:24:24 volumio volumio[827]: info: CoreStateMachine::getQueue Nov 28 15:24:24 volumio volumio[827]: info: CorePlayQueue::getQueue Nov 28 15:24:25 volumio volumio[827]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 15:24:25 volumio volumio[827]: { Error: certificate is not yet valid Nov 28 15:24:25 volumio volumio[827]: at TLSSocket. (_tls_wrap.js:1105:38) Nov 28 15:24:25 volumio volumio[827]: at emitNone (events.js:106:13) Nov 28 15:24:25 volumio volumio[827]: at TLSSocket.emit (events.js:208:7) Nov 28 15:24:25 volumio volumio[827]: at TLSSocket._finishInit (_tls_wrap.js:639:8) Nov 28 15:24:25 volumio volumio[827]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:469:38) code: 'CERT_NOT_YET_VALID' } Nov 28 15:24:25 volumio volumio[827]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 15:24:27 volumio sudo[3018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2022-11-28 15:23 Nov 28 15:24:27 volumio sudo[3018]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="ba3460f1f2fdad97f1693b755734da563c3f0beb" VOLUMIO_FE_VERSION="5f22e724b145ac14608b2c8a333ae1be9063d026" VOLUMIO_BE_VERSION="3118090624276f5aa7c67cf3a6a8581e26abe7f5" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Jul 10 16:53:14 EDT 2020" VOLUMIO_HASH="d41d8cd98f00b204e9800998ecf8427e"