-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Fri 2024-10-11 23:30:42 CEST. -- Oct 11 23:29:03 volumio volumio[897]: info: Preload queue cleared Oct 11 23:29:03 volumio volumio[897]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 11 23:29:03 volumio volumio[897]: info: CoreStateMachine::ClearQueue Oct 11 23:29:03 volumio volumio[897]: info: CoreStateMachine::stop Oct 11 23:29:03 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 23:29:03 volumio volumio[897]: info: CoreStateMachine::stPlaybackTimer Oct 11 23:29:03 volumio volumio[897]: info: CoreStateMachine::updateTrackBlock Oct 11 23:29:03 volumio volumio[897]: info: CorePlayQueue::getTrackBlock Oct 11 23:29:03 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:03 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:03 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:29:03 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:03 volumio volumio[897]: info: CoreStateMachine::serviceStop Oct 11 23:29:03 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:03 volumio volumio[897]: info: CoreCommandRouter::serviceStop Oct 11 23:29:03 volumio volumio[897]: info: [1728682143641] ControllerWebradio::stop Oct 11 23:29:03 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 23:29:03 volumio volumio[897]: info: CorePlayQueue::clearPlayQueue Oct 11 23:29:03 volumio volumio[897]: info: CorePlayQueue::saveQueue Oct 11 23:29:03 volumio volumio[897]: info: CoreCommandRouter::volumioPushQueue Oct 11 23:29:03 volumio volumio[897]: info: CoreStateMachine::addQueueItems Oct 11 23:29:03 volumio volumio[897]: info: CorePlayQueue::addQueueItems Oct 11 23:29:03 volumio volumio[897]: info: Preload queue cleared Oct 11 23:29:03 volumio volumio[897]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s116044 Oct 11 23:29:03 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 11 23:29:03 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status stop Oct 11 23:29:03 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:03 volumio sudo[2394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Oct 11 23:29:03 volumio sudo[2394]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:03 volumio systemd[1]: Stopping peppymeterbasic Daemon... Oct 11 23:29:03 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Oct 11 23:29:03 volumio volumio[897]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 11 23:29:03 volumio systemd[1]: peppymeterbasic.service: Succeeded. Oct 11 23:29:03 volumio systemd[1]: Stopped peppymeterbasic Daemon. Oct 11 23:29:03 volumio sudo[2394]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:03 volumio volumio[897]: info: peppymeterbasic Daemon Stop Oct 11 23:29:03 volumio volumio[897]: info: CoreCommandRouter::volumioPushQueue Oct 11 23:29:03 volumio volumio[897]: info: CorePlayQueue::saveQueue Oct 11 23:29:03 volumio volumio[897]: info: CoreStateMachine::updateTrackBlock Oct 11 23:29:03 volumio volumio[897]: info: CorePlayQueue::getTrackBlock Oct 11 23:29:03 volumio volumio[897]: info: CoreCommandRouter::volumioPlay Oct 11 23:29:03 volumio volumio[897]: info: CoreStateMachine::play index 0 Oct 11 23:29:03 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 23:29:03 volumio volumio[897]: info: CoreStateMachine::stop Oct 11 23:29:03 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 23:29:03 volumio volumio[897]: info: CoreStateMachine::play index undefined Oct 11 23:29:03 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 23:29:03 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:03 volumio volumio[897]: info: CoreStateMachine::startPlaybackTimer Oct 11 23:29:03 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:03 volumio volumio[897]: info: [1728682143995] ControllerWebradio::clearAddPlayTrack Oct 11 23:29:03 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 23:29:04 volumio volumio[897]: info: sendMpdCommand stop took 544 milliseconds Oct 11 23:29:04 volumio volumio[897]: info: Oct 11 23:29:04 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:04 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:04 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:04 volumio volumio[897]: info: sendMpdCommand stop took 192 milliseconds Oct 11 23:29:04 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand clear Oct 11 23:29:04 volumio volumio[897]: info: Oct 11 23:29:04 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:04 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:04 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:04 volumio volumio[897]: info: Oct 11 23:29:04 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:04 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:04 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:04 volumio volumio[897]: info: Oct 11 23:29:04 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:04 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:04 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:04 volumio volumio[897]: info: Oct 11 23:29:04 volumio volumio[897]: ---------------------------- MPD announces system playlist update Oct 11 23:29:04 volumio volumio[897]: info: Ignoring MPD Status Update Oct 11 23:29:04 volumio volumio[897]: info: sendMpdCommand status took 4 milliseconds Oct 11 23:29:04 volumio volumio[897]: info: sendMpdCommand clear took 4 milliseconds Oct 11 23:29:04 volumio volumio[897]: info: sendMpdCommand status took 3 milliseconds Oct 11 23:29:04 volumio volumio[897]: info: sendMpdCommand status took 2 milliseconds Oct 11 23:29:04 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Oct 11 23:29:04 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:04 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:04 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand load "https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein" Oct 11 23:29:04 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:04 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:04 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:04 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:04 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:04 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:04 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:04 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:29:04 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:04 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:04 volumio volumio[897]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 11 23:29:04 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:04 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:04 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:04 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:04 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:04 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:04 volumio volumio[897]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 11 23:29:04 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:04 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:04 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:04 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:04 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:04 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:04 volumio volumio[897]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 11 23:29:04 volumio volumio[897]: info: ------------------------------ 39ms Oct 11 23:29:04 volumio volumio[897]: info: ------------------------------ 38ms Oct 11 23:29:04 volumio volumio[897]: info: ------------------------------ 39ms Oct 11 23:29:04 volumio volumio[897]: info: Oct 11 23:29:04 volumio volumio[897]: ---------------------------- MPD announces system playlist update Oct 11 23:29:04 volumio volumio[897]: info: Ignoring MPD Status Update Oct 11 23:29:04 volumio volumio[897]: info: Oct 11 23:29:04 volumio volumio[897]: ---------------------------- MPD announces system playlist update Oct 11 23:29:04 volumio volumio[897]: info: Ignoring MPD Status Update Oct 11 23:29:04 volumio volumio[897]: info: Oct 11 23:29:04 volumio volumio[897]: ---------------------------- MPD announces system playlist update Oct 11 23:29:04 volumio volumio[897]: info: Ignoring MPD Status Update Oct 11 23:29:04 volumio volumio[897]: error: updateQueue error: null Oct 11 23:29:04 volumio volumio[897]: info: ------------------------------ 42ms Oct 11 23:29:04 volumio volumio[897]: info: sendMpdCommand playlistinfo took 41 milliseconds Oct 11 23:29:04 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:04 volumio volumio[897]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 11 23:29:04 volumio volumio[897]: info: ------------------------------ 49ms Oct 11 23:29:04 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status stop Oct 11 23:29:04 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status stop Oct 11 23:29:04 volumio sudo[2400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Oct 11 23:29:04 volumio sudo[2400]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:04 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status stop Oct 11 23:29:04 volumio sudo[2403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Oct 11 23:29:04 volumio sudo[2403]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:04 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:04 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:04 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:04 volumio sudo[2406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Oct 11 23:29:04 volumio sudo[2406]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:04 volumio sudo[2400]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:04 volumio volumio[897]: info: peppymeterbasic Daemon Stop Oct 11 23:29:04 volumio sudo[2403]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:04 volumio volumio[897]: info: peppymeterbasic Daemon Stop Oct 11 23:29:04 volumio sudo[2406]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:04 volumio volumio[897]: info: peppymeterbasic Daemon Stop Oct 11 23:29:05 volumio volumio[897]: error: updateQueue error: null Oct 11 23:29:05 volumio volumio[897]: error: updateQueue error: null Oct 11 23:29:05 volumio volumio[897]: error: updateQueue error: null Oct 11 23:29:05 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand add "https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein" Oct 11 23:29:05 volumio volumio[897]: info: ------------------------------ 1369ms Oct 11 23:29:05 volumio volumio[897]: info: ------------------------------ 1369ms Oct 11 23:29:05 volumio volumio[897]: info: ------------------------------ 1368ms Oct 11 23:29:05 volumio volumio[897]: info: Oct 11 23:29:05 volumio volumio[897]: ---------------------------- MPD announces system playlist update Oct 11 23:29:05 volumio volumio[897]: info: Ignoring MPD Status Update Oct 11 23:29:05 volumio volumio[897]: info: sendMpdCommand add "https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein" took 3 milliseconds Oct 11 23:29:05 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 11 23:29:05 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand play Oct 11 23:29:05 volumio volumio[897]: info: Oct 11 23:29:05 volumio volumio[897]: ---------------------------- MPD announces system playlist update Oct 11 23:29:05 volumio volumio[897]: info: Ignoring MPD Status Update Oct 11 23:29:05 volumio volumio[897]: info: Oct 11 23:29:05 volumio volumio[897]: ---------------------------- MPD announces system playlist update Oct 11 23:29:05 volumio volumio[897]: info: Ignoring MPD Status Update Oct 11 23:29:05 volumio volumio[897]: info: Oct 11 23:29:05 volumio volumio[897]: ---------------------------- MPD announces system playlist update Oct 11 23:29:05 volumio volumio[897]: info: Ignoring MPD Status Update Oct 11 23:29:05 volumio volumio[897]: info: ------------------------------ 11ms Oct 11 23:29:05 volumio volumio[897]: info: sendMpdCommand play took 10 milliseconds Oct 11 23:29:05 volumio volumio[897]: info: ------------------------------ 8ms Oct 11 23:29:05 volumio volumio[897]: info: ------------------------------ 6ms Oct 11 23:29:05 volumio volumio[897]: info: ------------------------------ 4ms Oct 11 23:29:06 volumio volumio[897]: info: Oct 11 23:29:06 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:06 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:06 volumio volumio[897]: info: Oct 11 23:29:06 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:06 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:06 volumio volumio[897]: info: Oct 11 23:29:06 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:06 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:06 volumio volumio[897]: info: Oct 11 23:29:06 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:06 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:06 volumio volumio[897]: info: Oct 11 23:29:06 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:06 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:06 volumio volumio[897]: info: sendMpdCommand status took 6 milliseconds Oct 11 23:29:06 volumio volumio[897]: info: sendMpdCommand status took 5 milliseconds Oct 11 23:29:06 volumio volumio[897]: info: sendMpdCommand status took 4 milliseconds Oct 11 23:29:06 volumio volumio[897]: info: sendMpdCommand status took 2 milliseconds Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:06 volumio volumio[897]: info: Oct 11 23:29:06 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:06 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:06 volumio volumio[897]: info: Oct 11 23:29:06 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:06 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:06 volumio volumio[897]: info: Oct 11 23:29:06 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:06 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:06 volumio volumio[897]: info: sendMpdCommand status took 9 milliseconds Oct 11 23:29:06 volumio volumio[897]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 11 23:29:06 volumio volumio[897]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 11 23:29:06 volumio volumio[897]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 11 23:29:06 volumio volumio[897]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 11 23:29:06 volumio volumio[897]: info: sendMpdCommand status took 5 milliseconds Oct 11 23:29:06 volumio volumio[897]: info: sendMpdCommand status took 4 milliseconds Oct 11 23:29:06 volumio volumio[897]: info: sendMpdCommand status took 3 milliseconds Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:06 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:06 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:06 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:06 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Oct 11 23:29:06 volumio volumio[897]: verbose: CURRENT POSITION 0 Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus stop Oct 11 23:29:06 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:06 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:06 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:06 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Oct 11 23:29:06 volumio volumio[897]: verbose: CURRENT POSITION 0 Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Oct 11 23:29:06 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:06 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:06 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:29:06 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:06 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:06 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:06 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:06 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:06 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Oct 11 23:29:06 volumio volumio[897]: verbose: CURRENT POSITION 0 Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Oct 11 23:29:06 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:06 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:06 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:06 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:06 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:06 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:06 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:06 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Oct 11 23:29:06 volumio volumio[897]: verbose: CURRENT POSITION 0 Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Oct 11 23:29:06 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:06 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:06 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:06 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:06 volumio volumio[897]: info: ------------------------------ 47ms Oct 11 23:29:06 volumio volumio[897]: info: ------------------------------ 85ms Oct 11 23:29:06 volumio volumio[897]: info: ------------------------------ 84ms Oct 11 23:29:06 volumio volumio[897]: info: ------------------------------ 83ms Oct 11 23:29:06 volumio volumio[897]: info: sendMpdCommand playlistinfo took 70 milliseconds Oct 11 23:29:06 volumio volumio[897]: info: sendMpdCommand playlistinfo took 68 milliseconds Oct 11 23:29:06 volumio volumio[897]: info: sendMpdCommand playlistinfo took 69 milliseconds Oct 11 23:29:06 volumio volumio[897]: info: sendMpdCommand playlistinfo took 68 milliseconds Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:06 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:06 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:06 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:06 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:06 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Oct 11 23:29:06 volumio volumio[897]: verbose: CURRENT POSITION 0 Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Oct 11 23:29:06 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:06 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:06 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:06 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:06 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:06 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:06 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:06 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Oct 11 23:29:06 volumio volumio[897]: verbose: CURRENT POSITION 0 Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Oct 11 23:29:06 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:06 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:06 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:06 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:06 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:06 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:06 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:06 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Oct 11 23:29:06 volumio volumio[897]: verbose: CURRENT POSITION 0 Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Oct 11 23:29:06 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:06 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:06 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:06 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:06 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:06 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:06 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:06 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Oct 11 23:29:06 volumio volumio[897]: verbose: CURRENT POSITION 0 Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Oct 11 23:29:06 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:06 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:06 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:06 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:06 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:06 volumio volumio[897]: info: ------------------------------ 168ms Oct 11 23:29:06 volumio volumio[897]: info: ------------------------------ 163ms Oct 11 23:29:06 volumio volumio[897]: info: ------------------------------ 163ms Oct 11 23:29:06 volumio volumio[897]: info: ------------------------------ 161ms Oct 11 23:29:06 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:06 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:06 volumio sudo[2416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:06 volumio sudo[2416]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:06 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:06 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:06 volumio sudo[2419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:06 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:06 volumio systemd[1]: Started peppymeterbasic Daemon. Oct 11 23:29:06 volumio sudo[2419]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:06 volumio sudo[2422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:06 volumio sudo[2422]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:06 volumio sudo[2426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:06 volumio sudo[2426]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:06 volumio sudo[2429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:06 volumio sudo[2429]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:06 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:06 volumio sudo[2432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:06 volumio sudo[2435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:06 volumio sudo[2435]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:06 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:06 volumio sudo[2432]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:06 volumio sudo[2416]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:06 volumio sudo[2439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:06 volumio sudo[2439]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:06 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:06 volumio sudo[2426]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:07 volumio sudo[2422]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:07 volumio sudo[2435]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:07 volumio sudo[2429]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:07 volumio sudo[2419]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:07 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:07 volumio sudo[2432]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:07 volumio sudo[2439]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:07 volumio sudo[2443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:07 volumio sudo[2443]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:07 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:07 volumio sudo[2446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:07 volumio sudo[2446]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:07 volumio sudo[2445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:07 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:07 volumio sudo[2443]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:07 volumio sudo[2445]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:07 volumio sudo[2450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:07 volumio sudo[2450]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:07 volumio sudo[2446]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:07 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:07 volumio sudo[2445]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:07 volumio sudo[2454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:07 volumio sudo[2454]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:07 volumio sudo[2450]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:07 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:07 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:07 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:07 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:07 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:07 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:07 volumio sudo[2454]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:07 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:07 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:07 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:07 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:07 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:07 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:07 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:07 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:07 volumio sudo[2457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:07 volumio sudo[2457]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:07 volumio volumio[897]: info: Oct 11 23:29:07 volumio volumio[897]: ---------------------------- MPD announces system playlist update Oct 11 23:29:07 volumio volumio[897]: info: Ignoring MPD Status Update Oct 11 23:29:07 volumio volumio[897]: info: Oct 11 23:29:07 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:07 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:07 volumio volumio[897]: info: Oct 11 23:29:07 volumio volumio[897]: ---------------------------- MPD announces system playlist update Oct 11 23:29:07 volumio volumio[897]: info: Ignoring MPD Status Update Oct 11 23:29:07 volumio volumio[897]: info: Oct 11 23:29:07 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:07 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:07 volumio volumio[897]: info: Oct 11 23:29:07 volumio volumio[897]: ---------------------------- MPD announces system playlist update Oct 11 23:29:07 volumio volumio[897]: info: Ignoring MPD Status Update Oct 11 23:29:07 volumio volumio[897]: info: Oct 11 23:29:07 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:07 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:07 volumio volumio[897]: info: Oct 11 23:29:07 volumio volumio[897]: ---------------------------- MPD announces system playlist update Oct 11 23:29:07 volumio volumio[897]: info: Ignoring MPD Status Update Oct 11 23:29:07 volumio volumio[897]: info: Oct 11 23:29:07 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:07 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:07 volumio sudo[2457]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:07 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:07 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:07 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:07 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:07 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:07 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:07 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:07 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:07 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:07 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:07 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:07 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:07 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:07 volumio volumio[897]: info: Oct 11 23:29:07 volumio volumio[897]: ---------------------------- MPD announces system playlist update Oct 11 23:29:07 volumio volumio[897]: info: Ignoring MPD Status Update Oct 11 23:29:07 volumio volumio[897]: info: Oct 11 23:29:07 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:07 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:07 volumio volumio[897]: info: ------------------------------ 43ms Oct 11 23:29:07 volumio volumio[897]: info: sendMpdCommand status took 40 milliseconds Oct 11 23:29:07 volumio volumio[897]: info: ------------------------------ 35ms Oct 11 23:29:07 volumio volumio[897]: info: sendMpdCommand status took 33 milliseconds Oct 11 23:29:07 volumio volumio[897]: info: ------------------------------ 32ms Oct 11 23:29:07 volumio volumio[897]: info: sendMpdCommand status took 29 milliseconds Oct 11 23:29:07 volumio volumio[897]: info: ------------------------------ 28ms Oct 11 23:29:07 volumio volumio[897]: info: sendMpdCommand status took 24 milliseconds Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:07 volumio volumio[897]: info: Oct 11 23:29:07 volumio volumio[897]: ---------------------------- MPD announces system playlist update Oct 11 23:29:07 volumio volumio[897]: info: Ignoring MPD Status Update Oct 11 23:29:07 volumio volumio[897]: info: Oct 11 23:29:07 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:07 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:07 volumio volumio[897]: info: Oct 11 23:29:07 volumio volumio[897]: ---------------------------- MPD announces system playlist update Oct 11 23:29:07 volumio volumio[897]: info: Ignoring MPD Status Update Oct 11 23:29:07 volumio volumio[897]: info: Oct 11 23:29:07 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:07 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:07 volumio volumio[897]: info: Oct 11 23:29:07 volumio volumio[897]: ---------------------------- MPD announces system playlist update Oct 11 23:29:07 volumio volumio[897]: info: Ignoring MPD Status Update Oct 11 23:29:07 volumio volumio[897]: info: Oct 11 23:29:07 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:07 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:07 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:07 volumio volumio[897]: info: ------------------------------ 32ms Oct 11 23:29:07 volumio volumio[897]: info: sendMpdCommand status took 29 milliseconds Oct 11 23:29:07 volumio volumio[897]: info: sendMpdCommand playlistinfo took 23 milliseconds Oct 11 23:29:07 volumio volumio[897]: info: sendMpdCommand playlistinfo took 23 milliseconds Oct 11 23:29:07 volumio volumio[897]: info: sendMpdCommand playlistinfo took 22 milliseconds Oct 11 23:29:07 volumio volumio[897]: info: sendMpdCommand playlistinfo took 22 milliseconds Oct 11 23:29:07 volumio volumio[897]: info: ------------------------------ 21ms Oct 11 23:29:07 volumio volumio[897]: info: sendMpdCommand status took 18 milliseconds Oct 11 23:29:07 volumio volumio[897]: info: ------------------------------ 18ms Oct 11 23:29:07 volumio volumio[897]: info: sendMpdCommand status took 16 milliseconds Oct 11 23:29:07 volumio volumio[897]: info: ------------------------------ 16ms Oct 11 23:29:07 volumio volumio[897]: info: sendMpdCommand status took 13 milliseconds Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:07 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:07 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:07 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1232,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Felix Jaehn feat. Polina - Book of love","artist":"hr3-Rhein-Main-HQ","album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Oct 11 23:29:07 volumio volumio[897]: verbose: CURRENT POSITION 0 Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Oct 11 23:29:07 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:07 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:07 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:07 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1232,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Felix Jaehn feat. Polina - Book of love","artist":"hr3-Rhein-Main-HQ","album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Oct 11 23:29:07 volumio volumio[897]: verbose: CURRENT POSITION 0 Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Oct 11 23:29:07 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:07 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:07 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:07 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1232,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Felix Jaehn feat. Polina - Book of love","artist":"hr3-Rhein-Main-HQ","album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Oct 11 23:29:07 volumio volumio[897]: verbose: CURRENT POSITION 0 Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Oct 11 23:29:07 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:07 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:07 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:07 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1232,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Felix Jaehn feat. Polina - Book of love","artist":"hr3-Rhein-Main-HQ","album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Oct 11 23:29:07 volumio volumio[897]: verbose: CURRENT POSITION 0 Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Oct 11 23:29:07 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:07 volumio volumio[897]: info: ------------------------------ 256ms Oct 11 23:29:07 volumio volumio[897]: info: ------------------------------ 250ms Oct 11 23:29:07 volumio volumio[897]: info: ------------------------------ 246ms Oct 11 23:29:07 volumio volumio[897]: info: ------------------------------ 242ms Oct 11 23:29:07 volumio volumio[897]: info: sendMpdCommand playlistinfo took 203 milliseconds Oct 11 23:29:07 volumio volumio[897]: info: sendMpdCommand playlistinfo took 201 milliseconds Oct 11 23:29:07 volumio volumio[897]: info: sendMpdCommand playlistinfo took 200 milliseconds Oct 11 23:29:07 volumio volumio[897]: info: sendMpdCommand playlistinfo took 200 milliseconds Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:07 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:07 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:07 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:07 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1358,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Felix Jaehn feat. Polina - Book of love","artist":"hr3-Rhein-Main-HQ","album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Oct 11 23:29:07 volumio volumio[897]: verbose: CURRENT POSITION 0 Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Oct 11 23:29:07 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:07 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:07 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:07 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1358,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Felix Jaehn feat. Polina - Book of love","artist":"hr3-Rhein-Main-HQ","album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Oct 11 23:29:07 volumio volumio[897]: verbose: CURRENT POSITION 0 Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Oct 11 23:29:07 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:07 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:07 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:07 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1358,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Felix Jaehn feat. Polina - Book of love","artist":"hr3-Rhein-Main-HQ","album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Oct 11 23:29:07 volumio volumio[897]: verbose: CURRENT POSITION 0 Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Oct 11 23:29:07 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:07 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:07 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:07 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1358,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Felix Jaehn feat. Polina - Book of love","artist":"hr3-Rhein-Main-HQ","album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Oct 11 23:29:07 volumio volumio[897]: verbose: CURRENT POSITION 0 Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Oct 11 23:29:07 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:07 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:07 volumio volumio[897]: info: ------------------------------ 438ms Oct 11 23:29:07 volumio volumio[897]: info: ------------------------------ 425ms Oct 11 23:29:07 volumio volumio[897]: info: ------------------------------ 423ms Oct 11 23:29:07 volumio volumio[897]: info: ------------------------------ 421ms Oct 11 23:29:07 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:07 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:07 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:07 volumio sudo[2461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:07 volumio sudo[2461]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:07 volumio sudo[2462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:07 volumio sudo[2462]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:07 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:07 volumio sudo[2461]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:07 volumio sudo[2466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:07 volumio sudo[2466]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:07 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:07 volumio sudo[2462]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:07 volumio sudo[2469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:07 volumio sudo[2469]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:07 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:07 volumio sudo[2472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:07 volumio sudo[2472]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:07 volumio sudo[2466]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:07 volumio sudo[2472]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:07 volumio sudo[2469]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:07 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:07 volumio sudo[2475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:07 volumio sudo[2475]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:08 volumio sudo[2475]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:08 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:08 volumio sudo[2478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:08 volumio sudo[2478]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:08 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:08 volumio sudo[2481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:08 volumio sudo[2481]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:08 volumio sudo[2478]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:08 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:08 volumio sudo[2484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:08 volumio sudo[2484]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:08 volumio sudo[2481]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:08 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:08 volumio sudo[2489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:08 volumio sudo[2489]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:08 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:08 volumio sudo[2489]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:08 volumio sudo[2484]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:08 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:08 volumio sudo[2492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:08 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:08 volumio sudo[2492]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:08 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:08 volumio sudo[2496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:08 volumio sudo[2496]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:08 volumio sudo[2495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:08 volumio sudo[2492]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:08 volumio sudo[2500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:08 volumio sudo[2500]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:08 volumio sudo[2496]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:08 volumio sudo[2495]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:08 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 11 23:29:08 volumio sudo[2500]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:08 volumio sudo[2504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:08 volumio sudo[2504]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:08 volumio sudo[2495]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:08 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:08 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:08 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:08 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:08 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:08 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:08 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:08 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:08 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:08 volumio sudo[2504]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:08 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:08 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:08 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:08 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:08 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:08 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:08 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:08 volumio sudo[2507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 11 23:29:08 volumio sudo[2507]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:08 volumio sudo[2507]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:08 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:08 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:08 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:08 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:08 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:08 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:08 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:08 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:08 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:08 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:08 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:08 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:08 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:08 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:08 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:08 volumio volumio[897]: info: peppymeterbasic Daemon Started Oct 11 23:29:08 volumio volumio[2421]: libpng warning: iCCP: known incorrect sRGB profile Oct 11 23:29:08 volumio volumio[2421]: libpng warning: iCCP: known incorrect sRGB profile Oct 11 23:29:28 volumio volumio[2421]: libpng warning: iCCP: known incorrect sRGB profile Oct 11 23:29:28 volumio volumio[897]: info: CoreCommandRouter::volumioStop Oct 11 23:29:28 volumio volumio[897]: info: CoreStateMachine::stop Oct 11 23:29:28 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 23:29:28 volumio volumio[897]: info: CoreStateMachine::stPlaybackTimer Oct 11 23:29:28 volumio volumio[897]: info: CoreStateMachine::updateTrackBlock Oct 11 23:29:28 volumio volumio[897]: info: CorePlayQueue::getTrackBlock Oct 11 23:29:28 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:28 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:28 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:29:28 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:28 volumio volumio[2421]: libpng warning: iCCP: known incorrect sRGB profile Oct 11 23:29:28 volumio volumio[897]: info: CoreStateMachine::serviceStop Oct 11 23:29:28 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:28 volumio volumio[897]: info: CoreCommandRouter::serviceStop Oct 11 23:29:28 volumio volumio[897]: info: [1728682168803] ControllerWebradio::stop Oct 11 23:29:28 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 23:29:28 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status stop Oct 11 23:29:28 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:28 volumio volumio[897]: info: Oct 11 23:29:28 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:28 volumio volumio[897]: info: sendMpdCommand stop took 37 milliseconds Oct 11 23:29:28 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:28 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:28 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Oct 11 23:29:28 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:28 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:28 volumio sudo[2557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Oct 11 23:29:28 volumio sudo[2557]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:28 volumio volumio[897]: info: Oct 11 23:29:28 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:28 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:28 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:28 volumio volumio[897]: info: Oct 11 23:29:28 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:28 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:28 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:28 volumio volumio[897]: info: Oct 11 23:29:28 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:28 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:28 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:28 volumio volumio[897]: info: sendMpdCommand playlistinfo took 29 milliseconds Oct 11 23:29:28 volumio volumio[897]: info: sendMpdCommand status took 5 milliseconds Oct 11 23:29:28 volumio volumio[897]: info: sendMpdCommand status took 4 milliseconds Oct 11 23:29:28 volumio volumio[897]: info: sendMpdCommand status took 3 milliseconds Oct 11 23:29:28 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:28 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:28 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:28 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:28 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:28 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:28 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:28 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:28 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:28 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:28 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:28 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:28 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:28 volumio volumio[897]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 11 23:29:28 volumio systemd[1]: Stopping peppymeterbasic Daemon... Oct 11 23:29:28 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Oct 11 23:29:28 volumio volumio[897]: info: ------------------------------ 63ms Oct 11 23:29:28 volumio volumio[897]: info: sendMpdCommand playlistinfo took 32 milliseconds Oct 11 23:29:28 volumio volumio[897]: info: sendMpdCommand playlistinfo took 32 milliseconds Oct 11 23:29:28 volumio volumio[897]: info: sendMpdCommand playlistinfo took 32 milliseconds Oct 11 23:29:28 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:28 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:28 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:28 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:28 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:28 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:28 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:28 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:28 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:28 volumio volumio[897]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 11 23:29:28 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:28 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:28 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:28 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:28 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:28 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:28 volumio volumio[897]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 11 23:29:28 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:28 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:28 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:28 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:28 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:28 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:28 volumio volumio[897]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 11 23:29:28 volumio volumio[897]: info: ------------------------------ 81ms Oct 11 23:29:28 volumio volumio[897]: info: ------------------------------ 79ms Oct 11 23:29:28 volumio volumio[897]: info: ------------------------------ 78ms Oct 11 23:29:28 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status stop Oct 11 23:29:28 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status stop Oct 11 23:29:28 volumio sudo[2564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Oct 11 23:29:28 volumio sudo[2564]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:28 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status stop Oct 11 23:29:29 volumio sudo[2567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Oct 11 23:29:29 volumio sudo[2567]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:29 volumio volumio[2421]: libpng warning: iCCP: known incorrect sRGB profile Oct 11 23:29:29 volumio volumio[897]: info: PeppyMeterBasic ---peppymeterbasic status stop Oct 11 23:29:29 volumio sudo[2570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Oct 11 23:29:29 volumio sudo[2570]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:29 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:29 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:29 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:29 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:29 volumio volumio[897]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 11 23:29:29 volumio sudo[2573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Oct 11 23:29:29 volumio sudo[2573]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:29 volumio systemd[1]: peppymeterbasic.service: Succeeded. Oct 11 23:29:29 volumio systemd[1]: Stopped peppymeterbasic Daemon. Oct 11 23:29:29 volumio sudo[2567]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:29 volumio sudo[2557]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:29 volumio volumio[897]: info: peppymeterbasic Daemon Stop Oct 11 23:29:29 volumio sudo[2564]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:29 volumio sudo[2570]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:29 volumio volumio[897]: info: peppymeterbasic Daemon Stop Oct 11 23:29:29 volumio volumio[897]: info: peppymeterbasic Daemon Stop Oct 11 23:29:29 volumio volumio[897]: info: peppymeterbasic Daemon Stop Oct 11 23:29:29 volumio sudo[2573]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:29 volumio volumio[897]: info: peppymeterbasic Daemon Stop Oct 11 23:29:34 volumio volumio[897]: info: CoreCommandRouter::volumioGetState Oct 11 23:29:34 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:36 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 23:29:36 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 11 23:29:42 volumio volumio[897]: info: Starting Uninstall of plugin user_interface - peppymeterbasic Oct 11 23:29:42 volumio volumio[897]: info: Uninstalling plugin peppymeterbasic Oct 11 23:29:42 volumio volumio[897]: info: Stopping peppymeterbasic service Oct 11 23:29:42 volumio volumio[897]: info: CoreStateMachine::stop Oct 11 23:29:42 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 23:29:42 volumio volumio[897]: info: Disabling plugin peppymeterbasic Oct 11 23:29:42 volumio volumio[897]: info: Preparing to generate the ALSA configuration file Oct 11 23:29:42 volumio sudo[2620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Oct 11 23:29:42 volumio sudo[2620]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:42 volumio volumio[897]: info: Asound.conf file written Oct 11 23:29:42 volumio sudo[2620]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:42 volumio sudo[2623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 11 23:29:42 volumio sudo[2623]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:42 volumio sudo[2623]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:42 volumio volumio[897]: info: Output device has changed, restarting MPD Oct 11 23:29:42 volumio sudo[2629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 23:29:42 volumio sudo[2629]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:42 volumio volumio[897]: info: Output device has changed, restarting Shairport Sync Oct 11 23:29:42 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 23:29:42 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 23:29:42 volumio sudo[2629]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:42 volumio sudo[2632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 23:29:42 volumio sudo[2632]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:42 volumio systemd[1]: Stopping Music Player Daemon... Oct 11 23:29:42 volumio volumio[897]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 23:29:42 volumio systemd[1]: mpd.service: Succeeded. Oct 11 23:29:42 volumio systemd[1]: Stopped Music Player Daemon. Oct 11 23:29:42 volumio volumio[897]: info: Checking if uninstall.sh is present Oct 11 23:29:42 volumio volumio[897]: info: Executing uninstall.sh Oct 11 23:29:42 volumio volumio[897]: info: MPD Permissions set Oct 11 23:29:42 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 23:29:42 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 23:29:42 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 23:29:42 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 23:29:42 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 23:29:42 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 23:29:42 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 23:29:42 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 23:29:42 volumio systemd[1]: Starting Music Player Daemon... Oct 11 23:29:42 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 23:29:42 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 23:29:42 volumio volumio[897]: info: Starting Shairport Sync Oct 11 23:29:42 volumio sudo[2640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/peppymeterbasic/uninstall.sh Oct 11 23:29:42 volumio sudo[2640]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:42 volumio sudo[2646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 23:29:42 volumio sudo[2646]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:42 volumio sudo[2648]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -Rf /data/plugins/user_interface/peppymeterbasic/BasicPeppyMeter/__pycache__ Oct 11 23:29:42 volumio sudo[2648]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:42 volumio sudo[2648]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:42 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 11 23:29:42 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 11 23:29:42 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 11 23:29:42 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 11 23:29:42 volumio sudo[2637]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 11 23:29:42 volumio sudo[2637]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:42 volumio sudo[2651]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /etc/systemd/system/peppymeterbasic.service Oct 11 23:29:42 volumio sudo[2651]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 23:29:42 volumio sudo[2637]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:42 volumio sudo[2651]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:42 volumio sudo[2646]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:42 volumio sudo[2640]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:42 volumio volumio[897]: info: Shairport-Sync Started Oct 11 23:29:42 volumio volumio[897]: info: Uninstall script completed Oct 11 23:29:42 volumio volumio[897]: info: Removing plugin peppymeterbasic from configuration Oct 11 23:29:42 volumio volumio[897]: info: Successfully removed peppymeterbasic configuration files Oct 11 23:29:42 volumio volumio[897]: info: Plugin folders cleanup Oct 11 23:29:42 volumio volumio[897]: info: Scanning into folder /volumio/app/plugins/ Oct 11 23:29:42 volumio volumio[897]: info: Scanning category audio_interface Oct 11 23:29:42 volumio volumio[897]: info: Scanning category miscellanea Oct 11 23:29:42 volumio volumio[897]: info: Scanning category music_service Oct 11 23:29:42 volumio volumio[897]: info: Scanning category plugins.json Oct 11 23:29:42 volumio volumio[897]: info: Scanning category system_controller Oct 11 23:29:42 volumio volumio[897]: info: Scanning category user_interface Oct 11 23:29:42 volumio volumio[897]: info: Scanning into folder /data/plugins/ Oct 11 23:29:42 volumio volumio[897]: info: Scanning category music_service Oct 11 23:29:42 volumio volumio[897]: info: Scanning category user_interface Oct 11 23:29:42 volumio volumio[897]: info: Cleaning folder for peppymeterbasic Oct 11 23:29:42 volumio volumio[897]: info: Plugin folders cleanup completed Oct 11 23:29:42 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 23:29:42 volumio volumio[897]: info: [{"prettyName":"Logitech Media Server","name":"lms","category":"music_service","version":"1.2.6","icon":"fa fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.1","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.5.0","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":true,"active":true}] Oct 11 23:29:42 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 11 23:29:43 volumio mpd[2655]: Oct 11 23:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 11 23:29:43 volumio systemd[1]: Started Music Player Daemon. Oct 11 23:29:43 volumio sudo[2632]: pam_unix(sudo:session): session closed for user root Oct 11 23:29:43 volumio volumio[897]: error: updateQueue error: null Oct 11 23:29:49 volumio volumio[897]: info: CoreCommandRouter::volumioPlay Oct 11 23:29:49 volumio volumio[897]: info: CoreStateMachine::play index undefined Oct 11 23:29:49 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 23:29:49 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:49 volumio volumio[897]: info: CoreStateMachine::startPlaybackTimer Oct 11 23:29:49 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:49 volumio volumio[897]: info: [1728682189818] ControllerWebradio::clearAddPlayTrack Oct 11 23:29:49 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 23:29:49 volumio volumio[897]: info: sendMpdCommand stop took 0 milliseconds Oct 11 23:29:49 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand clear Oct 11 23:29:49 volumio volumio[897]: info: Oct 11 23:29:49 volumio volumio[897]: ---------------------------- MPD announces system playlist update Oct 11 23:29:49 volumio volumio[897]: info: Ignoring MPD Status Update Oct 11 23:29:49 volumio volumio[897]: info: sendMpdCommand clear took 1 milliseconds Oct 11 23:29:49 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand load "https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein" Oct 11 23:29:49 volumio volumio[897]: error: updateQueue error: null Oct 11 23:29:49 volumio volumio[897]: info: ------------------------------ 2ms Oct 11 23:29:51 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand add "https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein" Oct 11 23:29:51 volumio volumio[897]: info: Oct 11 23:29:51 volumio volumio[897]: ---------------------------- MPD announces system playlist update Oct 11 23:29:51 volumio volumio[897]: info: Ignoring MPD Status Update Oct 11 23:29:51 volumio volumio[897]: info: sendMpdCommand add "https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein" took 2 milliseconds Oct 11 23:29:51 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 11 23:29:51 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand play Oct 11 23:29:51 volumio volumio[897]: info: ------------------------------ 2ms Oct 11 23:29:51 volumio volumio[897]: info: sendMpdCommand play took 2 milliseconds Oct 11 23:29:52 volumio volumio[897]: info: CoreCommandRouter::volumioPlay Oct 11 23:29:52 volumio volumio[897]: info: CoreStateMachine::play index undefined Oct 11 23:29:52 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 23:29:52 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:52 volumio volumio[897]: info: CoreStateMachine::startPlaybackTimer Oct 11 23:29:52 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:52 volumio volumio[897]: info: [1728682192125] ControllerWebradio::clearAddPlayTrack Oct 11 23:29:52 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 23:29:52 volumio volumio[897]: info: Oct 11 23:29:52 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:52 volumio volumio[897]: info: sendMpdCommand stop took 2 milliseconds Oct 11 23:29:52 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:52 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:52 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand clear Oct 11 23:29:52 volumio volumio[897]: info: sendMpdCommand status took 9 milliseconds Oct 11 23:29:52 volumio volumio[897]: info: sendMpdCommand clear took 9 milliseconds Oct 11 23:29:52 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:52 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:52 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand load "https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein" Oct 11 23:29:52 volumio volumio[897]: info: Oct 11 23:29:52 volumio volumio[897]: ---------------------------- MPD announces system playlist update Oct 11 23:29:52 volumio volumio[897]: info: Ignoring MPD Status Update Oct 11 23:29:52 volumio volumio[897]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 11 23:29:52 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:52 volumio volumio[897]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 11 23:29:52 volumio volumio[897]: info: ------------------------------ 11ms Oct 11 23:29:52 volumio volumio[897]: error: updateQueue error: null Oct 11 23:29:52 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand add "https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein" Oct 11 23:29:52 volumio volumio[897]: info: ------------------------------ 451ms Oct 11 23:29:52 volumio volumio[897]: info: Oct 11 23:29:52 volumio volumio[897]: ---------------------------- MPD announces system playlist update Oct 11 23:29:52 volumio volumio[897]: info: Ignoring MPD Status Update Oct 11 23:29:52 volumio volumio[897]: info: sendMpdCommand add "https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein" took 1 milliseconds Oct 11 23:29:52 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 11 23:29:52 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand play Oct 11 23:29:52 volumio volumio[897]: info: ------------------------------ 1ms Oct 11 23:29:52 volumio volumio[897]: info: sendMpdCommand play took 1 milliseconds Oct 11 23:29:52 volumio volumio[897]: info: Oct 11 23:29:52 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:52 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:52 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:52 volumio volumio[897]: info: Oct 11 23:29:52 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:52 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Oct 11 23:29:52 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:52 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:52 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:52 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:52 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Oct 11 23:29:52 volumio volumio[897]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 11 23:29:52 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:52 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:52 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:52 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:52 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:52 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:52 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Oct 11 23:29:52 volumio volumio[897]: verbose: CURRENT POSITION 0 Oct 11 23:29:52 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Oct 11 23:29:52 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus stop Oct 11 23:29:52 volumio volumio[897]: info: ------------------------------ 8ms Oct 11 23:29:52 volumio volumio[897]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 11 23:29:52 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:52 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:52 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:52 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:52 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Oct 11 23:29:52 volumio volumio[897]: verbose: CURRENT POSITION 0 Oct 11 23:29:52 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Oct 11 23:29:52 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Oct 11 23:29:52 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Oct 11 23:29:52 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:52 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:52 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:29:52 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:52 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:52 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:52 volumio volumio[897]: info: ------------------------------ 35ms Oct 11 23:29:52 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:52 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:53 volumio volumio[897]: info: Oct 11 23:29:53 volumio volumio[897]: ---------------------------- MPD announces system playlist update Oct 11 23:29:53 volumio volumio[897]: info: Ignoring MPD Status Update Oct 11 23:29:53 volumio volumio[897]: info: Oct 11 23:29:53 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:53 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:53 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:53 volumio volumio[897]: info: Oct 11 23:29:53 volumio volumio[897]: ---------------------------- MPD announces system playlist update Oct 11 23:29:53 volumio volumio[897]: info: Ignoring MPD Status Update Oct 11 23:29:53 volumio volumio[897]: info: Oct 11 23:29:53 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:29:53 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:29:53 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:29:53 volumio volumio[897]: info: ------------------------------ 4ms Oct 11 23:29:53 volumio volumio[897]: info: sendMpdCommand status took 2 milliseconds Oct 11 23:29:53 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:53 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:53 volumio volumio[897]: info: ------------------------------ 2ms Oct 11 23:29:53 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Oct 11 23:29:53 volumio volumio[897]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 11 23:29:53 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:29:53 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:29:53 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:53 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:53 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:53 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:53 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Felix Jaehn feat. Polina - Book of love","artist":"hr3-Rhein-Main-HQ","album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Oct 11 23:29:53 volumio volumio[897]: verbose: CURRENT POSITION 0 Oct 11 23:29:53 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Oct 11 23:29:53 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Oct 11 23:29:53 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Oct 11 23:29:53 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:53 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:29:53 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:53 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:53 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:53 volumio volumio[897]: info: ------------------------------ 22ms Oct 11 23:29:53 volumio volumio[897]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 11 23:29:53 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:29:53 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:29:53 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:29:53 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:29:53 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Felix Jaehn feat. Polina - Book of love","artist":"hr3-Rhein-Main-HQ","album":null,"uri":"https://dispatcher.rndfnk.com/hr/hr3-sued/mp3/high?aggregator=tunein","trackType":""} Oct 11 23:29:53 volumio volumio[897]: verbose: CURRENT POSITION 0 Oct 11 23:29:53 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Oct 11 23:29:53 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Oct 11 23:29:53 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Oct 11 23:29:53 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:53 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:53 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:29:53 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:29:53 volumio volumio[897]: info: ------------------------------ 53ms Oct 11 23:29:53 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:53 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:53 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:29:53 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:30:12 volumio volumio[897]: info: CoreCommandRouter::volumioStop Oct 11 23:30:12 volumio volumio[897]: info: CoreStateMachine::stop Oct 11 23:30:12 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 23:30:12 volumio volumio[897]: info: CoreStateMachine::stPlaybackTimer Oct 11 23:30:12 volumio volumio[897]: info: CoreStateMachine::updateTrackBlock Oct 11 23:30:12 volumio volumio[897]: info: CorePlayQueue::getTrackBlock Oct 11 23:30:12 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:30:12 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:30:12 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:30:12 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:30:12 volumio volumio[897]: info: CoreStateMachine::serviceStop Oct 11 23:30:12 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:30:12 volumio volumio[897]: info: CoreCommandRouter::serviceStop Oct 11 23:30:12 volumio volumio[897]: info: [1728682212074] ControllerWebradio::stop Oct 11 23:30:12 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 23:30:12 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:30:12 volumio volumio[897]: info: Oct 11 23:30:12 volumio volumio[897]: ---------------------------- MPD announces state update: player Oct 11 23:30:12 volumio volumio[897]: info: sendMpdCommand stop took 18 milliseconds Oct 11 23:30:12 volumio volumio[897]: info: ControllerMpd::getState Oct 11 23:30:12 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:30:12 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Oct 11 23:30:12 volumio volumio[897]: verbose: ControllerMpd::parseState Oct 11 23:30:12 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:30:12 volumio volumio[897]: info: sendMpdCommand playlistinfo took 23 milliseconds Oct 11 23:30:12 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:30:12 volumio volumio[897]: info: ControllerMpd::pushState Oct 11 23:30:12 volumio volumio[897]: info: CoreCommandRouter::servicePushState Oct 11 23:30:12 volumio volumio[897]: info: CoreStateMachine::pushState Oct 11 23:30:12 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:30:12 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:30:12 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Oct 11 23:30:12 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:30:12 volumio volumio[897]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 11 23:30:12 volumio volumio[897]: info: ------------------------------ 49ms Oct 11 23:30:12 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Oct 11 23:30:12 volumio volumio[897]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 11 23:30:14 volumio volumio[897]: info: CoreCommandRouter::volumioGetState Oct 11 23:30:14 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:30:16 volumio volumio[897]: info: CoreCommandRouter::volumioGetState Oct 11 23:30:16 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:30:18 volumio volumio[897]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 23:30:18 volumio volumio[897]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 23:30:18 volumio volumio[897]: info: Retrieving Cloud Streaming UI Oct 11 23:30:18 volumio volumio[897]: info: Getting Tidal Cloud Configuration Oct 11 23:30:18 volumio volumio[897]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 23:30:18 volumio volumio[897]: info: Getting Qobuz Cloud Configuration Oct 11 23:30:18 volumio volumio[897]: info: Asking plugin for UI Config Oct 11 23:30:18 volumio volumio[897]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 23:30:18 volumio volumio[897]: info: Getting Spotify Cloud Configuration Oct 11 23:30:18 volumio volumio[897]: info: Asking plugin for UI Config Oct 11 23:30:18 volumio volumio[897]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 23:30:18 volumio volumio[897]: info: Saving Spotify Acccount Oct 11 23:30:18 volumio volumio[897]: info: Got Tidal Cloud Configuration Oct 11 23:30:18 volumio volumio[897]: info: Got it Oct 11 23:30:18 volumio volumio[897]: info: Got it Oct 11 23:30:18 volumio volumio[897]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 11 23:30:18 volumio volumio[897]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 23:30:18 volumio volumio[897]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 11 23:30:18 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 11 23:30:18 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 11 23:30:18 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 11 23:30:18 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 11 23:30:18 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 23:30:18 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 23:30:18 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 23:30:18 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 23:30:18 volumio volumio[897]: info: CoreCommandRouter::volumioGetBrowseSources Oct 11 23:30:18 volumio volumio[897]: info: CoreCommandRouter::volumioGetBrowseSources Oct 11 23:30:18 volumio volumio[897]: info: CoreCommandRouter::volumioGetBrowseSources Oct 11 23:30:18 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 11 23:30:18 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 11 23:30:22 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 11 23:30:26 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 11 23:30:28 volumio volumio[897]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 23:30:29 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 23:30:29 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 11 23:30:31 volumio volumio[897]: info: CoreCommandRouter::volumioGetState Oct 11 23:30:31 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Oct 11 23:30:37 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 11 23:30:37 volumio volumio[897]: info: Preload queue cleared Oct 11 23:30:39 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 11 23:30:39 volumio volumio[897]: info: Preload queue cleared Oct 11 23:30:41 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 11 23:30:41 volumio volumio[897]: info: Preload queue cleared Oct 11 23:30:42 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 11 23:30:42 volumio volumio[897]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 23:30:42 volumio volumio[897]: TypeError: Cannot read property 'length' of undefined Oct 11 23:30:42 volumio volumio[897]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 11 23:30:42 volumio volumio[897]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 11 23:30:42 volumio volumio[897]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 11 23:30:42 volumio volumio[897]: at Parser.emit (events.js:315:20) Oct 11 23:30:42 volumio volumio[897]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 11 23:30:42 volumio volumio[897]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 11 23:30:42 volumio volumio[897]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 11 23:30:42 volumio volumio[897]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 11 23:30:42 volumio volumio[897]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 11 23:30:42 volumio volumio[897]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 11 23:30:42 volumio volumio[897]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 11 23:30:42 volumio volumio[897]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 11 23:30:42 volumio volumio[897]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 11 23:30:42 volumio volumio[897]: at IncomingMessage.emit (events.js:327:22) Oct 11 23:30:42 volumio volumio[897]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 11 23:30:42 volumio volumio[897]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 11 23:30:42 volumio volumio[897]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 23:30:42 volumio sudo[2729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-11 23:29 Oct 11 23:30:42 volumio sudo[2729]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"