Dec 28 15:15:02 volumio volumio[1196]: info: Preload queue cleared Dec 28 15:15:10 volumio volumio[1196]: Searching all installed plugins Dec 28 15:15:10 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 15:15:10 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: , search Dec 28 15:15:10 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 28 15:15:10 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Dec 28 15:15:10 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Dec 28 15:15:10 volumio volumio[1196]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Dec 28 15:15:10 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 28 15:15:10 volumio volumio[1196]: info: Wystąpił błąd podczas kolejkowania SHOUTCAST Dec 28 15:15:11 volumio volumio[1196]: info: All search sources collected, pushing search results Dec 28 15:15:12 volumio volumio[1196]: Searching all installed plugins Dec 28 15:15:12 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 15:15:12 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: , search Dec 28 15:15:12 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 28 15:15:12 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Dec 28 15:15:12 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Dec 28 15:15:12 volumio volumio[1196]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Dec 28 15:15:12 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 28 15:15:12 volumio volumio[1196]: info: All search sources collected, pushing search results Dec 28 15:15:14 volumio volumio[1196]: Searching all installed plugins Dec 28 15:15:14 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 15:15:14 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: , search Dec 28 15:15:14 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 28 15:15:14 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Dec 28 15:15:14 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Dec 28 15:15:14 volumio volumio[1196]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Dec 28 15:15:14 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 28 15:15:15 volumio volumio[1196]: info: All search sources collected, pushing search results Dec 28 15:15:22 volumio volumio[1196]: info: Preload queue cleared Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::ClearQueue Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::stop Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::stPlaybackTimer Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::updateTrackBlock Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::getTrackBlock Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::pushState Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Dec 28 15:15:22 volumio volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Dec 28 15:15:22 volumio volumio[1196]: info: MRS: Pushing multiroomSync output Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::serviceStop Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::serviceStop Dec 28 15:15:22 volumio volumio[1196]: info: [1766931322179] ControllerWebradio::stop Dec 28 15:15:22 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::clearPlayQueue Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::saveQueue Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::volumioPushQueue Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::addQueueItems Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::addQueueItems Dec 28 15:15:22 volumio volumio[1196]: info: Preload queue cleared Dec 28 15:15:22 volumio volumio[1196]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s114338 Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 28 15:15:22 volumio volumio[1196]: info: Dec 28 15:15:22 volumio volumio[1196]: ---------------------------- MPD announces state update: player Dec 28 15:15:22 volumio volumio[1196]: info: sendMpdCommand stop took 66 milliseconds Dec 28 15:15:22 volumio volumio[1196]: info: ControllerMpd::getState Dec 28 15:15:22 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Dec 28 15:15:22 volumio volumio[1196]: info: Dec 28 15:15:22 volumio volumio[1196]: ---------------------------- MPD announces state update: player Dec 28 15:15:22 volumio volumio[1196]: info: ControllerMpd::getState Dec 28 15:15:22 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Dec 28 15:15:22 volumio volumio[1196]: info: sendMpdCommand status took 17 milliseconds Dec 28 15:15:22 volumio volumio[1196]: info: sendMpdCommand status took 9 milliseconds Dec 28 15:15:22 volumio volumio[1196]: verbose: ControllerMpd::parseState Dec 28 15:15:22 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 15:15:22 volumio volumio[1196]: verbose: ControllerMpd::parseState Dec 28 15:15:22 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 15:15:22 volumio volumio[1196]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X' Dec 28 15:15:22 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 28 15:15:22 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 28 15:15:22 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Dec 28 15:15:22 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Dec 28 15:15:22 volumio volumio[1196]: info: ControllerMpd::pushState Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::pushState Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Dec 28 15:15:22 volumio volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Dec 28 15:15:22 volumio volumio[1196]: info: MRS: Pushing multiroomSync output Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:22 volumio volumio[1196]: 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":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Dec 28 15:15:22 volumio volumio[1196]: verbose: CURRENT POSITION 0 Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::syncState stateService stop Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus stop Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::pushState Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Dec 28 15:15:22 volumio volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Dec 28 15:15:22 volumio volumio[1196]: info: MRS: Pushing multiroomSync output Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:22 volumio volumio[1196]: info: No code Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::pushState Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Dec 28 15:15:22 volumio volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Dec 28 15:15:22 volumio volumio[1196]: info: MRS: Pushing multiroomSync output Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:22 volumio volumio[1196]: info: ControllerMpd::pushState Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::pushState Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Dec 28 15:15:22 volumio volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Dec 28 15:15:22 volumio volumio[1196]: info: MRS: Pushing multiroomSync output Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:22 volumio volumio[1196]: 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":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Dec 28 15:15:22 volumio volumio[1196]: verbose: CURRENT POSITION 0 Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::syncState stateService stop Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus stop Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::pushState Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Dec 28 15:15:22 volumio volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Dec 28 15:15:22 volumio volumio[1196]: info: MRS: Pushing multiroomSync output Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:22 volumio volumio[1196]: info: No code Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::pushState Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Dec 28 15:15:22 volumio volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Dec 28 15:15:22 volumio volumio[1196]: info: MRS: Pushing multiroomSync output Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:22 volumio volumio[1196]: info: ------------------------------ 94ms Dec 28 15:15:22 volumio volumio[1196]: info: ------------------------------ 86ms Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::volumioPushQueue Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::saveQueue Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::updateTrackBlock Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::getTrackBlock Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::volumioPlay Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::play index 0 Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::stop Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::play index undefined Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:22 volumio volumio[1196]: info: CoreStateMachine::startPlaybackTimer Dec 28 15:15:22 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::volumioGetVisibleSources Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 15:15:22 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 28 15:15:22 volumio volumio[1196]: info: [1766931322638] ControllerWebradio::clearAddPlayTrack Dec 28 15:15:22 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 15:15:22 volumio volumio[1196]: info: sendMpdCommand stop took 4 milliseconds Dec 28 15:15:22 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand clear Dec 28 15:15:22 volumio volumio[1196]: info: Dec 28 15:15:22 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Dec 28 15:15:22 volumio volumio[1196]: info: Ignoring MPD Status Update Dec 28 15:15:22 volumio volumio[1196]: info: sendMpdCommand clear took 2 milliseconds Dec 28 15:15:22 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand load "http://stream.streamaudio.de:8000/radio-piratona.ogg" Dec 28 15:15:22 volumio volumio[1196]: info: Dec 28 15:15:22 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Dec 28 15:15:22 volumio volumio[1196]: info: Ignoring MPD Status Update Dec 28 15:15:22 volumio volumio[1196]: error: updateQueue error: null Dec 28 15:15:22 volumio volumio[1196]: info: ------------------------------ 7ms Dec 28 15:15:30 volumio volumio[1196]: info: Preload queue cleared Dec 28 15:15:30 volumio volumio[1196]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 28 15:15:30 volumio volumio[1196]: info: CoreStateMachine::ClearQueue Dec 28 15:15:30 volumio volumio[1196]: info: CoreStateMachine::stop Dec 28 15:15:30 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 15:15:30 volumio volumio[1196]: info: CorePlayQueue::clearPlayQueue Dec 28 15:15:30 volumio volumio[1196]: info: CorePlayQueue::saveQueue Dec 28 15:15:30 volumio volumio[1196]: info: CoreCommandRouter::volumioPushQueue Dec 28 15:15:30 volumio volumio[1196]: info: CoreStateMachine::addQueueItems Dec 28 15:15:30 volumio volumio[1196]: info: CorePlayQueue::addQueueItems Dec 28 15:15:30 volumio volumio[1196]: info: Preload queue cleared Dec 28 15:15:30 volumio volumio[1196]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s114338 Dec 28 15:15:30 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 28 15:15:30 volumio volumio[1196]: info: CoreCommandRouter::volumioPushQueue Dec 28 15:15:30 volumio volumio[1196]: info: CorePlayQueue::saveQueue Dec 28 15:15:30 volumio volumio[1196]: info: CoreStateMachine::updateTrackBlock Dec 28 15:15:30 volumio volumio[1196]: info: CorePlayQueue::getTrackBlock Dec 28 15:15:30 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 28 15:15:30 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:30 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 28 15:15:30 volumio volumio[1196]: info: CoreCommandRouter::volumioPlay Dec 28 15:15:30 volumio volumio[1196]: info: CoreStateMachine::play index 0 Dec 28 15:15:30 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 15:15:30 volumio volumio[1196]: info: CoreStateMachine::stop Dec 28 15:15:30 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 15:15:30 volumio volumio[1196]: info: CoreStateMachine::play index undefined Dec 28 15:15:30 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 15:15:30 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:30 volumio volumio[1196]: info: CoreStateMachine::startPlaybackTimer Dec 28 15:15:30 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:30 volumio volumio[1196]: info: CoreCommandRouter::volumioGetVisibleSources Dec 28 15:15:30 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 15:15:30 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 28 15:15:30 volumio volumio[1196]: info: [1766931330250] ControllerWebradio::clearAddPlayTrack Dec 28 15:15:30 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 15:15:32 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand add "http://stream.streamaudio.de:8000/radio-piratona.ogg" Dec 28 15:15:32 volumio volumio[1196]: error: updateQueue error: null Dec 28 15:15:32 volumio volumio[1196]: info: Dec 28 15:15:32 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Dec 28 15:15:32 volumio volumio[1196]: info: Ignoring MPD Status Update Dec 28 15:15:32 volumio volumio[1196]: info: ------------------------------ 10314ms Dec 28 15:15:32 volumio volumio[1196]: info: sendMpdCommand stop took 2713 milliseconds Dec 28 15:15:32 volumio volumio[1196]: info: sendMpdCommand add "http://stream.streamaudio.de:8000/radio-piratona.ogg" took 3 milliseconds Dec 28 15:15:32 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand clear Dec 28 15:15:32 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 28 15:15:32 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand play Dec 28 15:15:32 volumio volumio[1196]: info: Dec 28 15:15:32 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Dec 28 15:15:32 volumio volumio[1196]: info: Ignoring MPD Status Update Dec 28 15:15:32 volumio volumio[1196]: info: Dec 28 15:15:32 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Dec 28 15:15:32 volumio volumio[1196]: info: Ignoring MPD Status Update Dec 28 15:15:32 volumio volumio[1196]: info: ------------------------------ 7ms Dec 28 15:15:32 volumio volumio[1196]: info: sendMpdCommand clear took 6 milliseconds Dec 28 15:15:32 volumio volumio[1196]: info: sendMpdCommand play took 5 milliseconds Dec 28 15:15:32 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand load "http://stream.streamaudio.de:8000/radio-piratona.ogg" Dec 28 15:15:32 volumio volumio[1196]: info: Dec 28 15:15:32 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Dec 28 15:15:32 volumio volumio[1196]: info: Ignoring MPD Status Update Dec 28 15:15:32 volumio volumio[1196]: error: updateQueue error: null Dec 28 15:15:32 volumio volumio[1196]: error: updateQueue error: null Dec 28 15:15:32 volumio volumio[1196]: info: ------------------------------ 10ms Dec 28 15:15:32 volumio volumio[1196]: info: ------------------------------ 9ms Dec 28 15:15:34 volumio volumio[1196]: info: Preload queue cleared Dec 28 15:15:34 volumio volumio[1196]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 28 15:15:34 volumio volumio[1196]: info: CoreStateMachine::ClearQueue Dec 28 15:15:34 volumio volumio[1196]: info: CoreStateMachine::stop Dec 28 15:15:34 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 15:15:34 volumio volumio[1196]: info: CorePlayQueue::clearPlayQueue Dec 28 15:15:34 volumio volumio[1196]: info: CorePlayQueue::saveQueue Dec 28 15:15:34 volumio volumio[1196]: info: CoreCommandRouter::volumioPushQueue Dec 28 15:15:34 volumio volumio[1196]: info: CoreStateMachine::addQueueItems Dec 28 15:15:34 volumio volumio[1196]: info: CorePlayQueue::addQueueItems Dec 28 15:15:34 volumio volumio[1196]: info: Preload queue cleared Dec 28 15:15:34 volumio volumio[1196]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s26356 Dec 28 15:15:34 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 28 15:15:34 volumio volumio[1196]: info: CoreCommandRouter::volumioPushQueue Dec 28 15:15:34 volumio volumio[1196]: info: CorePlayQueue::saveQueue Dec 28 15:15:34 volumio volumio[1196]: info: CoreStateMachine::updateTrackBlock Dec 28 15:15:34 volumio volumio[1196]: info: CorePlayQueue::getTrackBlock Dec 28 15:15:34 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 28 15:15:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:34 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 28 15:15:34 volumio volumio[1196]: info: CoreCommandRouter::volumioPlay Dec 28 15:15:34 volumio volumio[1196]: info: CoreStateMachine::play index 0 Dec 28 15:15:34 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 15:15:34 volumio volumio[1196]: info: CoreStateMachine::stop Dec 28 15:15:34 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 15:15:34 volumio volumio[1196]: info: CoreStateMachine::play index undefined Dec 28 15:15:34 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 15:15:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:34 volumio volumio[1196]: info: CoreStateMachine::startPlaybackTimer Dec 28 15:15:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:34 volumio volumio[1196]: info: CoreCommandRouter::volumioGetVisibleSources Dec 28 15:15:34 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 15:15:34 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 28 15:15:34 volumio volumio[1196]: info: [1766931334545] ControllerWebradio::clearAddPlayTrack Dec 28 15:15:34 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 15:15:42 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 28 15:15:42 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:42 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 28 15:15:42 volumio volumio[1196]: info: CoreCommandRouter::volumioPlay Dec 28 15:15:42 volumio volumio[1196]: info: CoreStateMachine::play index undefined Dec 28 15:15:42 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 15:15:42 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:42 volumio volumio[1196]: info: CoreStateMachine::startPlaybackTimer Dec 28 15:15:42 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:42 volumio volumio[1196]: info: CoreCommandRouter::volumioGetVisibleSources Dec 28 15:15:42 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 15:15:42 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 28 15:15:42 volumio volumio[1196]: info: [1766931342678] ControllerWebradio::clearAddPlayTrack Dec 28 15:15:42 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 15:15:43 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand add "http://stream.streamaudio.de:8000/radio-piratona.ogg" Dec 28 15:15:43 volumio volumio[1196]: error: updateQueue error: null Dec 28 15:15:43 volumio volumio[1196]: info: ------------------------------ 10990ms Dec 28 15:15:43 volumio volumio[1196]: info: sendMpdCommand stop took 9417 milliseconds Dec 28 15:15:43 volumio volumio[1196]: info: sendMpdCommand stop took 1285 milliseconds Dec 28 15:15:43 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand clear Dec 28 15:15:43 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand clear Dec 28 15:15:43 volumio volumio[1196]: info: Dec 28 15:15:43 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Dec 28 15:15:43 volumio volumio[1196]: info: Ignoring MPD Status Update Dec 28 15:15:43 volumio volumio[1196]: info: Dec 28 15:15:43 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Dec 28 15:15:43 volumio volumio[1196]: info: Ignoring MPD Status Update Dec 28 15:15:43 volumio volumio[1196]: info: Dec 28 15:15:43 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Dec 28 15:15:43 volumio volumio[1196]: info: Ignoring MPD Status Update Dec 28 15:15:43 volumio volumio[1196]: info: sendMpdCommand add "http://stream.streamaudio.de:8000/radio-piratona.ogg" took 8 milliseconds Dec 28 15:15:43 volumio volumio[1196]: info: sendMpdCommand clear took 5 milliseconds Dec 28 15:15:43 volumio volumio[1196]: info: sendMpdCommand clear took 4 milliseconds Dec 28 15:15:43 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 28 15:15:43 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand play Dec 28 15:15:43 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand load "https://tunein-live-c.cdnstream1.com/4452_96_2.mp3?aw_0_1st.skey=1766931334&aw_0_1st.abtest=&aw_0_1st.stationId=s26356&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g4154&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5ben%2c+es%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.programId=p3739591&aw_0_1st.affiliateIds=a39126%2ca40075%2ca39100%2ca39110&aw_0_1st.bandId=2" Dec 28 15:15:43 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand load "https://tunein-live-c.cdnstream1.com/4452_96_2.mp3?aw_0_1st.skey=1766931334&aw_0_1st.abtest=&aw_0_1st.stationId=s26356&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g4154&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5ben%2c+es%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.programId=p3739591&aw_0_1st.affiliateIds=a39126%2ca40075%2ca39100%2ca39110&aw_0_1st.bandId=2" Dec 28 15:15:43 volumio volumio[1196]: info: Dec 28 15:15:43 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Dec 28 15:15:43 volumio volumio[1196]: info: Ignoring MPD Status Update Dec 28 15:15:43 volumio volumio[1196]: error: updateQueue error: null Dec 28 15:15:43 volumio volumio[1196]: error: updateQueue error: null Dec 28 15:15:43 volumio volumio[1196]: error: updateQueue error: null Dec 28 15:15:43 volumio volumio[1196]: info: ------------------------------ 8ms Dec 28 15:15:43 volumio volumio[1196]: info: ------------------------------ 7ms Dec 28 15:15:43 volumio volumio[1196]: info: ------------------------------ 6ms Dec 28 15:15:43 volumio volumio[1196]: info: sendMpdCommand play took 5 milliseconds Dec 28 15:15:46 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand add "https://tunein-live-c.cdnstream1.com/4452_96_2.mp3?aw_0_1st.skey=1766931334&aw_0_1st.abtest=&aw_0_1st.stationId=s26356&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g4154&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5ben%2c+es%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.programId=p3739591&aw_0_1st.affiliateIds=a39126%2ca40075%2ca39100%2ca39110&aw_0_1st.bandId=2" Dec 28 15:15:48 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 28 15:15:48 volumio volumio[1196]: info: CURURI: artists:// Dec 28 15:15:48 volumio volumio[1196]: error: updateQueue error: null Dec 28 15:15:48 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand add "https://tunein-live-c.cdnstream1.com/4452_96_2.mp3?aw_0_1st.skey=1766931334&aw_0_1st.abtest=&aw_0_1st.stationId=s26356&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g4154&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5ben%2c+es%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.programId=p3739591&aw_0_1st.affiliateIds=a39126%2ca40075%2ca39100%2ca39110&aw_0_1st.bandId=2" Dec 28 15:15:48 volumio volumio[1196]: info: ------------------------------ 4635ms Dec 28 15:15:48 volumio volumio[1196]: info: Dec 28 15:15:48 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Dec 28 15:15:48 volumio volumio[1196]: info: Ignoring MPD Status Update Dec 28 15:15:48 volumio volumio[1196]: info: Dec 28 15:15:48 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Dec 28 15:15:48 volumio volumio[1196]: info: Ignoring MPD Status Update Dec 28 15:15:48 volumio volumio[1196]: info: Dec 28 15:15:48 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Dec 28 15:15:48 volumio volumio[1196]: info: Ignoring MPD Status Update Dec 28 15:15:48 volumio volumio[1196]: info: sendMpdCommand add "https://tunein-live-c.cdnstream1.com/4452_96_2.mp3?aw_0_1st.skey=1766931334&aw_0_1st.abtest=&aw_0_1st.stationId=s26356&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g4154&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5ben%2c+es%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.programId=p3739591&aw_0_1st.affiliateIds=a39126%2ca40075%2ca39100%2ca39110&aw_0_1st.bandId=2" took 2361 milliseconds Dec 28 15:15:48 volumio volumio[1196]: info: sendMpdCommand add "https://tunein-live-c.cdnstream1.com/4452_96_2.mp3?aw_0_1st.skey=1766931334&aw_0_1st.abtest=&aw_0_1st.stationId=s26356&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g4154&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5ben%2c+es%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.programId=p3739591&aw_0_1st.affiliateIds=a39126%2ca40075%2ca39100%2ca39110&aw_0_1st.bandId=2" took 5 milliseconds Dec 28 15:15:48 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 28 15:15:48 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand play Dec 28 15:15:48 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 28 15:15:48 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand play Dec 28 15:15:48 volumio volumio[1196]: info: ------------------------------ 8ms Dec 28 15:15:48 volumio volumio[1196]: info: ------------------------------ 6ms Dec 28 15:15:48 volumio volumio[1196]: info: ------------------------------ 5ms Dec 28 15:15:48 volumio volumio[1196]: info: sendMpdCommand play took 4 milliseconds Dec 28 15:15:48 volumio volumio[1196]: info: sendMpdCommand play took 5 milliseconds Dec 28 15:15:48 volumio volumio[1196]: info: Dec 28 15:15:48 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Dec 28 15:15:48 volumio volumio[1196]: info: Ignoring MPD Status Update Dec 28 15:15:48 volumio volumio[1196]: info: ------------------------------ 1ms Dec 28 15:15:48 volumio volumio[1196]: info: Preload queue cleared Dec 28 15:15:51 volumio volumio[1196]: info: Dec 28 15:15:51 volumio volumio[1196]: ---------------------------- MPD announces state update: player Dec 28 15:15:51 volumio volumio[1196]: info: ControllerMpd::getState Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Dec 28 15:15:51 volumio volumio[1196]: info: Dec 28 15:15:51 volumio volumio[1196]: ---------------------------- MPD announces state update: player Dec 28 15:15:51 volumio volumio[1196]: info: ControllerMpd::getState Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Dec 28 15:15:51 volumio volumio[1196]: info: Dec 28 15:15:51 volumio volumio[1196]: ---------------------------- MPD announces state update: player Dec 28 15:15:51 volumio volumio[1196]: info: ControllerMpd::getState Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Dec 28 15:15:51 volumio volumio[1196]: info: Dec 28 15:15:51 volumio volumio[1196]: ---------------------------- MPD announces state update: player Dec 28 15:15:51 volumio volumio[1196]: info: Dec 28 15:15:51 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Dec 28 15:15:51 volumio volumio[1196]: info: Ignoring MPD Status Update Dec 28 15:15:51 volumio volumio[1196]: info: Dec 28 15:15:51 volumio volumio[1196]: ---------------------------- MPD announces state update: player Dec 28 15:15:51 volumio volumio[1196]: info: sendMpdCommand status took 17 milliseconds Dec 28 15:15:51 volumio volumio[1196]: info: ControllerMpd::getState Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Dec 28 15:15:51 volumio volumio[1196]: info: sendMpdCommand status took 6 milliseconds Dec 28 15:15:51 volumio volumio[1196]: info: ControllerMpd::getState Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::parseState Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::parseState Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 15:15:51 volumio volumio[1196]: info: Dec 28 15:15:51 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Dec 28 15:15:51 volumio volumio[1196]: info: Ignoring MPD Status Update Dec 28 15:15:51 volumio volumio[1196]: info: Dec 28 15:15:51 volumio volumio[1196]: ---------------------------- MPD announces state update: player Dec 28 15:15:51 volumio volumio[1196]: info: ControllerMpd::getState Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Dec 28 15:15:51 volumio volumio[1196]: info: sendMpdCommand status took 26 milliseconds Dec 28 15:15:51 volumio volumio[1196]: info: sendMpdCommand status took 23 milliseconds Dec 28 15:15:51 volumio volumio[1196]: info: ------------------------------ 25ms Dec 28 15:15:51 volumio volumio[1196]: info: sendMpdCommand status took 23 milliseconds Dec 28 15:15:51 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 22 milliseconds Dec 28 15:15:51 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 22 milliseconds Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::parseState Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::parseState Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::parseState Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Dec 28 15:15:51 volumio volumio[1196]: info: ControllerMpd::pushState Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Dec 28 15:15:51 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:51 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4452_96_2.mp3?aw_0_1st.skey=1766931334&aw_0_1st.abtest=&aw_0_1st.stationId=s26356&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g4154&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5ben%2c+es%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.programId=p3739591&aw_0_1st.affiliateIds=a39126%2ca40075%2ca39100%2ca39110&aw_0_1st.bandId=2","artist":"KBUE","album":null,"uri":"https://tunein-live-c.cdnstream1.com/4452_96_2.mp3?aw_0_1st.skey=1766931334&aw_0_1st.abtest=&aw_0_1st.stationId=s26356&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g4154&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5ben%2c+es%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.programId=p3739591&aw_0_1st.affiliateIds=a39126%2ca40075%2ca39100%2ca39110&aw_0_1st.bandId=2","trackType":"bandId=2"} Dec 28 15:15:51 volumio volumio[1196]: verbose: CURRENT POSITION 0 Dec 28 15:15:51 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Dec 28 15:15:51 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus stop Dec 28 15:15:51 volumio volumio[1196]: info: ControllerMpd::pushState Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Dec 28 15:15:51 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:51 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"4452_96_2.mp3?aw_0_1st.skey=1766931334&aw_0_1st.abtest=&aw_0_1st.stationId=s26356&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g4154&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5ben%2c+es%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.programId=p3739591&aw_0_1st.affiliateIds=a39126%2ca40075%2ca39100%2ca39110&aw_0_1st.bandId=2","artist":"KBUE","album":null,"uri":"https://tunein-live-c.cdnstream1.com/4452_96_2.mp3?aw_0_1st.skey=1766931334&aw_0_1st.abtest=&aw_0_1st.stationId=s26356&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g4154&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5ben%2c+es%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.programId=p3739591&aw_0_1st.affiliateIds=a39126%2ca40075%2ca39100%2ca39110&aw_0_1st.bandId=2","trackType":"bandId=2"} Dec 28 15:15:51 volumio volumio[1196]: verbose: CURRENT POSITION 0 Dec 28 15:15:51 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Dec 28 15:15:51 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Dec 28 15:15:51 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Dec 28 15:15:51 volumio volumio[1196]: info: CoreStateMachine::pushState Dec 28 15:15:51 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Dec 28 15:15:51 volumio volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Dec 28 15:15:51 volumio volumio[1196]: info: MRS: Pushing multiroomSync output Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 28 15:15:51 volumio volumio[1196]: info: CoreStateMachine::pushState Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Dec 28 15:15:51 volumio volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Dec 28 15:15:51 volumio volumio[1196]: info: MRS: Pushing multiroomSync output Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 28 15:15:51 volumio volumio[1196]: info: ------------------------------ 64ms Dec 28 15:15:51 volumio volumio[1196]: info: ------------------------------ 76ms Dec 28 15:15:51 volumio volumio[1196]: info: ------------------------------ 42ms Dec 28 15:15:51 volumio volumio[1196]: info: sendMpdCommand status took 41 milliseconds Dec 28 15:15:51 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 36 milliseconds Dec 28 15:15:51 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 36 milliseconds Dec 28 15:15:51 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 35 milliseconds Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::parseState Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Dec 28 15:15:51 volumio volumio[1196]: info: ControllerMpd::pushState Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Dec 28 15:15:51 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:51 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":320,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"4452_96_2.mp3?aw_0_1st.skey=1766931334&aw_0_1st.abtest=&aw_0_1st.stationId=s26356&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g4154&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5ben%2c+es%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.programId=p3739591&aw_0_1st.affiliateIds=a39126%2ca40075%2ca39100%2ca39110&aw_0_1st.bandId=2","artist":"KBUE","album":null,"uri":"https://tunein-live-c.cdnstream1.com/4452_96_2.mp3?aw_0_1st.skey=1766931334&aw_0_1st.abtest=&aw_0_1st.stationId=s26356&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g4154&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5ben%2c+es%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.programId=p3739591&aw_0_1st.affiliateIds=a39126%2ca40075%2ca39100%2ca39110&aw_0_1st.bandId=2","trackType":"bandId=2"} Dec 28 15:15:51 volumio volumio[1196]: verbose: CURRENT POSITION 0 Dec 28 15:15:51 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Dec 28 15:15:51 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Dec 28 15:15:51 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Dec 28 15:15:51 volumio volumio[1196]: info: CoreStateMachine::pushState Dec 28 15:15:51 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Dec 28 15:15:51 volumio volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Dec 28 15:15:51 volumio volumio[1196]: info: MRS: Pushing multiroomSync output Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 28 15:15:51 volumio volumio[1196]: info: CoreStateMachine::pushState Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Dec 28 15:15:51 volumio volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Dec 28 15:15:51 volumio volumio[1196]: info: MRS: Pushing multiroomSync output Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 28 15:15:51 volumio volumio[1196]: info: ControllerMpd::pushState Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Dec 28 15:15:51 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:51 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1100,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"4452_96_2.mp3?aw_0_1st.skey=1766931334&aw_0_1st.abtest=&aw_0_1st.stationId=s26356&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g4154&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5ben%2c+es%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.programId=p3739591&aw_0_1st.affiliateIds=a39126%2ca40075%2ca39100%2ca39110&aw_0_1st.bandId=2","artist":"KBUE","album":null,"uri":"https://tunein-live-c.cdnstream1.com/4452_96_2.mp3?aw_0_1st.skey=1766931334&aw_0_1st.abtest=&aw_0_1st.stationId=s26356&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g4154&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5ben%2c+es%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.programId=p3739591&aw_0_1st.affiliateIds=a39126%2ca40075%2ca39100%2ca39110&aw_0_1st.bandId=2","trackType":"bandId=2"} Dec 28 15:15:51 volumio volumio[1196]: verbose: CURRENT POSITION 0 Dec 28 15:15:51 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Dec 28 15:15:51 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Dec 28 15:15:51 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Dec 28 15:15:51 volumio volumio[1196]: info: CoreStateMachine::pushState Dec 28 15:15:51 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Dec 28 15:15:51 volumio volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Dec 28 15:15:51 volumio volumio[1196]: info: MRS: Pushing multiroomSync output Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 28 15:15:51 volumio volumio[1196]: info: CoreStateMachine::pushState Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Dec 28 15:15:51 volumio volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Dec 28 15:15:51 volumio volumio[1196]: info: MRS: Pushing multiroomSync output Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 28 15:15:51 volumio volumio[1196]: info: ControllerMpd::pushState Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Dec 28 15:15:51 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:51 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1100,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"4452_96_2.mp3?aw_0_1st.skey=1766931334&aw_0_1st.abtest=&aw_0_1st.stationId=s26356&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g4154&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5ben%2c+es%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.programId=p3739591&aw_0_1st.affiliateIds=a39126%2ca40075%2ca39100%2ca39110&aw_0_1st.bandId=2","artist":"KBUE","album":null,"uri":"https://tunein-live-c.cdnstream1.com/4452_96_2.mp3?aw_0_1st.skey=1766931334&aw_0_1st.abtest=&aw_0_1st.stationId=s26356&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g4154&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5ben%2c+es%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.programId=p3739591&aw_0_1st.affiliateIds=a39126%2ca40075%2ca39100%2ca39110&aw_0_1st.bandId=2","trackType":"bandId=2"} Dec 28 15:15:51 volumio volumio[1196]: verbose: CURRENT POSITION 0 Dec 28 15:15:51 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Dec 28 15:15:51 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Dec 28 15:15:51 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Dec 28 15:15:51 volumio volumio[1196]: info: CoreStateMachine::pushState Dec 28 15:15:51 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Dec 28 15:15:51 volumio volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Dec 28 15:15:51 volumio volumio[1196]: info: MRS: Pushing multiroomSync output Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 28 15:15:51 volumio volumio[1196]: info: CoreStateMachine::pushState Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Dec 28 15:15:51 volumio volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Dec 28 15:15:51 volumio volumio[1196]: info: MRS: Pushing multiroomSync output Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 28 15:15:51 volumio volumio[1196]: info: ------------------------------ 120ms Dec 28 15:15:51 volumio volumio[1196]: info: ------------------------------ 118ms Dec 28 15:15:51 volumio volumio[1196]: info: ------------------------------ 117ms Dec 28 15:15:51 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 125 milliseconds Dec 28 15:15:51 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Dec 28 15:15:51 volumio volumio[1196]: info: ControllerMpd::pushState Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Dec 28 15:15:51 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:51 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1100,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"4452_96_2.mp3?aw_0_1st.skey=1766931334&aw_0_1st.abtest=&aw_0_1st.stationId=s26356&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g4154&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5ben%2c+es%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.programId=p3739591&aw_0_1st.affiliateIds=a39126%2ca40075%2ca39100%2ca39110&aw_0_1st.bandId=2","artist":"KBUE","album":null,"uri":"https://tunein-live-c.cdnstream1.com/4452_96_2.mp3?aw_0_1st.skey=1766931334&aw_0_1st.abtest=&aw_0_1st.stationId=s26356&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g4154&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5ben%2c+es%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.programId=p3739591&aw_0_1st.affiliateIds=a39126%2ca40075%2ca39100%2ca39110&aw_0_1st.bandId=2","trackType":"bandId=2"} Dec 28 15:15:51 volumio volumio[1196]: verbose: CURRENT POSITION 0 Dec 28 15:15:51 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Dec 28 15:15:51 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Dec 28 15:15:51 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Dec 28 15:15:51 volumio volumio[1196]: info: CoreStateMachine::pushState Dec 28 15:15:51 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Dec 28 15:15:51 volumio volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Dec 28 15:15:51 volumio volumio[1196]: info: MRS: Pushing multiroomSync output Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 28 15:15:51 volumio volumio[1196]: info: CoreStateMachine::pushState Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Dec 28 15:15:51 volumio volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Dec 28 15:15:51 volumio volumio[1196]: info: MRS: Pushing multiroomSync output Dec 28 15:15:51 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 28 15:15:51 volumio volumio[1196]: info: ------------------------------ 191ms Dec 28 15:15:54 volumio volumio[1196]: Searching plugin music_service/mpd Dec 28 15:15:54 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 28 15:15:54 volumio volumio[1196]: info: All search sources collected, pushing search results Dec 28 15:15:57 volumio volumio[1196]: Searching plugin music_service/mpd Dec 28 15:15:57 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 28 15:15:57 volumio volumio[1196]: info: All search sources collected, pushing search results Dec 28 15:16:12 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 15:16:12 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 28 15:16:17 volumio volumio[1196]: info: Tunnel connection is inactive, restarting it Dec 28 15:16:17 volumio volumio[1196]: info: Starting Tunnel 1 Dec 28 15:16:17 volumio volumio[1196]: info: Starting Tunnel Connection Checker Dec 28 15:16:17 volumio sudo[8644]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 28 15:16:17 volumio sudo[8644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 15:16:18 volumio autossh[8007]: received signal to exit (15) Dec 28 15:16:18 volumio systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Dec 28 15:16:18 volumio systemd[1]: sshtunnel.service: Deactivated successfully. Dec 28 15:16:18 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Dec 28 15:16:18 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 28 15:16:18 volumio sudo[8644]: pam_unix(sudo:session): session closed for user root Dec 28 15:16:18 volumio volumio[1196]: info: Remote SSH Started Dec 28 15:16:18 volumio autossh[8648]: port set to 0, monitoring disabled Dec 28 15:16:18 volumio autossh[8648]: starting ssh (count 1) Dec 28 15:16:18 volumio autossh[8648]: ssh child pid is 8651 Dec 28 15:16:18 volumio volumio[1196]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 28 15:16:18 volumio volumiossh-tunnel[8651]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Dec 28 15:16:18 volumio autossh[8648]: ssh exited prematurely with status 255; autossh exiting Dec 28 15:16:18 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Dec 28 15:16:18 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Dec 28 15:16:18 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 28 15:16:18 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 1. Dec 28 15:16:18 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Dec 28 15:16:18 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 28 15:16:18 volumio autossh[8653]: port set to 0, monitoring disabled Dec 28 15:16:18 volumio autossh[8653]: starting ssh (count 1) Dec 28 15:16:18 volumio autossh[8653]: ssh child pid is 8656 Dec 28 15:16:18 volumio volumiossh-tunnel[8656]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Dec 28 15:16:18 volumio autossh[8653]: ssh exited prematurely with status 255; autossh exiting Dec 28 15:16:18 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Dec 28 15:16:18 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Dec 28 15:16:18 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 2. Dec 28 15:16:18 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Dec 28 15:16:18 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 28 15:16:18 volumio autossh[8658]: port set to 0, monitoring disabled Dec 28 15:16:18 volumio autossh[8658]: starting ssh (count 1) Dec 28 15:16:18 volumio autossh[8658]: ssh child pid is 8661 Dec 28 15:16:18 volumio volumiossh-tunnel[8661]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Dec 28 15:16:18 volumio autossh[8658]: ssh exited prematurely with status 255; autossh exiting Dec 28 15:16:18 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Dec 28 15:16:18 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Dec 28 15:16:18 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 3. Dec 28 15:16:18 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Dec 28 15:16:19 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 28 15:16:19 volumio autossh[8663]: port set to 0, monitoring disabled Dec 28 15:16:19 volumio autossh[8663]: starting ssh (count 1) Dec 28 15:16:19 volumio autossh[8663]: ssh child pid is 8666 Dec 28 15:16:19 volumio volumiossh-tunnel[8666]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Dec 28 15:16:19 volumio autossh[8663]: ssh exited prematurely with status 255; autossh exiting Dec 28 15:16:19 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Dec 28 15:16:19 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Dec 28 15:16:19 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 4. Dec 28 15:16:19 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Dec 28 15:16:19 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 28 15:16:19 volumio autossh[8668]: port set to 0, monitoring disabled Dec 28 15:16:19 volumio autossh[8668]: starting ssh (count 1) Dec 28 15:16:19 volumio autossh[8668]: ssh child pid is 8671 Dec 28 15:16:19 volumio volumiossh-tunnel[8671]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Dec 28 15:16:19 volumio autossh[8668]: ssh exited prematurely with status 255; autossh exiting Dec 28 15:16:19 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Dec 28 15:16:19 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Dec 28 15:16:19 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 5. Dec 28 15:16:19 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Dec 28 15:16:19 volumio systemd[1]: sshtunnel.service: Start request repeated too quickly. Dec 28 15:16:19 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Dec 28 15:16:19 volumio systemd[1]: Failed to start sshtunnel.service - MyVolumio SSH Tunnel. Dec 28 15:16:20 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 28 15:16:23 volumio volumio[1196]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/touch_display/3.5.9/volumio/bookworm/armhf Dec 28 15:16:25 volumio volumio[1196]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/touch_display/3.5.9/volumio/bookworm/armhf Dec 28 15:16:25 volumio volumio[1196]: info: Folder /tmp/plugins removed Dec 28 15:16:25 volumio volumio[1196]: info: Check plugin dependencies Dec 28 15:16:25 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 15:16:25 volumio volumio[1196]: info: Checking if plugin already exists Dec 28 15:16:25 volumio volumio[1196]: info: An error occurred installing the plugin. Rolling back config Dec 28 15:16:25 volumio volumio[1196]: info: Plugin folders cleanup Dec 28 15:16:25 volumio volumio[1196]: info: Scanning into folder /volumio/app/plugins/ Dec 28 15:16:25 volumio volumio[1196]: info: Scanning category audio_interface Dec 28 15:16:25 volumio volumio[1196]: info: Scanning category miscellanea Dec 28 15:16:25 volumio volumio[1196]: info: Scanning category music_service Dec 28 15:16:25 volumio volumio[1196]: info: Scanning category plugins.json Dec 28 15:16:25 volumio volumio[1196]: info: Scanning category system_controller Dec 28 15:16:25 volumio volumio[1196]: info: Scanning category user_interface Dec 28 15:16:25 volumio volumio[1196]: info: Scanning into folder /data/plugins/ Dec 28 15:16:25 volumio volumio[1196]: info: Scanning category user_interface Dec 28 15:16:25 volumio volumio[1196]: info: Plugin folders cleanup completed Dec 28 15:16:25 volumio volumio[1196]: info: Error: Error Dec 28 15:16:25 volumio volumio[1196]: info: Folder /tmp/plugins removed Dec 28 15:16:25 volumio volumio[1196]: info: Folder /tmp/downloaded_plugin.zip removed Dec 28 15:16:25 volumio volumio[1196]: info: Folder /tmp/downloaded_plugin.zip removed Dec 28 15:16:25 volumio volumio[1196]: info: Folder /data/temp removed Dec 28 15:16:30 volumio volumio[1196]: info: Starting Uninstall of plugin user_interface - touch_display Dec 28 15:16:30 volumio volumio[1196]: info: Uninstalling plugin touch_display Dec 28 15:16:30 volumio sudo[8702]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Dec 28 15:16:30 volumio sudo[8702]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 15:16:30 volumio sudo[8704]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Dec 28 15:16:30 volumio sudo[8704]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 15:16:30 volumio sudo[8706]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Dec 28 15:16:30 volumio sudo[8706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 15:16:30 volumio sudo[8702]: pam_unix(sudo:session): session closed for user root Dec 28 15:16:30 volumio sudo[8710]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Dec 28 15:16:30 volumio sudo[8710]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 15:16:30 volumio volumio[1196]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Dec 28 15:16:30 volumio volumio[1196]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 15:16:30 volumio volumio[1196]: TypeError: Cannot read properties of undefined (reading 'split') Dec 28 15:16:30 volumio volumio[1196]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Dec 28 15:16:30 volumio volumio[1196]: at ChildProcess.exithandler (node:child_process:413:7) Dec 28 15:16:30 volumio volumio[1196]: at ChildProcess.emit (node:events:514:28) Dec 28 15:16:30 volumio volumio[1196]: at maybeClose (node:internal/child_process:1105:16) Dec 28 15:16:30 volumio volumio[1196]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Dec 28 15:16:30 volumio volumio[1196]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 15:16:30 volumio systemd[1]: Reloading. Dec 28 15:16:31 volumio sudo[8744]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-28 15:15' Dec 28 15:16:31 volumio sudo[8744]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"