-- Logs begin at Thu 2020-09-24 20:56:34 UTC, end at Thu 2020-09-24 21:28:28 UTC. -- Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::servicePushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreStateMachine::pushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::servicePushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreStateMachine::pushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::servicePushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreStateMachine::pushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::servicePushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreStateMachine::pushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::servicePushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreStateMachine::pushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::servicePushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreStateMachine::pushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::servicePushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreStateMachine::pushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::servicePushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreStateMachine::pushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::servicePushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreStateMachine::pushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::servicePushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreStateMachine::pushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::servicePushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreStateMachine::pushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::servicePushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreStateMachine::pushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 24 21:27:15 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 21:28:17 volumio-mosa volumio[1039]: info: CoreCommandRouter::servicePushState Sep 24 21:28:17 volumio-mosa volumio[1039]: info: CoreStateMachine::pushState Sep 24 21:28:17 volumio-mosa volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 24 21:28:17 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 21:28:17 volumio-mosa volumio[1039]: info: CoreCommandRouter::servicePushState Sep 24 21:28:17 volumio-mosa volumio[1039]: info: CoreStateMachine::pushState Sep 24 21:28:17 volumio-mosa volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 24 21:28:17 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 21:28:19 volumio-mosa volumio[1039]: info: CoreCommandRouter::servicePushState Sep 24 21:28:19 volumio-mosa volumio[1039]: info: CoreStateMachine::pushState Sep 24 21:28:19 volumio-mosa volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 24 21:28:19 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 21:28:19 volumio-mosa volumio[1039]: info: CoreCommandRouter::servicePushState Sep 24 21:28:19 volumio-mosa volumio[1039]: info: CoreStateMachine::pushState Sep 24 21:28:19 volumio-mosa volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 24 21:28:19 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 21:28:19 volumio-mosa shairport-sync[1236]: {"time":1600981259473,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Sep 24 21:28:19 volumio-mosa shairport-sync[1236]: Dload Upload Total Spent Left Speed Sep 24 21:28:19 volumio-mosa volumio[1039]: info: Sep 24 21:28:19 volumio-mosa volumio[1039]: ---------------------------- Client requests Stop Airplay Playback Sep 24 21:28:19 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Sep 24 21:28:19 volumio-mosa volumio[1039]: info: Airplay playback stop Sep 24 21:28:19 volumio-mosa volumio[1039]: info: Airplay Playback Stopped, clearing state Sep 24 21:28:19 volumio-mosa volumio[1039]: info: CoreStateMachine::resetVolumioState Sep 24 21:28:19 volumio-mosa shairport-sync[1236]: [155B blob data] Sep 24 21:28:19 volumio-mosa volumio[1039]: info: CoreStateMachine::getcurrentVolume Sep 24 21:28:19 volumio-mosa volumio[1039]: info: CoreCommandRouter::volumioRetrievevolume Sep 24 21:28:19 volumio-mosa volumio[1039]: info: CoreStateMachine::pushState Sep 24 21:28:19 volumio-mosa volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 24 21:28:19 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 21:28:25 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 24 21:28:25 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 24 21:28:25 volumio-mosa volumio[1039]: info: Discovery: Getting this device information Sep 24 21:28:25 volumio-mosa volumio[1039]: info: CoreCommandRouter::volumioGetState Sep 24 21:28:25 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Sep 24 21:28:25 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConf Sep 24 21:28:25 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConf Sep 24 21:28:25 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 24 21:28:25 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 24 21:28:25 volumio-mosa volumio[1039]: info: Discovery: Getting this device information Sep 24 21:28:25 volumio-mosa volumio[1039]: info: CoreCommandRouter::volumioGetState Sep 24 21:28:25 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Sep 24 21:28:25 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConf Sep 24 21:28:25 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConf Sep 24 21:28:26 volumio-mosa volumio[1039]: info: Airplay started streaming, receiving metadatas Sep 24 21:28:26 volumio-mosa kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Sep 24 21:28:26 volumio-mosa volumio[1039]: info: Airplay started streaming, receiving metadatas Sep 24 21:28:26 volumio-mosa shairport-sync[1236]: {"time":1600982899647,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Sep 24 21:28:26 volumio-mosa shairport-sync[1236]: Dload Upload Total Spent Left Speed Sep 24 21:28:26 volumio-mosa volumio[1039]: info: Sep 24 21:28:26 volumio-mosa volumio[1039]: ---------------------------- Client requests Start Airplay PlaybackRoutine Sep 24 21:28:26 volumio-mosa volumio[1039]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Sep 24 21:28:26 volumio-mosa volumio[1039]: info: Airplay playback start Sep 24 21:28:26 volumio-mosa shairport-sync[1236]: [155B blob data] Sep 24 21:28:26 volumio-mosa volumio[1039]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 24 21:28:26 volumio-mosa volumio[1039]: { Error: certificate is not yet valid Sep 24 21:28:26 volumio-mosa volumio[1039]: at TLSSocket. (_tls_wrap.js:1105:38) Sep 24 21:28:26 volumio-mosa volumio[1039]: at emitNone (events.js:106:13) Sep 24 21:28:26 volumio-mosa volumio[1039]: at TLSSocket.emit (events.js:208:7) Sep 24 21:28:26 volumio-mosa volumio[1039]: at TLSSocket._finishInit (_tls_wrap.js:639:8) Sep 24 21:28:26 volumio-mosa volumio[1039]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:469:38) code: 'CERT_NOT_YET_VALID' } Sep 24 21:28:26 volumio-mosa volumio[1039]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 24 21:28:28 volumio-mosa sudo[5280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2020-09-24 21:27 Sep 24 21:28:28 volumio-mosa sudo[5280]: 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="74e4cc9de715c64d553d35948d017f973a622b6d" VOLUMIO_FE_VERSION="2be6c28eb9de74ec1f9662ca333f7bd51a232c33" VOLUMIO_BE_VERSION="259a7f2894e1376413ffac331be84e0e9a6173dd" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Sep 24 22:42:43 CEST 2020" VOLUMIO_VERSION="2.834" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="e41ef0f29aa50c1af109d3064a380c69"