Sep 14 12:40:01 volumio kernel: hwmon hwmon1: Voltage normalised Sep 14 12:40:06 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Sep 14 12:40:06 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:10 volumio kernel: hwmon hwmon1: Undervoltage detected! Sep 14 12:40:16 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Sep 14 12:40:16 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:16 volumio volumio[1254]: info: Listing playlists Sep 14 12:40:16 volumio volumio[1254]: info: Listing playlists Sep 14 12:40:22 volumio kernel: hwmon hwmon1: Voltage normalised Sep 14 12:40:22 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 14 12:40:22 volumio dbus-daemon[586]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.38' (uid=0 pid=2904 comm="timedatectl show --property=NTPSynchronized --valu") Sep 14 12:40:22 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 14 12:40:23 volumio dbus-daemon[586]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 14 12:40:23 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 14 12:40:23 volumio setdatetime-helper.sh[2902]: Time is not synchronized. Attempting to sync... Sep 14 12:40:23 volumio sudo[2914]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sun, 14 Sep 2025 10:40:23 GMT#015' Sep 14 12:40:23 volumio sudo[2914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 14 12:40:23 volumio setdatetime-helper.sh[2915]: Sun Sep 14 12:40:23 CEST 2025 Sep 14 12:40:23 volumio systemd-journald[349]: Time jumped backwards, rotating. Sep 14 12:40:23 volumio setdatetime-helper.sh[2902]: Time synchronized successfully. Sep 14 12:40:23 volumio sudo[2914]: pam_unix(sudo:session): session closed for user root Sep 14 12:40:23 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 14 12:40:23 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 14 12:40:24 volumio kernel: hwmon hwmon1: Undervoltage detected! Sep 14 12:40:25 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Sep 14 12:40:25 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:26 volumio volumio[1254]: info: Preload queue cleared Sep 14 12:40:26 volumio volumio[1254]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 14 12:40:26 volumio volumio[1254]: info: CoreStateMachine::ClearQueue Sep 14 12:40:26 volumio volumio[1254]: info: CoreStateMachine::stop Sep 14 12:40:26 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 12:40:26 volumio volumio[1254]: info: CoreStateMachine::stPlaybackTimer Sep 14 12:40:26 volumio volumio[1254]: info: CoreStateMachine::updateTrackBlock Sep 14 12:40:26 volumio volumio[1254]: info: CorePlayQueue::getTrackBlock Sep 14 12:40:26 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:26 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:26 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 12:40:26 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:26 volumio volumio[1254]: info: CoreStateMachine::serviceStop Sep 14 12:40:26 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:26 volumio volumio[1254]: info: CoreCommandRouter::serviceStop Sep 14 12:40:26 volumio volumio[1254]: info: ControllerMpd::stop Sep 14 12:40:26 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 12:40:26 volumio volumio[1254]: info: CorePlayQueue::clearPlayQueue Sep 14 12:40:26 volumio volumio[1254]: info: CorePlayQueue::saveQueue Sep 14 12:40:26 volumio volumio[1254]: info: CoreCommandRouter::volumioPushQueue Sep 14 12:40:26 volumio volumio[1254]: info: CoreStateMachine::addQueueItems Sep 14 12:40:26 volumio volumio[1254]: info: CorePlayQueue::addQueueItems Sep 14 12:40:26 volumio volumio[1254]: info: Preload queue cleared Sep 14 12:40:26 volumio volumio[1254]: info: Adding Item to queue: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Cruz%20de%20Navajas%22%2C%22artist%22%3A%22Mecano%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F9rMZo4C22n9beu3FG4gyTEjpsn5Fx8OtKTFAUuYO4cYHknOVLGM5Bz1AohTABPE2ubsqLT3VxDSamcM%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22c_ivHFrF8XQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22c_ivHFrF8XQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Sep 14 12:40:26 volumio volumio[1254]: info: Exploding uri ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Cruz%20de%20Navajas%22%2C%22artist%22%3A%22Mecano%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F9rMZo4C22n9beu3FG4gyTEjpsn5Fx8OtKTFAUuYO4cYHknOVLGM5Bz1AohTABPE2ubsqLT3VxDSamcM%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22c_ivHFrF8XQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22c_ivHFrF8XQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D in service ytmusic Sep 14 12:40:26 volumio volumio[1254]: info: [ytmusic-browse] explodeUri: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Cruz%20de%20Navajas%22%2C%22artist%22%3A%22Mecano%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F9rMZo4C22n9beu3FG4gyTEjpsn5Fx8OtKTFAUuYO4cYHknOVLGM5Bz1AohTABPE2ubsqLT3VxDSamcM%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22c_ivHFrF8XQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22c_ivHFrF8XQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Sep 14 12:40:26 volumio volumio[1254]: info: CoreCommandRouter::volumioPushQueue Sep 14 12:40:26 volumio volumio[1254]: info: CorePlayQueue::saveQueue Sep 14 12:40:26 volumio volumio[1254]: info: CoreStateMachine::updateTrackBlock Sep 14 12:40:26 volumio volumio[1254]: info: CorePlayQueue::getTrackBlock Sep 14 12:40:26 volumio volumio[1254]: info: CoreCommandRouter::volumioPlay Sep 14 12:40:26 volumio volumio[1254]: info: CoreStateMachine::play index 0 Sep 14 12:40:26 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 12:40:26 volumio volumio[1254]: info: CoreStateMachine::stop Sep 14 12:40:26 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 12:40:26 volumio volumio[1254]: info: CoreStateMachine::play index undefined Sep 14 12:40:26 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 12:40:26 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:26 volumio volumio[1254]: info: CoreStateMachine::startPlaybackTimer Sep 14 12:40:26 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:26 volumio volumio[1254]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Cruz%20de%20Navajas%22%2C%22artist%22%3A%22Mecano%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F9rMZo4C22n9beu3FG4gyTEjpsn5Fx8OtKTFAUuYO4cYHknOVLGM5Bz1AohTABPE2ubsqLT3VxDSamcM%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22c_ivHFrF8XQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22c_ivHFrF8XQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Sep 14 12:40:26 volumio volumio[1254]: info: Sep 14 12:40:26 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:26 volumio volumio[1254]: info: sendMpdCommand stop took 62 milliseconds Sep 14 12:40:26 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:26 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:26 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:26 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:26 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:26 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 12:40:26 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:26 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:26 volumio volumio[1254]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received 80s80s Sep 14 12:40:26 volumio volumio[1254]: info: Sep 14 12:40:26 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:26 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:26 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:26 volumio volumio[1254]: info: Sep 14 12:40:26 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:26 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:26 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:26 volumio volumio[1254]: info: sendMpdCommand status took 27 milliseconds Sep 14 12:40:26 volumio volumio[1254]: info: sendMpdCommand status took 12 milliseconds Sep 14 12:40:26 volumio volumio[1254]: info: sendMpdCommand status took 11 milliseconds Sep 14 12:40:26 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:26 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:26 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:26 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:26 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:26 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:26 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 14 12:40:26 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 14 12:40:26 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 14 12:40:26 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:26 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:26 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:26 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:26 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:26 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:26 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:26 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:26 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:26 volumio volumio[1254]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Sep 14 12:40:26 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:26 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:26 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:26 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:26 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:26 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:26 volumio volumio[1254]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Sep 14 12:40:26 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:26 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:26 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:26 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:26 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:26 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:26 volumio volumio[1254]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Sep 14 12:40:26 volumio volumio[1254]: info: ------------------------------ 73ms Sep 14 12:40:26 volumio volumio[1254]: info: ------------------------------ 57ms Sep 14 12:40:26 volumio volumio[1254]: info: ------------------------------ 56ms Sep 14 12:40:32 volumio kernel: hwmon hwmon1: Voltage normalised Sep 14 12:40:34 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Sep 14 12:40:34 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:35 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Sep 14 12:40:35 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:35 volumio volumio[1254]: info: Listing playlists Sep 14 12:40:35 volumio volumio[1254]: info: Listing playlists Sep 14 12:40:36 volumio kernel: hwmon hwmon1: Undervoltage detected! Sep 14 12:40:41 volumio volumio[1254]: info: CoreCommandRouter::volumioSeek Sep 14 12:40:41 volumio volumio[1254]: info: CoreStateMachine::seek Sep 14 12:40:41 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:41 volumio volumio[1254]: info: TRACKBLOCK {"service":"ytmusic","uri":"ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Cruz%20de%20Navajas%22%2C%22artist%22%3A%22Mecano%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F9rMZo4C22n9beu3FG4gyTEjpsn5Fx8OtKTFAUuYO4cYHknOVLGM5Bz1AohTABPE2ubsqLT3VxDSamcM%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22c_ivHFrF8XQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22c_ivHFrF8XQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D","albumart":"https://lh3.googleusercontent.com/9rMZo4C22n9beu3FG4gyTEjpsn5Fx8OtKTFAUuYO4cYHknOVLGM5Bz1AohTABPE2ubsqLT3VxDSamcM=w701-h701-l90-rj","artist":"Mecano","album":"","name":"Cruz de Navajas","title":"Cruz de Navajas"} Sep 14 12:40:41 volumio volumio[1254]: info: CoreStateMachine::startPlaybackTimer Sep 14 12:40:41 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:41 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 12:40:41 volumio volumio[1254]: info: ControllerMpd::seek Sep 14 12:40:41 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:41 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 12:40:41 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:41 volumio volumio[1254]: error: null Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand status took 17 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand status took 19 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand status took 19 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand status took 3 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:42 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:42 volumio volumio[1254]: 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":"Mr. President - I Give You My Heart","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:42 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus stop Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 27ms Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand status took 10 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand status took 3 milliseconds Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:42 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:42 volumio volumio[1254]: 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":"Mr. President - I Give You My Heart","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:42 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:42 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:42 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:42 volumio volumio[1254]: 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":"Mr. President - I Give You My Heart","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:42 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:42 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:42 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:42 volumio volumio[1254]: 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":"Mr. President - I Give You My Heart","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:42 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:42 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 139ms Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 140ms Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 126ms Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Sep 14 12:40:42 volumio volumio[1254]: info: Ignoring MPD Status Update Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Sep 14 12:40:42 volumio volumio[1254]: info: Ignoring MPD Status Update Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 117 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 118 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:42 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:42 volumio volumio[1254]: 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":"Mr. President - I Give You My Heart","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:42 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:42 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:42 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:42 volumio volumio[1254]: 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":"90s90s - Eurodance","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:42 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:42 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 223ms Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 217ms Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Sep 14 12:40:42 volumio volumio[1254]: info: Ignoring MPD Status Update Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Sep 14 12:40:42 volumio volumio[1254]: info: Ignoring MPD Status Update Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Sep 14 12:40:42 volumio volumio[1254]: info: Ignoring MPD Status Update Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 172ms Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand status took 169 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 172ms Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand status took 168 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 73ms Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand status took 72 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 71ms Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand status took 70 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Sep 14 12:40:42 volumio volumio[1254]: info: Ignoring MPD Status Update Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Sep 14 12:40:42 volumio volumio[1254]: info: Ignoring MPD Status Update Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 15ms Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand status took 11 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 12 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 12 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 12 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 12 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 10ms Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand status took 9 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 9ms Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand status took 7 milliseconds Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:42 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:42 volumio volumio[1254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mr. President - I Give You My Heart","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:42 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:42 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:42 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:42 volumio volumio[1254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mr. President - I Give You My Heart","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:42 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:42 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:42 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:42 volumio volumio[1254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1086,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mr. President - I Give You My Heart","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:42 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:42 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:42 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:42 volumio volumio[1254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1086,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mr. President - I Give You My Heart","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:42 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:42 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 342ms Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 342ms Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 244ms Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 243ms Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Sep 14 12:40:42 volumio volumio[1254]: info: Ignoring MPD Status Update Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Sep 14 12:40:42 volumio volumio[1254]: info: Ignoring MPD Status Update Sep 14 12:40:42 volumio volumio[1254]: info: Sep 14 12:40:42 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 163 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 162 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 162 milliseconds Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:42 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:42 volumio volumio[1254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1086,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mr. President - I Give You My Heart","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:42 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:42 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:42 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:42 volumio volumio[1254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1086,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mr. President - I Give You My Heart","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:42 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:42 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:42 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:42 volumio volumio[1254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1086,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mr. President - I Give You My Heart","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:42 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:42 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 295ms Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 290ms Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 288ms Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 206ms Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand status took 206 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 205ms Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand status took 204 milliseconds Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 10 milliseconds Sep 14 12:40:42 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 10 milliseconds Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:42 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:42 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:42 volumio volumio[1254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1280,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mr. President - I Give You My Heart","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:42 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:42 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:42 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:42 volumio volumio[1254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1280,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mr. President - I Give You My Heart","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:42 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:42 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:42 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 292ms Sep 14 12:40:42 volumio volumio[1254]: info: ------------------------------ 291ms Sep 14 12:40:45 volumio volumio[1254]: info: CoreCommandRouter::volumioSeek Sep 14 12:40:45 volumio volumio[1254]: info: CoreStateMachine::seek Sep 14 12:40:45 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:45 volumio volumio[1254]: info: TRACKBLOCK {"service":"ytmusic","uri":"ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Cruz%20de%20Navajas%22%2C%22artist%22%3A%22Mecano%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F9rMZo4C22n9beu3FG4gyTEjpsn5Fx8OtKTFAUuYO4cYHknOVLGM5Bz1AohTABPE2ubsqLT3VxDSamcM%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22c_ivHFrF8XQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22c_ivHFrF8XQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D","albumart":"https://lh3.googleusercontent.com/9rMZo4C22n9beu3FG4gyTEjpsn5Fx8OtKTFAUuYO4cYHknOVLGM5Bz1AohTABPE2ubsqLT3VxDSamcM=w701-h701-l90-rj","artist":"Mecano","album":"","name":"Cruz de Navajas","title":"Cruz de Navajas"} Sep 14 12:40:45 volumio volumio[1254]: info: CoreStateMachine::startPlaybackTimer Sep 14 12:40:45 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:45 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 12:40:45 volumio volumio[1254]: info: ControllerMpd::seek Sep 14 12:40:45 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:45 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 12:40:45 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:45 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Sep 14 12:40:46 volumio volumio[1254]: error: null Sep 14 12:40:46 volumio volumio[1254]: info: Sep 14 12:40:46 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:46 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:46 volumio volumio[1254]: info: Sep 14 12:40:46 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:46 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:46 volumio volumio[1254]: info: Sep 14 12:40:46 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:46 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:46 volumio volumio[1254]: info: sendMpdCommand status took 4 milliseconds Sep 14 12:40:46 volumio volumio[1254]: info: sendMpdCommand status took 3 milliseconds Sep 14 12:40:46 volumio volumio[1254]: info: sendMpdCommand status took 2 milliseconds Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:46 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 14 12:40:46 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 14 12:40:46 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:46 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:46 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:46 volumio volumio[1254]: 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":"Mr. President - I Give You My Heart","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:46 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:46 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 12:40:46 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:46 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:46 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:46 volumio volumio[1254]: 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":"Mr. President - I Give You My Heart","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:46 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:46 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:46 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:46 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:46 volumio volumio[1254]: 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":"Mr. President - I Give You My Heart","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:46 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:46 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:46 volumio volumio[1254]: info: ------------------------------ 82ms Sep 14 12:40:46 volumio volumio[1254]: info: ------------------------------ 87ms Sep 14 12:40:46 volumio volumio[1254]: info: ------------------------------ 88ms Sep 14 12:40:46 volumio volumio[1254]: info: Sep 14 12:40:46 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Sep 14 12:40:46 volumio volumio[1254]: info: Ignoring MPD Status Update Sep 14 12:40:46 volumio volumio[1254]: info: Sep 14 12:40:46 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:46 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:46 volumio volumio[1254]: info: Sep 14 12:40:46 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Sep 14 12:40:46 volumio volumio[1254]: info: Ignoring MPD Status Update Sep 14 12:40:46 volumio volumio[1254]: info: Sep 14 12:40:46 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:46 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:46 volumio volumio[1254]: info: Sep 14 12:40:46 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Sep 14 12:40:46 volumio volumio[1254]: info: Ignoring MPD Status Update Sep 14 12:40:46 volumio volumio[1254]: info: Sep 14 12:40:46 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:46 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:46 volumio volumio[1254]: info: ------------------------------ 49ms Sep 14 12:40:46 volumio volumio[1254]: info: sendMpdCommand status took 48 milliseconds Sep 14 12:40:46 volumio volumio[1254]: info: ------------------------------ 14ms Sep 14 12:40:46 volumio volumio[1254]: info: sendMpdCommand status took 12 milliseconds Sep 14 12:40:46 volumio volumio[1254]: info: ------------------------------ 11ms Sep 14 12:40:46 volumio volumio[1254]: info: sendMpdCommand status took 10 milliseconds Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:46 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 28 milliseconds Sep 14 12:40:46 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 28 milliseconds Sep 14 12:40:46 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 28 milliseconds Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:46 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:46 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:46 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:46 volumio volumio[1254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"90s90s - Eurodance","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:46 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:46 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 12:40:46 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:46 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:46 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:46 volumio volumio[1254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"90s90s - Eurodance","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:46 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:46 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:46 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:46 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:46 volumio volumio[1254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"90s90s - Eurodance","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:46 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:46 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:46 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:46 volumio volumio[1254]: info: ------------------------------ 296ms Sep 14 12:40:46 volumio volumio[1254]: info: ------------------------------ 262ms Sep 14 12:40:46 volumio volumio[1254]: info: ------------------------------ 260ms Sep 14 12:40:47 volumio volumio[1254]: info: CoreCommandRouter::volumioSeek Sep 14 12:40:47 volumio volumio[1254]: info: CoreStateMachine::seek Sep 14 12:40:47 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:47 volumio volumio[1254]: info: TRACKBLOCK {"service":"ytmusic","uri":"ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Cruz%20de%20Navajas%22%2C%22artist%22%3A%22Mecano%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F9rMZo4C22n9beu3FG4gyTEjpsn5Fx8OtKTFAUuYO4cYHknOVLGM5Bz1AohTABPE2ubsqLT3VxDSamcM%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22c_ivHFrF8XQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22c_ivHFrF8XQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D","albumart":"https://lh3.googleusercontent.com/9rMZo4C22n9beu3FG4gyTEjpsn5Fx8OtKTFAUuYO4cYHknOVLGM5Bz1AohTABPE2ubsqLT3VxDSamcM=w701-h701-l90-rj","artist":"Mecano","album":"","name":"Cruz de Navajas","title":"Cruz de Navajas"} Sep 14 12:40:47 volumio volumio[1254]: info: CoreStateMachine::startPlaybackTimer Sep 14 12:40:47 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:47 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 12:40:47 volumio volumio[1254]: info: ControllerMpd::seek Sep 14 12:40:47 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:47 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 12:40:47 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:48 volumio volumio[1254]: error: null Sep 14 12:40:48 volumio volumio[1254]: info: Sep 14 12:40:48 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:48 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:48 volumio volumio[1254]: info: Sep 14 12:40:48 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:48 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:48 volumio volumio[1254]: info: Sep 14 12:40:48 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:48 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:48 volumio volumio[1254]: info: sendMpdCommand status took 6 milliseconds Sep 14 12:40:48 volumio volumio[1254]: info: sendMpdCommand status took 6 milliseconds Sep 14 12:40:48 volumio volumio[1254]: info: sendMpdCommand status took 5 milliseconds Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:48 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 14 12:40:48 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 14 12:40:48 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:48 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:48 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:48 volumio volumio[1254]: 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":"90s90s - Eurodance","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:48 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:48 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 12:40:48 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:48 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:48 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:48 volumio volumio[1254]: 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":"90s90s - Eurodance","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:48 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:48 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:48 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:48 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:48 volumio volumio[1254]: 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":"90s90s - Eurodance","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:48 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:48 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:48 volumio volumio[1254]: info: ------------------------------ 125ms Sep 14 12:40:48 volumio volumio[1254]: info: ------------------------------ 124ms Sep 14 12:40:48 volumio volumio[1254]: info: ------------------------------ 124ms Sep 14 12:40:48 volumio volumio[1254]: info: Sep 14 12:40:48 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Sep 14 12:40:48 volumio volumio[1254]: info: Ignoring MPD Status Update Sep 14 12:40:48 volumio volumio[1254]: info: Sep 14 12:40:48 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:48 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:48 volumio volumio[1254]: info: Sep 14 12:40:48 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Sep 14 12:40:48 volumio volumio[1254]: info: Ignoring MPD Status Update Sep 14 12:40:48 volumio volumio[1254]: info: Sep 14 12:40:48 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:48 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:48 volumio volumio[1254]: info: Sep 14 12:40:48 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Sep 14 12:40:48 volumio volumio[1254]: info: Ignoring MPD Status Update Sep 14 12:40:48 volumio volumio[1254]: info: Sep 14 12:40:48 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:48 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:48 volumio volumio[1254]: info: ------------------------------ 41ms Sep 14 12:40:48 volumio volumio[1254]: info: sendMpdCommand status took 40 milliseconds Sep 14 12:40:48 volumio volumio[1254]: info: ------------------------------ 7ms Sep 14 12:40:48 volumio volumio[1254]: info: sendMpdCommand status took 6 milliseconds Sep 14 12:40:48 volumio volumio[1254]: info: ------------------------------ 5ms Sep 14 12:40:48 volumio volumio[1254]: info: sendMpdCommand status took 4 milliseconds Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:48 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:48 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:48 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:48 volumio volumio[1254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"90s90s - Eurodance","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:48 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:48 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 12:40:48 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:48 volumio volumio[1254]: info: ------------------------------ 95ms Sep 14 12:40:48 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 54 milliseconds Sep 14 12:40:48 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 54 milliseconds Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:48 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:48 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:48 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:48 volumio volumio[1254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"90s90s - Eurodance","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:48 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:48 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:48 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:48 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:48 volumio volumio[1254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"90s90s - Eurodance","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:48 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Sep 14 12:40:48 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:48 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:48 volumio volumio[1254]: info: ------------------------------ 138ms Sep 14 12:40:48 volumio volumio[1254]: info: ------------------------------ 137ms Sep 14 12:40:50 volumio volumio[1254]: info: CoreCommandRouter::volumioStop Sep 14 12:40:50 volumio volumio[1254]: info: CoreStateMachine::stop Sep 14 12:40:50 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 12:40:50 volumio volumio[1254]: info: CoreStateMachine::stPlaybackTimer Sep 14 12:40:50 volumio volumio[1254]: info: CoreStateMachine::updateTrackBlock Sep 14 12:40:50 volumio volumio[1254]: info: CorePlayQueue::getTrackBlock Sep 14 12:40:50 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:50 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:50 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 12:40:50 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:50 volumio volumio[1254]: info: CoreStateMachine::serviceStop Sep 14 12:40:50 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:50 volumio volumio[1254]: info: CoreCommandRouter::serviceStop Sep 14 12:40:50 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 12:40:50 volumio volumio[1254]: info: ControllerMpd::stop Sep 14 12:40:50 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 12:40:50 volumio volumio[1254]: info: sendMpdCommand stop took 17 milliseconds Sep 14 12:40:50 volumio volumio[1254]: info: Sep 14 12:40:50 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:50 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:50 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:50 volumio volumio[1254]: info: Sep 14 12:40:50 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:50 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:50 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:50 volumio volumio[1254]: info: Sep 14 12:40:50 volumio volumio[1254]: ---------------------------- MPD announces state update: player Sep 14 12:40:50 volumio volumio[1254]: info: ControllerMpd::getState Sep 14 12:40:50 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Sep 14 12:40:50 volumio volumio[1254]: info: sendMpdCommand status took 5 milliseconds Sep 14 12:40:50 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:50 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:50 volumio volumio[1254]: info: sendMpdCommand status took 5 milliseconds Sep 14 12:40:50 volumio volumio[1254]: info: sendMpdCommand status took 3 milliseconds Sep 14 12:40:50 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 14 12:40:50 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:50 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:50 volumio volumio[1254]: verbose: ControllerMpd::parseState Sep 14 12:40:50 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 12:40:50 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:50 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:50 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:50 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:50 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 12:40:50 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:50 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:50 volumio volumio[1254]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"90s90s - Eurodance","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:50 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:50 volumio volumio[1254]: info: CoreStateMachine::syncState stateService stop Sep 14 12:40:50 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus stop Sep 14 12:40:50 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:50 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:50 volumio volumio[1254]: info: No code Sep 14 12:40:50 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:50 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:50 volumio volumio[1254]: info: ------------------------------ 64ms Sep 14 12:40:50 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 56 milliseconds Sep 14 12:40:50 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 56 milliseconds Sep 14 12:40:50 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:50 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Sep 14 12:40:50 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:50 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:50 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:50 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:50 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:50 volumio volumio[1254]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"90s90s - Eurodance","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:50 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:50 volumio volumio[1254]: info: CoreStateMachine::syncState stateService stop Sep 14 12:40:50 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus stop Sep 14 12:40:50 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:50 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:50 volumio volumio[1254]: info: No code Sep 14 12:40:50 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:50 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:50 volumio volumio[1254]: info: ControllerMpd::pushState Sep 14 12:40:50 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Sep 14 12:40:50 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:50 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:50 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:50 volumio volumio[1254]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"90s90s - Eurodance","artist":"90s90s - Eurodance","album":null,"uri":"http://streams.90s90s.de/eurodance/mp3-192/volumio","trackType":""} Sep 14 12:40:50 volumio volumio[1254]: verbose: CURRENT POSITION 0 Sep 14 12:40:50 volumio volumio[1254]: info: CoreStateMachine::syncState stateService stop Sep 14 12:40:50 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus stop Sep 14 12:40:50 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:50 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:50 volumio volumio[1254]: info: No code Sep 14 12:40:50 volumio volumio[1254]: info: CoreStateMachine::pushState Sep 14 12:40:50 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Sep 14 12:40:50 volumio volumio[1254]: info: ------------------------------ 159ms Sep 14 12:40:50 volumio volumio[1254]: info: ------------------------------ 158ms Sep 14 12:40:52 volumio volumio[1254]: info: CoreCommandRouter::volumioStop Sep 14 12:40:52 volumio volumio[1254]: info: CoreStateMachine::stop Sep 14 12:40:52 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 12:40:52 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 14 12:40:55 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Sep 14 12:40:55 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:40:55 volumio volumio[1254]: info: Listing playlists Sep 14 12:40:55 volumio volumio[1254]: info: Listing playlists Sep 14 12:40:57 volumio kernel: hwmon hwmon1: Voltage normalised Sep 14 12:40:59 volumio kernel: hwmon hwmon1: Undervoltage detected! Sep 14 12:41:03 volumio volumio[1254]: info: CoreCommandRouter::volumioPlay Sep 14 12:41:03 volumio volumio[1254]: info: CoreStateMachine::play index 0 Sep 14 12:41:03 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 12:41:03 volumio volumio[1254]: info: CoreStateMachine::stop Sep 14 12:41:03 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 12:41:03 volumio volumio[1254]: info: CoreStateMachine::play index undefined Sep 14 12:41:03 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 12:41:03 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:41:03 volumio volumio[1254]: info: CoreStateMachine::startPlaybackTimer Sep 14 12:41:03 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:41:03 volumio volumio[1254]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Cruz%20de%20Navajas%22%2C%22artist%22%3A%22Mecano%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F9rMZo4C22n9beu3FG4gyTEjpsn5Fx8OtKTFAUuYO4cYHknOVLGM5Bz1AohTABPE2ubsqLT3VxDSamcM%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22c_ivHFrF8XQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22c_ivHFrF8XQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Sep 14 12:41:03 volumio kernel: hwmon hwmon1: Voltage normalised Sep 14 12:41:05 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Sep 14 12:41:05 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:41:05 volumio volumio[1254]: info: CoreCommandRouter::volumioPlay Sep 14 12:41:05 volumio volumio[1254]: info: CoreStateMachine::play index 0 Sep 14 12:41:05 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 12:41:05 volumio volumio[1254]: info: CoreStateMachine::stop Sep 14 12:41:05 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 12:41:05 volumio volumio[1254]: info: CoreStateMachine::play index undefined Sep 14 12:41:05 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 12:41:05 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:41:05 volumio volumio[1254]: info: CoreStateMachine::startPlaybackTimer Sep 14 12:41:05 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:41:05 volumio volumio[1254]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Cruz%20de%20Navajas%22%2C%22artist%22%3A%22Mecano%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F9rMZo4C22n9beu3FG4gyTEjpsn5Fx8OtKTFAUuYO4cYHknOVLGM5Bz1AohTABPE2ubsqLT3VxDSamcM%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22c_ivHFrF8XQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22c_ivHFrF8XQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Sep 14 12:41:09 volumio kernel: hwmon hwmon1: Undervoltage detected! Sep 14 12:41:09 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Sep 14 12:41:09 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:41:13 volumio kernel: hwmon hwmon1: Voltage normalised Sep 14 12:41:15 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Sep 14 12:41:15 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:41:15 volumio volumio[1254]: info: Listing playlists Sep 14 12:41:15 volumio volumio[1254]: info: Listing playlists Sep 14 12:41:18 volumio kernel: hwmon hwmon1: Undervoltage detected! Sep 14 12:41:24 volumio kernel: hwmon hwmon1: Voltage normalised Sep 14 12:41:25 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Sep 14 12:41:25 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:41:28 volumio kernel: hwmon hwmon1: Undervoltage detected! Sep 14 12:41:30 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 14 12:41:30 volumio dbus-daemon[586]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.40' (uid=0 pid=3071 comm="timedatectl show --property=NTPSynchronized --valu") Sep 14 12:41:30 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 14 12:41:30 volumio dbus-daemon[586]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 14 12:41:30 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 14 12:41:30 volumio setdatetime-helper.sh[3070]: Time is not synchronized. Attempting to sync... Sep 14 12:41:31 volumio sudo[3079]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sun, 14 Sep 2025 10:41:31 GMT#015' Sep 14 12:41:31 volumio sudo[3079]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 14 12:41:31 volumio setdatetime-helper.sh[3080]: Sun Sep 14 12:41:31 CEST 2025 Sep 14 12:41:31 volumio systemd-journald[349]: Time jumped backwards, rotating. Sep 14 12:41:31 volumio setdatetime-helper.sh[3070]: Time synchronized successfully. Sep 14 12:41:31 volumio sudo[3079]: pam_unix(sudo:session): session closed for user root Sep 14 12:41:31 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 14 12:41:31 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 14 12:41:33 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 14 12:41:33 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 12:41:33 volumio volumio[1254]: info: Preload queue cleared Sep 14 12:41:34 volumio kernel: hwmon hwmon1: Voltage normalised Sep 14 12:41:35 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Sep 14 12:41:35 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Sep 14 12:41:35 volumio volumio[1254]: info: Listing playlists Sep 14 12:41:35 volumio volumio[1254]: info: Listing playlists Sep 14 12:41:36 volumio kernel: hwmon hwmon1: Undervoltage detected! Sep 14 12:41:37 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 14 12:41:37 volumio volumio[1254]: info: READ Sep 14 12:41:37 volumio volumio[1254]: Use XMLElement.setAttribute instead Sep 14 12:41:37 volumio volumio[1254]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 12:41:37 volumio volumio[1254]: TypeError: children[i].attr(...).value is not a function Sep 14 12:41:37 volumio volumio[1254]: at /volumio/app/plugins/music_service/webradio/index.js:286:47 Sep 14 12:41:37 volumio volumio[1254]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Sep 14 12:41:37 volumio volumio[1254]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 12:41:38 volumio sudo[3111]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-14 12:40' Sep 14 12:41:38 volumio sudo[3111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"