-- Logs begin at Fri 2020-11-20 19:05:14 UTC, end at Fri 2020-11-20 19:21:13 UTC. -- Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreStateMachine::ClearQueue Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreStateMachine::stop Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreStateMachine::serviceStop Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreCommandRouter::serviceStop Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: Airplay Stop Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: Stopping Airplay Playback and sending pause command to client via USR2 Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CorePlayQueue::clearPlayQueue Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CorePlayQueue::saveQueue Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushQueue Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreStateMachine::addQueueItems Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CorePlayQueue::addQueueItems Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: Adding Item to queue: http://eu8.fastcast4u.com:5068 Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CorePlayQueue::saveQueue Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushQueue Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreStateMachine::updateTrackBlock Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrackBlock Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPlay Nov 20 19:20:03 rvolumiok4 volumio[1274]: UNSET VOLATILE Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: Stopping Airplay Playback and sending pause command to client via USR2 Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreStateMachine::play index 0 Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreStateMachine::stop Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 19:20:03 rvolumiok4 volumio[1274]: UNSET VOLATILE Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreStateMachine::stPlaybackTimer Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreStateMachine::updateTrackBlock Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrackBlock Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrack 0 Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreStateMachine::serviceStop Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrack 0 Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreCommandRouter::serviceStop Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: [1605900003221] ControllerWebradio::stop Nov 20 19:20:03 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand stop Nov 20 19:20:03 rvolumiok4 sudo[3635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1444 Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreStateMachine::play index undefined Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrack 0 Nov 20 19:20:03 rvolumiok4 sudo[3635]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreStateMachine::startPlaybackTimer Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrack 0 Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: [1605900003233] ControllerWebradio::clearAddPlayTrack Nov 20 19:20:03 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand stop Nov 20 19:20:03 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand clear Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: Nov 20 19:20:03 rvolumiok4 volumio[1274]: ---------------------------- MPD announces system playlist update Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: Ignoring MPD Status Update Nov 20 19:20:03 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand load "http://eu8.fastcast4u.com:5068" Nov 20 19:20:03 rvolumiok4 sudo[3635]: pam_unix(sudo:session): session closed for user root Nov 20 19:20:03 rvolumiok4 systemd[1]: Starting Cleanup of Temporary Directories... Nov 20 19:20:03 rvolumiok4 systemd[1]: shairport-sync.service: main process exited, code=killed, status=12/USR2 Nov 20 19:20:03 rvolumiok4 systemd[1]: Unit shairport-sync.service entered failed state. Nov 20 19:20:03 rvolumiok4 volumio[1274]: error: updateQueue error: null Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: ------------------------------ 6ms Nov 20 19:20:03 rvolumiok4 shairport-sync[1444]: {"time":1605899944801,"response":"startAirplayPlayback Success"} Nov 20 19:20:03 rvolumiok4 sudo[3642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1444 Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: Shairport-Sync paused with USR2 Nov 20 19:20:03 rvolumiok4 systemd[1]: Started Cleanup of Temporary Directories. Nov 20 19:20:03 rvolumiok4 sudo[3642]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 19:20:03 rvolumiok4 sudo[3642]: pam_unix(sudo:session): session closed for user root Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: Cannot execute Shairport-sync USR2 kill: Error: Command failed: /usr/bin/sudo /bin/kill -USR2 $(pidof shairport-sync) Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: VolumeController::SetAlsaVolume46 Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrack 0 Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:20:03 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:20:04 rvolumiok4 volumio[1274]: info: Restarting Shairport-Sync after stop Nov 20 19:20:04 rvolumiok4 sudo[3660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 20 19:20:04 rvolumiok4 sudo[3660]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 19:20:04 rvolumiok4 systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Nov 20 19:20:04 rvolumiok4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 20 19:20:04 rvolumiok4 sudo[3660]: pam_unix(sudo:session): session closed for user root Nov 20 19:20:04 rvolumiok4 volumio[1274]: info: Shairport-Sync retarted Nov 20 19:20:05 rvolumiok4 wpa_supplicant[960]: wlan0: Failed to initiate sched scan Nov 20 19:20:08 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand add "http://eu8.fastcast4u.com:5068" Nov 20 19:20:08 rvolumiok4 volumio[1274]: info: Nov 20 19:20:08 rvolumiok4 volumio[1274]: ---------------------------- MPD announces system playlist update Nov 20 19:20:08 rvolumiok4 volumio[1274]: info: Ignoring MPD Status Update Nov 20 19:20:08 rvolumiok4 volumio[1274]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 20 19:20:08 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand play Nov 20 19:20:08 rvolumiok4 volumio[1274]: info: ------------------------------ 4ms Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: Nov 20 19:20:09 rvolumiok4 volumio[1274]: ---------------------------- MPD announces state update: player Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: ControllerMpd::getState Nov 20 19:20:09 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand status Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: Nov 20 19:20:09 rvolumiok4 volumio[1274]: ---------------------------- MPD announces system playlist update Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: Ignoring MPD Status Update Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: Nov 20 19:20:09 rvolumiok4 volumio[1274]: ---------------------------- MPD announces state update: player Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: ControllerMpd::getState Nov 20 19:20:09 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand status Nov 20 19:20:09 rvolumiok4 volumio[1274]: verbose: ControllerMpd::parseState Nov 20 19:20:09 rvolumiok4 kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Nov 20 19:20:09 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: ------------------------------ 6ms Nov 20 19:20:09 rvolumiok4 volumio[1274]: verbose: ControllerMpd::parseState Nov 20 19:20:09 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 20 19:20:09 rvolumiok4 volumio[1274]: verbose: ControllerMpd::parseTrackInfo Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: ControllerMpd::pushState Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: CoreCommandRouter::servicePushState Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrack 0 Nov 20 19:20:09 rvolumiok4 volumio[1274]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Jazz Lounge - Paco De Lucia - Soniquete","artist":"L Stream","album":null,"uri":"http://eu8.fastcast4u.com:5068","trackType":"com:5068"} Nov 20 19:20:09 rvolumiok4 volumio[1274]: verbose: CURRENT POSITION 0 Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: CoreStateMachine::syncState stateService play Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: CoreStateMachine::syncState currentStatus stop Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: ------------------------------ 32ms Nov 20 19:20:09 rvolumiok4 volumio[1274]: verbose: ControllerMpd::parseTrackInfo Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: ControllerMpd::pushState Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: CoreCommandRouter::servicePushState Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrack 0 Nov 20 19:20:09 rvolumiok4 volumio[1274]: verbose: STATE SERVICE {"status":"play","position":0,"seek":478,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Jazz Lounge - Paco De Lucia - Soniquete","artist":"L Stream","album":null,"uri":"http://eu8.fastcast4u.com:5068","trackType":"com:5068"} Nov 20 19:20:09 rvolumiok4 volumio[1274]: verbose: CURRENT POSITION 0 Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: CoreStateMachine::syncState stateService play Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: CoreStateMachine::syncState currentStatus play Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: Received an update from plugin. extracting info from payload Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:20:09 rvolumiok4 volumio[1274]: info: ------------------------------ 31ms Nov 20 19:20:11 rvolumiok4 volumio[1274]: info: VolumeController::SetAlsaVolume50 Nov 20 19:20:11 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:20:11 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:20:11 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:20:11 rvolumiok4 volumio[1274]: info: VolumeController::SetAlsaVolume65 Nov 20 19:20:11 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:20:11 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:20:11 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:20:11 rvolumiok4 volumio[1274]: info: VolumeController::SetAlsaVolume70 Nov 20 19:20:11 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:20:11 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:20:11 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:20:11 rvolumiok4 volumio[1274]: info: VolumeController::SetAlsaVolume75 Nov 20 19:20:11 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:20:11 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:20:11 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:20:11 rvolumiok4 volumio[1274]: info: VolumeController::SetAlsaVolume80 Nov 20 19:20:11 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:20:11 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:20:11 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:20:12 rvolumiok4 volumio[1274]: info: VolumeController::SetAlsaVolume85 Nov 20 19:20:12 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:20:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:20:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:20:12 rvolumiok4 wpa_supplicant[960]: wlan0: Failed to initiate sched scan Nov 20 19:20:12 rvolumiok4 volumio[1274]: info: VolumeController::SetAlsaVolume95 Nov 20 19:20:12 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:20:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:20:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:20:12 rvolumiok4 volumio[1274]: info: VolumeController::SetAlsaVolume100 Nov 20 19:20:12 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:20:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:20:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:20:20 rvolumiok4 wpa_supplicant[960]: wlan0: Failed to initiate sched scan Nov 20 19:20:27 rvolumiok4 wpa_supplicant[960]: wlan0: Failed to initiate sched scan Nov 20 19:20:35 rvolumiok4 wpa_supplicant[960]: wlan0: Failed to initiate sched scan Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreStateMachine::ClearQueue Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreStateMachine::stop Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 19:20:37 rvolumiok4 volumio[1274]: UNSET VOLATILE Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreStateMachine::stPlaybackTimer Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreStateMachine::updateTrackBlock Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrackBlock Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrack 0 Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreStateMachine::serviceStop Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrack 0 Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreCommandRouter::serviceStop Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: [1605900037419] ControllerWebradio::stop Nov 20 19:20:37 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand stop Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CorePlayQueue::clearPlayQueue Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CorePlayQueue::saveQueue Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushQueue Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreStateMachine::addQueueItems Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CorePlayQueue::addQueueItems Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: Adding Item to queue: http://eu8.fastcast4u.com:5068 Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CorePlayQueue::saveQueue Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushQueue Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreStateMachine::updateTrackBlock Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrackBlock Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPlay Nov 20 19:20:37 rvolumiok4 volumio[1274]: UNSET VOLATILE Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreStateMachine::play index 0 Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreStateMachine::stop Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 19:20:37 rvolumiok4 volumio[1274]: UNSET VOLATILE Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreStateMachine::play index undefined Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrack 0 Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CoreStateMachine::startPlaybackTimer Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrack 0 Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: [1605900037439] ControllerWebradio::clearAddPlayTrack Nov 20 19:20:37 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand stop Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: Nov 20 19:20:37 rvolumiok4 volumio[1274]: ---------------------------- MPD announces state update: player Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: ControllerMpd::getState Nov 20 19:20:37 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand status Nov 20 19:20:37 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand clear Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: Nov 20 19:20:37 rvolumiok4 volumio[1274]: ---------------------------- MPD announces system playlist update Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: Ignoring MPD Status Update Nov 20 19:20:37 rvolumiok4 volumio[1274]: verbose: ControllerMpd::parseState Nov 20 19:20:37 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 20 19:20:37 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand load "http://eu8.fastcast4u.com:5068" Nov 20 19:20:37 rvolumiok4 volumio[1274]: error: updateQueue error: null Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: ------------------------------ 3ms Nov 20 19:20:37 rvolumiok4 volumio[1274]: verbose: ControllerMpd::parseTrackInfo Nov 20 19:20:37 rvolumiok4 volumio[1274]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 20 19:20:37 rvolumiok4 volumio[1274]: info: ------------------------------ 6ms Nov 20 19:20:38 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand add "http://eu8.fastcast4u.com:5068" Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: Nov 20 19:20:38 rvolumiok4 volumio[1274]: ---------------------------- MPD announces system playlist update Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: Ignoring MPD Status Update Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 20 19:20:38 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand play Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: ------------------------------ 2ms Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: Nov 20 19:20:38 rvolumiok4 volumio[1274]: ---------------------------- MPD announces state update: player Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: ControllerMpd::getState Nov 20 19:20:38 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand status Nov 20 19:20:38 rvolumiok4 volumio[1274]: verbose: ControllerMpd::parseState Nov 20 19:20:38 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: Nov 20 19:20:38 rvolumiok4 volumio[1274]: ---------------------------- MPD announces state update: player Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: Nov 20 19:20:38 rvolumiok4 volumio[1274]: ---------------------------- MPD announces system playlist update Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: Ignoring MPD Status Update Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: ControllerMpd::getState Nov 20 19:20:38 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand status Nov 20 19:20:38 rvolumiok4 volumio[1274]: verbose: ControllerMpd::parseTrackInfo Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: ControllerMpd::pushState Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: CoreCommandRouter::servicePushState Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrack 0 Nov 20 19:20:38 rvolumiok4 volumio[1274]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Jazz Lounge - Paco De Lucia - Soniquete","artist":"L Stream","album":null,"uri":"http://eu8.fastcast4u.com:5068","trackType":"com:5068"} Nov 20 19:20:38 rvolumiok4 volumio[1274]: verbose: CURRENT POSITION 0 Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: CoreStateMachine::syncState stateService play Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: CoreStateMachine::syncState currentStatus stop Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: ------------------------------ 23ms Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: ------------------------------ 7ms Nov 20 19:20:38 rvolumiok4 volumio[1274]: verbose: ControllerMpd::parseState Nov 20 19:20:38 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 20 19:20:38 rvolumiok4 volumio[1274]: verbose: ControllerMpd::parseTrackInfo Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: ControllerMpd::pushState Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: CoreCommandRouter::servicePushState Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrack 0 Nov 20 19:20:38 rvolumiok4 volumio[1274]: verbose: STATE SERVICE {"status":"play","position":0,"seek":478,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Jazz Lounge - Paco De Lucia - Soniquete","artist":"L Stream","album":null,"uri":"http://eu8.fastcast4u.com:5068","trackType":"com:5068"} Nov 20 19:20:38 rvolumiok4 volumio[1274]: verbose: CURRENT POSITION 0 Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: CoreStateMachine::syncState stateService play Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: CoreStateMachine::syncState currentStatus play Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: Received an update from plugin. extracting info from payload Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:20:38 rvolumiok4 volumio[1274]: info: ------------------------------ 17ms Nov 20 19:20:42 rvolumiok4 wpa_supplicant[960]: wlan0: Failed to initiate sched scan Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreStateMachine::ClearQueue Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreStateMachine::stop Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 19:20:49 rvolumiok4 volumio[1274]: UNSET VOLATILE Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreStateMachine::stPlaybackTimer Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreStateMachine::updateTrackBlock Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrackBlock Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrack 0 Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreStateMachine::serviceStop Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrack 0 Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreCommandRouter::serviceStop Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: [1605900049123] ControllerWebradio::stop Nov 20 19:20:49 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand stop Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CorePlayQueue::clearPlayQueue Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CorePlayQueue::saveQueue Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushQueue Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreStateMachine::addQueueItems Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CorePlayQueue::addQueueItems Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99501042 Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CorePlayQueue::saveQueue Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushQueue Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreStateMachine::updateTrackBlock Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrackBlock Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPlay Nov 20 19:20:49 rvolumiok4 volumio[1274]: UNSET VOLATILE Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreStateMachine::play index 0 Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreStateMachine::stop Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 19:20:49 rvolumiok4 volumio[1274]: UNSET VOLATILE Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreStateMachine::play index undefined Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrack 0 Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CoreStateMachine::startPlaybackTimer Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrack 0 Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: [1605900049133] ControllerWebradio::clearAddPlayTrack Nov 20 19:20:49 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand stop Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: Nov 20 19:20:49 rvolumiok4 volumio[1274]: ---------------------------- MPD announces state update: player Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: ControllerMpd::getState Nov 20 19:20:49 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand status Nov 20 19:20:49 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand clear Nov 20 19:20:49 rvolumiok4 volumio[1274]: verbose: ControllerMpd::parseState Nov 20 19:20:49 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: Nov 20 19:20:49 rvolumiok4 volumio[1274]: ---------------------------- MPD announces system playlist update Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: Ignoring MPD Status Update Nov 20 19:20:49 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99501042" Nov 20 19:20:49 rvolumiok4 volumio[1274]: verbose: ControllerMpd::parseTrackInfo Nov 20 19:20:49 rvolumiok4 volumio[1274]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: ------------------------------ 6ms Nov 20 19:20:49 rvolumiok4 volumio[1274]: error: updateQueue error: null Nov 20 19:20:49 rvolumiok4 volumio[1274]: info: ------------------------------ 2ms Nov 20 19:20:50 rvolumiok4 volumio[1274]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 20 19:20:50 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand play Nov 20 19:20:50 rvolumiok4 wpa_supplicant[960]: wlan0: Failed to initiate sched scan Nov 20 19:20:56 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPlay Nov 20 19:20:56 rvolumiok4 volumio[1274]: UNSET VOLATILE Nov 20 19:20:56 rvolumiok4 volumio[1274]: info: CoreStateMachine::play index undefined Nov 20 19:20:56 rvolumiok4 volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 19:20:56 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrack 0 Nov 20 19:20:56 rvolumiok4 volumio[1274]: info: CoreStateMachine::startPlaybackTimer Nov 20 19:20:56 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrack 0 Nov 20 19:20:56 rvolumiok4 volumio[1274]: info: [1605900056194] ControllerWebradio::clearAddPlayTrack Nov 20 19:20:56 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand stop Nov 20 19:20:56 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand clear Nov 20 19:20:56 rvolumiok4 volumio[1274]: info: Nov 20 19:20:56 rvolumiok4 volumio[1274]: ---------------------------- MPD announces system playlist update Nov 20 19:20:56 rvolumiok4 volumio[1274]: info: Ignoring MPD Status Update Nov 20 19:20:56 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99501042" Nov 20 19:20:56 rvolumiok4 volumio[1274]: error: updateQueue error: null Nov 20 19:20:56 rvolumiok4 volumio[1274]: info: ------------------------------ 2ms Nov 20 19:20:56 rvolumiok4 volumio[1274]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 20 19:20:56 rvolumiok4 volumio[1274]: verbose: ControllerMpd::sendMpdCommand play Nov 20 19:20:58 rvolumiok4 wpa_supplicant[960]: wlan0: Failed to initiate sched scan Nov 20 19:21:05 rvolumiok4 wpa_supplicant[960]: wlan0: Failed to initiate sched scan Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: CoreCommandRouter::servicePushState Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrack 0 Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received airplay_emulation Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: CoreCommandRouter::servicePushState Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrack 0 Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received airplay_emulation Nov 20 19:21:11 rvolumiok4 shairport-sync[3665]: Failed to create secure directory (/home/shairport-sync/.config/pulse): No such file or directory Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: Airplay started streaming, receiving metadatas Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioStop Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: CoreStateMachine::stop Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 19:21:11 rvolumiok4 volumio[1274]: UNSET VOLATILE Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: Airplay started streaming, receiving metadatas Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrack 0 Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioStop Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: CoreStateMachine::stop Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 19:21:11 rvolumiok4 volumio[1274]: UNSET VOLATILE Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 19:21:11 rvolumiok4 kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Nov 20 19:21:11 rvolumiok4 shairport-sync[3665]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 20 19:21:11 rvolumiok4 shairport-sync[3665]: Dload Upload T%o tTaolta l Sp e n%t R e cLeeifvted %S pXefeedr Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: Nov 20 19:21:11 rvolumiok4 volumio[1274]: ---------------------------- Client requests Start Airplay PlaybackRoutine Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: Airplay playback start Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: CorePlayQueue::getTrack 0 Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioStop Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: CoreStateMachine::stop Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 19:21:11 rvolumiok4 volumio[1274]: UNSET VOLATILE Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: Nov 20 19:21:11 rvolumiok4 volumio[1274]: ---------------------------- Client requests AirplayActive Nov 20 19:21:11 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive Nov 20 19:21:11 rvolumiok4 shairport-sync[3665]: [113B blob data] Nov 20 19:21:11 rvolumiok4 shairport-sync[3665]: - - : - - : - - 0 Dload Upload Total Spent Left Speed Nov 20 19:21:11 rvolumiok4 shairport-sync[3665]: [155B blob data] Nov 20 19:21:11 rvolumiok4 shairport-sync[3665]: [143B blob data] Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::servicePushState Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::servicePushState Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::servicePushState Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::servicePushState Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::servicePushState Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::servicePushState Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::servicePushState Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreStateMachine::pushState Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::volumioPushState Nov 20 19:21:12 rvolumiok4 volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:21:13 rvolumiok4 wpa_supplicant[960]: wlan0: Failed to initiate sched scan Nov 20 19:21:13 rvolumiok4 volumio[1274]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 20 19:21:13 rvolumiok4 volumio[1274]: { Error: certificate is not yet valid Nov 20 19:21:13 rvolumiok4 volumio[1274]: at TLSSocket. (_tls_wrap.js:1105:38) Nov 20 19:21:13 rvolumiok4 volumio[1274]: at emitNone (events.js:106:13) Nov 20 19:21:13 rvolumiok4 volumio[1274]: at TLSSocket.emit (events.js:208:7) Nov 20 19:21:13 rvolumiok4 volumio[1274]: at TLSSocket._finishInit (_tls_wrap.js:639:8) Nov 20 19:21:13 rvolumiok4 volumio[1274]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:469:38) code: 'CERT_NOT_YET_VALID' } Nov 20 19:21:13 rvolumiok4 volumio[1274]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 20 19:21:13 rvolumiok4 sudo[3855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2020-11-20 19:20 Nov 20 19:21:13 rvolumiok4 sudo[3855]: 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="2c20ceacdd8365d3bd2fdff070d8c78700ff2903" VOLUMIO_FE_VERSION="fa55af43e5eb642e968aa6de2f8a985962941d63" VOLUMIO_BE_VERSION="c9e4849495e810bd6ebb6833232daba25d86dd4c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Nov 20 20:05:14 CET 2020" VOLUMIO_VERSION="2.853" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="f7f9725bb5ce7d6da77135f468c28964"