-- Logs begin at Tue 2022-03-22 23:05:54 UTC, end at Thu 2022-03-24 22:29:54 UTC. -- Mar 24 22:28:01 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 22:28:02 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Mar 24 22:28:43 volumio dhcpd[1324]: DHCPDISCOVER from b8:27:eb:b5:6a:12 (volumio) via wlan0 Mar 24 22:28:44 volumio dhcpd[1324]: DHCPOFFER on 192.168.211.2 to b8:27:eb:b5:6a:12 (volumio) via wlan0 Mar 24 22:28:45 volumio volumio[1307]: info: Following Redirect to: http://plugins.volumio.workers.dev/plugins/volumio/armhf/plugins.json Mar 24 22:29:21 volumio volumio[1307]: info: CoreCommandRouter::servicePushState Mar 24 22:29:21 volumio volumio[1307]: info: CorePlayQueue::getTrack 6 Mar 24 22:29:21 volumio volumio[1307]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation Mar 24 22:29:21 volumio volumio[1307]: info: CoreCommandRouter::servicePushState Mar 24 22:29:21 volumio volumio[1307]: info: CorePlayQueue::getTrack 6 Mar 24 22:29:21 volumio volumio[1307]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation Mar 24 22:29:21 volumio shairport-sync[1539]: Failed to create secure directory (/home/shairport-sync/.config/pulse): No such file or directory Mar 24 22:29:21 volumio volumio[1307]: info: Airplay started streaming, receiving metadatas Mar 24 22:29:21 volumio volumio[1307]: info: CorePlayQueue::getTrack 6 Mar 24 22:29:21 volumio volumio[1307]: info: CoreCommandRouter::volumioStop Mar 24 22:29:21 volumio shairport-sync[1539]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 24 22:29:21 volumio shairport-sync[1539]: Dload Upload Total Spent Left Speed Mar 24 22:29:21 volumio shairport-sync[1539]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 % Total % Received % Xferd Average Speed Time Time Time Current Mar 24 22:29:21 volumio shairport-sync[1539]: Dload Upload Total Spent Left Speed Mar 24 22:29:21 volumio volumio[1307]: info: CoreStateMachine::stop Mar 24 22:29:21 volumio volumio[1307]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 22:29:21 volumio volumio[1307]: UNSET VOLATILE Mar 24 22:29:21 volumio volumio[1307]: info: CoreStateMachine::updateTrackBlock Mar 24 22:29:21 volumio volumio[1307]: info: CorePlayQueue::getTrackBlock Mar 24 22:29:21 volumio volumio[1307]: info: CoreStateMachine::stPlaybackTimer Mar 24 22:29:21 volumio volumio[1307]: info: CoreStateMachine::pushState Mar 24 22:29:21 volumio volumio[1307]: info: CorePlayQueue::getTrack 6 Mar 24 22:29:21 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Mar 24 22:29:21 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 22:29:21 volumio volumio[1307]: info: CoreStateMachine::serviceStop Mar 24 22:29:21 volumio volumio[1307]: info: CorePlayQueue::getTrack 6 Mar 24 22:29:21 volumio volumio[1307]: info: CoreCommandRouter::serviceStop Mar 24 22:29:21 volumio volumio[1307]: info: ControllerMpd::stop Mar 24 22:29:21 volumio volumio[1307]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 22:29:21 volumio volumio[1307]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 22:29:21 volumio volumio[1307]: info: Airplay started streaming, receiving metadatas Mar 24 22:29:21 volumio volumio[1307]: info: CorePlayQueue::getTrack 6 Mar 24 22:29:21 volumio volumio[1307]: info: CoreCommandRouter::volumioStop Mar 24 22:29:21 volumio volumio[1307]: info: CoreStateMachine::stop Mar 24 22:29:21 volumio volumio[1307]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 22:29:21 volumio volumio[1307]: UNSET VOLATILE Mar 24 22:29:21 volumio volumio[1307]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 22:29:21 volumio volumio[1307]: info: Mar 24 22:29:21 volumio volumio[1307]: ---------------------------- MPD announces state update: player Mar 24 22:29:21 volumio volumio[1307]: info: sendMpdCommand stop took 12 milliseconds Mar 24 22:29:21 volumio volumio[1307]: info: ControllerMpd::getState Mar 24 22:29:21 volumio volumio[1307]: verbose: ControllerMpd::sendMpdCommand status Mar 24 22:29:21 volumio volumio[1307]: info: sendMpdCommand status took 1 milliseconds Mar 24 22:29:21 volumio volumio[1307]: verbose: ControllerMpd::parseState Mar 24 22:29:21 volumio volumio[1307]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 22:29:21 volumio volumio[1307]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 24 22:29:21 volumio volumio[1307]: verbose: ControllerMpd::parseTrackInfo Mar 24 22:29:21 volumio volumio[1307]: info: ControllerMpd::pushState Mar 24 22:29:21 volumio volumio[1307]: info: CoreCommandRouter::servicePushState Mar 24 22:29:21 volumio volumio[1307]: info: CoreStateMachine::pushState Mar 24 22:29:21 volumio volumio[1307]: info: CorePlayQueue::getTrack 6 Mar 24 22:29:21 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Mar 24 22:29:21 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 22:29:21 volumio volumio[1307]: info: CorePlayQueue::getTrack 6 Mar 24 22:29:21 volumio volumio[1307]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Thirsty Dog","artist":"Nick Cave & The Bad Seeds","album":"Let Love In","uri":"NAS/DS216/Public/mp3/div/Nick Cave & The Bad Seeds/08-Let Love In (1994)/07-Thirsty Dog.mp3","trackType":"mp3"} Mar 24 22:29:21 volumio volumio[1307]: verbose: CURRENT POSITION 6 Mar 24 22:29:21 volumio volumio[1307]: info: CoreStateMachine::syncState stateService stop Mar 24 22:29:21 volumio volumio[1307]: info: CoreStateMachine::syncState currentStatus stop Mar 24 22:29:21 volumio volumio[1307]: info: CoreStateMachine::pushState Mar 24 22:29:21 volumio volumio[1307]: info: CorePlayQueue::getTrack 6 Mar 24 22:29:21 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Mar 24 22:29:21 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 22:29:21 volumio volumio[1307]: info: No code Mar 24 22:29:21 volumio volumio[1307]: info: CoreStateMachine::pushState Mar 24 22:29:21 volumio volumio[1307]: info: CorePlayQueue::getTrack 6 Mar 24 22:29:21 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Mar 24 22:29:21 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 22:29:21 volumio volumio[1307]: info: ------------------------------ 29ms Mar 24 22:29:21 volumio volumio[1307]: info: Mar 24 22:29:21 volumio volumio[1307]: ---------------------------- Client requests Start Airplay PlaybackRoutine Mar 24 22:29:21 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Mar 24 22:29:21 volumio volumio[1307]: info: Airplay playback start Mar 24 22:29:21 volumio volumio[1307]: info: CorePlayQueue::getTrack 6 Mar 24 22:29:21 volumio volumio[1307]: info: CoreCommandRouter::volumioStop Mar 24 22:29:21 volumio volumio[1307]: info: CoreStateMachine::stop Mar 24 22:29:21 volumio volumio[1307]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 22:29:21 volumio volumio[1307]: UNSET VOLATILE Mar 24 22:29:21 volumio volumio[1307]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 22:29:21 volumio volumio[1307]: info: Mar 24 22:29:21 volumio volumio[1307]: ---------------------------- Client requests AirplayActive Mar 24 22:29:21 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive Mar 24 22:29:21 volumio shairport-sync[1539]: [155B blob data] Mar 24 22:29:21 volumio shairport-sync[1539]: [143B blob data] Mar 24 22:29:22 volumio volumio[1307]: info: CoreCommandRouter::servicePushState Mar 24 22:29:22 volumio volumio[1307]: info: CoreStateMachine::pushState Mar 24 22:29:22 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Mar 24 22:29:22 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 22:29:22 volumio volumio[1307]: info: CoreCommandRouter::servicePushState Mar 24 22:29:22 volumio volumio[1307]: info: CoreStateMachine::pushState Mar 24 22:29:22 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Mar 24 22:29:22 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 22:29:22 volumio volumio[1307]: info: CoreCommandRouter::servicePushState Mar 24 22:29:22 volumio volumio[1307]: info: CoreStateMachine::pushState Mar 24 22:29:22 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Mar 24 22:29:22 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 22:29:23 volumio volumio[1307]: info: CoreCommandRouter::servicePushState Mar 24 22:29:23 volumio volumio[1307]: info: CoreStateMachine::pushState Mar 24 22:29:23 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Mar 24 22:29:23 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 22:29:23 volumio volumio[1307]: info: CoreCommandRouter::servicePushState Mar 24 22:29:23 volumio volumio[1307]: info: CoreStateMachine::pushState Mar 24 22:29:23 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Mar 24 22:29:23 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 22:29:23 volumio volumio[1307]: info: CoreCommandRouter::servicePushState Mar 24 22:29:23 volumio volumio[1307]: info: CoreStateMachine::pushState Mar 24 22:29:23 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Mar 24 22:29:23 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 22:29:23 volumio volumio[1307]: info: CoreCommandRouter::servicePushState Mar 24 22:29:23 volumio volumio[1307]: info: CoreStateMachine::pushState Mar 24 22:29:23 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Mar 24 22:29:23 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 22:29:47 volumio dhcpd[1324]: DHCPDISCOVER from b8:27:eb:b5:6a:12 (volumio) via wlan0 Mar 24 22:29:48 volumio dhcpd[1324]: DHCPOFFER on 192.168.211.2 to b8:27:eb:b5:6a:12 (volumio) via wlan0 Mar 24 22:29:53 volumio volumio[1307]: info: CoreCommandRouter::volumioGetVisibleSources Mar 24 22:29:53 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 22:29:53 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState Mar 24 22:29:53 volumio volumio[1307]: info: CoreCommandRouter::volumioGetQueue Mar 24 22:29:53 volumio volumio[1307]: info: CoreStateMachine::getQueue Mar 24 22:29:53 volumio volumio[1307]: info: CorePlayQueue::getQueue Mar 24 22:29:53 volumio volumio[1307]: info: Listing playlists Mar 24 22:29:53 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 24 22:29:53 volumio volumio[1307]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 22:29:53 volumio volumio[1307]: { Error: certificate is not yet valid Mar 24 22:29:53 volumio volumio[1307]: at TLSSocket. (_tls_wrap.js:1105:38) Mar 24 22:29:53 volumio volumio[1307]: at emitNone (events.js:106:13) Mar 24 22:29:53 volumio volumio[1307]: at TLSSocket.emit (events.js:208:7) Mar 24 22:29:53 volumio volumio[1307]: at TLSSocket._finishInit (_tls_wrap.js:639:8) Mar 24 22:29:53 volumio volumio[1307]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:469:38) code: 'CERT_NOT_YET_VALID' } Mar 24 22:29:53 volumio volumio[1307]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 22:29:54 volumio sudo[28773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2022-03-24 22:28 Mar 24 22:29:54 volumio sudo[28773]: 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="e81b2037a65d8f554cb0c857a1007d659ad7f838" VOLUMIO_FE_VERSION="e489037accd8e0b2dcbd6e0b054400d8cd36d4e2" VOLUMIO_BE_VERSION="25875b197c75cd1ac7eb5664d94cdd19afb06985" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Feb 19 17:39:10 CET 2021" VOLUMIO_VERSION="2.873" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="781d4c9fbf86d562939968306a6a26f4"