-- Logs begin at Sat 2021-03-20 05:15:23 UTC, end at Fri 2021-03-26 03:45:29 UTC. -- Mar 26 03:44:56 volumio volumio[744]: info: CoreCommandRouter::servicePushState Mar 26 03:44:56 volumio volumio[744]: info: CoreStateMachine::pushState Mar 26 03:44:56 volumio volumio[744]: info: CoreCommandRouter::volumioPushState Mar 26 03:44:56 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:44:56 volumio volumio[744]: info: CoreCommandRouter::servicePushState Mar 26 03:44:56 volumio volumio[744]: info: CoreStateMachine::pushState Mar 26 03:44:56 volumio volumio[744]: info: CoreCommandRouter::volumioPushState Mar 26 03:44:56 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:44:56 volumio volumio[744]: info: CoreCommandRouter::servicePushState Mar 26 03:44:56 volumio volumio[744]: info: CoreStateMachine::pushState Mar 26 03:44:56 volumio volumio[744]: info: CoreCommandRouter::volumioPushState Mar 26 03:44:56 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:44:56 volumio volumio[744]: info: CoreCommandRouter::servicePushState Mar 26 03:44:56 volumio volumio[744]: info: CoreStateMachine::pushState Mar 26 03:44:56 volumio volumio[744]: info: CoreCommandRouter::volumioPushState Mar 26 03:44:56 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:44:56 volumio volumio[744]: info: CoreCommandRouter::servicePushState Mar 26 03:44:56 volumio volumio[744]: info: CoreStateMachine::pushState Mar 26 03:44:56 volumio volumio[744]: info: CoreCommandRouter::volumioPushState Mar 26 03:44:56 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:44:56 volumio volumio[744]: info: CoreCommandRouter::servicePushState Mar 26 03:44:56 volumio volumio[744]: info: CoreStateMachine::pushState Mar 26 03:44:56 volumio volumio[744]: info: CoreCommandRouter::volumioPushState Mar 26 03:44:56 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:45:04 volumio volumio[744]: info: CoreCommandRouter::servicePushState Mar 26 03:45:04 volumio volumio[744]: info: CoreStateMachine::pushState Mar 26 03:45:04 volumio volumio[744]: info: CoreCommandRouter::volumioPushState Mar 26 03:45:04 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:45:04 volumio kernel: aotg_hub_endpoint_disable Mar 26 03:45:04 volumio kernel: aotg_hcd aotg_hcd.1: ep1 index 1 from ep [out] Mar 26 03:45:04 volumio kernel: aotg_hub_endpoint_disable Mar 26 03:45:04 volumio kernel: aotg_hcd aotg_hcd.1: ep1 index 2 from ep [in] Mar 26 03:45:04 volumio volumio[744]: info: CoreCommandRouter::servicePushState Mar 26 03:45:04 volumio volumio[744]: info: CoreStateMachine::pushState Mar 26 03:45:04 volumio volumio[744]: info: CoreCommandRouter::volumioPushState Mar 26 03:45:04 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:45:04 volumio shairport-sync[6953]: {"time":1616729680388,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Mar 26 03:45:04 volumio shairport-sync[6953]: Dload Upload Total Spent Left Speed Mar 26 03:45:04 volumio volumio[744]: info: Mar 26 03:45:04 volumio volumio[744]: ---------------------------- Client requests Stop Airplay Playback Mar 26 03:45:04 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Mar 26 03:45:04 volumio volumio[744]: info: Airplay playback stop Mar 26 03:45:04 volumio volumio[744]: info: Airplay Playback Stopped, clearing state Mar 26 03:45:04 volumio volumio[744]: info: CoreStateMachine::resetVolumioState Mar 26 03:45:04 volumio volumio[744]: info: CoreStateMachine::getcurrentVolume Mar 26 03:45:04 volumio volumio[744]: info: CoreCommandRouter::volumioRetrievevolume Mar 26 03:45:04 volumio shairport-sync[6953]: [155B blob data] Mar 26 03:45:04 volumio volumio[744]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Mar 26 03:45:04 volumio volumio[744]: info: VolumeController:: Volume=undefined Mute =false Mar 26 03:45:04 volumio volumio[744]: info: CoreStateMachine::pushState Mar 26 03:45:04 volumio volumio[744]: info: CoreCommandRouter::volumioPushState Mar 26 03:45:04 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:45:04 volumio volumio[744]: info: CoreStateMachine::updateTrackBlock Mar 26 03:45:04 volumio volumio[744]: info: CorePlayQueue::getTrackBlock Mar 26 03:45:04 volumio volumio[744]: info: CoreCommandRouter::volumioRetrievevolume Mar 26 03:45:04 volumio volumio[744]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Mar 26 03:45:04 volumio volumio[744]: info: VolumeController:: Volume=undefined Mute =false Mar 26 03:45:04 volumio volumio[744]: info: CoreStateMachine::pushState Mar 26 03:45:04 volumio volumio[744]: info: CoreCommandRouter::volumioPushState Mar 26 03:45:04 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:45:05 volumio volumio[744]: info: Airplay started streaming, receiving metadatas Mar 26 03:45:05 volumio kernel: snd_pcm_mmap,default Mar 26 03:45:05 volumio kernel: iso_packets:0, bInterval:1, urb_interval:1, reg_con:0x4 Mar 26 03:45:05 volumio kernel: iso_packets:0, bInterval:4, urb_interval:8, reg_con:0x4 Mar 26 03:45:05 volumio volumio[744]: info: Airplay started streaming, receiving metadatas Mar 26 03:45:05 volumio kernel: aotg_hub_endpoint_disable Mar 26 03:45:05 volumio kernel: aotg_hcd aotg_hcd.1: ep1 index 1 from ep [out] Mar 26 03:45:05 volumio kernel: aotg_hub_endpoint_disable Mar 26 03:45:05 volumio kernel: aotg_hcd aotg_hcd.1: ep1 index 2 from ep [in] Mar 26 03:45:05 volumio kernel: snd_pcm_mmap,default Mar 26 03:45:05 volumio kernel: iso_packets:0, bInterval:1, urb_interval:1, reg_con:0x4 Mar 26 03:45:05 volumio kernel: iso_packets:0, bInterval:4, urb_interval:8, reg_con:0x4 Mar 26 03:45:05 volumio shairport-sync[6953]: {"time":1616730304422,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Mar 26 03:45:05 volumio shairport-sync[6953]: Dload Upload Total Spent Left Speed Mar 26 03:45:05 volumio volumio[744]: info: Mar 26 03:45:05 volumio volumio[744]: ---------------------------- Client requests Start Airplay PlaybackRoutine Mar 26 03:45:05 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Mar 26 03:45:05 volumio volumio[744]: info: Airplay playback start Mar 26 03:45:05 volumio shairport-sync[6953]: [155B blob data] Mar 26 03:45:05 volumio kernel: aotg_hub_endpoint_disable Mar 26 03:45:05 volumio kernel: aotg_hcd aotg_hcd.1: ep1 index 1 from ep [out] Mar 26 03:45:05 volumio kernel: aotg_hub_endpoint_disable Mar 26 03:45:05 volumio kernel: aotg_hcd aotg_hcd.1: ep1 index 2 from ep [in] Mar 26 03:45:05 volumio kernel: snd_pcm_mmap,default Mar 26 03:45:05 volumio kernel: iso_packets:0, bInterval:1, urb_interval:1, reg_con:0x4 Mar 26 03:45:05 volumio kernel: iso_packets:0, bInterval:4, urb_interval:8, reg_con:0x4 Mar 26 03:45:06 volumio volumio[744]: info: CoreCommandRouter::servicePushState Mar 26 03:45:06 volumio volumio[744]: info: CoreStateMachine::pushState Mar 26 03:45:06 volumio volumio[744]: info: CoreCommandRouter::volumioPushState Mar 26 03:45:06 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:45:06 volumio volumio[744]: info: CoreCommandRouter::servicePushState Mar 26 03:45:06 volumio volumio[744]: info: CoreStateMachine::pushState Mar 26 03:45:06 volumio volumio[744]: info: CoreCommandRouter::volumioPushState Mar 26 03:45:06 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:45:06 volumio volumio[744]: info: CoreCommandRouter::servicePushState Mar 26 03:45:06 volumio volumio[744]: info: CoreStateMachine::pushState Mar 26 03:45:06 volumio volumio[744]: info: CoreCommandRouter::volumioPushState Mar 26 03:45:06 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:45:06 volumio volumio[744]: info: CoreCommandRouter::servicePushState Mar 26 03:45:06 volumio volumio[744]: info: CoreStateMachine::pushState Mar 26 03:45:06 volumio volumio[744]: info: CoreCommandRouter::volumioPushState Mar 26 03:45:06 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:45:06 volumio volumio[744]: info: CoreCommandRouter::servicePushState Mar 26 03:45:06 volumio volumio[744]: info: CoreStateMachine::pushState Mar 26 03:45:06 volumio volumio[744]: info: CoreCommandRouter::volumioPushState Mar 26 03:45:06 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:45:06 volumio volumio[744]: info: CoreCommandRouter::servicePushState Mar 26 03:45:06 volumio volumio[744]: info: CoreStateMachine::pushState Mar 26 03:45:06 volumio volumio[744]: info: CoreCommandRouter::volumioPushState Mar 26 03:45:06 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:45:06 volumio volumio[744]: info: CoreCommandRouter::servicePushState Mar 26 03:45:06 volumio volumio[744]: info: CoreStateMachine::pushState Mar 26 03:45:06 volumio volumio[744]: info: CoreCommandRouter::volumioPushState Mar 26 03:45:06 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:45:09 volumio volumio[744]: verbose: ControllerMpd::sendMpdCommand status Mar 26 03:45:09 volumio volumio[744]: info: Mar 26 03:45:09 volumio volumio[744]: ---------------------------- MPD announces state update: update Mar 26 03:45:09 volumio volumio[744]: info: ControllerMpd::getState Mar 26 03:45:09 volumio volumio[744]: verbose: ControllerMpd::sendMpdCommand status Mar 26 03:45:09 volumio volumio[744]: verbose: ControllerMpd::sendMpdCommand status Mar 26 03:45:09 volumio volumio[744]: info: Mar 26 03:45:09 volumio volumio[744]: ---------------------------- MPD announces state update: update Mar 26 03:45:09 volumio volumio[744]: info: ControllerMpd::getState Mar 26 03:45:09 volumio volumio[744]: verbose: ControllerMpd::sendMpdCommand status Mar 26 03:45:09 volumio volumio[744]: info: sendMpdCommand status took 6 milliseconds Mar 26 03:45:09 volumio volumio[744]: info: sendMpdCommand status took 5 milliseconds Mar 26 03:45:09 volumio volumio[744]: verbose: ControllerMpd::parseState Mar 26 03:45:09 volumio volumio[744]: info: Command Router : Notfying DB Updatefalse Mar 26 03:45:09 volumio volumio[744]: info: CoreCommandRouter::Close All Modals sent Mar 26 03:45:09 volumio volumio[744]: verbose: ControllerMpd::parseState Mar 26 03:45:09 volumio volumio[744]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 03:45:09 volumio volumio[744]: info: sendMpdCommand status took 59 milliseconds Mar 26 03:45:09 volumio volumio[744]: info: sendMpdCommand status took 57 milliseconds Mar 26 03:45:09 volumio volumio[744]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 26 03:45:09 volumio volumio[744]: verbose: ControllerMpd::parseState Mar 26 03:45:09 volumio volumio[744]: info: Command Router : Notfying DB Updatefalse Mar 26 03:45:09 volumio volumio[744]: info: CoreCommandRouter::Close All Modals sent Mar 26 03:45:09 volumio volumio[744]: verbose: ControllerMpd::parseState Mar 26 03:45:09 volumio volumio[744]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 03:45:09 volumio volumio[744]: verbose: ControllerMpd::parseTrackInfo Mar 26 03:45:09 volumio volumio[744]: info: ControllerMpd::pushState Mar 26 03:45:09 volumio volumio[744]: info: CoreCommandRouter::servicePushState Mar 26 03:45:09 volumio volumio[744]: info: CoreStateMachine::pushState Mar 26 03:45:09 volumio volumio[744]: info: CoreCommandRouter::volumioPushState Mar 26 03:45:09 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:45:09 volumio volumio[744]: info: CorePlayQueue::getTrack 0 Mar 26 03:45:09 volumio volumio[744]: 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":"Dance of Ixtab (Psychopomp & Circumstance March No. 1 in B)","artist":"CARCASS","album":"Torn Arteries","uri":"USB/Elements SE/MUZYKA 3/Саrсаss - Тоrn Аrtеries (2021)/CARCASS - Torn Arteries.flac","trackType":"flac"} Mar 26 03:45:09 volumio volumio[744]: verbose: CURRENT POSITION 0 Mar 26 03:45:09 volumio volumio[744]: info: CoreStateMachine::syncState stateService stop Mar 26 03:45:09 volumio volumio[744]: info: CoreStateMachine::syncState currentStatus play Mar 26 03:45:09 volumio volumio[744]: info: CoreCommandRouter::volumioPushState Mar 26 03:45:09 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:45:09 volumio volumio[744]: info: CoreStateMachine::stPlaybackTimer Mar 26 03:45:09 volumio volumio[744]: info: ------------------------------ 117ms Mar 26 03:45:09 volumio volumio[744]: info: sendMpdCommand playlistinfo took 26 milliseconds Mar 26 03:45:09 volumio volumio[744]: verbose: ControllerMpd::parseTrackInfo Mar 26 03:45:09 volumio volumio[744]: info: ControllerMpd::pushState Mar 26 03:45:09 volumio volumio[744]: info: CoreCommandRouter::servicePushState Mar 26 03:45:09 volumio volumio[744]: info: CoreStateMachine::pushState Mar 26 03:45:09 volumio volumio[744]: info: CoreCommandRouter::volumioPushState Mar 26 03:45:09 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:45:09 volumio volumio[744]: info: CorePlayQueue::getTrack 0 Mar 26 03:45:09 volumio volumio[744]: 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":"Dance of Ixtab (Psychopomp & Circumstance March No. 1 in B)","artist":"CARCASS","album":"Torn Arteries","uri":"USB/Elements SE/MUZYKA 3/Саrсаss - Тоrn Аrtеries (2021)/CARCASS - Torn Arteries.flac","trackType":"flac"} Mar 26 03:45:09 volumio volumio[744]: verbose: CURRENT POSITION 0 Mar 26 03:45:09 volumio volumio[744]: info: CoreStateMachine::syncState stateService stop Mar 26 03:45:09 volumio volumio[744]: info: CoreStateMachine::syncState currentStatus stop Mar 26 03:45:09 volumio volumio[744]: info: CoreStateMachine::pushState Mar 26 03:45:09 volumio volumio[744]: info: CoreCommandRouter::volumioPushState Mar 26 03:45:09 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:45:09 volumio volumio[744]: info: No code Mar 26 03:45:09 volumio volumio[744]: info: CoreStateMachine::pushState Mar 26 03:45:09 volumio volumio[744]: info: CoreCommandRouter::volumioPushState Mar 26 03:45:09 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:45:09 volumio volumio[744]: info: ------------------------------ 141ms Mar 26 03:45:26 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 03:45:26 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 03:45:26 volumio volumio[744]: info: Discovery: Getting this device information Mar 26 03:45:26 volumio volumio[744]: info: CoreCommandRouter::volumioGetState Mar 26 03:45:26 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Mar 26 03:45:26 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getConf Mar 26 03:45:26 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getConf Mar 26 03:45:26 volumio volumio[744]: info: CoreCommandRouter::volumioGetState Mar 26 03:45:26 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 26 03:45:26 volumio volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 26 03:45:27 volumio volumio[744]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 03:45:27 volumio volumio[744]: { Error: certificate is not yet valid Mar 26 03:45:27 volumio volumio[744]: at TLSSocket. (_tls_wrap.js:1105:38) Mar 26 03:45:27 volumio volumio[744]: at emitNone (events.js:106:13) Mar 26 03:45:27 volumio volumio[744]: at TLSSocket.emit (events.js:208:7) Mar 26 03:45:27 volumio volumio[744]: at TLSSocket._finishInit (_tls_wrap.js:639:8) Mar 26 03:45:27 volumio volumio[744]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:469:38) code: 'CERT_NOT_YET_VALID' } Mar 26 03:45:27 volumio volumio[744]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 03:45:29 volumio sudo[7858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2021-03-26 03:44 Mar 26 03:45:29 volumio sudo[7858]: 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="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 12:12:00 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="sparky" VOLUMIO_HASH="1507c65028120e16c14994c066bc5dd4"