-- Logs begin at Sun 2024-10-06 09:10:16 CST, end at Sun 2024-10-06 10:23:01 CST. -- Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:00 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:02 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:02 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:02 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:22:02 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:02 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:02 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:02 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:02 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:02 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:02 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:02 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:02 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:02 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:03 volumio volumio[4211]: info: Preload queue cleared Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::ClearQueue Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::stop Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::serviceStop Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::serviceStop Oct 06 10:22:03 volumio volumio[4211]: info: Airplay Stop Oct 06 10:22:03 volumio volumio[4211]: info: Stopping Airplay Playback and sending pause command to client via USR2 Oct 06 10:22:03 volumio volumio[4211]: info: CorePlayQueue::clearPlayQueue Oct 06 10:22:03 volumio volumio[4211]: info: CorePlayQueue::saveQueue Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::volumioPushQueue Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::addQueueItems Oct 06 10:22:03 volumio volumio[4211]: info: CorePlayQueue::addQueueItems Oct 06 10:22:03 volumio volumio[4211]: info: Preload queue cleared Oct 06 10:22:03 volumio volumio[4211]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1821355 Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::volumioPushQueue Oct 06 10:22:03 volumio volumio[4211]: info: CorePlayQueue::saveQueue Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::updateTrackBlock Oct 06 10:22:03 volumio volumio[4211]: info: CorePlayQueue::getTrackBlock Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::volumioPlay Oct 06 10:22:03 volumio volumio[4211]: verbose: UNSET VOLATILE: Service: airplay_emulation Oct 06 10:22:03 volumio volumio[4211]: info: Stopping Airplay Playback and sending pause command to client via USR2 Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::play index 0 Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:03 volumio sudo[9324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 8854 Oct 06 10:22:03 volumio sudo[9324]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::stop Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::stPlaybackTimer Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::updateTrackBlock Oct 06 10:22:03 volumio volumio[4211]: info: CorePlayQueue::getTrackBlock Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:03 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::serviceStop Oct 06 10:22:03 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::serviceStop Oct 06 10:22:03 volumio volumio[4211]: info: [1728181323095] ControllerWebradio::stop Oct 06 10:22:03 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand stop Oct 06 10:22:03 volumio sudo[9324]: pam_unix(sudo:session): session closed for user root Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:03 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:03 volumio volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received airplay_emulation Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:03 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:03 volumio volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received airplay_emulation Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:03 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:03 volumio volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received airplay_emulation Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:03 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:03 volumio volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received airplay_emulation Oct 06 10:22:03 volumio volumio[4211]: info: sendMpdCommand stop took 8 milliseconds Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::play index undefined Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:03 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::startPlaybackTimer Oct 06 10:22:03 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:03 volumio volumio[4211]: info: [1728181323105] ControllerWebradio::clearAddPlayTrack Oct 06 10:22:03 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand stop Oct 06 10:22:03 volumio volumio[4211]: info: sendMpdCommand stop took 1 milliseconds Oct 06 10:22:03 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand clear Oct 06 10:22:03 volumio volumio[4211]: info: Shairport-Sync paused with USR2 Oct 06 10:22:03 volumio sudo[9327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 8854 Oct 06 10:22:03 volumio volumio[4211]: info: Oct 06 10:22:03 volumio volumio[4211]: ---------------------------- MPD announces system playlist update Oct 06 10:22:03 volumio volumio[4211]: info: Ignoring MPD Status Update Oct 06 10:22:03 volumio sudo[9327]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:22:03 volumio volumio[4211]: info: sendMpdCommand clear took 3 milliseconds Oct 06 10:22:03 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1821355" Oct 06 10:22:03 volumio volumio[4211]: Upnp client error: Error: This socket has been ended by the other party Oct 06 10:22:03 volumio volumio[4211]: Upnp client error: Error: This socket has been ended by the other party Oct 06 10:22:03 volumio volumio[4211]: Upnp client error: Error: This socket has been ended by the other party Oct 06 10:22:03 volumio volumio[4211]: error: Upnp client error: Error: This socket has been ended by the other party Oct 06 10:22:03 volumio sudo[9327]: pam_unix(sudo:session): session closed for user root Oct 06 10:22:03 volumio volumio[4211]: info: Shairport-Sync paused with USR2 Oct 06 10:22:03 volumio volumio[4211]: info: Preload queue cleared Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::ClearQueue Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::stop Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:03 volumio volumio[4211]: info: CorePlayQueue::clearPlayQueue Oct 06 10:22:03 volumio volumio[4211]: info: CorePlayQueue::saveQueue Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::volumioPushQueue Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::addQueueItems Oct 06 10:22:03 volumio volumio[4211]: info: CorePlayQueue::addQueueItems Oct 06 10:22:03 volumio volumio[4211]: info: Preload queue cleared Oct 06 10:22:03 volumio volumio[4211]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1821355 Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::volumioPushQueue Oct 06 10:22:03 volumio volumio[4211]: info: CorePlayQueue::saveQueue Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::updateTrackBlock Oct 06 10:22:03 volumio volumio[4211]: info: CorePlayQueue::getTrackBlock Oct 06 10:22:03 volumio volumio[4211]: info: CoreCommandRouter::volumioPlay Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::play index 0 Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::stop Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::play index undefined Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:03 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:03 volumio volumio[4211]: info: CoreStateMachine::startPlaybackTimer Oct 06 10:22:03 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:03 volumio volumio[4211]: info: [1728181323275] ControllerWebradio::clearAddPlayTrack Oct 06 10:22:03 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand stop Oct 06 10:22:04 volumio volumio[4211]: info: Restarting Shairport-Sync after stop Oct 06 10:22:04 volumio volumio[4211]: info: Restarting Shairport-Sync after stop Oct 06 10:22:04 volumio sudo[9331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 06 10:22:04 volumio sudo[9331]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:22:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 06 10:22:04 volumio shairport-sync[8854]: {"time":1728181021195,"response":"startAirplayPlayback Success"} Oct 06 10:22:04 volumio sudo[9334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 06 10:22:04 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 06 10:22:04 volumio sudo[9334]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:22:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 06 10:22:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 06 10:22:04 volumio sudo[9331]: pam_unix(sudo:session): session closed for user root Oct 06 10:22:04 volumio volumio[4211]: info: Shairport-Sync retarted Oct 06 10:22:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 06 10:22:04 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 06 10:22:04 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 06 10:22:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 06 10:22:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 06 10:22:04 volumio sudo[9334]: pam_unix(sudo:session): session closed for user root Oct 06 10:22:04 volumio volumio[4211]: info: Shairport-Sync retarted Oct 06 10:22:05 volumio volumio[4211]: error: updateQueue error: null Oct 06 10:22:05 volumio volumio[4211]: info: Oct 06 10:22:05 volumio volumio[4211]: ---------------------------- MPD announces system playlist update Oct 06 10:22:05 volumio volumio[4211]: info: Ignoring MPD Status Update Oct 06 10:22:05 volumio volumio[4211]: info: ------------------------------ 1936ms Oct 06 10:22:05 volumio volumio[4211]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1821355" took 1935 milliseconds Oct 06 10:22:05 volumio volumio[4211]: info: sendMpdCommand stop took 1769 milliseconds Oct 06 10:22:05 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 06 10:22:05 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand play Oct 06 10:22:05 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand clear Oct 06 10:22:05 volumio volumio[4211]: info: ------------------------------ 2ms Oct 06 10:22:05 volumio volumio[4211]: info: sendMpdCommand play took 1 milliseconds Oct 06 10:22:05 volumio volumio[4211]: info: Oct 06 10:22:05 volumio volumio[4211]: ---------------------------- MPD announces system playlist update Oct 06 10:22:05 volumio volumio[4211]: info: Ignoring MPD Status Update Oct 06 10:22:05 volumio volumio[4211]: info: Oct 06 10:22:05 volumio volumio[4211]: ---------------------------- MPD announces state update: player Oct 06 10:22:05 volumio volumio[4211]: info: sendMpdCommand clear took 2 milliseconds Oct 06 10:22:05 volumio volumio[4211]: info: ControllerMpd::getState Oct 06 10:22:05 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Oct 06 10:22:05 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1821355" Oct 06 10:22:05 volumio volumio[4211]: error: updateQueue error: null Oct 06 10:22:05 volumio volumio[4211]: info: ------------------------------ 1ms Oct 06 10:22:05 volumio volumio[4211]: info: sendMpdCommand status took 0 milliseconds Oct 06 10:22:05 volumio volumio[4211]: verbose: ControllerMpd::parseState Oct 06 10:22:05 volumio volumio[4211]: info: ControllerMpd::pushState Oct 06 10:22:05 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:05 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:05 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:22:05 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:05 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:05 volumio volumio[4211]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 06 10:22:05 volumio volumio[4211]: verbose: CURRENT POSITION 0 Oct 06 10:22:05 volumio volumio[4211]: info: CoreStateMachine::syncState stateService stop Oct 06 10:22:05 volumio volumio[4211]: info: CoreStateMachine::syncState currentStatus stop Oct 06 10:22:05 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:05 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:05 volumio volumio[4211]: info: No code Oct 06 10:22:05 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:05 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:05 volumio volumio[4211]: info: ------------------------------ 8ms Oct 06 10:22:05 volumio volumio[4211]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1821355" took 341 milliseconds Oct 06 10:22:05 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 06 10:22:05 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand play Oct 06 10:22:05 volumio volumio[4211]: info: Oct 06 10:22:05 volumio volumio[4211]: ---------------------------- MPD announces system playlist update Oct 06 10:22:05 volumio volumio[4211]: info: Ignoring MPD Status Update Oct 06 10:22:05 volumio volumio[4211]: info: sendMpdCommand play took 2 milliseconds Oct 06 10:22:05 volumio volumio[4211]: info: ------------------------------ 1ms Oct 06 10:22:06 volumio volumio[4211]: info: Oct 06 10:22:06 volumio volumio[4211]: ---------------------------- MPD announces state update: player Oct 06 10:22:06 volumio volumio[4211]: info: ControllerMpd::getState Oct 06 10:22:06 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Oct 06 10:22:06 volumio volumio[4211]: info: Oct 06 10:22:06 volumio volumio[4211]: ---------------------------- MPD announces state update: player Oct 06 10:22:06 volumio volumio[4211]: info: sendMpdCommand status took 109 milliseconds Oct 06 10:22:06 volumio volumio[4211]: info: ControllerMpd::getState Oct 06 10:22:06 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Oct 06 10:22:06 volumio volumio[4211]: verbose: ControllerMpd::parseState Oct 06 10:22:06 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 10:22:06 volumio volumio[4211]: info: sendMpdCommand status took 1 milliseconds Oct 06 10:22:06 volumio volumio[4211]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 06 10:22:06 volumio volumio[4211]: verbose: ControllerMpd::parseState Oct 06 10:22:06 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 10:22:06 volumio volumio[4211]: verbose: ControllerMpd::parseTrackInfo Oct 06 10:22:06 volumio volumio[4211]: info: ControllerMpd::pushState Oct 06 10:22:06 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:06 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:06 volumio volumio[4211]: 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":null,"isStreaming":false,"title":"atr_128","artist":"1.FM - Amsterdam Trance (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/atr_128","trackType":""} Oct 06 10:22:06 volumio volumio[4211]: verbose: CURRENT POSITION 0 Oct 06 10:22:06 volumio volumio[4211]: info: CoreStateMachine::syncState stateService play Oct 06 10:22:06 volumio volumio[4211]: info: CoreStateMachine::syncState currentStatus stop Oct 06 10:22:06 volumio volumio[4211]: info: ------------------------------ 114ms Oct 06 10:22:06 volumio volumio[4211]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 06 10:22:06 volumio volumio[4211]: verbose: ControllerMpd::parseTrackInfo Oct 06 10:22:06 volumio volumio[4211]: info: ControllerMpd::pushState Oct 06 10:22:06 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:06 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:06 volumio volumio[4211]: 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":null,"isStreaming":false,"title":"atr_128","artist":"1.FM - Amsterdam Trance (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/atr_128","trackType":""} Oct 06 10:22:06 volumio volumio[4211]: verbose: CURRENT POSITION 0 Oct 06 10:22:06 volumio volumio[4211]: info: CoreStateMachine::syncState stateService play Oct 06 10:22:06 volumio volumio[4211]: info: CoreStateMachine::syncState currentStatus play Oct 06 10:22:06 volumio volumio[4211]: info: Received an update from plugin. extracting info from payload Oct 06 10:22:06 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:06 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:06 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:22:06 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:06 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:06 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:06 volumio volumio[4211]: info: ------------------------------ 13ms Oct 06 10:22:07 volumio volumio[4211]: info: Oct 06 10:22:07 volumio volumio[4211]: ---------------------------- MPD announces system playlist update Oct 06 10:22:07 volumio volumio[4211]: info: Ignoring MPD Status Update Oct 06 10:22:07 volumio volumio[4211]: info: Oct 06 10:22:07 volumio volumio[4211]: ---------------------------- MPD announces state update: player Oct 06 10:22:07 volumio volumio[4211]: info: ControllerMpd::getState Oct 06 10:22:07 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Oct 06 10:22:07 volumio volumio[4211]: info: ------------------------------ 2ms Oct 06 10:22:07 volumio volumio[4211]: info: sendMpdCommand status took 1 milliseconds Oct 06 10:22:07 volumio volumio[4211]: verbose: ControllerMpd::parseState Oct 06 10:22:07 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 10:22:07 volumio volumio[4211]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 06 10:22:07 volumio volumio[4211]: verbose: ControllerMpd::parseTrackInfo Oct 06 10:22:07 volumio volumio[4211]: info: ControllerMpd::pushState Oct 06 10:22:07 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:07 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:07 volumio volumio[4211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":854,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"ATR - Monkey Forest (Danilo Ercole Radio Mix)","artist":"1.FM - Amsterdam Trance Radio","album":null,"uri":"http://185.33.21.112:80/atr_128","trackType":""} Oct 06 10:22:07 volumio volumio[4211]: verbose: CURRENT POSITION 0 Oct 06 10:22:07 volumio volumio[4211]: info: CoreStateMachine::syncState stateService play Oct 06 10:22:07 volumio volumio[4211]: info: CoreStateMachine::syncState currentStatus play Oct 06 10:22:07 volumio volumio[4211]: info: Received an update from plugin. extracting info from payload Oct 06 10:22:07 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:07 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:22:07 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:07 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:07 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:07 volumio volumio[4211]: info: ------------------------------ 8ms Oct 06 10:22:22 volumio volumio[4211]: info: Preload queue cleared Oct 06 10:22:22 volumio volumio[4211]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::ClearQueue Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::stop Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::stPlaybackTimer Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::updateTrackBlock Oct 06 10:22:22 volumio volumio[4211]: info: CorePlayQueue::getTrackBlock Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:22 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:22 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:22:22 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::serviceStop Oct 06 10:22:22 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:22 volumio volumio[4211]: info: CoreCommandRouter::serviceStop Oct 06 10:22:22 volumio volumio[4211]: info: [1728181342372] ControllerWebradio::stop Oct 06 10:22:22 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand stop Oct 06 10:22:22 volumio volumio[4211]: info: CorePlayQueue::clearPlayQueue Oct 06 10:22:22 volumio volumio[4211]: info: CorePlayQueue::saveQueue Oct 06 10:22:22 volumio volumio[4211]: info: CoreCommandRouter::volumioPushQueue Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::addQueueItems Oct 06 10:22:22 volumio volumio[4211]: info: CorePlayQueue::addQueueItems Oct 06 10:22:22 volumio volumio[4211]: info: Preload queue cleared Oct 06 10:22:22 volumio volumio[4211]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99490846 Oct 06 10:22:22 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 06 10:22:22 volumio volumio[4211]: info: CoreCommandRouter::volumioPushQueue Oct 06 10:22:22 volumio volumio[4211]: info: CorePlayQueue::saveQueue Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::updateTrackBlock Oct 06 10:22:22 volumio volumio[4211]: info: CorePlayQueue::getTrackBlock Oct 06 10:22:22 volumio volumio[4211]: info: CoreCommandRouter::volumioPlay Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::play index 0 Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::stop Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::play index undefined Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:22 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::startPlaybackTimer Oct 06 10:22:22 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:22 volumio volumio[4211]: info: [1728181342377] ControllerWebradio::clearAddPlayTrack Oct 06 10:22:22 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand stop Oct 06 10:22:22 volumio volumio[4211]: info: Preload queue cleared Oct 06 10:22:22 volumio volumio[4211]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::ClearQueue Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::stop Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:22 volumio volumio[4211]: info: CorePlayQueue::clearPlayQueue Oct 06 10:22:22 volumio volumio[4211]: info: CorePlayQueue::saveQueue Oct 06 10:22:22 volumio volumio[4211]: info: CoreCommandRouter::volumioPushQueue Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::addQueueItems Oct 06 10:22:22 volumio volumio[4211]: info: CorePlayQueue::addQueueItems Oct 06 10:22:22 volumio volumio[4211]: info: Preload queue cleared Oct 06 10:22:22 volumio volumio[4211]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99490846 Oct 06 10:22:22 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 06 10:22:22 volumio volumio[4211]: info: CoreCommandRouter::volumioPushQueue Oct 06 10:22:22 volumio volumio[4211]: info: CorePlayQueue::saveQueue Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::updateTrackBlock Oct 06 10:22:22 volumio volumio[4211]: info: CorePlayQueue::getTrackBlock Oct 06 10:22:22 volumio volumio[4211]: info: CoreCommandRouter::volumioPlay Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::play index 0 Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::stop Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::play index undefined Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:22 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:22 volumio volumio[4211]: info: CoreStateMachine::startPlaybackTimer Oct 06 10:22:22 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:22 volumio volumio[4211]: info: [1728181342563] ControllerWebradio::clearAddPlayTrack Oct 06 10:22:22 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand stop Oct 06 10:22:22 volumio volumio[4211]: info: sendMpdCommand stop took 413 milliseconds Oct 06 10:22:22 volumio volumio[4211]: info: Oct 06 10:22:22 volumio volumio[4211]: ---------------------------- MPD announces state update: player Oct 06 10:22:22 volumio volumio[4211]: info: ControllerMpd::getState Oct 06 10:22:22 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Oct 06 10:22:22 volumio volumio[4211]: info: sendMpdCommand stop took 409 milliseconds Oct 06 10:22:22 volumio volumio[4211]: info: sendMpdCommand stop took 223 milliseconds Oct 06 10:22:22 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand clear Oct 06 10:22:22 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand clear Oct 06 10:22:22 volumio volumio[4211]: info: Oct 06 10:22:22 volumio volumio[4211]: ---------------------------- MPD announces system playlist update Oct 06 10:22:22 volumio volumio[4211]: info: Ignoring MPD Status Update Oct 06 10:22:22 volumio volumio[4211]: info: Oct 06 10:22:22 volumio volumio[4211]: ---------------------------- MPD announces system playlist update Oct 06 10:22:22 volumio volumio[4211]: info: Ignoring MPD Status Update Oct 06 10:22:22 volumio volumio[4211]: info: sendMpdCommand status took 1 milliseconds Oct 06 10:22:22 volumio volumio[4211]: info: sendMpdCommand clear took 1 milliseconds Oct 06 10:22:22 volumio volumio[4211]: info: sendMpdCommand clear took 2 milliseconds Oct 06 10:22:22 volumio volumio[4211]: verbose: ControllerMpd::parseState Oct 06 10:22:22 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 10:22:22 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99490846" Oct 06 10:22:22 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99490846" Oct 06 10:22:22 volumio volumio[4211]: error: updateQueue error: null Oct 06 10:22:22 volumio volumio[4211]: info: ------------------------------ 2ms Oct 06 10:22:24 volumio volumio[4211]: error: updateQueue error: null Oct 06 10:22:24 volumio volumio[4211]: info: Oct 06 10:22:24 volumio volumio[4211]: ---------------------------- MPD announces system playlist update Oct 06 10:22:24 volumio volumio[4211]: info: Ignoring MPD Status Update Oct 06 10:22:24 volumio volumio[4211]: info: ------------------------------ 1251ms Oct 06 10:22:24 volumio volumio[4211]: info: sendMpdCommand playlistinfo took 1250 milliseconds Oct 06 10:22:24 volumio volumio[4211]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99490846" took 1251 milliseconds Oct 06 10:22:24 volumio volumio[4211]: verbose: ControllerMpd::parseTrackInfo Oct 06 10:22:24 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 06 10:22:24 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand play Oct 06 10:22:24 volumio volumio[4211]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 06 10:22:24 volumio volumio[4211]: info: ------------------------------ 1255ms Oct 06 10:22:24 volumio volumio[4211]: info: Oct 06 10:22:24 volumio volumio[4211]: ---------------------------- MPD announces system playlist update Oct 06 10:22:24 volumio volumio[4211]: info: Ignoring MPD Status Update Oct 06 10:22:24 volumio volumio[4211]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99490846" took 1570 milliseconds Oct 06 10:22:24 volumio volumio[4211]: info: ------------------------------ 320ms Oct 06 10:22:24 volumio volumio[4211]: info: sendMpdCommand play took 318 milliseconds Oct 06 10:22:24 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 06 10:22:24 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand play Oct 06 10:22:24 volumio volumio[4211]: info: ------------------------------ 1ms Oct 06 10:22:24 volumio volumio[4211]: info: sendMpdCommand play took 0 milliseconds Oct 06 10:22:27 volumio volumio[4211]: info: Oct 06 10:22:27 volumio volumio[4211]: ---------------------------- MPD announces state update: player Oct 06 10:22:27 volumio volumio[4211]: info: ControllerMpd::getState Oct 06 10:22:27 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Oct 06 10:22:27 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 06 10:22:27 volumio volumio[4211]: info: sendMpdCommand status took 2 milliseconds Oct 06 10:22:27 volumio volumio[4211]: verbose: ControllerMpd::parseState Oct 06 10:22:27 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 10:22:27 volumio volumio[4211]: info: sendMpdCommand clearerror took 2 milliseconds Oct 06 10:22:27 volumio volumio[4211]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 06 10:22:27 volumio volumio[4211]: verbose: ControllerMpd::parseTrackInfo Oct 06 10:22:27 volumio volumio[4211]: info: ControllerMpd::pushState Oct 06 10:22:27 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:27 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:27 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:22:27 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:27 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:27 volumio volumio[4211]: verbose: STATE SERVICE {"status":"stop","position":1,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"BollyHits Radio","album":null,"uri":"https://hoth.alonhosting.com:1080/","trackType":"com:1080/"} Oct 06 10:22:27 volumio volumio[4211]: verbose: CURRENT POSITION 0 Oct 06 10:22:27 volumio volumio[4211]: info: CoreStateMachine::syncState stateService stop Oct 06 10:22:27 volumio volumio[4211]: info: CoreStateMachine::syncState currentStatus stop Oct 06 10:22:27 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:27 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:27 volumio volumio[4211]: info: No code Oct 06 10:22:27 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:27 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:27 volumio volumio[4211]: info: ------------------------------ 10ms Oct 06 10:22:40 volumio volumio[4211]: info: Preload queue cleared Oct 06 10:22:40 volumio volumio[4211]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 06 10:22:40 volumio volumio[4211]: info: CoreStateMachine::ClearQueue Oct 06 10:22:40 volumio volumio[4211]: info: CoreStateMachine::stop Oct 06 10:22:40 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:40 volumio volumio[4211]: info: CorePlayQueue::clearPlayQueue Oct 06 10:22:40 volumio volumio[4211]: info: CorePlayQueue::saveQueue Oct 06 10:22:40 volumio volumio[4211]: info: CoreCommandRouter::volumioPushQueue Oct 06 10:22:40 volumio volumio[4211]: info: CoreStateMachine::addQueueItems Oct 06 10:22:40 volumio volumio[4211]: info: CorePlayQueue::addQueueItems Oct 06 10:22:40 volumio volumio[4211]: info: Preload queue cleared Oct 06 10:22:40 volumio volumio[4211]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1822004 Oct 06 10:22:40 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 06 10:22:40 volumio volumio[4211]: info: CoreCommandRouter::volumioPushQueue Oct 06 10:22:40 volumio volumio[4211]: info: CorePlayQueue::saveQueue Oct 06 10:22:40 volumio volumio[4211]: info: CoreStateMachine::updateTrackBlock Oct 06 10:22:40 volumio volumio[4211]: info: CorePlayQueue::getTrackBlock Oct 06 10:22:40 volumio volumio[4211]: info: CoreCommandRouter::volumioPlay Oct 06 10:22:40 volumio volumio[4211]: info: CoreStateMachine::play index 0 Oct 06 10:22:40 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:40 volumio volumio[4211]: info: CoreStateMachine::stop Oct 06 10:22:40 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:40 volumio volumio[4211]: info: CoreStateMachine::play index undefined Oct 06 10:22:40 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:40 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:40 volumio volumio[4211]: info: CoreStateMachine::startPlaybackTimer Oct 06 10:22:40 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:40 volumio volumio[4211]: info: [1728181360972] ControllerWebradio::clearAddPlayTrack Oct 06 10:22:40 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand stop Oct 06 10:22:40 volumio volumio[4211]: info: sendMpdCommand stop took 3 milliseconds Oct 06 10:22:40 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand clear Oct 06 10:22:40 volumio volumio[4211]: info: Oct 06 10:22:40 volumio volumio[4211]: ---------------------------- MPD announces system playlist update Oct 06 10:22:40 volumio volumio[4211]: info: Ignoring MPD Status Update Oct 06 10:22:40 volumio volumio[4211]: info: sendMpdCommand clear took 1 milliseconds Oct 06 10:22:40 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1822004" Oct 06 10:22:40 volumio volumio[4211]: error: updateQueue error: null Oct 06 10:22:40 volumio volumio[4211]: info: ------------------------------ 2ms Oct 06 10:22:41 volumio volumio[4211]: info: Preload queue cleared Oct 06 10:22:41 volumio volumio[4211]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 06 10:22:41 volumio volumio[4211]: info: CoreStateMachine::ClearQueue Oct 06 10:22:41 volumio volumio[4211]: info: CoreStateMachine::stop Oct 06 10:22:41 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:41 volumio volumio[4211]: info: CorePlayQueue::clearPlayQueue Oct 06 10:22:41 volumio volumio[4211]: info: CorePlayQueue::saveQueue Oct 06 10:22:41 volumio volumio[4211]: info: CoreCommandRouter::volumioPushQueue Oct 06 10:22:41 volumio volumio[4211]: info: CoreStateMachine::addQueueItems Oct 06 10:22:41 volumio volumio[4211]: info: CorePlayQueue::addQueueItems Oct 06 10:22:41 volumio volumio[4211]: info: Preload queue cleared Oct 06 10:22:41 volumio volumio[4211]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1822004 Oct 06 10:22:41 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 06 10:22:41 volumio volumio[4211]: info: CoreCommandRouter::volumioPushQueue Oct 06 10:22:41 volumio volumio[4211]: info: CorePlayQueue::saveQueue Oct 06 10:22:41 volumio volumio[4211]: info: CoreStateMachine::updateTrackBlock Oct 06 10:22:41 volumio volumio[4211]: info: CorePlayQueue::getTrackBlock Oct 06 10:22:41 volumio volumio[4211]: info: CoreCommandRouter::volumioPlay Oct 06 10:22:41 volumio volumio[4211]: info: CoreStateMachine::play index 0 Oct 06 10:22:41 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:41 volumio volumio[4211]: info: CoreStateMachine::stop Oct 06 10:22:41 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:41 volumio volumio[4211]: info: CoreStateMachine::play index undefined Oct 06 10:22:41 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:41 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:41 volumio volumio[4211]: info: CoreStateMachine::startPlaybackTimer Oct 06 10:22:41 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:41 volumio volumio[4211]: info: [1728181361170] ControllerWebradio::clearAddPlayTrack Oct 06 10:22:41 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand stop Oct 06 10:22:41 volumio volumio[4211]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1822004" took 327 milliseconds Oct 06 10:22:41 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 06 10:22:41 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand play Oct 06 10:22:41 volumio volumio[4211]: info: Oct 06 10:22:41 volumio volumio[4211]: ---------------------------- MPD announces system playlist update Oct 06 10:22:41 volumio volumio[4211]: info: Ignoring MPD Status Update Oct 06 10:22:41 volumio volumio[4211]: info: sendMpdCommand stop took 134 milliseconds Oct 06 10:22:41 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand clear Oct 06 10:22:41 volumio volumio[4211]: info: sendMpdCommand play took 1 milliseconds Oct 06 10:22:41 volumio volumio[4211]: info: Oct 06 10:22:41 volumio volumio[4211]: ---------------------------- MPD announces system playlist update Oct 06 10:22:41 volumio volumio[4211]: info: Ignoring MPD Status Update Oct 06 10:22:41 volumio volumio[4211]: info: Oct 06 10:22:41 volumio volumio[4211]: ---------------------------- MPD announces state update: player Oct 06 10:22:41 volumio volumio[4211]: info: ------------------------------ 3ms Oct 06 10:22:41 volumio volumio[4211]: info: sendMpdCommand clear took 2 milliseconds Oct 06 10:22:41 volumio volumio[4211]: info: ControllerMpd::getState Oct 06 10:22:41 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Oct 06 10:22:41 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1822004" Oct 06 10:22:41 volumio volumio[4211]: error: updateQueue error: null Oct 06 10:22:41 volumio volumio[4211]: info: ------------------------------ 2ms Oct 06 10:22:41 volumio volumio[4211]: info: sendMpdCommand status took 1 milliseconds Oct 06 10:22:41 volumio volumio[4211]: verbose: ControllerMpd::parseState Oct 06 10:22:41 volumio volumio[4211]: info: ControllerMpd::pushState Oct 06 10:22:41 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:41 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:41 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:22:41 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:41 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:41 volumio volumio[4211]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 06 10:22:41 volumio volumio[4211]: verbose: CURRENT POSITION 0 Oct 06 10:22:41 volumio volumio[4211]: info: CoreStateMachine::syncState stateService stop Oct 06 10:22:41 volumio volumio[4211]: info: CoreStateMachine::syncState currentStatus stop Oct 06 10:22:41 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:41 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:41 volumio volumio[4211]: info: No code Oct 06 10:22:41 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:41 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:41 volumio volumio[4211]: info: ------------------------------ 9ms Oct 06 10:22:41 volumio volumio[4211]: info: Oct 06 10:22:41 volumio volumio[4211]: ---------------------------- MPD announces system playlist update Oct 06 10:22:41 volumio volumio[4211]: info: Ignoring MPD Status Update Oct 06 10:22:41 volumio volumio[4211]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1822004" took 332 milliseconds Oct 06 10:22:41 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 06 10:22:41 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand play Oct 06 10:22:41 volumio volumio[4211]: info: ------------------------------ 1ms Oct 06 10:22:41 volumio volumio[4211]: info: sendMpdCommand play took 1 milliseconds Oct 06 10:22:42 volumio volumio[4211]: info: Oct 06 10:22:42 volumio volumio[4211]: ---------------------------- MPD announces state update: player Oct 06 10:22:42 volumio volumio[4211]: info: ControllerMpd::getState Oct 06 10:22:42 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Oct 06 10:22:43 volumio volumio[4211]: info: Oct 06 10:22:43 volumio volumio[4211]: ---------------------------- MPD announces system playlist update Oct 06 10:22:43 volumio volumio[4211]: info: Ignoring MPD Status Update Oct 06 10:22:43 volumio volumio[4211]: info: Oct 06 10:22:43 volumio volumio[4211]: ---------------------------- MPD announces state update: player Oct 06 10:22:43 volumio volumio[4211]: info: sendMpdCommand status took 132 milliseconds Oct 06 10:22:43 volumio volumio[4211]: info: ControllerMpd::getState Oct 06 10:22:43 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Oct 06 10:22:43 volumio volumio[4211]: verbose: ControllerMpd::parseState Oct 06 10:22:43 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 10:22:43 volumio volumio[4211]: info: ------------------------------ 2ms Oct 06 10:22:43 volumio volumio[4211]: info: sendMpdCommand status took 1 milliseconds Oct 06 10:22:43 volumio volumio[4211]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 06 10:22:43 volumio volumio[4211]: verbose: ControllerMpd::parseState Oct 06 10:22:43 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 10:22:43 volumio volumio[4211]: verbose: ControllerMpd::parseTrackInfo Oct 06 10:22:43 volumio volumio[4211]: info: ControllerMpd::pushState Oct 06 10:22:43 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:43 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:43 volumio volumio[4211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"83 Kbps","isStreaming":false,"title":"stream","artist":"Rclasicos - Reproduce tus recuerdos","album":null,"uri":"http://107.150.61.146:8186/stream","trackType":""} Oct 06 10:22:43 volumio volumio[4211]: verbose: CURRENT POSITION 0 Oct 06 10:22:43 volumio volumio[4211]: info: CoreStateMachine::syncState stateService play Oct 06 10:22:43 volumio volumio[4211]: info: CoreStateMachine::syncState currentStatus stop Oct 06 10:22:43 volumio volumio[4211]: info: ------------------------------ 134ms Oct 06 10:22:43 volumio volumio[4211]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 06 10:22:43 volumio volumio[4211]: verbose: ControllerMpd::parseTrackInfo Oct 06 10:22:43 volumio volumio[4211]: info: ControllerMpd::pushState Oct 06 10:22:43 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:43 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:43 volumio volumio[4211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":596,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"83 Kbps","isStreaming":false,"title":"stream","artist":"Rclasicos - Reproduce tus recuerdos","album":null,"uri":"http://107.150.61.146:8186/stream","trackType":""} Oct 06 10:22:43 volumio volumio[4211]: verbose: CURRENT POSITION 0 Oct 06 10:22:43 volumio volumio[4211]: info: CoreStateMachine::syncState stateService play Oct 06 10:22:43 volumio volumio[4211]: info: CoreStateMachine::syncState currentStatus play Oct 06 10:22:43 volumio volumio[4211]: info: Received an update from plugin. extracting info from payload Oct 06 10:22:43 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 06 10:22:43 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:43 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:43 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:22:43 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:43 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:43 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:43 volumio volumio[4211]: info: ------------------------------ 7ms Oct 06 10:22:44 volumio volumio[4211]: info: Oct 06 10:22:44 volumio volumio[4211]: ---------------------------- MPD announces system playlist update Oct 06 10:22:44 volumio volumio[4211]: info: Ignoring MPD Status Update Oct 06 10:22:44 volumio volumio[4211]: info: Oct 06 10:22:44 volumio volumio[4211]: ---------------------------- MPD announces state update: player Oct 06 10:22:44 volumio volumio[4211]: info: ControllerMpd::getState Oct 06 10:22:44 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Oct 06 10:22:44 volumio volumio[4211]: info: ------------------------------ 1ms Oct 06 10:22:44 volumio volumio[4211]: info: sendMpdCommand status took 1 milliseconds Oct 06 10:22:44 volumio volumio[4211]: verbose: ControllerMpd::parseState Oct 06 10:22:44 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 10:22:44 volumio volumio[4211]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 06 10:22:44 volumio volumio[4211]: verbose: ControllerMpd::parseTrackInfo Oct 06 10:22:44 volumio volumio[4211]: info: ControllerMpd::pushState Oct 06 10:22:44 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:44 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:44 volumio volumio[4211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2102,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"83 Kbps","isStreaming":false,"title":"Aleks Syntek - Bendito Tu Corazon","artist":"Rclasicos - Reproduce tus recuerdos","album":null,"uri":"http://107.150.61.146:8186/stream","trackType":""} Oct 06 10:22:44 volumio volumio[4211]: verbose: CURRENT POSITION 0 Oct 06 10:22:44 volumio volumio[4211]: info: CoreStateMachine::syncState stateService play Oct 06 10:22:44 volumio volumio[4211]: info: CoreStateMachine::syncState currentStatus play Oct 06 10:22:44 volumio volumio[4211]: info: Received an update from plugin. extracting info from payload Oct 06 10:22:44 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 06 10:22:44 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:44 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:22:44 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:44 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:44 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:44 volumio volumio[4211]: info: ------------------------------ 6ms Oct 06 10:22:48 volumio volumio[4211]: info: Preload queue cleared Oct 06 10:22:48 volumio volumio[4211]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::ClearQueue Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::stop Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::stPlaybackTimer Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::updateTrackBlock Oct 06 10:22:48 volumio volumio[4211]: info: CorePlayQueue::getTrackBlock Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:48 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:48 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:22:48 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::serviceStop Oct 06 10:22:48 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:48 volumio volumio[4211]: info: CoreCommandRouter::serviceStop Oct 06 10:22:48 volumio volumio[4211]: info: [1728181368383] ControllerWebradio::stop Oct 06 10:22:48 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand stop Oct 06 10:22:48 volumio volumio[4211]: info: CorePlayQueue::clearPlayQueue Oct 06 10:22:48 volumio volumio[4211]: info: CorePlayQueue::saveQueue Oct 06 10:22:48 volumio volumio[4211]: info: CoreCommandRouter::volumioPushQueue Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::addQueueItems Oct 06 10:22:48 volumio volumio[4211]: info: CorePlayQueue::addQueueItems Oct 06 10:22:48 volumio volumio[4211]: info: Preload queue cleared Oct 06 10:22:48 volumio volumio[4211]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99601341 Oct 06 10:22:48 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 06 10:22:48 volumio volumio[4211]: info: CoreCommandRouter::volumioPushQueue Oct 06 10:22:48 volumio volumio[4211]: info: CorePlayQueue::saveQueue Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::updateTrackBlock Oct 06 10:22:48 volumio volumio[4211]: info: CorePlayQueue::getTrackBlock Oct 06 10:22:48 volumio volumio[4211]: info: CoreCommandRouter::volumioPlay Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::play index 0 Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::stop Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::play index undefined Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:48 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::startPlaybackTimer Oct 06 10:22:48 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:48 volumio volumio[4211]: info: [1728181368391] ControllerWebradio::clearAddPlayTrack Oct 06 10:22:48 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand stop Oct 06 10:22:48 volumio volumio[4211]: info: Preload queue cleared Oct 06 10:22:48 volumio volumio[4211]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::ClearQueue Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::stop Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:48 volumio volumio[4211]: info: CorePlayQueue::clearPlayQueue Oct 06 10:22:48 volumio volumio[4211]: info: CorePlayQueue::saveQueue Oct 06 10:22:48 volumio volumio[4211]: info: CoreCommandRouter::volumioPushQueue Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::addQueueItems Oct 06 10:22:48 volumio volumio[4211]: info: CorePlayQueue::addQueueItems Oct 06 10:22:48 volumio volumio[4211]: info: Preload queue cleared Oct 06 10:22:48 volumio volumio[4211]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99601341 Oct 06 10:22:48 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 06 10:22:48 volumio volumio[4211]: info: CoreCommandRouter::volumioPushQueue Oct 06 10:22:48 volumio volumio[4211]: info: CorePlayQueue::saveQueue Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::updateTrackBlock Oct 06 10:22:48 volumio volumio[4211]: info: CorePlayQueue::getTrackBlock Oct 06 10:22:48 volumio volumio[4211]: info: CoreCommandRouter::volumioPlay Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::play index 0 Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::stop Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::play index undefined Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:22:48 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:48 volumio volumio[4211]: info: CoreStateMachine::startPlaybackTimer Oct 06 10:22:48 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:48 volumio volumio[4211]: info: [1728181368605] ControllerWebradio::clearAddPlayTrack Oct 06 10:22:48 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand stop Oct 06 10:22:48 volumio volumio[4211]: info: sendMpdCommand stop took 418 milliseconds Oct 06 10:22:48 volumio volumio[4211]: info: Oct 06 10:22:48 volumio volumio[4211]: ---------------------------- MPD announces state update: player Oct 06 10:22:48 volumio volumio[4211]: info: ControllerMpd::getState Oct 06 10:22:48 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Oct 06 10:22:48 volumio volumio[4211]: info: sendMpdCommand stop took 411 milliseconds Oct 06 10:22:48 volumio volumio[4211]: info: sendMpdCommand stop took 197 milliseconds Oct 06 10:22:48 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand clear Oct 06 10:22:48 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand clear Oct 06 10:22:48 volumio volumio[4211]: info: Oct 06 10:22:48 volumio volumio[4211]: ---------------------------- MPD announces system playlist update Oct 06 10:22:48 volumio volumio[4211]: info: Ignoring MPD Status Update Oct 06 10:22:48 volumio volumio[4211]: info: Oct 06 10:22:48 volumio volumio[4211]: ---------------------------- MPD announces system playlist update Oct 06 10:22:48 volumio volumio[4211]: info: Ignoring MPD Status Update Oct 06 10:22:48 volumio volumio[4211]: info: sendMpdCommand status took 2 milliseconds Oct 06 10:22:48 volumio volumio[4211]: info: sendMpdCommand clear took 2 milliseconds Oct 06 10:22:48 volumio volumio[4211]: info: sendMpdCommand clear took 1 milliseconds Oct 06 10:22:48 volumio volumio[4211]: verbose: ControllerMpd::parseState Oct 06 10:22:48 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 10:22:48 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99601341" Oct 06 10:22:48 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99601341" Oct 06 10:22:48 volumio volumio[4211]: error: updateQueue error: null Oct 06 10:22:48 volumio volumio[4211]: error: updateQueue error: null Oct 06 10:22:48 volumio volumio[4211]: info: ------------------------------ 2ms Oct 06 10:22:48 volumio volumio[4211]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 06 10:22:48 volumio volumio[4211]: verbose: ControllerMpd::parseTrackInfo Oct 06 10:22:48 volumio volumio[4211]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 06 10:22:48 volumio volumio[4211]: info: ------------------------------ 4ms Oct 06 10:22:52 volumio volumio[4211]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99601341" took 3521 milliseconds Oct 06 10:22:52 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 06 10:22:52 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand play Oct 06 10:22:52 volumio volumio[4211]: Upnp client error: Error: This socket has been ended by the other party Oct 06 10:22:52 volumio volumio[4211]: info: Oct 06 10:22:52 volumio volumio[4211]: ---------------------------- MPD announces system playlist update Oct 06 10:22:52 volumio volumio[4211]: info: Ignoring MPD Status Update Oct 06 10:22:52 volumio volumio[4211]: info: Oct 06 10:22:52 volumio volumio[4211]: ---------------------------- MPD announces system playlist update Oct 06 10:22:52 volumio volumio[4211]: info: Ignoring MPD Status Update Oct 06 10:22:52 volumio volumio[4211]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99601341" took 3855 milliseconds Oct 06 10:22:52 volumio volumio[4211]: info: sendMpdCommand play took 333 milliseconds Oct 06 10:22:52 volumio volumio[4211]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 06 10:22:52 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand play Oct 06 10:22:52 volumio volumio[4211]: info: ------------------------------ 2ms Oct 06 10:22:52 volumio volumio[4211]: info: ------------------------------ 1ms Oct 06 10:22:52 volumio volumio[4211]: info: sendMpdCommand play took 1 milliseconds Oct 06 10:22:53 volumio volumio[4211]: info: Oct 06 10:22:53 volumio volumio[4211]: ---------------------------- MPD announces state update: player Oct 06 10:22:53 volumio volumio[4211]: info: ControllerMpd::getState Oct 06 10:22:53 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Oct 06 10:22:53 volumio volumio[4211]: info: Oct 06 10:22:53 volumio volumio[4211]: ---------------------------- MPD announces state update: player Oct 06 10:22:53 volumio volumio[4211]: info: sendMpdCommand status took 129 milliseconds Oct 06 10:22:53 volumio volumio[4211]: info: ControllerMpd::getState Oct 06 10:22:53 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Oct 06 10:22:53 volumio volumio[4211]: verbose: ControllerMpd::parseState Oct 06 10:22:53 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 10:22:53 volumio volumio[4211]: info: sendMpdCommand status took 1 milliseconds Oct 06 10:22:53 volumio volumio[4211]: verbose: ControllerMpd::parseState Oct 06 10:22:53 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 10:22:53 volumio volumio[4211]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 06 10:22:53 volumio volumio[4211]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 06 10:22:53 volumio volumio[4211]: verbose: ControllerMpd::parseTrackInfo Oct 06 10:22:53 volumio volumio[4211]: verbose: ControllerMpd::parseTrackInfo Oct 06 10:22:53 volumio volumio[4211]: info: ControllerMpd::pushState Oct 06 10:22:53 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:53 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:53 volumio volumio[4211]: 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":null,"isStreaming":false,"title":"","artist":"(#1 - 181/8000) Radio Kosova 1 - 95.7 FM","album":null,"uri":"http://82.114.72.2:8088/","trackType":"2:8088/"} Oct 06 10:22:53 volumio volumio[4211]: verbose: CURRENT POSITION 0 Oct 06 10:22:53 volumio volumio[4211]: info: CoreStateMachine::syncState stateService play Oct 06 10:22:53 volumio volumio[4211]: info: CoreStateMachine::syncState currentStatus stop Oct 06 10:22:53 volumio volumio[4211]: info: ControllerMpd::pushState Oct 06 10:22:53 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:53 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:53 volumio volumio[4211]: 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":null,"isStreaming":false,"title":"","artist":"(#1 - 181/8000) Radio Kosova 1 - 95.7 FM","album":null,"uri":"http://82.114.72.2:8088/","trackType":"2:8088/"} Oct 06 10:22:53 volumio volumio[4211]: verbose: CURRENT POSITION 0 Oct 06 10:22:53 volumio volumio[4211]: info: CoreStateMachine::syncState stateService play Oct 06 10:22:53 volumio volumio[4211]: info: CoreStateMachine::syncState currentStatus play Oct 06 10:22:53 volumio volumio[4211]: info: Received an update from plugin. extracting info from payload Oct 06 10:22:53 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 06 10:22:53 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:53 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:22:53 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:53 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:53 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:53 volumio volumio[4211]: info: ------------------------------ 136ms Oct 06 10:22:53 volumio volumio[4211]: info: ------------------------------ 10ms Oct 06 10:22:53 volumio volumio[4211]: info: Oct 06 10:22:53 volumio volumio[4211]: ---------------------------- MPD announces system playlist update Oct 06 10:22:53 volumio volumio[4211]: info: Ignoring MPD Status Update Oct 06 10:22:53 volumio volumio[4211]: info: Oct 06 10:22:53 volumio volumio[4211]: ---------------------------- MPD announces state update: player Oct 06 10:22:53 volumio volumio[4211]: info: ControllerMpd::getState Oct 06 10:22:53 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Oct 06 10:22:53 volumio volumio[4211]: info: ------------------------------ 1ms Oct 06 10:22:53 volumio volumio[4211]: info: sendMpdCommand status took 1 milliseconds Oct 06 10:22:53 volumio volumio[4211]: verbose: ControllerMpd::parseState Oct 06 10:22:53 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 10:22:53 volumio volumio[4211]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 06 10:22:53 volumio volumio[4211]: verbose: ControllerMpd::parseTrackInfo Oct 06 10:22:53 volumio volumio[4211]: info: ControllerMpd::pushState Oct 06 10:22:53 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:53 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:53 volumio volumio[4211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"","artist":"Radio Kosova 1 - 95.7 FM","album":null,"uri":"http://82.114.72.2:8088/","trackType":"2:8088/"} Oct 06 10:22:53 volumio volumio[4211]: verbose: CURRENT POSITION 0 Oct 06 10:22:53 volumio volumio[4211]: info: CoreStateMachine::syncState stateService play Oct 06 10:22:53 volumio volumio[4211]: info: CoreStateMachine::syncState currentStatus play Oct 06 10:22:53 volumio volumio[4211]: info: Received an update from plugin. extracting info from payload Oct 06 10:22:53 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 06 10:22:53 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:53 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:22:53 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:53 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:53 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:53 volumio volumio[4211]: info: ------------------------------ 7ms Oct 06 10:22:53 volumio volumio[4211]: info: Oct 06 10:22:53 volumio volumio[4211]: ---------------------------- MPD announces system playlist update Oct 06 10:22:53 volumio volumio[4211]: info: Ignoring MPD Status Update Oct 06 10:22:53 volumio volumio[4211]: info: Oct 06 10:22:53 volumio volumio[4211]: ---------------------------- MPD announces state update: player Oct 06 10:22:53 volumio volumio[4211]: info: ControllerMpd::getState Oct 06 10:22:53 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Oct 06 10:22:53 volumio volumio[4211]: info: ------------------------------ 1ms Oct 06 10:22:53 volumio volumio[4211]: info: sendMpdCommand status took 1 milliseconds Oct 06 10:22:53 volumio volumio[4211]: verbose: ControllerMpd::parseState Oct 06 10:22:53 volumio volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 10:22:53 volumio volumio[4211]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 06 10:22:53 volumio volumio[4211]: verbose: ControllerMpd::parseTrackInfo Oct 06 10:22:53 volumio volumio[4211]: info: ControllerMpd::pushState Oct 06 10:22:53 volumio volumio[4211]: info: CoreCommandRouter::servicePushState Oct 06 10:22:53 volumio volumio[4211]: info: CorePlayQueue::getTrack 0 Oct 06 10:22:53 volumio volumio[4211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"As Vet S'e Di 2020","artist":"Radio Kosova 1 - 95.7 FM","album":null,"uri":"http://82.114.72.2:8088/","trackType":"2:8088/"} Oct 06 10:22:53 volumio volumio[4211]: verbose: CURRENT POSITION 0 Oct 06 10:22:53 volumio volumio[4211]: info: CoreStateMachine::syncState stateService play Oct 06 10:22:53 volumio volumio[4211]: info: CoreStateMachine::syncState currentStatus play Oct 06 10:22:53 volumio volumio[4211]: info: Received an update from plugin. extracting info from payload Oct 06 10:22:53 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 06 10:22:53 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:53 volumio volumio[4211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:22:53 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:53 volumio volumio[4211]: info: CoreStateMachine::pushState Oct 06 10:22:53 volumio volumio[4211]: info: CoreCommandRouter::volumioPushState Oct 06 10:22:53 volumio volumio[4211]: info: ------------------------------ 6ms Oct 06 10:23:01 volumio volumio[4211]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 06 10:23:01 volumio volumio[4211]: Error: connect ETIMEDOUT 108.160.169.55:443 Oct 06 10:23:01 volumio volumio[4211]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 06 10:23:01 volumio volumio[4211]: errno: -110, Oct 06 10:23:01 volumio volumio[4211]: code: 'ETIMEDOUT', Oct 06 10:23:01 volumio volumio[4211]: syscall: 'connect', Oct 06 10:23:01 volumio volumio[4211]: address: '108.160.169.55', Oct 06 10:23:01 volumio volumio[4211]: port: 443 Oct 06 10:23:01 volumio volumio[4211]: } Oct 06 10:23:01 volumio volumio[4211]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 06 10:23:01 volumio sudo[9504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-06 10:22 Oct 06 10:23:01 volumio sudo[9504]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"