-- 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"