Dec 07 19:52:01 volumio volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 19:52:01 volumio volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 19:52:01 volumio volumio[1039]: info: Retrieving Cloud Streaming UI Dec 07 19:52:01 volumio volumio[1039]: info: Getting Tidal Cloud Configuration Dec 07 19:52:01 volumio volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 19:52:01 volumio volumio[1039]: info: Getting Qobuz Cloud Configuration Dec 07 19:52:01 volumio volumio[1039]: info: Asking plugin for UI Config Dec 07 19:52:01 volumio volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 19:52:01 volumio volumio[1039]: info: Getting Spotify Cloud Configuration Dec 07 19:52:01 volumio volumio[1039]: info: Asking plugin for UI Config Dec 07 19:52:01 volumio volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 19:52:01 volumio volumio[1039]: info: Saving Spotify Acccount Dec 07 19:52:01 volumio volumio[1039]: info: Got Tidal Cloud Configuration Dec 07 19:52:01 volumio volumio[1039]: info: Got it Dec 07 19:52:01 volumio volumio[1039]: info: Got it Dec 07 19:52:01 volumio volumio[1039]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 07 19:52:01 volumio volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 19:52:01 volumio volumio[1039]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 07 19:52:01 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 07 19:52:01 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 07 19:52:01 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 07 19:52:01 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 07 19:52:01 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 19:52:01 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 19:52:01 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 19:52:01 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 19:52:01 volumio volumio[1039]: info: CoreCommandRouter::volumioGetBrowseSources Dec 07 19:52:01 volumio volumio[1039]: info: CoreCommandRouter::volumioGetBrowseSources Dec 07 19:52:01 volumio volumio[1039]: info: CoreCommandRouter::volumioGetBrowseSources Dec 07 19:52:01 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 07 19:52:01 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 07 19:52:08 volumio volumio[1039]: info: CoreCommandRouter::volumioStop Dec 07 19:52:08 volumio volumio[1039]: info: CoreStateMachine::stop Dec 07 19:52:08 volumio volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 19:52:08 volumio volumio[1039]: info: CoreStateMachine::stPlaybackTimer Dec 07 19:52:08 volumio volumio[1039]: info: CoreStateMachine::updateTrackBlock Dec 07 19:52:08 volumio volumio[1039]: info: CorePlayQueue::getTrackBlock Dec 07 19:52:08 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:08 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:08 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 19:52:08 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:08 volumio volumio[1039]: info: CoreStateMachine::serviceStop Dec 07 19:52:08 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:08 volumio volumio[1039]: info: CoreCommandRouter::serviceStop Dec 07 19:52:08 volumio volumio[1039]: info: [1765133528659] ControllerWebradio::stop Dec 07 19:52:08 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 19:52:08 volumio volumio[1039]: info: Dec 07 19:52:08 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:08 volumio volumio[1039]: info: sendMpdCommand stop took 36 milliseconds Dec 07 19:52:08 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:08 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:08 volumio volumio[1039]: info: sendMpdCommand status took 3 milliseconds Dec 07 19:52:08 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:08 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:08 volumio volumio[1039]: info: Dec 07 19:52:08 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:08 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:08 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:08 volumio volumio[1039]: info: Dec 07 19:52:08 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:08 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:08 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:08 volumio volumio[1039]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X' Dec 07 19:52:08 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 26 milliseconds Dec 07 19:52:08 volumio volumio[1039]: info: sendMpdCommand status took 23 milliseconds Dec 07 19:52:08 volumio volumio[1039]: info: sendMpdCommand status took 19 milliseconds Dec 07 19:52:08 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:08 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:08 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:08 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:08 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:08 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:08 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:08 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:08 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:08 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 19:52:08 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:08 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:08 volumio volumio[1039]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 07 19:52:08 volumio volumio[1039]: info: ------------------------------ 66ms Dec 07 19:52:08 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 40 milliseconds Dec 07 19:52:08 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 40 milliseconds Dec 07 19:52:08 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:08 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:08 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:08 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:08 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:08 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:08 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:08 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:08 volumio volumio[1039]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 07 19:52:08 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:08 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:08 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:08 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:08 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:08 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:08 volumio volumio[1039]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 07 19:52:08 volumio volumio[1039]: info: ------------------------------ 113ms Dec 07 19:52:08 volumio volumio[1039]: info: ------------------------------ 109ms Dec 07 19:52:09 volumio volumio[1039]: info: CoreCommandRouter::volumioPlay Dec 07 19:52:09 volumio volumio[1039]: info: CoreStateMachine::play index undefined Dec 07 19:52:09 volumio volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 19:52:09 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:09 volumio volumio[1039]: info: CoreStateMachine::startPlaybackTimer Dec 07 19:52:09 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:09 volumio volumio[1039]: info: [1765133529676] ControllerWebradio::clearAddPlayTrack Dec 07 19:52:09 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 19:52:09 volumio volumio[1039]: info: sendMpdCommand stop took 2 milliseconds Dec 07 19:52:09 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand clear Dec 07 19:52:09 volumio volumio[1039]: info: Dec 07 19:52:09 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:09 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:09 volumio volumio[1039]: info: sendMpdCommand clear took 3 milliseconds Dec 07 19:52:09 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand load "http://streams.rsh.de/rsh-live/mp3-192/tunein/play.m3u" Dec 07 19:52:09 volumio volumio[1039]: info: Dec 07 19:52:09 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:09 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:09 volumio volumio[1039]: info: Dec 07 19:52:09 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:09 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:09 volumio volumio[1039]: error: updateQueue error: null Dec 07 19:52:09 volumio volumio[1039]: info: ------------------------------ 9ms Dec 07 19:52:09 volumio volumio[1039]: info: Dec 07 19:52:09 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:09 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:09 volumio volumio[1039]: info: sendMpdCommand load "http://streams.rsh.de/rsh-live/mp3-192/tunein/play.m3u" took 237 milliseconds Dec 07 19:52:09 volumio volumio[1039]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 07 19:52:09 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand play Dec 07 19:52:09 volumio volumio[1039]: info: ------------------------------ 241ms Dec 07 19:52:09 volumio volumio[1039]: info: ------------------------------ 239ms Dec 07 19:52:09 volumio volumio[1039]: info: ------------------------------ 7ms Dec 07 19:52:09 volumio volumio[1039]: info: Dec 07 19:52:09 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:09 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:09 volumio volumio[1039]: info: Dec 07 19:52:09 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:09 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:09 volumio volumio[1039]: info: sendMpdCommand play took 11 milliseconds Dec 07 19:52:09 volumio volumio[1039]: info: ------------------------------ 7ms Dec 07 19:52:09 volumio volumio[1039]: info: ------------------------------ 5ms Dec 07 19:52:10 volumio volumio[1039]: info: Dec 07 19:52:10 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:10 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:10 volumio volumio[1039]: info: Dec 07 19:52:10 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:10 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:10 volumio volumio[1039]: info: Dec 07 19:52:10 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:10 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:10 volumio volumio[1039]: info: Dec 07 19:52:10 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:10 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:10 volumio volumio[1039]: info: Dec 07 19:52:10 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:10 volumio volumio[1039]: info: sendMpdCommand status took 13 milliseconds Dec 07 19:52:10 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:10 volumio volumio[1039]: info: sendMpdCommand status took 13 milliseconds Dec 07 19:52:10 volumio volumio[1039]: info: sendMpdCommand status took 11 milliseconds Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:10 volumio volumio[1039]: info: sendMpdCommand status took 15 milliseconds Dec 07 19:52:10 volumio volumio[1039]: info: sendMpdCommand status took 11 milliseconds Dec 07 19:52:10 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 07 19:52:10 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 07 19:52:10 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:10 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:10 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:10 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"regc-rshlive-mp3-192-6238539?sABC=6935ppq9%230%23n52nqon0ns0n106p2r9o2qp128811n7p%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1765133529","artist":null,"album":null,"uri":"http://rsh.streamabc.net/regc-rshlive-mp3-192-6238539?sABC=6935ppq9%230%23n52nqon0ns0n106p2r9o2qp128811n7p%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1765133529","trackType":""} Dec 07 19:52:10 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus stop Dec 07 19:52:10 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:10 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:10 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"regc-rshlive-mp3-192-6238539?sABC=6935ppq9%230%23n52nqon0ns0n106p2r9o2qp128811n7p%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1765133529","artist":null,"album":null,"uri":"http://rsh.streamabc.net/regc-rshlive-mp3-192-6238539?sABC=6935ppq9%230%23n52nqon0ns0n106p2r9o2qp128811n7p%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1765133529","trackType":""} Dec 07 19:52:10 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:10 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:10 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:10 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:10 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"regc-rshlive-mp3-192-6238539?sABC=6935ppq9%230%23n52nqon0ns0n106p2r9o2qp128811n7p%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1765133529","artist":null,"album":null,"uri":"http://rsh.streamabc.net/regc-rshlive-mp3-192-6238539?sABC=6935ppq9%230%23n52nqon0ns0n106p2r9o2qp128811n7p%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1765133529","trackType":""} Dec 07 19:52:10 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:10 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:10 volumio volumio[1039]: info: ------------------------------ 97ms Dec 07 19:52:10 volumio volumio[1039]: info: ------------------------------ 155ms Dec 07 19:52:10 volumio volumio[1039]: info: ------------------------------ 154ms Dec 07 19:52:10 volumio volumio[1039]: info: Dec 07 19:52:10 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:10 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:10 volumio volumio[1039]: info: Dec 07 19:52:10 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:10 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:10 volumio volumio[1039]: info: Dec 07 19:52:10 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:10 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:10 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 137 milliseconds Dec 07 19:52:10 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 135 milliseconds Dec 07 19:52:10 volumio volumio[1039]: info: sendMpdCommand status took 6 milliseconds Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:10 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:10 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:10 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"R.SH Live","artist":"R.SH Live","album":null,"uri":"http://rsh.streamabc.net/regc-rshlive-mp3-192-6238539?sABC=6935ppq9%230%23n52nqon0ns0n106p2r9o2qp128811n7p%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1765133529","trackType":""} Dec 07 19:52:10 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:10 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:10 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:10 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:10 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"R.SH Live","artist":"R.SH Live","album":null,"uri":"http://rsh.streamabc.net/regc-rshlive-mp3-192-6238539?sABC=6935ppq9%230%23n52nqon0ns0n106p2r9o2qp128811n7p%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1765133529","trackType":""} Dec 07 19:52:10 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:10 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:10 volumio volumio[1039]: info: ------------------------------ 245ms Dec 07 19:52:10 volumio volumio[1039]: info: ------------------------------ 244ms Dec 07 19:52:10 volumio volumio[1039]: info: Dec 07 19:52:10 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:10 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:10 volumio volumio[1039]: info: Dec 07 19:52:10 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:10 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:10 volumio volumio[1039]: info: Dec 07 19:52:10 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:10 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:10 volumio volumio[1039]: info: Dec 07 19:52:10 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:10 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:10 volumio volumio[1039]: info: ------------------------------ 174ms Dec 07 19:52:10 volumio volumio[1039]: info: sendMpdCommand status took 172 milliseconds Dec 07 19:52:10 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 168 milliseconds Dec 07 19:52:10 volumio volumio[1039]: info: ------------------------------ 83ms Dec 07 19:52:10 volumio volumio[1039]: info: sendMpdCommand status took 80 milliseconds Dec 07 19:52:10 volumio volumio[1039]: info: ------------------------------ 80ms Dec 07 19:52:10 volumio volumio[1039]: info: sendMpdCommand status took 77 milliseconds Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:10 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:10 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:10 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"R.SH Live","artist":"R.SH Live","album":null,"uri":"http://rsh.streamabc.net/regc-rshlive-mp3-192-6238539?sABC=6935ppq9%230%23n52nqon0ns0n106p2r9o2qp128811n7p%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1765133529","trackType":""} Dec 07 19:52:10 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:10 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:10 volumio volumio[1039]: info: ------------------------------ 230ms Dec 07 19:52:10 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 50 milliseconds Dec 07 19:52:10 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 50 milliseconds Dec 07 19:52:10 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 49 milliseconds Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:10 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:10 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:10 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:10 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"R.SH Live","artist":"R.SH Live","album":null,"uri":"http://rsh.streamabc.net/regc-rshlive-mp3-192-6238539?sABC=6935ppq9%230%23n52nqon0ns0n106p2r9o2qp128811n7p%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1765133529","trackType":""} Dec 07 19:52:10 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:10 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:10 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:10 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:10 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1110,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"R.SH Live","artist":"R.SH Live","album":null,"uri":"http://rsh.streamabc.net/regc-rshlive-mp3-192-6238539?sABC=6935ppq9%230%23n52nqon0ns0n106p2r9o2qp128811n7p%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1765133529","trackType":""} Dec 07 19:52:10 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:10 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:10 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:10 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:10 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1110,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"R.SH Live","artist":"R.SH Live","album":null,"uri":"http://rsh.streamabc.net/regc-rshlive-mp3-192-6238539?sABC=6935ppq9%230%23n52nqon0ns0n106p2r9o2qp128811n7p%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1765133529","trackType":""} Dec 07 19:52:10 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:10 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:10 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:10 volumio volumio[1039]: info: ------------------------------ 350ms Dec 07 19:52:10 volumio volumio[1039]: info: ------------------------------ 258ms Dec 07 19:52:10 volumio volumio[1039]: info: ------------------------------ 256ms Dec 07 19:52:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Dec 07 19:52:15 volumio volumio[1039]: info: CoreStateMachine::getQueue Dec 07 19:52:15 volumio volumio[1039]: info: CorePlayQueue::getQueue Dec 07 19:52:18 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 07 19:52:18 volumio volumio[1039]: info: Preload queue cleared Dec 07 19:52:21 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 07 19:52:21 volumio volumio[1039]: info: Preload queue cleared Dec 07 19:52:25 volumio volumio[1039]: info: Preload queue cleared Dec 07 19:52:25 volumio volumio[1039]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 07 19:52:25 volumio volumio[1039]: info: CoreStateMachine::ClearQueue Dec 07 19:52:25 volumio volumio[1039]: info: CoreStateMachine::stop Dec 07 19:52:25 volumio volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 19:52:25 volumio volumio[1039]: info: CoreStateMachine::stPlaybackTimer Dec 07 19:52:25 volumio volumio[1039]: info: CoreStateMachine::updateTrackBlock Dec 07 19:52:25 volumio volumio[1039]: info: CorePlayQueue::getTrackBlock Dec 07 19:52:25 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:25 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:25 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 19:52:25 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:25 volumio volumio[1039]: info: CoreStateMachine::serviceStop Dec 07 19:52:25 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:25 volumio volumio[1039]: info: CoreCommandRouter::serviceStop Dec 07 19:52:25 volumio volumio[1039]: info: [1765133545980] ControllerWebradio::stop Dec 07 19:52:25 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 19:52:25 volumio volumio[1039]: info: CorePlayQueue::clearPlayQueue Dec 07 19:52:25 volumio volumio[1039]: info: CorePlayQueue::saveQueue Dec 07 19:52:25 volumio volumio[1039]: info: CoreCommandRouter::volumioPushQueue Dec 07 19:52:25 volumio volumio[1039]: info: CoreStateMachine::addQueueItems Dec 07 19:52:25 volumio volumio[1039]: info: CorePlayQueue::addQueueItems Dec 07 19:52:25 volumio volumio[1039]: info: Preload queue cleared Dec 07 19:52:25 volumio volumio[1039]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99532146 Dec 07 19:52:25 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::volumioPushQueue Dec 07 19:52:26 volumio volumio[1039]: info: CorePlayQueue::saveQueue Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::updateTrackBlock Dec 07 19:52:26 volumio volumio[1039]: info: CorePlayQueue::getTrackBlock Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::volumioPlay Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::play index 0 Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::stop Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::play index undefined Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 19:52:26 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::startPlaybackTimer Dec 07 19:52:26 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:26 volumio volumio[1039]: info: [1765133546051] ControllerWebradio::clearAddPlayTrack Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 19:52:26 volumio volumio[1039]: info: Dec 07 19:52:26 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:26 volumio volumio[1039]: info: sendMpdCommand stop took 98 milliseconds Dec 07 19:52:26 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:26 volumio volumio[1039]: info: sendMpdCommand stop took 28 milliseconds Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand clear Dec 07 19:52:26 volumio volumio[1039]: info: Dec 07 19:52:26 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:26 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:26 volumio volumio[1039]: info: Dec 07 19:52:26 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:26 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:26 volumio volumio[1039]: info: Dec 07 19:52:26 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:26 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:26 volumio volumio[1039]: info: sendMpdCommand status took 12 milliseconds Dec 07 19:52:26 volumio volumio[1039]: info: sendMpdCommand clear took 10 milliseconds Dec 07 19:52:26 volumio volumio[1039]: info: sendMpdCommand status took 9 milliseconds Dec 07 19:52:26 volumio volumio[1039]: info: sendMpdCommand status took 7 milliseconds Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99532146" Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:26 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:26 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:26 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:26 volumio volumio[1039]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 07 19:52:26 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:26 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:26 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:26 volumio volumio[1039]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 07 19:52:26 volumio volumio[1039]: info: ------------------------------ 60ms Dec 07 19:52:26 volumio volumio[1039]: info: ------------------------------ 59ms Dec 07 19:52:26 volumio volumio[1039]: info: Dec 07 19:52:26 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:26 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:26 volumio volumio[1039]: info: Dec 07 19:52:26 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:26 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:26 volumio volumio[1039]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X' Dec 07 19:52:26 volumio volumio[1039]: error: updateQueue error: null Dec 07 19:52:26 volumio volumio[1039]: info: ------------------------------ 73ms Dec 07 19:52:26 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 69 milliseconds Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:26 volumio volumio[1039]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 07 19:52:26 volumio volumio[1039]: info: ------------------------------ 89ms Dec 07 19:52:26 volumio volumio[1039]: info: Dec 07 19:52:26 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:26 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:26 volumio volumio[1039]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99532146" took 337 milliseconds Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand play Dec 07 19:52:26 volumio volumio[1039]: info: ------------------------------ 293ms Dec 07 19:52:26 volumio volumio[1039]: info: ------------------------------ 292ms Dec 07 19:52:26 volumio volumio[1039]: info: ------------------------------ 7ms Dec 07 19:52:26 volumio volumio[1039]: info: Dec 07 19:52:26 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:26 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:26 volumio volumio[1039]: info: Dec 07 19:52:26 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:26 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:26 volumio volumio[1039]: info: sendMpdCommand play took 13 milliseconds Dec 07 19:52:26 volumio volumio[1039]: info: ------------------------------ 8ms Dec 07 19:52:26 volumio volumio[1039]: info: ------------------------------ 6ms Dec 07 19:52:26 volumio volumio[1039]: info: Dec 07 19:52:26 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:26 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:26 volumio volumio[1039]: info: Dec 07 19:52:26 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:26 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:26 volumio volumio[1039]: info: Dec 07 19:52:26 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:26 volumio volumio[1039]: info: Dec 07 19:52:26 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:26 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:26 volumio volumio[1039]: info: sendMpdCommand status took 8 milliseconds Dec 07 19:52:26 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:26 volumio volumio[1039]: info: sendMpdCommand status took 6 milliseconds Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:26 volumio volumio[1039]: info: Dec 07 19:52:26 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:26 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:26 volumio volumio[1039]: info: Dec 07 19:52:26 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:26 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:26 volumio volumio[1039]: info: sendMpdCommand status took 14 milliseconds Dec 07 19:52:26 volumio volumio[1039]: info: sendMpdCommand status took 13 milliseconds Dec 07 19:52:26 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 07 19:52:26 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 07 19:52:26 volumio volumio[1039]: info: sendMpdCommand status took 8 milliseconds Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:26 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:26 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:26 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 80/99999) soul central radio","album":null,"uri":"http://65.21.202.84:8519/stream","trackType":""} Dec 07 19:52:26 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus stop Dec 07 19:52:26 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:26 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:26 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 80/99999) soul central radio","album":null,"uri":"http://65.21.202.84:8519/stream","trackType":""} Dec 07 19:52:26 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:26 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:26 volumio volumio[1039]: info: ------------------------------ 61ms Dec 07 19:52:26 volumio volumio[1039]: info: ------------------------------ 80ms Dec 07 19:52:26 volumio volumio[1039]: info: sendMpdCommand status took 64 milliseconds Dec 07 19:52:26 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 59 milliseconds Dec 07 19:52:26 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 59 milliseconds Dec 07 19:52:26 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 55 milliseconds Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:26 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:26 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:26 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:26 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 80/99999) soul central radio","album":null,"uri":"http://65.21.202.84:8519/stream","trackType":""} Dec 07 19:52:26 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:26 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:26 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:26 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:26 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 80/99999) soul central radio","album":null,"uri":"http://65.21.202.84:8519/stream","trackType":""} Dec 07 19:52:26 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:26 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:26 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:26 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:26 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 80/99999) soul central radio","album":null,"uri":"http://65.21.202.84:8519/stream","trackType":""} Dec 07 19:52:26 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:26 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:26 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:26 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:26 volumio volumio[1039]: info: ------------------------------ 224ms Dec 07 19:52:26 volumio volumio[1039]: info: ------------------------------ 225ms Dec 07 19:52:26 volumio volumio[1039]: info: ------------------------------ 217ms Dec 07 19:52:27 volumio volumio[1039]: info: Dec 07 19:52:27 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:27 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:27 volumio volumio[1039]: info: Dec 07 19:52:27 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:27 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 241 milliseconds Dec 07 19:52:27 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:27 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:27 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:27 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 80/99999) soul central radio","album":null,"uri":"http://65.21.202.84:8519/stream","trackType":""} Dec 07 19:52:27 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:27 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:27 volumio volumio[1039]: info: ------------------------------ 358ms Dec 07 19:52:27 volumio volumio[1039]: info: Dec 07 19:52:27 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:27 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:27 volumio volumio[1039]: info: Dec 07 19:52:27 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:27 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:27 volumio volumio[1039]: info: Dec 07 19:52:27 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:27 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:27 volumio volumio[1039]: info: Dec 07 19:52:27 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:27 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:27 volumio volumio[1039]: info: Dec 07 19:52:27 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:27 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:27 volumio volumio[1039]: info: Dec 07 19:52:27 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:27 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:27 volumio volumio[1039]: info: ------------------------------ 67ms Dec 07 19:52:27 volumio volumio[1039]: info: sendMpdCommand status took 63 milliseconds Dec 07 19:52:27 volumio volumio[1039]: info: ------------------------------ 17ms Dec 07 19:52:27 volumio volumio[1039]: info: sendMpdCommand status took 14 milliseconds Dec 07 19:52:27 volumio volumio[1039]: info: ------------------------------ 14ms Dec 07 19:52:27 volumio volumio[1039]: info: sendMpdCommand status took 8 milliseconds Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:27 volumio volumio[1039]: info: Dec 07 19:52:27 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:27 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:27 volumio volumio[1039]: info: Dec 07 19:52:27 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:27 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:27 volumio volumio[1039]: info: Dec 07 19:52:27 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:27 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:27 volumio volumio[1039]: info: Dec 07 19:52:27 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:27 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:27 volumio volumio[1039]: info: ------------------------------ 40ms Dec 07 19:52:27 volumio volumio[1039]: info: sendMpdCommand status took 36 milliseconds Dec 07 19:52:27 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 33 milliseconds Dec 07 19:52:27 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 33 milliseconds Dec 07 19:52:27 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 33 milliseconds Dec 07 19:52:27 volumio volumio[1039]: info: ------------------------------ 32ms Dec 07 19:52:27 volumio volumio[1039]: info: sendMpdCommand status took 30 milliseconds Dec 07 19:52:27 volumio volumio[1039]: info: ------------------------------ 30ms Dec 07 19:52:27 volumio volumio[1039]: info: sendMpdCommand status took 28 milliseconds Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:27 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:27 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:27 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1103,"duration":2,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Soul Central Radio - Live","artist":"soul central radio","album":null,"uri":"http://65.21.202.84:8519/stream","trackType":""} Dec 07 19:52:27 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:27 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:27 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:27 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:27 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1103,"duration":2,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Soul Central Radio - Live","artist":"soul central radio","album":null,"uri":"http://65.21.202.84:8519/stream","trackType":""} Dec 07 19:52:27 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:27 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:27 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:27 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:27 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1103,"duration":2,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Soul Central Radio - Live","artist":"soul central radio","album":null,"uri":"http://65.21.202.84:8519/stream","trackType":""} Dec 07 19:52:27 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:27 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:27 volumio volumio[1039]: info: ------------------------------ 264ms Dec 07 19:52:27 volumio volumio[1039]: info: ------------------------------ 215ms Dec 07 19:52:27 volumio volumio[1039]: info: ------------------------------ 212ms Dec 07 19:52:27 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 161 milliseconds Dec 07 19:52:27 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 158 milliseconds Dec 07 19:52:27 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 157 milliseconds Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:27 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:27 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:27 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:27 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1103,"duration":2,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Soul Central Radio - Live","artist":"soul central radio","album":null,"uri":"http://65.21.202.84:8519/stream","trackType":""} Dec 07 19:52:27 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:27 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:27 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:27 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:27 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1103,"duration":2,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Soul Central Radio - Live","artist":"soul central radio","album":null,"uri":"http://65.21.202.84:8519/stream","trackType":""} Dec 07 19:52:27 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:27 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:27 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:27 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:27 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1103,"duration":2,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Soul Central Radio - Live","artist":"soul central radio","album":null,"uri":"http://65.21.202.84:8519/stream","trackType":""} Dec 07 19:52:27 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:27 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:27 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:27 volumio volumio[1039]: info: ------------------------------ 331ms Dec 07 19:52:27 volumio volumio[1039]: info: ------------------------------ 321ms Dec 07 19:52:27 volumio volumio[1039]: info: ------------------------------ 319ms Dec 07 19:52:34 volumio volumio[1039]: info: Preload queue cleared Dec 07 19:52:34 volumio volumio[1039]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::ClearQueue Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::stop Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::stPlaybackTimer Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::updateTrackBlock Dec 07 19:52:34 volumio volumio[1039]: info: CorePlayQueue::getTrackBlock Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:34 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:34 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 19:52:34 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::serviceStop Dec 07 19:52:34 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:34 volumio volumio[1039]: info: CoreCommandRouter::serviceStop Dec 07 19:52:34 volumio volumio[1039]: info: [1765133554118] ControllerWebradio::stop Dec 07 19:52:34 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 19:52:34 volumio volumio[1039]: info: CorePlayQueue::clearPlayQueue Dec 07 19:52:34 volumio volumio[1039]: info: CorePlayQueue::saveQueue Dec 07 19:52:34 volumio volumio[1039]: info: CoreCommandRouter::volumioPushQueue Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::addQueueItems Dec 07 19:52:34 volumio volumio[1039]: info: CorePlayQueue::addQueueItems Dec 07 19:52:34 volumio volumio[1039]: info: Preload queue cleared Dec 07 19:52:34 volumio volumio[1039]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s97156 Dec 07 19:52:34 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 07 19:52:34 volumio volumio[1039]: info: Dec 07 19:52:34 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:34 volumio volumio[1039]: info: sendMpdCommand stop took 96 milliseconds Dec 07 19:52:34 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:34 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:34 volumio volumio[1039]: info: Dec 07 19:52:34 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:34 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:34 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:34 volumio volumio[1039]: info: Dec 07 19:52:34 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:34 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:34 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:34 volumio volumio[1039]: info: sendMpdCommand status took 8 milliseconds Dec 07 19:52:34 volumio volumio[1039]: info: sendMpdCommand status took 5 milliseconds Dec 07 19:52:34 volumio volumio[1039]: info: sendMpdCommand status took 4 milliseconds Dec 07 19:52:34 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:34 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:34 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:34 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:34 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:34 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:34 volumio volumio[1039]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X' Dec 07 19:52:34 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 20 milliseconds Dec 07 19:52:34 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 07 19:52:34 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 20 milliseconds Dec 07 19:52:34 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:34 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:34 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:34 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:34 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:34 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:34 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 19:52:34 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:34 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:34 volumio volumio[1039]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Soul Central Radio - Live","artist":"soul central radio","album":null,"uri":"http://65.21.202.84:8519/stream","trackType":""} Dec 07 19:52:34 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::syncState stateService stop Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus stop Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:34 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:34 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:34 volumio volumio[1039]: info: No code Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:34 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:34 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:34 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:34 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:34 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:34 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:34 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:34 volumio volumio[1039]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Soul Central Radio - Live","artist":"soul central radio","album":null,"uri":"http://65.21.202.84:8519/stream","trackType":""} Dec 07 19:52:34 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::syncState stateService stop Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus stop Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:34 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:34 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:34 volumio volumio[1039]: info: No code Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:34 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:34 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:34 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:34 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:34 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:34 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:34 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:34 volumio volumio[1039]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Soul Central Radio - Live","artist":"soul central radio","album":null,"uri":"http://65.21.202.84:8519/stream","trackType":""} Dec 07 19:52:34 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::syncState stateService stop Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus stop Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:34 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:34 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:34 volumio volumio[1039]: info: No code Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:34 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:34 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:34 volumio volumio[1039]: info: ------------------------------ 194ms Dec 07 19:52:34 volumio volumio[1039]: info: ------------------------------ 193ms Dec 07 19:52:34 volumio volumio[1039]: info: ------------------------------ 192ms Dec 07 19:52:34 volumio volumio[1039]: info: CoreCommandRouter::volumioPushQueue Dec 07 19:52:34 volumio volumio[1039]: info: CorePlayQueue::saveQueue Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::updateTrackBlock Dec 07 19:52:34 volumio volumio[1039]: info: CorePlayQueue::getTrackBlock Dec 07 19:52:34 volumio volumio[1039]: info: CoreCommandRouter::volumioPlay Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::play index 0 Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::stop Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::play index undefined Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 19:52:34 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:34 volumio volumio[1039]: info: CoreStateMachine::startPlaybackTimer Dec 07 19:52:34 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:34 volumio volumio[1039]: info: [1765133554856] ControllerWebradio::clearAddPlayTrack Dec 07 19:52:34 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 19:52:34 volumio volumio[1039]: info: sendMpdCommand stop took 9 milliseconds Dec 07 19:52:34 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand clear Dec 07 19:52:34 volumio volumio[1039]: info: Dec 07 19:52:34 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:34 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:34 volumio volumio[1039]: info: sendMpdCommand clear took 3 milliseconds Dec 07 19:52:34 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand load "https://stream.radiosaw.de/saw-70er/mp3-192/tunein/" Dec 07 19:52:34 volumio volumio[1039]: info: Dec 07 19:52:34 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:34 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:34 volumio volumio[1039]: info: Dec 07 19:52:34 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:34 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:34 volumio volumio[1039]: error: updateQueue error: null Dec 07 19:52:34 volumio volumio[1039]: info: ------------------------------ 10ms Dec 07 19:52:38 volumio volumio[1039]: error: updateQueue error: null Dec 07 19:52:38 volumio volumio[1039]: error: updateQueue error: null Dec 07 19:52:38 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radiosaw.de/saw-70er/mp3-192/tunein/" Dec 07 19:52:38 volumio volumio[1039]: info: ------------------------------ 3800ms Dec 07 19:52:38 volumio volumio[1039]: info: ------------------------------ 3798ms Dec 07 19:52:38 volumio volumio[1039]: info: Dec 07 19:52:38 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:38 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:38 volumio volumio[1039]: info: sendMpdCommand add "https://stream.radiosaw.de/saw-70er/mp3-192/tunein/" took 5 milliseconds Dec 07 19:52:38 volumio volumio[1039]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 07 19:52:38 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand play Dec 07 19:52:38 volumio volumio[1039]: info: Dec 07 19:52:38 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:38 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:38 volumio volumio[1039]: info: Dec 07 19:52:38 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:38 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:38 volumio volumio[1039]: info: ------------------------------ 12ms Dec 07 19:52:38 volumio volumio[1039]: info: sendMpdCommand play took 10 milliseconds Dec 07 19:52:38 volumio volumio[1039]: info: ------------------------------ 7ms Dec 07 19:52:38 volumio volumio[1039]: info: ------------------------------ 6ms Dec 07 19:52:40 volumio volumio[1039]: info: Dec 07 19:52:40 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:40 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:40 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:40 volumio volumio[1039]: info: Dec 07 19:52:40 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:40 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:40 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:40 volumio volumio[1039]: info: Dec 07 19:52:40 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:40 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:40 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:40 volumio volumio[1039]: info: Dec 07 19:52:40 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:40 volumio volumio[1039]: info: Dec 07 19:52:40 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:40 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:40 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:40 volumio volumio[1039]: info: sendMpdCommand status took 11 milliseconds Dec 07 19:52:40 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:40 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:40 volumio volumio[1039]: info: sendMpdCommand status took 10 milliseconds Dec 07 19:52:40 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:40 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:40 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:40 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:40 volumio volumio[1039]: info: Dec 07 19:52:40 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:40 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:40 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:40 volumio volumio[1039]: info: sendMpdCommand status took 18 milliseconds Dec 07 19:52:40 volumio volumio[1039]: info: sendMpdCommand status took 14 milliseconds Dec 07 19:52:40 volumio volumio[1039]: info: sendMpdCommand status took 13 milliseconds Dec 07 19:52:40 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 07 19:52:40 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 07 19:52:40 volumio volumio[1039]: info: sendMpdCommand status took 8 milliseconds Dec 07 19:52:40 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:40 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:40 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:40 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:40 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:40 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:40 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:40 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:40 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:40 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:40 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:40 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:40 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:40 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"https://stream.radiosaw.de/saw-70er/mp3-192/tunein/","trackType":""} Dec 07 19:52:40 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:40 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:40 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus stop Dec 07 19:52:40 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:40 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:40 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:40 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"https://stream.radiosaw.de/saw-70er/mp3-192/tunein/","trackType":""} Dec 07 19:52:40 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:40 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:40 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:40 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:40 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:40 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 19:52:40 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:40 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:40 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:40 volumio volumio[1039]: info: ------------------------------ 80ms Dec 07 19:52:41 volumio volumio[1039]: info: ------------------------------ 113ms Dec 07 19:52:41 volumio volumio[1039]: info: Dec 07 19:52:41 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:41 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:41 volumio volumio[1039]: info: Dec 07 19:52:41 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:41 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:41 volumio volumio[1039]: info: Dec 07 19:52:41 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:41 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:41 volumio volumio[1039]: info: Dec 07 19:52:41 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:41 volumio volumio[1039]: info: Dec 07 19:52:41 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:41 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:41 volumio volumio[1039]: info: Dec 07 19:52:41 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:41 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:41 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 94 milliseconds Dec 07 19:52:41 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:41 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 95 milliseconds Dec 07 19:52:41 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 94 milliseconds Dec 07 19:52:41 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 92 milliseconds Dec 07 19:52:41 volumio volumio[1039]: info: ------------------------------ 12ms Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:41 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:41 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:41 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio SAW 70er","artist":"radio SAW 70er","album":null,"uri":"https://stream.radiosaw.de/saw-70er/mp3-192/tunein/","trackType":""} Dec 07 19:52:41 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:41 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:41 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:41 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:41 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio SAW 70er","artist":"radio SAW 70er","album":null,"uri":"https://stream.radiosaw.de/saw-70er/mp3-192/tunein/","trackType":""} Dec 07 19:52:41 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:41 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:41 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:41 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:41 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio SAW 70er","artist":"radio SAW 70er","album":null,"uri":"https://stream.radiosaw.de/saw-70er/mp3-192/tunein/","trackType":""} Dec 07 19:52:41 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:41 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:41 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:41 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:41 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio SAW 70er","artist":"radio SAW 70er","album":null,"uri":"https://stream.radiosaw.de/saw-70er/mp3-192/tunein/","trackType":""} Dec 07 19:52:41 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:41 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:41 volumio volumio[1039]: info: ------------------------------ 305ms Dec 07 19:52:41 volumio volumio[1039]: info: ------------------------------ 305ms Dec 07 19:52:41 volumio volumio[1039]: info: ------------------------------ 307ms Dec 07 19:52:41 volumio volumio[1039]: info: ------------------------------ 297ms Dec 07 19:52:41 volumio volumio[1039]: info: Dec 07 19:52:41 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:41 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:41 volumio volumio[1039]: info: Dec 07 19:52:41 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:41 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:41 volumio volumio[1039]: info: Dec 07 19:52:41 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:41 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:41 volumio volumio[1039]: info: Dec 07 19:52:41 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:41 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:41 volumio volumio[1039]: info: sendMpdCommand status took 316 milliseconds Dec 07 19:52:41 volumio volumio[1039]: info: ------------------------------ 315ms Dec 07 19:52:41 volumio volumio[1039]: info: sendMpdCommand status took 311 milliseconds Dec 07 19:52:41 volumio volumio[1039]: info: ------------------------------ 314ms Dec 07 19:52:41 volumio volumio[1039]: info: sendMpdCommand status took 311 milliseconds Dec 07 19:52:41 volumio volumio[1039]: info: ------------------------------ 118ms Dec 07 19:52:41 volumio volumio[1039]: info: sendMpdCommand status took 116 milliseconds Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:41 volumio volumio[1039]: info: Dec 07 19:52:41 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:41 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:41 volumio volumio[1039]: info: Dec 07 19:52:41 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:41 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:41 volumio volumio[1039]: info: ------------------------------ 29ms Dec 07 19:52:41 volumio volumio[1039]: info: sendMpdCommand status took 27 milliseconds Dec 07 19:52:41 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 07 19:52:41 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 07 19:52:41 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 17 milliseconds Dec 07 19:52:41 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 17 milliseconds Dec 07 19:52:41 volumio volumio[1039]: info: ------------------------------ 14ms Dec 07 19:52:41 volumio volumio[1039]: info: sendMpdCommand status took 11 milliseconds Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:41 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:41 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:41 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"radio SAW 70er","artist":"radio SAW 70er","album":null,"uri":"https://stream.radiosaw.de/saw-70er/mp3-192/tunein/","trackType":""} Dec 07 19:52:41 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:41 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:41 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:41 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:41 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"radio SAW 70er","artist":"radio SAW 70er","album":null,"uri":"https://stream.radiosaw.de/saw-70er/mp3-192/tunein/","trackType":""} Dec 07 19:52:41 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:41 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:41 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:41 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:41 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"radio SAW 70er","artist":"radio SAW 70er","album":null,"uri":"https://stream.radiosaw.de/saw-70er/mp3-192/tunein/","trackType":""} Dec 07 19:52:41 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:41 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:41 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:41 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:41 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1231,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"radio SAW 70er","artist":"radio SAW 70er","album":null,"uri":"https://stream.radiosaw.de/saw-70er/mp3-192/tunein/","trackType":""} Dec 07 19:52:41 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:41 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:41 volumio volumio[1039]: info: ------------------------------ 508ms Dec 07 19:52:41 volumio volumio[1039]: info: ------------------------------ 505ms Dec 07 19:52:41 volumio volumio[1039]: info: ------------------------------ 506ms Dec 07 19:52:41 volumio volumio[1039]: info: ------------------------------ 309ms Dec 07 19:52:41 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 167 milliseconds Dec 07 19:52:41 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 164 milliseconds Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:41 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:41 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:41 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:41 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1231,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"radio SAW 70er","artist":"radio SAW 70er","album":null,"uri":"https://stream.radiosaw.de/saw-70er/mp3-192/tunein/","trackType":""} Dec 07 19:52:41 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:41 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:41 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:41 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:41 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1357,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"radio SAW 70er","artist":"radio SAW 70er","album":null,"uri":"https://stream.radiosaw.de/saw-70er/mp3-192/tunein/","trackType":""} Dec 07 19:52:41 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:52:41 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:41 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:41 volumio volumio[1039]: info: ------------------------------ 278ms Dec 07 19:52:41 volumio volumio[1039]: info: ------------------------------ 259ms Dec 07 19:52:58 volumio volumio[1039]: info: Preload queue cleared Dec 07 19:52:58 volumio volumio[1039]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::ClearQueue Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::stop Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::stPlaybackTimer Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::updateTrackBlock Dec 07 19:52:58 volumio volumio[1039]: info: CorePlayQueue::getTrackBlock Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:58 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:58 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 19:52:58 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::serviceStop Dec 07 19:52:58 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:58 volumio volumio[1039]: info: CoreCommandRouter::serviceStop Dec 07 19:52:58 volumio volumio[1039]: info: [1765133578169] ControllerWebradio::stop Dec 07 19:52:58 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 19:52:58 volumio volumio[1039]: info: CorePlayQueue::clearPlayQueue Dec 07 19:52:58 volumio volumio[1039]: info: CorePlayQueue::saveQueue Dec 07 19:52:58 volumio volumio[1039]: info: CoreCommandRouter::volumioPushQueue Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::addQueueItems Dec 07 19:52:58 volumio volumio[1039]: info: CorePlayQueue::addQueueItems Dec 07 19:52:58 volumio volumio[1039]: info: Preload queue cleared Dec 07 19:52:58 volumio volumio[1039]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s47794 Dec 07 19:52:58 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 07 19:52:58 volumio volumio[1039]: info: Dec 07 19:52:58 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:58 volumio volumio[1039]: info: sendMpdCommand stop took 99 milliseconds Dec 07 19:52:58 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:58 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:58 volumio volumio[1039]: info: Dec 07 19:52:58 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:58 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:58 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:58 volumio volumio[1039]: info: Dec 07 19:52:58 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:52:58 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:52:58 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:52:58 volumio volumio[1039]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X' Dec 07 19:52:58 volumio volumio[1039]: info: sendMpdCommand status took 33 milliseconds Dec 07 19:52:58 volumio volumio[1039]: info: sendMpdCommand status took 25 milliseconds Dec 07 19:52:58 volumio volumio[1039]: info: sendMpdCommand status took 22 milliseconds Dec 07 19:52:58 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:58 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:58 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:58 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:58 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:52:58 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:52:58 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 15 milliseconds Dec 07 19:52:58 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 15 milliseconds Dec 07 19:52:58 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 07 19:52:58 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:58 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:58 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:52:58 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:58 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:58 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:58 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 19:52:58 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:58 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:58 volumio volumio[1039]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio SAW 70er","artist":"radio SAW 70er","album":null,"uri":"https://stream.radiosaw.de/saw-70er/mp3-192/tunein/","trackType":""} Dec 07 19:52:58 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::syncState stateService stop Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus stop Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:58 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:58 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:58 volumio volumio[1039]: info: No code Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:58 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:58 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:58 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:58 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:58 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:58 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:58 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:58 volumio volumio[1039]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio SAW 70er","artist":"radio SAW 70er","album":null,"uri":"https://stream.radiosaw.de/saw-70er/mp3-192/tunein/","trackType":""} Dec 07 19:52:58 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::syncState stateService stop Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus stop Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:58 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:58 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:58 volumio volumio[1039]: info: No code Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:58 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:58 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:58 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:52:58 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:58 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:58 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:58 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:58 volumio volumio[1039]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio SAW 70er","artist":"radio SAW 70er","album":null,"uri":"https://stream.radiosaw.de/saw-70er/mp3-192/tunein/","trackType":""} Dec 07 19:52:58 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::syncState stateService stop Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus stop Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:58 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:58 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:58 volumio volumio[1039]: info: No code Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:52:58 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:58 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:52:58 volumio volumio[1039]: info: ------------------------------ 235ms Dec 07 19:52:58 volumio volumio[1039]: info: ------------------------------ 229ms Dec 07 19:52:58 volumio volumio[1039]: info: ------------------------------ 225ms Dec 07 19:52:58 volumio volumio[1039]: info: CoreCommandRouter::volumioPushQueue Dec 07 19:52:58 volumio volumio[1039]: info: CorePlayQueue::saveQueue Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::updateTrackBlock Dec 07 19:52:58 volumio volumio[1039]: info: CorePlayQueue::getTrackBlock Dec 07 19:52:58 volumio volumio[1039]: info: CoreCommandRouter::volumioPlay Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::play index 0 Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::stop Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::play index undefined Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 19:52:58 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:58 volumio volumio[1039]: info: CoreStateMachine::startPlaybackTimer Dec 07 19:52:58 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:52:58 volumio volumio[1039]: info: [1765133578866] ControllerWebradio::clearAddPlayTrack Dec 07 19:52:58 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 19:52:58 volumio volumio[1039]: info: sendMpdCommand stop took 5 milliseconds Dec 07 19:52:58 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand clear Dec 07 19:52:58 volumio volumio[1039]: info: Dec 07 19:52:58 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:58 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:58 volumio volumio[1039]: info: sendMpdCommand clear took 3 milliseconds Dec 07 19:52:58 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand load "https://stream.lokalradio.nrw/444zcfy" Dec 07 19:52:58 volumio volumio[1039]: info: Dec 07 19:52:58 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:58 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:58 volumio volumio[1039]: info: Dec 07 19:52:58 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:52:58 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:52:58 volumio volumio[1039]: error: updateQueue error: null Dec 07 19:52:58 volumio volumio[1039]: info: ------------------------------ 8ms Dec 07 19:53:02 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand add "https://stream.lokalradio.nrw/444zcfy" Dec 07 19:53:02 volumio volumio[1039]: error: updateQueue error: null Dec 07 19:53:02 volumio volumio[1039]: error: updateQueue error: null Dec 07 19:53:02 volumio volumio[1039]: info: ------------------------------ 3300ms Dec 07 19:53:02 volumio volumio[1039]: info: ------------------------------ 3299ms Dec 07 19:53:02 volumio volumio[1039]: info: sendMpdCommand add "https://stream.lokalradio.nrw/444zcfy" took 7 milliseconds Dec 07 19:53:02 volumio volumio[1039]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 07 19:53:02 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand play Dec 07 19:53:02 volumio volumio[1039]: info: Dec 07 19:53:02 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:53:02 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:53:02 volumio volumio[1039]: info: Dec 07 19:53:02 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:53:02 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:53:02 volumio volumio[1039]: info: Dec 07 19:53:02 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:53:02 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:53:02 volumio volumio[1039]: info: sendMpdCommand play took 14 milliseconds Dec 07 19:53:02 volumio volumio[1039]: info: ------------------------------ 11ms Dec 07 19:53:02 volumio volumio[1039]: info: ------------------------------ 8ms Dec 07 19:53:02 volumio volumio[1039]: info: ------------------------------ 6ms Dec 07 19:53:03 volumio volumio[1039]: info: Dec 07 19:53:03 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:53:03 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:53:03 volumio volumio[1039]: info: Dec 07 19:53:03 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:53:03 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:53:03 volumio volumio[1039]: info: Dec 07 19:53:03 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:53:03 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:53:03 volumio volumio[1039]: info: Dec 07 19:53:03 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:53:03 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:53:03 volumio volumio[1039]: info: Dec 07 19:53:03 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:53:03 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:53:03 volumio volumio[1039]: info: Dec 07 19:53:03 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:53:03 volumio volumio[1039]: info: sendMpdCommand status took 26 milliseconds Dec 07 19:53:03 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:53:03 volumio volumio[1039]: info: sendMpdCommand status took 23 milliseconds Dec 07 19:53:03 volumio volumio[1039]: info: sendMpdCommand status took 19 milliseconds Dec 07 19:53:03 volumio volumio[1039]: info: sendMpdCommand status took 15 milliseconds Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:53:03 volumio volumio[1039]: info: sendMpdCommand status took 29 milliseconds Dec 07 19:53:03 volumio volumio[1039]: info: sendMpdCommand status took 23 milliseconds Dec 07 19:53:03 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 07 19:53:03 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 07 19:53:03 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 07 19:53:03 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 17 milliseconds Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:53:03 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:53:03 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:53:03 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"444zcfy","artist":null,"album":null,"uri":"https://stream.lokalradio.nrw/444zcfy","trackType":""} Dec 07 19:53:03 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus stop Dec 07 19:53:03 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:53:03 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:53:03 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"444zcfy","artist":null,"album":null,"uri":"https://stream.lokalradio.nrw/444zcfy","trackType":""} Dec 07 19:53:03 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:53:03 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:53:03 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:53:03 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:53:03 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"444zcfy","artist":null,"album":null,"uri":"https://stream.lokalradio.nrw/444zcfy","trackType":""} Dec 07 19:53:03 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:53:03 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:53:03 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:53:03 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:53:03 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"444zcfy","artist":null,"album":null,"uri":"https://stream.lokalradio.nrw/444zcfy","trackType":""} Dec 07 19:53:03 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:53:03 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:53:03 volumio volumio[1039]: info: ------------------------------ 133ms Dec 07 19:53:03 volumio volumio[1039]: info: ------------------------------ 234ms Dec 07 19:53:03 volumio volumio[1039]: info: ------------------------------ 228ms Dec 07 19:53:03 volumio volumio[1039]: info: ------------------------------ 227ms Dec 07 19:53:03 volumio volumio[1039]: info: Dec 07 19:53:03 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:53:03 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:53:03 volumio volumio[1039]: info: Dec 07 19:53:03 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:53:03 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:53:03 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 187 milliseconds Dec 07 19:53:03 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 185 milliseconds Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:53:03 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:53:03 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:53:03 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Radio Herne","artist":"Radio Herne","album":null,"uri":"https://stream.lokalradio.nrw/444zcfy","trackType":""} Dec 07 19:53:03 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:53:03 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:53:03 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:53:03 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:53:03 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Radio Herne","artist":"Radio Herne","album":null,"uri":"https://stream.lokalradio.nrw/444zcfy","trackType":""} Dec 07 19:53:03 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:53:03 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:53:03 volumio volumio[1039]: info: ------------------------------ 344ms Dec 07 19:53:03 volumio volumio[1039]: info: ------------------------------ 342ms Dec 07 19:53:03 volumio volumio[1039]: info: Dec 07 19:53:03 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:53:03 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:53:03 volumio volumio[1039]: info: Dec 07 19:53:03 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:53:03 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:53:03 volumio volumio[1039]: info: Dec 07 19:53:03 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Dec 07 19:53:03 volumio volumio[1039]: info: Ignoring MPD Status Update Dec 07 19:53:03 volumio volumio[1039]: info: Dec 07 19:53:03 volumio volumio[1039]: ---------------------------- MPD announces state update: player Dec 07 19:53:03 volumio volumio[1039]: info: ControllerMpd::getState Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Dec 07 19:53:03 volumio volumio[1039]: info: ------------------------------ 240ms Dec 07 19:53:03 volumio volumio[1039]: info: sendMpdCommand status took 239 milliseconds Dec 07 19:53:03 volumio volumio[1039]: info: ------------------------------ 111ms Dec 07 19:53:03 volumio volumio[1039]: info: sendMpdCommand status took 108 milliseconds Dec 07 19:53:03 volumio volumio[1039]: info: ------------------------------ 107ms Dec 07 19:53:03 volumio volumio[1039]: info: sendMpdCommand status took 103 milliseconds Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::parseState Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 19:53:03 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 07 19:53:03 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 07 19:53:03 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:53:03 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Dec 07 19:53:03 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:53:03 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:53:03 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1110,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio Herne","artist":"Radio Herne","album":null,"uri":"https://stream.lokalradio.nrw/444zcfy","trackType":""} Dec 07 19:53:03 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:53:03 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:53:03 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:53:03 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:53:03 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1236,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio Herne","artist":"Radio Herne","album":null,"uri":"https://stream.lokalradio.nrw/444zcfy","trackType":""} Dec 07 19:53:03 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:53:03 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:53:03 volumio volumio[1039]: info: ControllerMpd::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Dec 07 19:53:03 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Dec 07 19:53:03 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1236,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio Herne","artist":"Radio Herne","album":null,"uri":"https://stream.lokalradio.nrw/444zcfy","trackType":""} Dec 07 19:53:03 volumio volumio[1039]: verbose: CURRENT POSITION 0 Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Dec 07 19:53:03 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreStateMachine::pushState Dec 07 19:53:03 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Dec 07 19:53:03 volumio volumio[1039]: info: ------------------------------ 365ms Dec 07 19:53:03 volumio volumio[1039]: info: ------------------------------ 236ms Dec 07 19:53:03 volumio volumio[1039]: info: ------------------------------ 231ms Dec 07 19:53:11 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 07 19:53:11 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 07 19:53:17 volumio sudo[4058]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Dec 07 19:53:18 volumio sudo[4058]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 19:53:18 volumio sudo[4060]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Dec 07 19:53:18 volumio sudo[4060]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 19:53:18 volumio sudo[4058]: pam_unix(sudo:session): session closed for user root Dec 07 19:53:18 volumio sudo[4062]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Dec 07 19:53:18 volumio sudo[4062]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 19:53:18 volumio volumio[1039]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Dec 07 19:53:18 volumio volumio[1039]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 19:53:18 volumio sudo[4064]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Dec 07 19:53:18 volumio sudo[4064]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 19:53:18 volumio volumio[1039]: TypeError: Cannot read properties of undefined (reading 'split') Dec 07 19:53:18 volumio volumio[1039]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Dec 07 19:53:18 volumio volumio[1039]: at ChildProcess.exithandler (node:child_process:413:7) Dec 07 19:53:18 volumio volumio[1039]: at ChildProcess.emit (node:events:514:28) Dec 07 19:53:18 volumio volumio[1039]: at maybeClose (node:internal/child_process:1105:16) Dec 07 19:53:18 volumio volumio[1039]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Dec 07 19:53:18 volumio volumio[1039]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 19:53:18 volumio systemd[1]: Reloading. Dec 07 19:53:19 volumio sudo[4104]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-07 19:52' Dec 07 19:53:19 volumio sudo[4104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="ec1ce6b3390e13e82b9cc0453f957c4f5298419e" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Nov 17 16:31:40 UTC 2025" VOLUMIO_VERSION="4.071" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5fbe1bcf6936184712979c0bfaf061f2"