-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Thu 2025-05-01 21:20:25 CST. -- May 01 21:19:08 volumio ntpd[863]: Soliciting pool server 84.16.67.12 May 01 21:19:09 volumio ntpd[863]: Soliciting pool server 84.16.67.12 May 01 21:19:09 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:09 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:09 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:09 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:09 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:09 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:09 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:09 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:09 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:09 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:09 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:09 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:09 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:09 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:09 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:09 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:09 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:09 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:09 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:09 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:09 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:09 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:09 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:09 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:09 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:09 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:11 volumio ntpd[863]: Soliciting pool server 2a03:8600::dd May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:12 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:12 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:12 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:12 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:12 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:12 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:12 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:12 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:12 volumio shairport-sync[1325]: {"time":1745657507597,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current May 01 21:19:12 volumio shairport-sync[1325]: Dload Upload Total Spent Left Speed May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:12 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:12 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:12 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:12 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:12 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:12 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:12 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:12 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:12 volumio volumio[1016]: info: May 01 21:19:12 volumio volumio[1016]: ---------------------------- Client requests Stop Airplay Playback May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback May 01 21:19:12 volumio volumio[1016]: info: Airplay playback stop May 01 21:19:12 volumio volumio[1016]: info: Airplay Playback Stopped, clearing state May 01 21:19:12 volumio volumio[1016]: info: CoreStateMachine::resetVolumioState May 01 21:19:12 volumio volumio[1016]: info: CoreStateMachine::getcurrentVolume May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::volumioRetrievevolume May 01 21:19:12 volumio shairport-sync[1325]: [158B blob data] May 01 21:19:12 volumio volumio[1016]: info: touch_display: Setting screensaver timeout to 10 seconds. May 01 21:19:12 volumio volumio[1016]: info: peppy_screensaver: Start PeppyMeter May 01 21:19:12 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:12 volumio volumio[1016]: info: CoreStateMachine::updateTrackBlock May 01 21:19:12 volumio volumio[1016]: info: CorePlayQueue::getTrackBlock May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::volumioRetrievevolume May 01 21:19:12 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:12 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:17 volumio volumio[1016]: info: Preload queue cleared May 01 21:19:17 volumio volumio[1016]: info: CoreCommandRouter::volumioReplaceandPlayItems May 01 21:19:17 volumio volumio[1016]: info: CoreStateMachine::ClearQueue May 01 21:19:17 volumio volumio[1016]: info: CoreStateMachine::stop May 01 21:19:17 volumio volumio[1016]: info: CoreStateMachine::serviceStop May 01 21:19:17 volumio volumio[1016]: info: CoreCommandRouter::serviceStop May 01 21:19:17 volumio volumio[1016]: info: Airplay Stop May 01 21:19:17 volumio volumio[1016]: info: Stopping Airplay Playback and sending pause command to client via USR2 May 01 21:19:17 volumio volumio[1016]: info: CorePlayQueue::clearPlayQueue May 01 21:19:17 volumio volumio[1016]: info: CorePlayQueue::saveQueue May 01 21:19:17 volumio volumio[1016]: info: CoreCommandRouter::volumioPushQueue May 01 21:19:17 volumio volumio[1016]: info: CoreStateMachine::addQueueItems May 01 21:19:17 volumio volumio[1016]: info: CorePlayQueue::addQueueItems May 01 21:19:17 volumio volumio[1016]: info: Preload queue cleared May 01 21:19:17 volumio volumio[1016]: info: Adding Item to queue: http://lhttp.qingting.fm/live/20207761/64k.mp3 May 01 21:19:17 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 01 21:19:17 volumio volumio[1016]: info: CoreCommandRouter::volumioPushQueue May 01 21:19:17 volumio volumio[1016]: info: CorePlayQueue::saveQueue May 01 21:19:17 volumio volumio[1016]: info: CoreStateMachine::updateTrackBlock May 01 21:19:17 volumio volumio[1016]: info: CorePlayQueue::getTrackBlock May 01 21:19:17 volumio volumio[1016]: info: CoreCommandRouter::volumioPlay May 01 21:19:17 volumio volumio[1016]: verbose: UNSET VOLATILE: Service: airplay_emulation May 01 21:19:17 volumio volumio[1016]: info: Stopping Airplay Playback and sending pause command to client via USR2 May 01 21:19:17 volumio sudo[2085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1325 May 01 21:19:17 volumio sudo[2085]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:19:17 volumio volumio[1016]: info: CoreStateMachine::play index 0 May 01 21:19:17 volumio volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 21:19:17 volumio volumio[1016]: info: CoreStateMachine::stop May 01 21:19:17 volumio volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 21:19:17 volumio volumio[1016]: info: CoreStateMachine::play index undefined May 01 21:19:17 volumio volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 21:19:17 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:17 volumio volumio[1016]: info: CoreStateMachine::startPlaybackTimer May 01 21:19:17 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:17 volumio volumio[1016]: info: [1746105557722] ControllerWebradio::clearAddPlayTrack May 01 21:19:17 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand stop May 01 21:19:17 volumio sudo[2085]: pam_unix(sudo:session): session closed for user root May 01 21:19:17 volumio volumio[1016]: info: sendMpdCommand stop took 7 milliseconds May 01 21:19:17 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand clear May 01 21:19:17 volumio volumio[1016]: info: Shairport-Sync paused with USR2 May 01 21:19:17 volumio volumio[1016]: info: May 01 21:19:17 volumio volumio[1016]: ---------------------------- MPD announces system playlist update May 01 21:19:17 volumio volumio[1016]: info: Ignoring MPD Status Update May 01 21:19:17 volumio volumio[1016]: info: sendMpdCommand clear took 2 milliseconds May 01 21:19:17 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand load "http://lhttp.qingting.fm/live/20207761/64k.mp3" May 01 21:19:17 volumio volumio[1016]: info: May 01 21:19:17 volumio volumio[1016]: ---------------------------- MPD announces system playlist update May 01 21:19:17 volumio volumio[1016]: info: Ignoring MPD Status Update May 01 21:19:17 volumio volumio[1016]: error: updateQueue error: null May 01 21:19:17 volumio volumio[1016]: info: ------------------------------ 5ms May 01 21:19:17 volumio volumio[1016]: error: Upnp client error: Error: This socket has been ended by the other party May 01 21:19:17 volumio sudo[2090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1325 May 01 21:19:17 volumio sudo[2090]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:19:17 volumio sudo[2090]: pam_unix(sudo:session): session closed for user root May 01 21:19:17 volumio volumio[1016]: info: Shairport-Sync paused with USR2 May 01 21:19:18 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand add "http://lhttp.qingting.fm/live/20207761/64k.mp3" May 01 21:19:18 volumio volumio[1016]: error: updateQueue error: null May 01 21:19:18 volumio volumio[1016]: info: May 01 21:19:18 volumio volumio[1016]: ---------------------------- MPD announces system playlist update May 01 21:19:18 volumio volumio[1016]: info: Ignoring MPD Status Update May 01 21:19:18 volumio volumio[1016]: info: ------------------------------ 913ms May 01 21:19:18 volumio volumio[1016]: info: sendMpdCommand add "http://lhttp.qingting.fm/live/20207761/64k.mp3" took 1 milliseconds May 01 21:19:18 volumio volumio[1016]: info: CoreStateMachine::setConsumeUpdateService mpd May 01 21:19:18 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand play May 01 21:19:18 volumio volumio[1016]: info: May 01 21:19:18 volumio volumio[1016]: ---------------------------- MPD announces system playlist update May 01 21:19:18 volumio volumio[1016]: info: Ignoring MPD Status Update May 01 21:19:18 volumio volumio[1016]: info: ------------------------------ 8ms May 01 21:19:18 volumio volumio[1016]: info: sendMpdCommand play took 8 milliseconds May 01 21:19:18 volumio volumio[1016]: info: ------------------------------ 8ms May 01 21:19:18 volumio volumio[1016]: info: Restarting Shairport-Sync after stop May 01 21:19:18 volumio volumio[1016]: info: Preload queue cleared May 01 21:19:18 volumio volumio[1016]: info: CoreCommandRouter::volumioReplaceandPlayItems May 01 21:19:18 volumio volumio[1016]: info: CoreStateMachine::ClearQueue May 01 21:19:18 volumio volumio[1016]: info: CoreStateMachine::stop May 01 21:19:18 volumio volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 21:19:18 volumio volumio[1016]: info: CorePlayQueue::clearPlayQueue May 01 21:19:18 volumio volumio[1016]: info: CorePlayQueue::saveQueue May 01 21:19:18 volumio volumio[1016]: info: CoreCommandRouter::volumioPushQueue May 01 21:19:18 volumio volumio[1016]: info: CoreStateMachine::addQueueItems May 01 21:19:18 volumio volumio[1016]: info: CorePlayQueue::addQueueItems May 01 21:19:18 volumio volumio[1016]: info: Preload queue cleared May 01 21:19:18 volumio volumio[1016]: info: Adding Item to queue: http://lhttp.qingting.fm/live/20207761/64k.mp3 May 01 21:19:18 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 01 21:19:18 volumio volumio[1016]: info: CoreCommandRouter::volumioPushQueue May 01 21:19:18 volumio volumio[1016]: info: CorePlayQueue::saveQueue May 01 21:19:18 volumio volumio[1016]: info: CoreStateMachine::updateTrackBlock May 01 21:19:18 volumio volumio[1016]: info: CorePlayQueue::getTrackBlock May 01 21:19:18 volumio volumio[1016]: info: CoreCommandRouter::volumioPlay May 01 21:19:18 volumio volumio[1016]: info: CoreStateMachine::play index 0 May 01 21:19:18 volumio volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 21:19:18 volumio volumio[1016]: info: CoreStateMachine::stop May 01 21:19:18 volumio volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 21:19:18 volumio volumio[1016]: info: CoreStateMachine::play index undefined May 01 21:19:18 volumio volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 21:19:18 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:18 volumio volumio[1016]: info: CoreStateMachine::startPlaybackTimer May 01 21:19:18 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:18 volumio volumio[1016]: info: [1746105558768] ControllerWebradio::clearAddPlayTrack May 01 21:19:18 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand stop May 01 21:19:18 volumio sudo[2093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 01 21:19:18 volumio sudo[2093]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:19:18 volumio volumio[1016]: info: May 01 21:19:18 volumio volumio[1016]: ---------------------------- MPD announces state update: player May 01 21:19:18 volumio volumio[1016]: info: sendMpdCommand stop took 4 milliseconds May 01 21:19:18 volumio volumio[1016]: info: ControllerMpd::getState May 01 21:19:18 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand status May 01 21:19:18 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand clear May 01 21:19:18 volumio volumio[1016]: info: May 01 21:19:18 volumio volumio[1016]: ---------------------------- MPD announces state update: player May 01 21:19:18 volumio volumio[1016]: info: ControllerMpd::getState May 01 21:19:18 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand status May 01 21:19:18 volumio volumio[1016]: info: Restarting Shairport-Sync after stop May 01 21:19:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 01 21:19:18 volumio shairport-sync[1325]: {"time":1746105552751,"response":"stopAirplayPlayback Success"} May 01 21:19:18 volumio systemd[1]: shairport-sync.service: Succeeded. May 01 21:19:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 01 21:19:18 volumio volumio[1016]: info: May 01 21:19:18 volumio volumio[1016]: ---------------------------- MPD announces system playlist update May 01 21:19:18 volumio volumio[1016]: info: Ignoring MPD Status Update May 01 21:19:18 volumio volumio[1016]: info: sendMpdCommand status took 26 milliseconds May 01 21:19:18 volumio volumio[1016]: info: sendMpdCommand clear took 26 milliseconds May 01 21:19:18 volumio volumio[1016]: info: sendMpdCommand status took 25 milliseconds May 01 21:19:18 volumio volumio[1016]: verbose: ControllerMpd::parseState May 01 21:19:18 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 21:19:18 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand load "http://lhttp.qingting.fm/live/20207761/64k.mp3" May 01 21:19:18 volumio volumio[1016]: verbose: ControllerMpd::parseState May 01 21:19:18 volumio volumio[1016]: info: ControllerMpd::pushState May 01 21:19:18 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:18 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:18 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:18 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:18 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:18 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:18 volumio volumio[1016]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 21:19:18 volumio sudo[2097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 01 21:19:18 volumio sudo[2097]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:19:18 volumio volumio[1016]: info: ------------------------------ 48ms May 01 21:19:18 volumio volumio[1016]: info: May 01 21:19:18 volumio volumio[1016]: ---------------------------- MPD announces system playlist update May 01 21:19:18 volumio volumio[1016]: info: Ignoring MPD Status Update May 01 21:19:18 volumio volumio[1016]: error: updateQueue error: null May 01 21:19:18 volumio volumio[1016]: info: ------------------------------ 27ms May 01 21:19:18 volumio volumio[1016]: info: sendMpdCommand playlistinfo took 24 milliseconds May 01 21:19:18 volumio volumio[1016]: verbose: ControllerMpd::parseTrackInfo May 01 21:19:18 volumio volumio[1016]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 01 21:19:18 volumio volumio[1016]: info: ------------------------------ 55ms May 01 21:19:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 01 21:19:18 volumio sudo[2093]: pam_unix(sudo:session): session closed for user root May 01 21:19:18 volumio volumio[1016]: info: Shairport-Sync retarted May 01 21:19:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 01 21:19:18 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 01 21:19:18 volumio systemd[1]: shairport-sync.service: Succeeded. May 01 21:19:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 01 21:19:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 01 21:19:18 volumio sudo[2097]: pam_unix(sudo:session): session closed for user root May 01 21:19:18 volumio volumio[1016]: info: Shairport-Sync retarted May 01 21:19:19 volumio volumio[1016]: error: updateQueue error: null May 01 21:19:19 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand add "http://lhttp.qingting.fm/live/20207761/64k.mp3" May 01 21:19:19 volumio volumio[1016]: info: ------------------------------ 827ms May 01 21:19:19 volumio volumio[1016]: info: May 01 21:19:19 volumio volumio[1016]: ---------------------------- MPD announces system playlist update May 01 21:19:19 volumio volumio[1016]: info: Ignoring MPD Status Update May 01 21:19:19 volumio volumio[1016]: info: sendMpdCommand add "http://lhttp.qingting.fm/live/20207761/64k.mp3" took 1 milliseconds May 01 21:19:19 volumio volumio[1016]: info: CoreStateMachine::setConsumeUpdateService mpd May 01 21:19:19 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand play May 01 21:19:19 volumio volumio[1016]: info: ------------------------------ 2ms May 01 21:19:19 volumio volumio[1016]: info: sendMpdCommand play took 1 milliseconds May 01 21:19:19 volumio volumio[1016]: info: May 01 21:19:19 volumio volumio[1016]: ---------------------------- MPD announces system playlist update May 01 21:19:19 volumio volumio[1016]: info: Ignoring MPD Status Update May 01 21:19:19 volumio volumio[1016]: info: ------------------------------ 4ms May 01 21:19:19 volumio volumio[1016]: info: May 01 21:19:19 volumio volumio[1016]: ---------------------------- MPD announces state update: player May 01 21:19:19 volumio volumio[1016]: info: ControllerMpd::getState May 01 21:19:19 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand status May 01 21:19:19 volumio volumio[1016]: info: May 01 21:19:19 volumio volumio[1016]: ---------------------------- MPD announces state update: player May 01 21:19:19 volumio volumio[1016]: info: ControllerMpd::getState May 01 21:19:19 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand status May 01 21:19:19 volumio volumio[1016]: info: May 01 21:19:19 volumio volumio[1016]: ---------------------------- MPD announces state update: player May 01 21:19:19 volumio volumio[1016]: info: sendMpdCommand status took 19 milliseconds May 01 21:19:19 volumio volumio[1016]: info: ControllerMpd::getState May 01 21:19:19 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand status May 01 21:19:19 volumio volumio[1016]: verbose: ControllerMpd::parseState May 01 21:19:19 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 21:19:19 volumio volumio[1016]: info: May 01 21:19:19 volumio volumio[1016]: ---------------------------- MPD announces state update: player May 01 21:19:19 volumio volumio[1016]: info: ControllerMpd::getState May 01 21:19:19 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand status May 01 21:19:19 volumio volumio[1016]: info: sendMpdCommand status took 20 milliseconds May 01 21:19:19 volumio volumio[1016]: info: sendMpdCommand status took 2 milliseconds May 01 21:19:19 volumio volumio[1016]: info: sendMpdCommand playlistinfo took 2 milliseconds May 01 21:19:19 volumio volumio[1016]: info: sendMpdCommand status took 2 milliseconds May 01 21:19:19 volumio volumio[1016]: verbose: ControllerMpd::parseState May 01 21:19:19 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 21:19:19 volumio volumio[1016]: verbose: ControllerMpd::parseState May 01 21:19:19 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 21:19:19 volumio volumio[1016]: verbose: ControllerMpd::parseTrackInfo May 01 21:19:19 volumio volumio[1016]: verbose: ControllerMpd::parseState May 01 21:19:19 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 21:19:19 volumio volumio[1016]: info: ControllerMpd::pushState May 01 21:19:19 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:19 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:19 volumio volumio[1016]: 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":"64 Kbps","isStreaming":false,"title":"64k.mp3","artist":null,"album":null,"uri":"http://lhttp.qingting.fm/live/20207761/64k.mp3","trackType":"mp3"} May 01 21:19:19 volumio volumio[1016]: verbose: CURRENT POSITION 0 May 01 21:19:19 volumio volumio[1016]: info: CoreStateMachine::syncState stateService play May 01 21:19:19 volumio volumio[1016]: info: CoreStateMachine::syncState currentStatus stop May 01 21:19:19 volumio volumio[1016]: info: ------------------------------ 27ms May 01 21:19:19 volumio volumio[1016]: info: sendMpdCommand playlistinfo took 3 milliseconds May 01 21:19:19 volumio volumio[1016]: info: sendMpdCommand playlistinfo took 4 milliseconds May 01 21:19:19 volumio volumio[1016]: info: sendMpdCommand playlistinfo took 3 milliseconds May 01 21:19:19 volumio volumio[1016]: verbose: ControllerMpd::parseTrackInfo May 01 21:19:19 volumio volumio[1016]: verbose: ControllerMpd::parseTrackInfo May 01 21:19:19 volumio volumio[1016]: verbose: ControllerMpd::parseTrackInfo May 01 21:19:19 volumio volumio[1016]: info: ControllerMpd::pushState May 01 21:19:19 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:19 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:19 volumio volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":628,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"64k.mp3","artist":null,"album":null,"uri":"http://lhttp.qingting.fm/live/20207761/64k.mp3","trackType":"mp3"} May 01 21:19:19 volumio volumio[1016]: verbose: CURRENT POSITION 0 May 01 21:19:19 volumio volumio[1016]: info: CoreStateMachine::syncState stateService play May 01 21:19:19 volumio volumio[1016]: info: CoreStateMachine::syncState currentStatus play May 01 21:19:19 volumio volumio[1016]: info: Received an update from plugin. extracting info from payload May 01 21:19:19 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:19 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:19 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:19 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:19 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:19 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:19 volumio volumio[1016]: info: ControllerMpd::pushState May 01 21:19:19 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:19 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:19 volumio volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"64k.mp3","artist":null,"album":null,"uri":"http://lhttp.qingting.fm/live/20207761/64k.mp3","trackType":"mp3"} May 01 21:19:19 volumio volumio[1016]: verbose: CURRENT POSITION 0 May 01 21:19:19 volumio volumio[1016]: info: CoreStateMachine::syncState stateService play May 01 21:19:19 volumio volumio[1016]: info: CoreStateMachine::syncState currentStatus play May 01 21:19:19 volumio volumio[1016]: info: Received an update from plugin. extracting info from payload May 01 21:19:19 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:19 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:19 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:19 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:19 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:19 volumio volumio[1016]: info: ControllerMpd::pushState May 01 21:19:19 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:19 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:19 volumio volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"64k.mp3","artist":null,"album":null,"uri":"http://lhttp.qingting.fm/live/20207761/64k.mp3","trackType":"mp3"} May 01 21:19:19 volumio volumio[1016]: verbose: CURRENT POSITION 0 May 01 21:19:19 volumio volumio[1016]: info: CoreStateMachine::syncState stateService play May 01 21:19:19 volumio volumio[1016]: info: CoreStateMachine::syncState currentStatus play May 01 21:19:19 volumio volumio[1016]: info: Received an update from plugin. extracting info from payload May 01 21:19:19 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:19 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:19 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:19 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:19 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:19 volumio volumio[1016]: info: ------------------------------ 73ms May 01 21:19:19 volumio volumio[1016]: info: ------------------------------ 57ms May 01 21:19:19 volumio volumio[1016]: info: ------------------------------ 55ms May 01 21:19:19 volumio volumio[1016]: info: May 01 21:19:19 volumio volumio[1016]: ---------------------------- MPD announces system playlist update May 01 21:19:19 volumio volumio[1016]: info: Ignoring MPD Status Update May 01 21:19:19 volumio volumio[1016]: info: May 01 21:19:19 volumio volumio[1016]: ---------------------------- MPD announces state update: player May 01 21:19:19 volumio volumio[1016]: info: ControllerMpd::getState May 01 21:19:19 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand status May 01 21:19:20 volumio volumio[1016]: info: May 01 21:19:20 volumio volumio[1016]: ---------------------------- MPD announces system playlist update May 01 21:19:20 volumio volumio[1016]: info: Ignoring MPD Status Update May 01 21:19:20 volumio volumio[1016]: info: May 01 21:19:20 volumio volumio[1016]: ---------------------------- MPD announces state update: player May 01 21:19:20 volumio volumio[1016]: info: ControllerMpd::getState May 01 21:19:20 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand status May 01 21:19:20 volumio volumio[1016]: info: ------------------------------ 37ms May 01 21:19:20 volumio volumio[1016]: info: sendMpdCommand status took 36 milliseconds May 01 21:19:20 volumio volumio[1016]: info: ------------------------------ 5ms May 01 21:19:20 volumio volumio[1016]: info: sendMpdCommand status took 3 milliseconds May 01 21:19:20 volumio volumio[1016]: verbose: ControllerMpd::parseState May 01 21:19:20 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 21:19:20 volumio volumio[1016]: verbose: ControllerMpd::parseState May 01 21:19:20 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 21:19:20 volumio volumio[1016]: info: sendMpdCommand playlistinfo took 1 milliseconds May 01 21:19:20 volumio volumio[1016]: info: sendMpdCommand playlistinfo took 1 milliseconds May 01 21:19:20 volumio volumio[1016]: verbose: ControllerMpd::parseTrackInfo May 01 21:19:20 volumio volumio[1016]: verbose: ControllerMpd::parseTrackInfo May 01 21:19:20 volumio volumio[1016]: info: ControllerMpd::pushState May 01 21:19:20 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:20 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:20 volumio volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"64k.mp3","artist":null,"album":null,"uri":"http://lhttp.qingting.fm/live/20207761/64k.mp3","trackType":"mp3"} May 01 21:19:20 volumio volumio[1016]: verbose: CURRENT POSITION 0 May 01 21:19:20 volumio volumio[1016]: info: CoreStateMachine::syncState stateService play May 01 21:19:20 volumio volumio[1016]: info: CoreStateMachine::syncState currentStatus play May 01 21:19:20 volumio volumio[1016]: info: Received an update from plugin. extracting info from payload May 01 21:19:20 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:20 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:20 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:20 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:20 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:20 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:20 volumio volumio[1016]: info: ControllerMpd::pushState May 01 21:19:20 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:20 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:20 volumio volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":822,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"64k.mp3","artist":null,"album":null,"uri":"http://lhttp.qingting.fm/live/20207761/64k.mp3","trackType":"mp3"} May 01 21:19:20 volumio volumio[1016]: verbose: CURRENT POSITION 0 May 01 21:19:20 volumio volumio[1016]: info: CoreStateMachine::syncState stateService play May 01 21:19:20 volumio volumio[1016]: info: CoreStateMachine::syncState currentStatus play May 01 21:19:20 volumio volumio[1016]: info: Received an update from plugin. extracting info from payload May 01 21:19:20 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:20 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:20 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:20 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:20 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:20 volumio volumio[1016]: info: ------------------------------ 86ms May 01 21:19:20 volumio volumio[1016]: info: ------------------------------ 53ms May 01 21:19:27 volumio volumio[1016]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 May 01 21:19:27 volumio volumio[1016]: info: CoreCommandRouter::volumioGetState May 01 21:19:27 volumio volumio[1016]: info: Airplay started streaming, receiving metadatas May 01 21:19:27 volumio volumio[1016]: info: CoreCommandRouter::volumioStop May 01 21:19:27 volumio volumio[1016]: info: CoreStateMachine::stop May 01 21:19:27 volumio volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 21:19:27 volumio volumio[1016]: info: CoreStateMachine::stPlaybackTimer May 01 21:19:27 volumio volumio[1016]: info: CoreStateMachine::updateTrackBlock May 01 21:19:27 volumio volumio[1016]: info: CorePlayQueue::getTrackBlock May 01 21:19:27 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:27 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:27 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:27 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:27 volumio volumio[1016]: info: CoreStateMachine::serviceStop May 01 21:19:27 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:27 volumio volumio[1016]: info: CoreCommandRouter::serviceStop May 01 21:19:27 volumio volumio[1016]: info: [1746105567194] ControllerWebradio::stop May 01 21:19:27 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand stop May 01 21:19:27 volumio volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 21:19:27 volumio volumio[1016]: info: Airplay started streaming, receiving metadatas May 01 21:19:27 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:27 volumio volumio[1016]: info: CoreCommandRouter::volumioStop May 01 21:19:27 volumio volumio[1016]: info: CoreStateMachine::stop May 01 21:19:27 volumio volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 21:19:27 volumio volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 21:19:27 volumio volumio[1016]: info: Airplay started streaming, receiving metadatas May 01 21:19:27 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:27 volumio volumio[1016]: info: CoreCommandRouter::volumioStop May 01 21:19:27 volumio volumio[1016]: info: CoreStateMachine::stop May 01 21:19:27 volumio volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 21:19:27 volumio volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 21:19:27 volumio volumio[1016]: info: Airplay started streaming, receiving metadatas May 01 21:19:27 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:27 volumio volumio[1016]: info: CoreCommandRouter::volumioStop May 01 21:19:27 volumio volumio[1016]: info: CoreStateMachine::stop May 01 21:19:27 volumio volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 21:19:27 volumio volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 21:19:27 volumio shairport-sync[2099]: % Total % Received % Xferd Average Speed Time Time Time Current May 01 21:19:27 volumio shairport-sync[2099]: Dload Upload Total Spent Left Speed May 01 21:19:27 volumio volumio[1016]: info: May 01 21:19:27 volumio volumio[1016]: ---------------------------- MPD announces state update: player May 01 21:19:27 volumio volumio[1016]: info: sendMpdCommand stop took 55 milliseconds May 01 21:19:27 volumio volumio[1016]: info: ControllerMpd::getState May 01 21:19:27 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand status May 01 21:19:27 volumio volumio[1016]: info: May 01 21:19:27 volumio volumio[1016]: ---------------------------- MPD announces state update: player May 01 21:19:27 volumio volumio[1016]: info: ControllerMpd::getState May 01 21:19:27 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand status May 01 21:19:27 volumio volumio[1016]: info: sendMpdCommand status took 4 milliseconds May 01 21:19:27 volumio volumio[1016]: info: sendMpdCommand status took 2 milliseconds May 01 21:19:27 volumio volumio[1016]: verbose: ControllerMpd::parseState May 01 21:19:27 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 21:19:27 volumio volumio[1016]: verbose: ControllerMpd::parseState May 01 21:19:27 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 21:19:27 volumio volumio[1016]: info: sendMpdCommand playlistinfo took 6 milliseconds May 01 21:19:27 volumio volumio[1016]: info: sendMpdCommand playlistinfo took 6 milliseconds May 01 21:19:27 volumio volumio[1016]: verbose: ControllerMpd::parseTrackInfo May 01 21:19:27 volumio volumio[1016]: verbose: ControllerMpd::parseTrackInfo May 01 21:19:27 volumio volumio[1016]: info: ControllerMpd::pushState May 01 21:19:27 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:27 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:27 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:27 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:27 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:27 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:27 volumio volumio[1016]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 21:19:27 volumio volumio[1016]: info: ControllerMpd::pushState May 01 21:19:27 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:27 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:27 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:27 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:27 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:27 volumio volumio[1016]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 21:19:27 volumio volumio[1016]: info: ------------------------------ 39ms May 01 21:19:27 volumio volumio[1016]: info: ------------------------------ 36ms May 01 21:19:27 volumio volumio[1016]: info: May 01 21:19:27 volumio volumio[1016]: ---------------------------- Client requests Start Airplay PlaybackRoutine May 01 21:19:27 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback May 01 21:19:27 volumio volumio[1016]: info: Airplay playback start May 01 21:19:27 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 May 01 21:19:27 volumio volumio[1016]: info: CoreCommandRouter::volumioStop May 01 21:19:27 volumio volumio[1016]: info: CoreStateMachine::stop May 01 21:19:27 volumio volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 21:19:27 volumio volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 21:19:27 volumio shairport-sync[2099]: [158B blob data] May 01 21:19:27 volumio volumio[1016]: info: touch_display: Setting screensaver timeout to 10 seconds. May 01 21:19:27 volumio volumio[1016]: info: peppy_screensaver: Start PeppyMeter May 01 21:19:28 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:28 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:28 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:28 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:28 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:28 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:28 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:28 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:28 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:28 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:28 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:28 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:28 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:28 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:28 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:28 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:29 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:29 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:29 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:29 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:29 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:29 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:29 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:29 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:29 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:29 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:29 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:29 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:29 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:29 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:29 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:29 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:29 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:29 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:29 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:29 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:29 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:29 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:29 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:29 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:29 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:29 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:29 volumio volumio[1016]: info: touch_display: Setting screensaver timeout to 10 seconds. May 01 21:19:32 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:32 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:32 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:32 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:32 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:32 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:32 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:32 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:32 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:32 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:32 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:32 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:32 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:33 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:33 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:33 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:33 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:33 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:33 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:33 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:33 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:33 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:33 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:33 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:33 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:33 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:33 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:33 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:33 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:33 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:33 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:33 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:33 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:33 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:33 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:33 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:33 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:33 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:37 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:37 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:37 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:37 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:37 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:37 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:37 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:37 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:37 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:37 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:37 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:37 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:37 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:37 volumio shairport-sync[2099]: {"time":1746105567294,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current May 01 21:19:37 volumio shairport-sync[2099]: Dload Upload Total Spent Left Speed May 01 21:19:37 volumio volumio[1016]: info: May 01 21:19:37 volumio volumio[1016]: ---------------------------- Client requests Stop Airplay Playback May 01 21:19:37 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback May 01 21:19:37 volumio volumio[1016]: info: Airplay playback stop May 01 21:19:37 volumio volumio[1016]: info: Airplay Playback Stopped, clearing state May 01 21:19:37 volumio volumio[1016]: info: CoreStateMachine::resetVolumioState May 01 21:19:37 volumio volumio[1016]: info: CoreStateMachine::getcurrentVolume May 01 21:19:37 volumio volumio[1016]: info: CoreCommandRouter::volumioRetrievevolume May 01 21:19:37 volumio shairport-sync[2099]: [158B blob data] May 01 21:19:37 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:37 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:37 volumio volumio[1016]: info: CoreStateMachine::updateTrackBlock May 01 21:19:37 volumio volumio[1016]: info: CorePlayQueue::getTrackBlock May 01 21:19:37 volumio volumio[1016]: info: CoreCommandRouter::volumioRetrievevolume May 01 21:19:37 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:37 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:40 volumio volumio[1016]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 May 01 21:19:40 volumio volumio[1016]: info: CoreCommandRouter::volumioGetState May 01 21:19:41 volumio volumio[1016]: info: Airplay started streaming, receiving metadatas May 01 21:19:41 volumio volumio[1016]: info: Airplay started streaming, receiving metadatas May 01 21:19:41 volumio shairport-sync[2099]: {"time":1746105577483,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current May 01 21:19:41 volumio shairport-sync[2099]: Dload Upload Total Spent Left Speed May 01 21:19:41 volumio volumio[1016]: info: May 01 21:19:41 volumio volumio[1016]: ---------------------------- Client requests Start Airplay PlaybackRoutine May 01 21:19:41 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback May 01 21:19:41 volumio volumio[1016]: info: Airplay playback start May 01 21:19:41 volumio shairport-sync[2099]: [158B blob data] May 01 21:19:42 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:42 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:42 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:42 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:42 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:42 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:42 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:42 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:42 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:42 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:42 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:42 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:42 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:42 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:42 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:42 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:43 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:43 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:43 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:43 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:43 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:43 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:43 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:43 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:43 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:43 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:43 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:43 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:43 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:43 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:43 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:43 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:43 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:43 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:43 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:43 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:43 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:43 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:43 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:43 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:43 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:43 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:45 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:45 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:45 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:45 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:45 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:45 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:45 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:45 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:45 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:45 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:45 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:45 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:45 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:45 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:45 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:45 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:45 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:45 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:45 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:45 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:45 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:45 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:45 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:45 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:45 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:46 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:46 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:46 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:46 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:46 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:46 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:46 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:46 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:46 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:46 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:46 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:46 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:46 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:46 volumio shairport-sync[2099]: {"time":1746105581861,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current May 01 21:19:46 volumio shairport-sync[2099]: Dload Upload Total Spent Left Speed May 01 21:19:46 volumio volumio[1016]: info: May 01 21:19:46 volumio volumio[1016]: ---------------------------- Client requests Stop Airplay Playback May 01 21:19:46 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback May 01 21:19:46 volumio volumio[1016]: info: Airplay playback stop May 01 21:19:46 volumio volumio[1016]: info: Airplay Playback Stopped, clearing state May 01 21:19:46 volumio volumio[1016]: info: CoreStateMachine::resetVolumioState May 01 21:19:46 volumio volumio[1016]: info: CoreStateMachine::getcurrentVolume May 01 21:19:46 volumio volumio[1016]: info: CoreCommandRouter::volumioRetrievevolume May 01 21:19:46 volumio shairport-sync[2099]: [158B blob data] May 01 21:19:46 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:46 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:46 volumio volumio[1016]: info: CoreStateMachine::updateTrackBlock May 01 21:19:46 volumio volumio[1016]: info: CorePlayQueue::getTrackBlock May 01 21:19:46 volumio volumio[1016]: info: CoreCommandRouter::volumioRetrievevolume May 01 21:19:46 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:46 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:54 volumio volumio[1016]: info: Airplay started streaming, receiving metadatas May 01 21:19:54 volumio volumio[1016]: info: Airplay started streaming, receiving metadatas May 01 21:19:54 volumio shairport-sync[2099]: {"time":1746105586500,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current May 01 21:19:54 volumio shairport-sync[2099]: Dload Upload Total Spent Left Speed May 01 21:19:54 volumio volumio[1016]: info: May 01 21:19:54 volumio volumio[1016]: ---------------------------- Client requests Start Airplay PlaybackRoutine May 01 21:19:54 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback May 01 21:19:54 volumio volumio[1016]: info: Airplay playback start May 01 21:19:54 volumio shairport-sync[2099]: [158B blob data] May 01 21:19:55 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:55 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:55 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:55 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:55 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:55 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:55 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:55 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:55 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:55 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:55 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:55 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:55 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:55 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:55 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:55 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:56 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:56 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:56 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:56 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:56 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:56 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:56 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:56 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:56 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:56 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:56 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:56 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:56 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:56 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:56 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:56 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:19:56 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:56 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:56 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:56 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:56 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:56 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:56 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:56 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:19:56 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:19:56 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:19:56 volumio volumio[1016]: info: touch_display: Setting screensaver timeout to 10 seconds. May 01 21:19:56 volumio volumio[1016]: info: peppy_screensaver: Start PeppyMeter May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:03 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:03 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:04 volumio volumio[1016]: info: touch_display: Setting screensaver timeout to 10 seconds. May 01 21:20:05 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:05 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:05 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:20:05 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:05 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:05 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:05 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:05 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:05 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:05 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:05 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:05 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:05 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:06 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:06 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:06 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:06 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:06 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:06 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:06 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:06 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:06 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:06 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:06 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:06 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:06 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:06 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:06 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:20:06 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:06 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:06 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:06 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:06 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:06 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:06 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:06 volumio volumio[1016]: info: CoreCommandRouter::servicePushState May 01 21:20:06 volumio volumio[1016]: info: CoreStateMachine::pushState May 01 21:20:06 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState May 01 21:20:11 volumio volumio[1016]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 May 01 21:20:11 volumio volumio[1016]: info: CoreCommandRouter::volumioGetState May 01 21:20:12 volumio volumio[1016]: info: peppy_screensaver: Start PeppyMeter May 01 21:20:16 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 01 21:20:16 volumio volumio[1016]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 May 01 21:20:16 volumio volumio[1016]: info: CoreCommandRouter::volumioGetState May 01 21:20:17 volumio volumio[1016]: info: peppy_screensaver: Start PeppyMeter May 01 21:20:18 volumio volumio[1016]: info: CoreCommandRouter::Close All Modals sent May 01 21:20:21 volumio volumio[1016]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 May 01 21:20:21 volumio volumio[1016]: info: CoreCommandRouter::volumioGetState May 01 21:20:22 volumio volumio[1016]: info: peppy_screensaver: Start PeppyMeter May 01 21:20:25 volumio volumio[1016]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 21:20:25 volumio volumio[1016]: Error: connect ETIMEDOUT 202.160.128.205:443 May 01 21:20:25 volumio volumio[1016]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { May 01 21:20:25 volumio volumio[1016]: errno: -110, May 01 21:20:25 volumio volumio[1016]: code: 'ETIMEDOUT', May 01 21:20:25 volumio volumio[1016]: syscall: 'connect', May 01 21:20:25 volumio volumio[1016]: address: '202.160.128.205', May 01 21:20:25 volumio volumio[1016]: port: 443 May 01 21:20:25 volumio volumio[1016]: } May 01 21:20:25 volumio volumio[1016]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 21:20:25 volumio sudo[2476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-01 21:19 May 01 21:20:25 volumio sudo[2476]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="ef589a0572b08c3d1e9a984ec6a655ef3698e415" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="abe72e92bc7de90ab6193cbc17e21ad2750980a2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 28 May 2024 12:17:12 PM CEST" VOLUMIO_VERSION="3.695" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8f37ff40efaaf3752fac55d6a95d61cb"