-- Logs begin at Tue 2023-05-23 15:22:30 UTC, end at Tue 2023-05-23 15:26:16 UTC. -- May 23 15:25:20 volumio2 volumio[989]: info: CoreCommandRouter::servicePushState May 23 15:25:20 volumio2 volumio[989]: info: CorePlayQueue::getTrack 0 May 23 15:25:20 volumio2 volumio[989]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":0,"duration":0,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true} May 23 15:25:20 volumio2 volumio[989]: verbose: CURRENT POSITION 0 May 23 15:25:20 volumio2 volumio[989]: info: CoreStateMachine::syncState stateService play May 23 15:25:20 volumio2 volumio[989]: info: CoreStateMachine::syncState currentStatus stop May 23 15:25:20 volumio2 volumio[989]: info: CoreStateMachine::pushState May 23 15:25:20 volumio2 volumio[989]: info: CorePlayQueue::getTrack 0 May 23 15:25:20 volumio2 volumio[989]: info: CoreCommandRouter::volumioPushState May 23 15:25:20 volumio2 volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 15:25:20 volumio2 volumio[989]: info: CoreCommandRouter::servicePushState May 23 15:25:20 volumio2 volumio[989]: info: CorePlayQueue::getTrack 0 May 23 15:25:20 volumio2 volumio[989]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":0,"duration":0,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true} May 23 15:25:20 volumio2 volumio[989]: verbose: CURRENT POSITION 0 May 23 15:25:20 volumio2 volumio[989]: info: CoreStateMachine::syncState stateService play May 23 15:25:20 volumio2 volumio[989]: info: CoreStateMachine::syncState currentStatus play May 23 15:25:20 volumio2 volumio[989]: info: Received an update from plugin. extracting info from payload May 23 15:25:20 volumio2 volumio[989]: info: CoreStateMachine::pushState May 23 15:25:20 volumio2 shairport-sync[1276]: Failed to create secure directory (/home/shairport-sync/.config/pulse): No such file or directory May 23 15:25:20 volumio2 volumio[989]: info: CorePlayQueue::getTrack 0 May 23 15:25:20 volumio2 volumio[989]: info: CoreCommandRouter::volumioPushState May 23 15:25:20 volumio2 volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 15:25:20 volumio2 volumio[989]: info: CoreStateMachine::pushState May 23 15:25:20 volumio2 volumio[989]: info: CorePlayQueue::getTrack 0 May 23 15:25:20 volumio2 volumio[989]: info: CoreCommandRouter::volumioPushState May 23 15:25:20 volumio2 volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 15:25:20 volumio2 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 23 15:25:20 volumio2 shairport-sync[1276]: % Total % Received % Xferd Average Speed Time Time Time Current May 23 15:25:20 volumio2 shairport-sync[1276]: Dload Upload Total Spent Left Speed May 23 15:25:20 volumio2 volumio[989]: info: Airplay started streaming, receiving metadatas May 23 15:25:20 volumio2 volumio[989]: info: CorePlayQueue::getTrack 0 May 23 15:25:20 volumio2 volumio[989]: info: CoreCommandRouter::volumioStop May 23 15:25:20 volumio2 volumio[989]: info: CoreStateMachine::stop May 23 15:25:20 volumio2 volumio[989]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 15:25:20 volumio2 shairport-sync[1276]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 % Total % Received % Xferd Average Speed Time Time Time Current May 23 15:25:20 volumio2 shairport-sync[1276]: Dload Upload Total Spent Left Speed May 23 15:25:20 volumio2 volumio[989]: verbose: UNSET VOLATILE May 23 15:25:20 volumio2 volumio[989]: info: CoreStateMachine::stPlaybackTimer May 23 15:25:20 volumio2 volumio[989]: info: CoreStateMachine::updateTrackBlock May 23 15:25:20 volumio2 volumio[989]: info: CorePlayQueue::getTrackBlock May 23 15:25:20 volumio2 volumio[989]: info: CoreStateMachine::pushState May 23 15:25:20 volumio2 volumio[989]: info: CorePlayQueue::getTrack 0 May 23 15:25:20 volumio2 volumio[989]: info: CoreCommandRouter::volumioPushState May 23 15:25:20 volumio2 volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 15:25:20 volumio2 volumio[989]: info: CoreStateMachine::serviceStop May 23 15:25:20 volumio2 volumio[989]: info: CorePlayQueue::getTrack 0 May 23 15:25:20 volumio2 volumio[989]: info: ControllerMpd::stop May 23 15:25:20 volumio2 volumio[989]: verbose: ControllerMpd::sendMpdCommand stop May 23 15:25:20 volumio2 volumio[989]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 15:25:20 volumio2 volumio[989]: info: Airplay started streaming, receiving metadatas May 23 15:25:20 volumio2 volumio[989]: info: CorePlayQueue::getTrack 0 May 23 15:25:20 volumio2 volumio[989]: info: CoreCommandRouter::volumioStop May 23 15:25:20 volumio2 volumio[989]: info: CoreStateMachine::stop May 23 15:25:20 volumio2 volumio[989]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 15:25:20 volumio2 volumio[989]: verbose: UNSET VOLATILE May 23 15:25:20 volumio2 volumio[989]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 15:25:20 volumio2 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 23 15:25:20 volumio2 volumio[989]: info: sendMpdCommand stop took 45 milliseconds May 23 15:25:20 volumio2 volumio[989]: info: May 23 15:25:20 volumio2 volumio[989]: ---------------------------- Client requests AirplayActive May 23 15:25:20 volumio2 volumio[989]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive May 23 15:25:20 volumio2 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 23 15:25:20 volumio2 shairport-sync[1276]: [234B blob data] May 23 15:25:20 volumio2 volumio[989]: info: May 23 15:25:20 volumio2 volumio[989]: ---------------------------- Client requests Start Airplay PlaybackRoutine May 23 15:25:20 volumio2 volumio[989]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback May 23 15:25:20 volumio2 volumio[989]: info: Airplay playback start May 23 15:25:20 volumio2 volumio[989]: info: CorePlayQueue::getTrack 0 May 23 15:25:20 volumio2 volumio[989]: info: CoreCommandRouter::volumioStop May 23 15:25:20 volumio2 volumio[989]: info: CoreStateMachine::stop May 23 15:25:20 volumio2 volumio[989]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 15:25:20 volumio2 volumio[989]: verbose: UNSET VOLATILE May 23 15:25:20 volumio2 volumio[989]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 15:25:20 volumio2 shairport-sync[1276]: [136B blob data] May 23 15:25:21 volumio2 volumio[989]: info: CoreCommandRouter::servicePushState May 23 15:25:21 volumio2 volumio[989]: info: CoreStateMachine::pushState May 23 15:25:21 volumio2 volumio[989]: info: CoreCommandRouter::volumioPushState May 23 15:25:21 volumio2 volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 15:25:21 volumio2 volumio[989]: info: CoreCommandRouter::servicePushState May 23 15:25:21 volumio2 volumio[989]: info: CoreStateMachine::pushState May 23 15:25:21 volumio2 volumio[989]: info: CoreCommandRouter::volumioPushState May 23 15:25:21 volumio2 volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 15:25:21 volumio2 volumio[989]: info: CoreCommandRouter::servicePushState May 23 15:25:21 volumio2 volumio[989]: info: CoreStateMachine::pushState May 23 15:25:21 volumio2 volumio[989]: info: CoreCommandRouter::volumioPushState May 23 15:25:21 volumio2 volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 15:25:22 volumio2 volumio[989]: info: CoreCommandRouter::servicePushState May 23 15:25:22 volumio2 volumio[989]: info: CoreStateMachine::pushState May 23 15:25:22 volumio2 volumio[989]: info: CoreCommandRouter::volumioPushState May 23 15:25:22 volumio2 volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 15:25:22 volumio2 volumio[989]: info: CoreCommandRouter::servicePushState May 23 15:25:22 volumio2 volumio[989]: info: CoreStateMachine::pushState May 23 15:25:22 volumio2 volumio[989]: info: CoreCommandRouter::volumioPushState May 23 15:25:22 volumio2 volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 15:25:22 volumio2 volumio[989]: info: CoreCommandRouter::servicePushState May 23 15:25:22 volumio2 volumio[989]: info: CoreStateMachine::pushState May 23 15:25:22 volumio2 volumio[989]: info: CoreCommandRouter::volumioPushState May 23 15:25:22 volumio2 volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 15:25:22 volumio2 volumio[989]: info: CoreCommandRouter::servicePushState May 23 15:25:22 volumio2 volumio[989]: info: CoreStateMachine::pushState May 23 15:25:22 volumio2 volumio[989]: info: CoreCommandRouter::volumioPushState May 23 15:25:22 volumio2 volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 15:26:13 volumio2 volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 15:26:13 volumio2 volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 15:26:13 volumio2 volumio[989]: info: Discovery: Getting this device information May 23 15:26:13 volumio2 volumio[989]: info: CoreCommandRouter::volumioGetState May 23 15:26:13 volumio2 volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses May 23 15:26:13 volumio2 volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConf May 23 15:26:13 volumio2 volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConf May 23 15:26:13 volumio2 volumio[989]: info: CoreCommandRouter::volumioGetState May 23 15:26:13 volumio2 volumio[989]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 23 15:26:13 volumio2 volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 23 15:26:14 volumio2 volumio[989]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 15:26:14 volumio2 volumio[989]: { Error: certificate is not yet valid May 23 15:26:14 volumio2 volumio[989]: at TLSSocket. (_tls_wrap.js:1105:38) May 23 15:26:14 volumio2 volumio[989]: at emitNone (events.js:106:13) May 23 15:26:14 volumio2 volumio[989]: at TLSSocket.emit (events.js:208:7) May 23 15:26:14 volumio2 volumio[989]: at TLSSocket._finishInit (_tls_wrap.js:639:8) May 23 15:26:14 volumio2 volumio[989]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:469:38) code: 'CERT_NOT_YET_VALID' } May 23 15:26:14 volumio2 volumio[989]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 15:26:16 volumio2 sudo[1698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-05-23 15:25 May 23 15:26:16 volumio2 sudo[1698]: 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"