-- Logs begin at Sat 2026-08-29 09:10:49 UTC, end at Sat 2026-08-29 21:59:58 UTC. -- Aug 29 21:58:00 stream-box-s2-ultra volumio[1050]: info: Removing uri http://stream-dc1.radioparadise.com/rp_192m.ogg from favourites Aug 29 21:58:00 stream-box-s2-ultra volumio[1050]: info: Saving local item radio-favourites Aug 29 21:58:00 stream-box-s2-ultra volumio[1050]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Naim%20Jazz/44ac1ba2-44e7-4aab-b18f-31dab4949168.jpg' Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::ClearQueue Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::stop Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: verbose: UNSET VOLATILE Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::stPlaybackTimer Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::updateTrackBlock Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrackBlock Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::serviceStop Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::serviceStop Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: [1788040688571] ControllerWebradio::stop Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::clearPlayQueue Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::saveQueue Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushQueue Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::addQueueItems Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::addQueueItems Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: Adding Item to queue: http://stream.radioparadise.com/rock-flacm Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::saveQueue Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushQueue Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::updateTrackBlock Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrackBlock Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPlay Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: verbose: UNSET VOLATILE Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::play index 0 Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::stop Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: verbose: UNSET VOLATILE Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::play index undefined Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::startPlaybackTimer Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: [1788040688609] ControllerWebradio::clearAddPlayTrack Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: Updating push state because input changed Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumiosetSourceActivempd Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand stop took 64 milliseconds Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand stop took 28 milliseconds Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 8 milliseconds Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand clear took 6 milliseconds Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:58:08 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand load "http://stream.radioparadise.com/rock-flacm" Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: error: updateQueue error: null Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 452ms Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 447 milliseconds Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/rock-flacm" Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 460ms Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand add "http://stream.radioparadise.com/rock-flacm" took 3 milliseconds Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand play Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 3ms Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand play took 3 milliseconds Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 8 milliseconds Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 6ms Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 5 milliseconds Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":23,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":"Radio Paradise: Rock Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 28ms Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":"Radio Paradise: Rock Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 48ms Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: Updating push state because input changed Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumiosetSourceActivempd Aug 29 21:58:09 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 3ms Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 2 milliseconds Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3459,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Red Hot Chili Peppers - It's Only Natural","artist":"Radio Paradise: Rock Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:12 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 23ms Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::ClearQueue Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::stop Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: verbose: UNSET VOLATILE Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::stPlaybackTimer Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::updateTrackBlock Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrackBlock Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::serviceStop Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::serviceStop Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: [1788040706061] ControllerWebradio::stop Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::clearPlayQueue Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::saveQueue Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushQueue Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::addQueueItems Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::addQueueItems Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: Adding Item to queue: http://listen.jazz88.org/ksds.mp3 Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::saveQueue Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushQueue Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::updateTrackBlock Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrackBlock Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPlay Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: verbose: UNSET VOLATILE Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::play index 0 Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::stop Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: verbose: UNSET VOLATILE Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::play index undefined Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::startPlaybackTimer Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: [1788040706093] ControllerWebradio::clearAddPlayTrack Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: Updating push state because input changed Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumiosetSourceActivempd Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand stop took 83 milliseconds Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand stop took 52 milliseconds Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 7 milliseconds Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand clear took 7 milliseconds Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand load "http://listen.jazz88.org/ksds.mp3" Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: error: updateQueue error: null Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 6ms Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 29 21:58:26 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 17ms Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand add "http://listen.jazz88.org/ksds.mp3" Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand add "http://listen.jazz88.org/ksds.mp3" took 3 milliseconds Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand play Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 6ms Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand play took 5 milliseconds Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 2 milliseconds Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 4 milliseconds Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 25ms Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 17 milliseconds Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 44ms Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: Updating push state because input changed Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumiosetSourceActivempd Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 4ms Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 6 milliseconds Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":603,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:27 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 36ms Aug 29 21:58:30 stream-box-s2-ultra smbd[1194]: [2026/08/29 21:58:30.791722, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 29 21:58:30 stream-box-s2-ultra smbd[1194]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::ClearQueue Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::stop Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: verbose: UNSET VOLATILE Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::stPlaybackTimer Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::updateTrackBlock Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrackBlock Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::serviceStop Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::serviceStop Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: [1788040719896] ControllerWebradio::stop Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::clearPlayQueue Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::saveQueue Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushQueue Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::addQueueItems Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::addQueueItems Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: Adding Item to queue: http://media-sov.musicradio.com:80/RadioXLondonMP3 Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::saveQueue Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushQueue Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::updateTrackBlock Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrackBlock Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPlay Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: verbose: UNSET VOLATILE Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::play index 0 Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::stop Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: verbose: UNSET VOLATILE Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::play index undefined Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::startPlaybackTimer Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: [1788040719927] ControllerWebradio::clearAddPlayTrack Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: Updating push state because input changed Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumiosetSourceActivempd Aug 29 21:58:39 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand stop took 125 milliseconds Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand stop took 106 milliseconds Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 4 milliseconds Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand clear took 3 milliseconds Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand load "http://media-sov.musicradio.com:80/RadioXLondonMP3" Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: error: updateQueue error: null Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 6ms Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 13ms Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand add "http://media-sov.musicradio.com:80/RadioXLondonMP3" Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand add "http://media-sov.musicradio.com:80/RadioXLondonMP3" took 2 milliseconds Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand play Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 3ms Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand play took 2 milliseconds Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 6 milliseconds Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 9ms Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 12 milliseconds Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":92,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 41ms Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 34ms Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 33 milliseconds Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 24 milliseconds Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":484,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 75ms Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: Updating push state because input changed Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumiosetSourceActivempd Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 63ms Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 59 milliseconds Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 58 milliseconds Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":484,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 119ms Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 23 milliseconds Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":484,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 106ms Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 7ms Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 3 milliseconds Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:40 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 35ms Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 4ms Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 3 milliseconds Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2229,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:42 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 46ms Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 11ms Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 10 milliseconds Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3610,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:43 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 32ms Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: VolumeController::SetAlsaVolume+ Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: xcb_connection_has_error() returned true Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 11ms Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 9 milliseconds Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5233,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 44ms Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: VolumeController::SetAlsaVolume+ Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Aug 29 21:58:45 stream-box-s2-ultra volumio[1050]: xcb_connection_has_error() returned true Aug 29 21:58:46 stream-box-s2-ultra volumio[1050]: info: VolumeController::SetAlsaVolume+ Aug 29 21:58:46 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:46 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:46 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 12ms Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 10 milliseconds Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7729,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:47 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 33ms Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 2ms Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 2 milliseconds Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10480,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:50 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 44ms Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 7ms Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 7 milliseconds Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12733,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:52 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 22ms Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 7ms Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 6 milliseconds Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":15728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:55 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 22ms Aug 29 21:58:57 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:57 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:58:57 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:58:57 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 5ms Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 4 milliseconds Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:58:58 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 26ms Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 3ms Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 2 milliseconds Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20233,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:00 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 16ms Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 3ms Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 2 milliseconds Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:03 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 29ms Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 5ms Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 4 milliseconds Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":25480,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:05 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 26ms Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 4ms Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 4 milliseconds Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":28104,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:08 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 25ms Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 18ms Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 12 milliseconds Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30740,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:10 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 37ms Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 7ms Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 6 milliseconds Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":32238,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:12 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 21ms Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 3ms Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 2 milliseconds Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34232,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:14 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 24ms Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 3ms Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 2 milliseconds Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":36739,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:16 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 19ms Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 5ms Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 4 milliseconds Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":39363,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:19 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 26ms Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 3ms Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 2 milliseconds Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":41987,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:22 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 21ms Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::ClearQueue Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::stop Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: verbose: UNSET VOLATILE Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::stPlaybackTimer Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::updateTrackBlock Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrackBlock Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::serviceStop Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::serviceStop Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: [1788040763204] ControllerWebradio::stop Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::clearPlayQueue Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::saveQueue Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushQueue Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::addQueueItems Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::addQueueItems Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: Adding Item to queue: http://stream.srg-ssr.ch/m/rsj/aacp_96 Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::saveQueue Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushQueue Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::updateTrackBlock Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrackBlock Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPlay Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: verbose: UNSET VOLATILE Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::play index 0 Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::stop Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: verbose: UNSET VOLATILE Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::play index undefined Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::startPlaybackTimer Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: [1788040763225] ControllerWebradio::clearAddPlayTrack Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: Updating push state because input changed Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumiosetSourceActivempd Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand stop took 50 milliseconds Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand stop took 29 milliseconds Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 4 milliseconds Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand clear took 4 milliseconds Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand load "http://stream.srg-ssr.ch/m/rsj/aacp_96" Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: error: updateQueue error: null Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 7ms Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 29 21:59:23 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 14ms Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand add "http://stream.srg-ssr.ch/m/rsj/aacp_96" Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand add "http://stream.srg-ssr.ch/m/rsj/aacp_96" took 2 milliseconds Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand play Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 6ms Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand play took 5 milliseconds Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 8 milliseconds Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 6ms Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 3 milliseconds Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"aacp_96","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 29ms Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":85,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"101 Kbps","isStreaming":false,"title":"aacp_96","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 46ms Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: Updating push state because input changed Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumiosetSourceActivempd Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Swiss%20Jazz/e342f1b5-24ba-4cbc-9f17-52a87d308dd1.jpg' Aug 29 21:59:24 stream-box-s2-ultra volumio[1050]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Swiss%20Jazz/e342f1b5-24ba-4cbc-9f17-52a87d308dd1.jpg' Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 3ms Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 4 milliseconds Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":618,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"130 Kbps","isStreaming":false,"title":"Torsten Goods - What Makes The Irish Heart Beat","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:25 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 23ms Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces system playlist update Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: info: Ignoring MPD Status Update Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: info: Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: ---------------------------- MPD announces state update: player Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::getState Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 8ms Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand status took 7 milliseconds Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseState Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: verbose: ControllerMpd::parseTrackInfo Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: info: ControllerMpd::pushState Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::servicePushState Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: info: CorePlayQueue::getTrack 0 Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":26240,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"90 Kbps","isStreaming":false,"title":"Radio Swiss Jazz - www.radioswissjazz.ch","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: verbose: CURRENT POSITION 0 Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState stateService play Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::syncState currentStatus play Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: info: Received an update from plugin. extracting info from payload Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: info: CoreStateMachine::pushState Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::volumioPushState Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:59:50 stream-box-s2-ultra volumio[1050]: info: ------------------------------ 35ms Aug 29 21:59:57 stream-box-s2-ultra volumio[1050]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 21:59:57 stream-box-s2-ultra volumio[1050]: { Error: connect ETIMEDOUT 80.239.138.32:443 Aug 29 21:59:57 stream-box-s2-ultra volumio[1050]: at Object._errnoException (util.js:1022:11) Aug 29 21:59:57 stream-box-s2-ultra volumio[1050]: at _exceptionWithHostPort (util.js:1044:20) Aug 29 21:59:57 stream-box-s2-ultra volumio[1050]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Aug 29 21:59:57 stream-box-s2-ultra volumio[1050]: code: 'ETIMEDOUT', Aug 29 21:59:57 stream-box-s2-ultra volumio[1050]: errno: 'ETIMEDOUT', Aug 29 21:59:57 stream-box-s2-ultra volumio[1050]: syscall: 'connect', Aug 29 21:59:57 stream-box-s2-ultra volumio[1050]: address: '80.239.138.32', Aug 29 21:59:57 stream-box-s2-ultra volumio[1050]: port: 443 } Aug 29 21:59:57 stream-box-s2-ultra volumio[1050]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 21:59:58 stream-box-s2-ultra sudo[31530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-29 21:58 Aug 29 21:59:58 stream-box-s2-ultra sudo[31530]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="6d9442f761618503268d0366db805a252514dec1" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="projectsbus2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Jul 13 17:22:02 CEST 2022" VOLUMIO_VERSION="1.079" VOLUMIO_HARDWARE="pi" VOLUMIO_VENDOR="Pro-Ject Audio Systems" VOLUMIO_MODEL="Stream Box S2 ultra" VOLUMIO_VENDOR_MODEL="Pro-Ject Stream Box S2 ultra" VOLUMIO_HASH="a5127ace113e6b9fcf2a1eaed5431093"