-- Logs begin at Fri 2024-10-25 22:07:10 UTC, end at Fri 2024-10-25 22:20:43 UTC. -- Oct 25 22:19:00 volumio volumio[923]: info: Preload queue cleared Oct 25 22:19:02 volumio volumio[923]: info: Preload queue cleared Oct 25 22:19:02 volumio volumio[923]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 25 22:19:02 volumio volumio[923]: info: CoreStateMachine::ClearQueue Oct 25 22:19:02 volumio volumio[923]: info: CoreStateMachine::stop Oct 25 22:19:02 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:19:02 volumio volumio[923]: info: CoreStateMachine::stPlaybackTimer Oct 25 22:19:02 volumio volumio[923]: info: CoreStateMachine::updateTrackBlock Oct 25 22:19:02 volumio volumio[923]: info: CorePlayQueue::getTrackBlock Oct 25 22:19:02 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:19:02 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:02 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 22:19:02 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:19:02 volumio volumio[923]: info: CoreStateMachine::serviceStop Oct 25 22:19:02 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:02 volumio volumio[923]: info: CoreCommandRouter::serviceStop Oct 25 22:19:02 volumio volumio[923]: info: [1729894742153] ControllerWebradio::stop Oct 25 22:19:02 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 22:19:02 volumio volumio[923]: info: CorePlayQueue::clearPlayQueue Oct 25 22:19:02 volumio volumio[923]: info: CorePlayQueue::saveQueue Oct 25 22:19:02 volumio volumio[923]: info: CoreCommandRouter::volumioPushQueue Oct 25 22:19:02 volumio volumio[923]: info: CoreStateMachine::addQueueItems Oct 25 22:19:02 volumio volumio[923]: info: CorePlayQueue::addQueueItems Oct 25 22:19:02 volumio volumio[923]: info: Preload queue cleared Oct 25 22:19:02 volumio volumio[923]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99489484 Oct 25 22:19:02 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 25 22:19:02 volumio volumio[923]: info: CoreCommandRouter::volumioPushQueue Oct 25 22:19:02 volumio volumio[923]: info: CorePlayQueue::saveQueue Oct 25 22:19:02 volumio volumio[923]: info: CoreStateMachine::updateTrackBlock Oct 25 22:19:02 volumio volumio[923]: info: CorePlayQueue::getTrackBlock Oct 25 22:19:02 volumio volumio[923]: info: CoreCommandRouter::volumioPlay Oct 25 22:19:02 volumio volumio[923]: info: CoreStateMachine::play index 0 Oct 25 22:19:02 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:19:02 volumio volumio[923]: info: CoreStateMachine::stop Oct 25 22:19:02 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:19:02 volumio volumio[923]: info: CoreStateMachine::play index undefined Oct 25 22:19:02 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:19:02 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:02 volumio volumio[923]: info: CoreStateMachine::startPlaybackTimer Oct 25 22:19:02 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:02 volumio volumio[923]: info: [1729894742167] ControllerWebradio::clearAddPlayTrack Oct 25 22:19:02 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 22:19:02 volumio volumio[923]: info: Oct 25 22:19:02 volumio volumio[923]: ---------------------------- MPD announces state update: player Oct 25 22:19:02 volumio volumio[923]: info: sendMpdCommand stop took 86 milliseconds Oct 25 22:19:02 volumio volumio[923]: info: ControllerMpd::getState Oct 25 22:19:02 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand status Oct 25 22:19:02 volumio volumio[923]: info: sendMpdCommand stop took 71 milliseconds Oct 25 22:19:02 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand clear Oct 25 22:19:02 volumio volumio[923]: info: Oct 25 22:19:02 volumio volumio[923]: ---------------------------- MPD announces system playlist update Oct 25 22:19:02 volumio volumio[923]: info: Ignoring MPD Status Update Oct 25 22:19:02 volumio volumio[923]: info: sendMpdCommand status took 3 milliseconds Oct 25 22:19:02 volumio volumio[923]: info: sendMpdCommand clear took 2 milliseconds Oct 25 22:19:02 volumio volumio[923]: verbose: ControllerMpd::parseState Oct 25 22:19:02 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 22:19:02 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99489484" Oct 25 22:19:02 volumio volumio[923]: error: updateQueue error: null Oct 25 22:19:02 volumio volumio[923]: info: ------------------------------ 3ms Oct 25 22:19:02 volumio volumio[923]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 25 22:19:02 volumio volumio[923]: verbose: ControllerMpd::parseTrackInfo Oct 25 22:19:02 volumio volumio[923]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 25 22:19:02 volumio volumio[923]: info: ------------------------------ 8ms Oct 25 22:19:02 volumio volumio[923]: info: Oct 25 22:19:02 volumio volumio[923]: ---------------------------- MPD announces system playlist update Oct 25 22:19:02 volumio volumio[923]: info: Ignoring MPD Status Update Oct 25 22:19:02 volumio volumio[923]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99489484" took 234 milliseconds Oct 25 22:19:02 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 25 22:19:02 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 25 22:19:02 volumio volumio[923]: info: ------------------------------ 1ms Oct 25 22:19:02 volumio volumio[923]: info: sendMpdCommand play took 1 milliseconds Oct 25 22:19:04 volumio volumio[923]: info: Oct 25 22:19:04 volumio volumio[923]: ---------------------------- MPD announces state update: player Oct 25 22:19:04 volumio volumio[923]: info: ControllerMpd::getState Oct 25 22:19:04 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand status Oct 25 22:19:04 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 25 22:19:04 volumio volumio[923]: info: sendMpdCommand status took 4 milliseconds Oct 25 22:19:04 volumio volumio[923]: verbose: ControllerMpd::parseState Oct 25 22:19:04 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 22:19:04 volumio volumio[923]: info: sendMpdCommand clearerror took 1 milliseconds Oct 25 22:19:04 volumio volumio[923]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 25 22:19:04 volumio volumio[923]: verbose: ControllerMpd::parseTrackInfo Oct 25 22:19:04 volumio volumio[923]: info: ControllerMpd::pushState Oct 25 22:19:04 volumio volumio[923]: info: CoreCommandRouter::servicePushState Oct 25 22:19:04 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:19:04 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 22:19:04 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:19:04 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:04 volumio volumio[923]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"Parani Gee Live Radio","album":null,"uri":"https://nws.ninewire.com.au:1040/","trackType":"au:1040/"} Oct 25 22:19:04 volumio volumio[923]: verbose: CURRENT POSITION 0 Oct 25 22:19:04 volumio volumio[923]: info: CoreStateMachine::syncState stateService stop Oct 25 22:19:04 volumio volumio[923]: info: CoreStateMachine::syncState currentStatus stop Oct 25 22:19:04 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:19:04 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:19:04 volumio volumio[923]: info: No code Oct 25 22:19:04 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:19:04 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:19:04 volumio volumio[923]: info: ------------------------------ 22ms Oct 25 22:19:07 volumio volumio[923]: info: Preload queue cleared Oct 25 22:19:07 volumio volumio[923]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 25 22:19:07 volumio volumio[923]: info: CoreStateMachine::ClearQueue Oct 25 22:19:07 volumio volumio[923]: info: CoreStateMachine::stop Oct 25 22:19:07 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:19:07 volumio volumio[923]: info: CorePlayQueue::clearPlayQueue Oct 25 22:19:07 volumio volumio[923]: info: CorePlayQueue::saveQueue Oct 25 22:19:07 volumio volumio[923]: info: CoreCommandRouter::volumioPushQueue Oct 25 22:19:07 volumio volumio[923]: info: CoreStateMachine::addQueueItems Oct 25 22:19:07 volumio volumio[923]: info: CorePlayQueue::addQueueItems Oct 25 22:19:07 volumio volumio[923]: info: Preload queue cleared Oct 25 22:19:07 volumio volumio[923]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99596973 Oct 25 22:19:07 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 25 22:19:07 volumio volumio[923]: info: CoreCommandRouter::volumioPushQueue Oct 25 22:19:07 volumio volumio[923]: info: CorePlayQueue::saveQueue Oct 25 22:19:07 volumio volumio[923]: info: CoreStateMachine::updateTrackBlock Oct 25 22:19:07 volumio volumio[923]: info: CorePlayQueue::getTrackBlock Oct 25 22:19:07 volumio volumio[923]: info: CoreCommandRouter::volumioPlay Oct 25 22:19:07 volumio volumio[923]: info: CoreStateMachine::play index 0 Oct 25 22:19:07 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:19:07 volumio volumio[923]: info: CoreStateMachine::stop Oct 25 22:19:07 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:19:07 volumio volumio[923]: info: CoreStateMachine::play index undefined Oct 25 22:19:07 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:19:07 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:07 volumio volumio[923]: info: CoreStateMachine::startPlaybackTimer Oct 25 22:19:07 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:07 volumio volumio[923]: info: [1729894747901] ControllerWebradio::clearAddPlayTrack Oct 25 22:19:07 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 22:19:07 volumio volumio[923]: info: sendMpdCommand stop took 2 milliseconds Oct 25 22:19:07 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand clear Oct 25 22:19:07 volumio volumio[923]: info: Oct 25 22:19:07 volumio volumio[923]: ---------------------------- MPD announces system playlist update Oct 25 22:19:07 volumio volumio[923]: info: Ignoring MPD Status Update Oct 25 22:19:07 volumio volumio[923]: info: sendMpdCommand clear took 1 milliseconds Oct 25 22:19:07 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99596973" Oct 25 22:19:07 volumio volumio[923]: error: updateQueue error: null Oct 25 22:19:07 volumio volumio[923]: info: ------------------------------ 1ms Oct 25 22:19:08 volumio volumio[923]: info: Oct 25 22:19:08 volumio volumio[923]: ---------------------------- MPD announces system playlist update Oct 25 22:19:08 volumio volumio[923]: info: Ignoring MPD Status Update Oct 25 22:19:08 volumio volumio[923]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99596973" took 246 milliseconds Oct 25 22:19:08 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 25 22:19:08 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 25 22:19:08 volumio volumio[923]: info: ------------------------------ 2ms Oct 25 22:19:08 volumio volumio[923]: info: sendMpdCommand play took 1 milliseconds Oct 25 22:19:11 volumio volumio[923]: info: Oct 25 22:19:11 volumio volumio[923]: ---------------------------- MPD announces state update: player Oct 25 22:19:11 volumio volumio[923]: info: ControllerMpd::getState Oct 25 22:19:11 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand status Oct 25 22:19:11 volumio volumio[923]: info: sendMpdCommand status took 268 milliseconds Oct 25 22:19:11 volumio volumio[923]: verbose: ControllerMpd::parseState Oct 25 22:19:11 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 22:19:11 volumio volumio[923]: info: Oct 25 22:19:11 volumio volumio[923]: ---------------------------- MPD announces system playlist update Oct 25 22:19:11 volumio volumio[923]: info: Ignoring MPD Status Update Oct 25 22:19:11 volumio volumio[923]: info: Oct 25 22:19:11 volumio volumio[923]: ---------------------------- MPD announces state update: player Oct 25 22:19:11 volumio volumio[923]: info: ControllerMpd::getState Oct 25 22:19:11 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand status Oct 25 22:19:11 volumio volumio[923]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 25 22:19:11 volumio volumio[923]: verbose: ControllerMpd::parseTrackInfo Oct 25 22:19:11 volumio volumio[923]: info: ControllerMpd::pushState Oct 25 22:19:11 volumio volumio[923]: info: CoreCommandRouter::servicePushState Oct 25 22:19:11 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:11 volumio volumio[923]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"57 Kbps","isStreaming":false,"title":"","artist":"Radyo Arabesk Alemi","album":null,"uri":"https://panel.radyomhd.com:9300/","trackType":"com:9300/"} Oct 25 22:19:11 volumio volumio[923]: verbose: CURRENT POSITION 0 Oct 25 22:19:11 volumio volumio[923]: info: CoreStateMachine::syncState stateService play Oct 25 22:19:11 volumio volumio[923]: info: CoreStateMachine::syncState currentStatus stop Oct 25 22:19:11 volumio volumio[923]: info: ------------------------------ 273ms Oct 25 22:19:11 volumio volumio[923]: info: ------------------------------ 4ms Oct 25 22:19:11 volumio volumio[923]: info: sendMpdCommand status took 3 milliseconds Oct 25 22:19:11 volumio volumio[923]: verbose: ControllerMpd::parseState Oct 25 22:19:11 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 22:19:11 volumio volumio[923]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 25 22:19:11 volumio volumio[923]: verbose: ControllerMpd::parseTrackInfo Oct 25 22:19:11 volumio volumio[923]: info: ControllerMpd::pushState Oct 25 22:19:11 volumio volumio[923]: info: CoreCommandRouter::servicePushState Oct 25 22:19:11 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:11 volumio volumio[923]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"57 Kbps","isStreaming":false,"title":"","artist":"Radyo Arabesk Alemi","album":null,"uri":"https://panel.radyomhd.com:9300/","trackType":"com:9300/"} Oct 25 22:19:11 volumio volumio[923]: verbose: CURRENT POSITION 0 Oct 25 22:19:11 volumio volumio[923]: info: CoreStateMachine::syncState stateService play Oct 25 22:19:11 volumio volumio[923]: info: CoreStateMachine::syncState currentStatus play Oct 25 22:19:11 volumio volumio[923]: info: Received an update from plugin. extracting info from payload Oct 25 22:19:11 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 25 22:19:11 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:19:11 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 22:19:11 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:19:11 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:19:11 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:19:11 volumio volumio[923]: info: ------------------------------ 17ms Oct 25 22:19:14 volumio volumio[923]: info: Oct 25 22:19:14 volumio volumio[923]: ---------------------------- MPD announces system playlist update Oct 25 22:19:14 volumio volumio[923]: info: Ignoring MPD Status Update Oct 25 22:19:14 volumio volumio[923]: info: Oct 25 22:19:14 volumio volumio[923]: ---------------------------- MPD announces state update: player Oct 25 22:19:14 volumio volumio[923]: info: ControllerMpd::getState Oct 25 22:19:14 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand status Oct 25 22:19:14 volumio volumio[923]: info: ------------------------------ 1ms Oct 25 22:19:14 volumio volumio[923]: info: sendMpdCommand status took 1 milliseconds Oct 25 22:19:14 volumio volumio[923]: verbose: ControllerMpd::parseState Oct 25 22:19:14 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 22:19:14 volumio volumio[923]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 25 22:19:14 volumio volumio[923]: verbose: ControllerMpd::parseTrackInfo Oct 25 22:19:14 volumio volumio[923]: info: ControllerMpd::pushState Oct 25 22:19:14 volumio volumio[923]: info: CoreCommandRouter::servicePushState Oct 25 22:19:14 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:14 volumio volumio[923]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3358,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"57 Kbps","isStreaming":false,"title":"Ceylan - Sacina Kar Yagdi","artist":"Radyo Arabesk Alemi","album":null,"uri":"https://panel.radyomhd.com:9300/","trackType":"com:9300/"} Oct 25 22:19:14 volumio volumio[923]: verbose: CURRENT POSITION 0 Oct 25 22:19:14 volumio volumio[923]: info: CoreStateMachine::syncState stateService play Oct 25 22:19:14 volumio volumio[923]: info: CoreStateMachine::syncState currentStatus play Oct 25 22:19:14 volumio volumio[923]: info: Received an update from plugin. extracting info from payload Oct 25 22:19:14 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 25 22:19:14 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:19:14 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 22:19:14 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:19:14 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:19:14 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:19:14 volumio volumio[923]: info: ------------------------------ 16ms Oct 25 22:19:18 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 25 22:19:19 volumio volumio[923]: info: Preload queue cleared Oct 25 22:19:20 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 25 22:19:21 volumio volumio[923]: info: Preload queue cleared Oct 25 22:19:23 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 25 22:19:23 volumio volumio[923]: info: Preload queue cleared Oct 25 22:19:25 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 25 22:19:28 volumio volumio[923]: info: Preload queue cleared Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@94720 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@113883 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@113892 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@113885 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@113890 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@113887 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@113882 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@113891 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@113881 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@113884 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@113894 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@113893 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103862 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103870 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103854 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103866 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103848 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103859 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103868 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103878 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103872 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103863 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103871 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103857 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103875 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103858 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103873 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103850 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103853 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103874 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103864 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103860 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103849 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103869 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103851 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103876 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103856 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103867 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@88692 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104064 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104057 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104059 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104048 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104060 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104065 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104055 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103983 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103987 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103986 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103994 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103982 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103988 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103977 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103985 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103990 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103981 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@88638 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@88637 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104075 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104082 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104083 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104076 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104079 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104069 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104068 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104072 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104081 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104118 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104109 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104106 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104115 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104107 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104103 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104110 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104105 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@88651 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104017 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104021 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104011 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104018 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104016 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104019 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104024 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104025 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104087 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104096 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104089 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104094 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104091 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104086 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104095 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104085 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104088 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104098 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104097 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103998 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104009 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104003 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103999 Oct 25 22:19:28 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103997 Oct 25 22:19:28 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@94720 in service upnp_browser Oct 25 22:19:28 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@113883 in service upnp_browser Oct 25 22:19:28 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@113892 in service upnp_browser Oct 25 22:19:28 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@113885 in service upnp_browser Oct 25 22:19:28 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@113890 in service upnp_browser Oct 25 22:19:28 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@113887 in service upnp_browser Oct 25 22:19:28 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@113882 in service upnp_browser Oct 25 22:19:28 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@113891 in service upnp_browser Oct 25 22:19:28 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@113881 in service upnp_browser Oct 25 22:19:28 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@113884 in service upnp_browser Oct 25 22:19:28 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@113894 in service upnp_browser Oct 25 22:19:28 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@113893 in service upnp_browser Oct 25 22:19:28 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103862 in service upnp_browser Oct 25 22:19:28 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103870 in service upnp_browser Oct 25 22:19:28 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103854 in service upnp_browser Oct 25 22:19:29 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103866 in service upnp_browser Oct 25 22:19:29 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103848 in service upnp_browser Oct 25 22:19:29 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103859 in service upnp_browser Oct 25 22:19:29 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103868 in service upnp_browser Oct 25 22:19:29 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103878 in service upnp_browser Oct 25 22:19:29 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103872 in service upnp_browser Oct 25 22:19:29 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103863 in service upnp_browser Oct 25 22:19:29 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103871 in service upnp_browser Oct 25 22:19:29 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103857 in service upnp_browser Oct 25 22:19:29 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103875 in service upnp_browser Oct 25 22:19:29 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103858 in service upnp_browser Oct 25 22:19:29 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103873 in service upnp_browser Oct 25 22:19:29 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103850 in service upnp_browser Oct 25 22:19:29 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103853 in service upnp_browser Oct 25 22:19:29 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103874 in service upnp_browser Oct 25 22:19:29 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103864 in service upnp_browser Oct 25 22:19:29 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103860 in service upnp_browser Oct 25 22:19:29 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103849 in service upnp_browser Oct 25 22:19:29 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103869 in service upnp_browser Oct 25 22:19:29 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103851 in service upnp_browser Oct 25 22:19:30 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103876 in service upnp_browser Oct 25 22:19:30 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103856 in service upnp_browser Oct 25 22:19:30 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103867 in service upnp_browser Oct 25 22:19:30 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@88692 in service upnp_browser Oct 25 22:19:30 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104064 in service upnp_browser Oct 25 22:19:30 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104057 in service upnp_browser Oct 25 22:19:30 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104059 in service upnp_browser Oct 25 22:19:30 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104048 in service upnp_browser Oct 25 22:19:30 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104060 in service upnp_browser Oct 25 22:19:30 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104065 in service upnp_browser Oct 25 22:19:30 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104055 in service upnp_browser Oct 25 22:19:30 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103983 in service upnp_browser Oct 25 22:19:30 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103987 in service upnp_browser Oct 25 22:19:30 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103986 in service upnp_browser Oct 25 22:19:30 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103994 in service upnp_browser Oct 25 22:19:30 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103982 in service upnp_browser Oct 25 22:19:30 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103988 in service upnp_browser Oct 25 22:19:30 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103977 in service upnp_browser Oct 25 22:19:30 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103985 in service upnp_browser Oct 25 22:19:30 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103990 in service upnp_browser Oct 25 22:19:31 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103981 in service upnp_browser Oct 25 22:19:31 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@88638 in service upnp_browser Oct 25 22:19:31 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@88637 in service upnp_browser Oct 25 22:19:31 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104075 in service upnp_browser Oct 25 22:19:31 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104082 in service upnp_browser Oct 25 22:19:31 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104083 in service upnp_browser Oct 25 22:19:31 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104076 in service upnp_browser Oct 25 22:19:31 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104079 in service upnp_browser Oct 25 22:19:31 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104069 in service upnp_browser Oct 25 22:19:31 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104068 in service upnp_browser Oct 25 22:19:31 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104072 in service upnp_browser Oct 25 22:19:31 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104081 in service upnp_browser Oct 25 22:19:31 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104118 in service upnp_browser Oct 25 22:19:31 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104109 in service upnp_browser Oct 25 22:19:31 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104106 in service upnp_browser Oct 25 22:19:31 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104115 in service upnp_browser Oct 25 22:19:31 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104107 in service upnp_browser Oct 25 22:19:31 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104103 in service upnp_browser Oct 25 22:19:31 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104110 in service upnp_browser Oct 25 22:19:31 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104105 in service upnp_browser Oct 25 22:19:32 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@88651 in service upnp_browser Oct 25 22:19:32 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104017 in service upnp_browser Oct 25 22:19:32 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104021 in service upnp_browser Oct 25 22:19:32 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104011 in service upnp_browser Oct 25 22:19:32 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104018 in service upnp_browser Oct 25 22:19:32 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104016 in service upnp_browser Oct 25 22:19:32 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104019 in service upnp_browser Oct 25 22:19:32 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104024 in service upnp_browser Oct 25 22:19:32 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104025 in service upnp_browser Oct 25 22:19:32 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104087 in service upnp_browser Oct 25 22:19:32 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104096 in service upnp_browser Oct 25 22:19:32 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104089 in service upnp_browser Oct 25 22:19:32 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104094 in service upnp_browser Oct 25 22:19:32 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104091 in service upnp_browser Oct 25 22:19:32 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104086 in service upnp_browser Oct 25 22:19:32 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104095 in service upnp_browser Oct 25 22:19:32 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104085 in service upnp_browser Oct 25 22:19:32 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104088 in service upnp_browser Oct 25 22:19:32 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104098 in service upnp_browser Oct 25 22:19:32 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104097 in service upnp_browser Oct 25 22:19:33 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103998 in service upnp_browser Oct 25 22:19:33 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104009 in service upnp_browser Oct 25 22:19:33 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@104003 in service upnp_browser Oct 25 22:19:33 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103999 in service upnp_browser Oct 25 22:19:33 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@23$@103997 in service upnp_browser Oct 25 22:19:34 volumio volumio[923]: info: Preload queue cleared Oct 25 22:19:34 volumio volumio[923]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 25 22:19:34 volumio volumio[923]: info: CoreStateMachine::ClearQueue Oct 25 22:19:34 volumio volumio[923]: info: CoreStateMachine::stop Oct 25 22:19:34 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:19:34 volumio volumio[923]: info: CoreStateMachine::stPlaybackTimer Oct 25 22:19:34 volumio volumio[923]: info: CoreStateMachine::updateTrackBlock Oct 25 22:19:34 volumio volumio[923]: info: CorePlayQueue::getTrackBlock Oct 25 22:19:34 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:19:34 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:34 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 22:19:34 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:19:34 volumio volumio[923]: info: CoreStateMachine::serviceStop Oct 25 22:19:34 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:34 volumio volumio[923]: info: CoreCommandRouter::serviceStop Oct 25 22:19:34 volumio volumio[923]: info: [1729894774422] ControllerWebradio::stop Oct 25 22:19:34 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 22:19:34 volumio volumio[923]: info: CorePlayQueue::clearPlayQueue Oct 25 22:19:34 volumio volumio[923]: info: CorePlayQueue::saveQueue Oct 25 22:19:34 volumio volumio[923]: info: CoreCommandRouter::volumioPushQueue Oct 25 22:19:34 volumio volumio[923]: info: CoreStateMachine::addQueueItems Oct 25 22:19:34 volumio volumio[923]: info: CorePlayQueue::addQueueItems Oct 25 22:19:34 volumio volumio[923]: info: Preload queue cleared Oct 25 22:19:34 volumio volumio[923]: info: Adding Item to queue: upnp/folder/http://192.168.0.9:50001/ContentDirectory/control@23 Oct 25 22:19:34 volumio volumio[923]: info: Exploding uri upnp/folder/http://192.168.0.9:50001/ContentDirectory/control@23 in service upnp_browser Oct 25 22:19:34 volumio volumio[923]: info: Oct 25 22:19:34 volumio volumio[923]: ---------------------------- MPD announces state update: player Oct 25 22:19:34 volumio volumio[923]: info: sendMpdCommand stop took 92 milliseconds Oct 25 22:19:34 volumio volumio[923]: info: ControllerMpd::getState Oct 25 22:19:34 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand status Oct 25 22:19:34 volumio volumio[923]: info: sendMpdCommand status took 1 milliseconds Oct 25 22:19:34 volumio volumio[923]: verbose: ControllerMpd::parseState Oct 25 22:19:34 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 22:19:34 volumio volumio[923]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 25 22:19:34 volumio volumio[923]: verbose: ControllerMpd::parseTrackInfo Oct 25 22:19:34 volumio volumio[923]: info: ControllerMpd::pushState Oct 25 22:19:34 volumio volumio[923]: info: CoreCommandRouter::servicePushState Oct 25 22:19:34 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:19:34 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:34 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 22:19:34 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:19:34 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:34 volumio volumio[923]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ceylan - Sacina Kar Yagdi","artist":"Radyo Arabesk Alemi","album":null,"uri":"https://panel.radyomhd.com:9300/","trackType":"com:9300/"} Oct 25 22:19:34 volumio volumio[923]: verbose: CURRENT POSITION 0 Oct 25 22:19:34 volumio volumio[923]: info: CoreStateMachine::syncState stateService stop Oct 25 22:19:34 volumio volumio[923]: info: CoreStateMachine::syncState currentStatus stop Oct 25 22:19:34 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:19:34 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:34 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:19:34 volumio volumio[923]: info: No code Oct 25 22:19:34 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:19:34 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:34 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:19:34 volumio volumio[923]: info: ------------------------------ 20ms Oct 25 22:19:35 volumio volumio[923]: info: CoreCommandRouter::volumioPushQueue Oct 25 22:19:35 volumio volumio[923]: info: CorePlayQueue::saveQueue Oct 25 22:19:35 volumio volumio[923]: info: CoreStateMachine::updateTrackBlock Oct 25 22:19:35 volumio volumio[923]: info: CorePlayQueue::getTrackBlock Oct 25 22:19:35 volumio volumio[923]: info: CoreCommandRouter::volumioPlay Oct 25 22:19:35 volumio volumio[923]: info: CoreStateMachine::play index 0 Oct 25 22:19:35 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:19:35 volumio volumio[923]: info: CoreStateMachine::stop Oct 25 22:19:35 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:19:35 volumio volumio[923]: info: CoreStateMachine::play index undefined Oct 25 22:19:35 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:19:35 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:35 volumio volumio[923]: info: CoreStateMachine::startPlaybackTimer Oct 25 22:19:35 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:35 volumio volumio[923]: info: [1729894775751] ControllerUPNPBrowser::clearAddPlayTrack Oct 25 22:19:35 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 22:19:35 volumio volumio[923]: info: sendMpdCommand stop took 44 milliseconds Oct 25 22:19:35 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand clear Oct 25 22:19:35 volumio volumio[923]: info: Oct 25 22:19:35 volumio volumio[923]: ---------------------------- MPD announces system playlist update Oct 25 22:19:35 volumio volumio[923]: info: Ignoring MPD Status Update Oct 25 22:19:35 volumio volumio[923]: info: sendMpdCommand clear took 1 milliseconds Oct 25 22:19:35 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.9:50002/transcoder/genericoder.cgi/id=94720.wav?format=WAV&downsample=true" Oct 25 22:19:35 volumio volumio[923]: error: updateQueue error: null Oct 25 22:19:35 volumio volumio[923]: info: ------------------------------ 2ms Oct 25 22:19:36 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.9:50002/transcoder/genericoder.cgi/id=94720.wav?format=WAV&downsample=true" Oct 25 22:19:36 volumio volumio[923]: info: Oct 25 22:19:36 volumio volumio[923]: ---------------------------- MPD announces system playlist update Oct 25 22:19:36 volumio volumio[923]: info: Ignoring MPD Status Update Oct 25 22:19:36 volumio volumio[923]: info: sendMpdCommand add "http://192.168.0.9:50002/transcoder/genericoder.cgi/id=94720.wav?format=WAV&downsample=true" took 0 milliseconds Oct 25 22:19:36 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 25 22:19:36 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 25 22:19:36 volumio volumio[923]: info: ------------------------------ 1ms Oct 25 22:19:36 volumio volumio[923]: info: sendMpdCommand play took 1 milliseconds Oct 25 22:19:36 volumio volumio[923]: info: Oct 25 22:19:36 volumio volumio[923]: ---------------------------- MPD announces state update: player Oct 25 22:19:36 volumio volumio[923]: info: ControllerMpd::getState Oct 25 22:19:36 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand status Oct 25 22:19:37 volumio volumio[923]: info: sendMpdCommand status took 275 milliseconds Oct 25 22:19:37 volumio volumio[923]: verbose: ControllerMpd::parseState Oct 25 22:19:37 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 22:19:37 volumio volumio[923]: info: Oct 25 22:19:37 volumio volumio[923]: ---------------------------- MPD announces state update: player Oct 25 22:19:37 volumio volumio[923]: info: ControllerMpd::getState Oct 25 22:19:37 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand status Oct 25 22:19:37 volumio volumio[923]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 25 22:19:37 volumio volumio[923]: verbose: ControllerMpd::parseTrackInfo Oct 25 22:19:37 volumio volumio[923]: info: ControllerMpd::pushState Oct 25 22:19:37 volumio volumio[923]: info: CoreCommandRouter::servicePushState Oct 25 22:19:37 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:37 volumio volumio[923]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":4518,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=94720.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.0.9:50002/transcoder/genericoder.cgi/id=94720.wav?format=WAV&downsample=true","trackType":""} Oct 25 22:19:37 volumio volumio[923]: verbose: CURRENT POSITION 0 Oct 25 22:19:37 volumio volumio[923]: info: CoreStateMachine::syncState stateService play Oct 25 22:19:37 volumio volumio[923]: info: CoreStateMachine::syncState currentStatus stop Oct 25 22:19:37 volumio volumio[923]: info: ------------------------------ 278ms Oct 25 22:19:37 volumio volumio[923]: info: sendMpdCommand status took 3 milliseconds Oct 25 22:19:37 volumio volumio[923]: verbose: ControllerMpd::parseState Oct 25 22:19:37 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 22:19:37 volumio volumio[923]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 25 22:19:37 volumio volumio[923]: verbose: ControllerMpd::parseTrackInfo Oct 25 22:19:37 volumio volumio[923]: info: ControllerMpd::pushState Oct 25 22:19:37 volumio volumio[923]: info: CoreCommandRouter::servicePushState Oct 25 22:19:37 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:37 volumio volumio[923]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":4518,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=94720.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.0.9:50002/transcoder/genericoder.cgi/id=94720.wav?format=WAV&downsample=true","trackType":""} Oct 25 22:19:37 volumio volumio[923]: verbose: CURRENT POSITION 0 Oct 25 22:19:37 volumio volumio[923]: info: CoreStateMachine::syncState stateService play Oct 25 22:19:37 volumio volumio[923]: info: CoreStateMachine::syncState currentStatus play Oct 25 22:19:37 volumio volumio[923]: info: Received an update from plugin. extracting info from payload Oct 25 22:19:37 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:19:37 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 22:19:37 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:19:37 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:19:37 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:19:37 volumio volumio[923]: info: ------------------------------ 15ms Oct 25 22:19:41 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 25 22:19:41 volumio volumio[923]: info: Preload queue cleared Oct 25 22:19:44 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 25 22:19:44 volumio volumio[923]: info: Preload queue cleared Oct 25 22:19:47 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 25 22:19:47 volumio volumio[923]: info: Preload queue cleared Oct 25 22:19:52 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 25 22:19:52 volumio volumio[923]: info: Preload queue cleared Oct 25 22:19:52 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104486 Oct 25 22:19:52 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104483 Oct 25 22:19:52 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104488 Oct 25 22:19:52 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104480 Oct 25 22:19:52 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104484 Oct 25 22:19:52 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104482 Oct 25 22:19:52 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104481 Oct 25 22:19:52 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104489 Oct 25 22:19:52 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104487 Oct 25 22:19:52 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104485 Oct 25 22:19:52 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104490 Oct 25 22:19:52 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104486 in service upnp_browser Oct 25 22:19:52 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104483 in service upnp_browser Oct 25 22:19:52 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104488 in service upnp_browser Oct 25 22:19:52 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104480 in service upnp_browser Oct 25 22:19:52 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104484 in service upnp_browser Oct 25 22:19:52 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104482 in service upnp_browser Oct 25 22:19:52 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104481 in service upnp_browser Oct 25 22:19:52 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104489 in service upnp_browser Oct 25 22:19:52 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104487 in service upnp_browser Oct 25 22:19:52 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104485 in service upnp_browser Oct 25 22:19:52 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104490 in service upnp_browser Oct 25 22:19:58 volumio volumio[923]: info: Preload queue cleared Oct 25 22:19:58 volumio volumio[923]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 25 22:19:58 volumio volumio[923]: info: CoreStateMachine::ClearQueue Oct 25 22:19:58 volumio volumio[923]: info: CoreStateMachine::stop Oct 25 22:19:58 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:19:58 volumio volumio[923]: info: CoreStateMachine::stPlaybackTimer Oct 25 22:19:58 volumio volumio[923]: info: CoreStateMachine::updateTrackBlock Oct 25 22:19:58 volumio volumio[923]: info: CorePlayQueue::getTrackBlock Oct 25 22:19:58 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:19:58 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:58 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 22:19:58 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:19:58 volumio volumio[923]: info: CoreStateMachine::serviceStop Oct 25 22:19:58 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:58 volumio volumio[923]: info: CoreCommandRouter::serviceStop Oct 25 22:19:58 volumio volumio[923]: info: [1729894798024] ControllerUPNPBrowser::stop Oct 25 22:19:58 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 22:19:58 volumio volumio[923]: info: CorePlayQueue::clearPlayQueue Oct 25 22:19:58 volumio volumio[923]: info: CorePlayQueue::saveQueue Oct 25 22:19:58 volumio volumio[923]: info: CoreCommandRouter::volumioPushQueue Oct 25 22:19:58 volumio volumio[923]: info: CoreStateMachine::addQueueItems Oct 25 22:19:58 volumio volumio[923]: info: CorePlayQueue::addQueueItems Oct 25 22:19:58 volumio volumio[923]: info: Preload queue cleared Oct 25 22:19:58 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104486 Oct 25 22:19:58 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104486 Oct 25 22:19:58 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104483 Oct 25 22:19:58 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104483 Oct 25 22:19:58 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104488 Oct 25 22:19:58 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104488 Oct 25 22:19:58 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104480 Oct 25 22:19:58 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104480 Oct 25 22:19:58 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104484 Oct 25 22:19:58 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104484 Oct 25 22:19:58 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104482 Oct 25 22:19:58 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104482 Oct 25 22:19:58 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104481 Oct 25 22:19:58 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104481 Oct 25 22:19:58 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104489 Oct 25 22:19:58 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104489 Oct 25 22:19:58 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104487 Oct 25 22:19:58 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104487 Oct 25 22:19:58 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104485 Oct 25 22:19:58 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104485 Oct 25 22:19:58 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104490 Oct 25 22:19:58 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@104490 Oct 25 22:19:58 volumio volumio[923]: info: CoreCommandRouter::volumioPushQueue Oct 25 22:19:58 volumio volumio[923]: info: CorePlayQueue::saveQueue Oct 25 22:19:58 volumio volumio[923]: info: CoreStateMachine::updateTrackBlock Oct 25 22:19:58 volumio volumio[923]: info: CorePlayQueue::getTrackBlock Oct 25 22:19:58 volumio volumio[923]: info: CoreCommandRouter::volumioPlay Oct 25 22:19:58 volumio volumio[923]: info: CoreStateMachine::play index 1 Oct 25 22:19:58 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:19:58 volumio volumio[923]: info: CoreStateMachine::stop Oct 25 22:19:58 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:19:58 volumio volumio[923]: info: CoreStateMachine::play index undefined Oct 25 22:19:58 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:19:58 volumio volumio[923]: info: CorePlayQueue::getTrack 1 Oct 25 22:19:58 volumio volumio[923]: info: Oct 25 22:19:58 volumio volumio[923]: ---------------------------- MPD announces state update: player Oct 25 22:19:58 volumio volumio[923]: info: sendMpdCommand stop took 94 milliseconds Oct 25 22:19:58 volumio volumio[923]: info: ControllerMpd::getState Oct 25 22:19:58 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand status Oct 25 22:19:58 volumio volumio[923]: info: sendMpdCommand status took 1 milliseconds Oct 25 22:19:58 volumio volumio[923]: verbose: ControllerMpd::parseState Oct 25 22:19:58 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 22:19:58 volumio volumio[923]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 25 22:19:58 volumio volumio[923]: verbose: ControllerMpd::parseTrackInfo Oct 25 22:19:58 volumio volumio[923]: info: ControllerMpd::pushState Oct 25 22:19:58 volumio volumio[923]: info: CoreCommandRouter::servicePushState Oct 25 22:19:58 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:19:58 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:58 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 22:19:58 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:19:58 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:58 volumio volumio[923]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=94720.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.0.9:50002/transcoder/genericoder.cgi/id=94720.wav?format=WAV&downsample=true","trackType":""} Oct 25 22:19:58 volumio volumio[923]: verbose: CURRENT POSITION 0 Oct 25 22:19:58 volumio volumio[923]: info: CoreStateMachine::syncState stateService stop Oct 25 22:19:58 volumio volumio[923]: info: CoreStateMachine::syncState currentStatus stop Oct 25 22:19:58 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:19:58 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:58 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:19:58 volumio volumio[923]: info: No code Oct 25 22:19:58 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:19:58 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:19:58 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:19:58 volumio volumio[923]: info: ------------------------------ 18ms Oct 25 22:20:02 volumio volumio[923]: info: Preload queue cleared Oct 25 22:20:02 volumio volumio[923]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 25 22:20:02 volumio volumio[923]: info: CoreStateMachine::ClearQueue Oct 25 22:20:02 volumio volumio[923]: info: CoreStateMachine::stop Oct 25 22:20:02 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:20:02 volumio volumio[923]: info: CorePlayQueue::clearPlayQueue Oct 25 22:20:02 volumio volumio[923]: info: CorePlayQueue::saveQueue Oct 25 22:20:02 volumio volumio[923]: info: CoreCommandRouter::volumioPushQueue Oct 25 22:20:02 volumio volumio[923]: info: CoreStateMachine::addQueueItems Oct 25 22:20:02 volumio volumio[923]: info: CorePlayQueue::addQueueItems Oct 25 22:20:02 volumio volumio[923]: info: Preload queue cleared Oct 25 22:20:02 volumio volumio[923]: info: Adding Item to queue: upnp/folder/http://192.168.0.9:50001/ContentDirectory/control@22$17849 Oct 25 22:20:02 volumio volumio[923]: info: Exploding uri upnp/folder/http://192.168.0.9:50001/ContentDirectory/control@22$17849 in service upnp_browser Oct 25 22:20:02 volumio volumio[923]: info: CoreCommandRouter::volumioPushQueue Oct 25 22:20:02 volumio volumio[923]: info: CorePlayQueue::saveQueue Oct 25 22:20:02 volumio volumio[923]: info: CoreStateMachine::updateTrackBlock Oct 25 22:20:02 volumio volumio[923]: info: CorePlayQueue::getTrackBlock Oct 25 22:20:02 volumio volumio[923]: info: CoreCommandRouter::volumioPlay Oct 25 22:20:02 volumio volumio[923]: info: CoreStateMachine::play index 0 Oct 25 22:20:02 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:20:02 volumio volumio[923]: info: CoreStateMachine::stop Oct 25 22:20:02 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:20:02 volumio volumio[923]: info: CoreStateMachine::play index undefined Oct 25 22:20:02 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:20:02 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:20:02 volumio volumio[923]: info: CoreStateMachine::startPlaybackTimer Oct 25 22:20:02 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:20:02 volumio volumio[923]: info: [1729894802383] ControllerUPNPBrowser::clearAddPlayTrack Oct 25 22:20:02 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 22:20:02 volumio volumio[923]: info: sendMpdCommand stop took 3 milliseconds Oct 25 22:20:02 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand clear Oct 25 22:20:02 volumio volumio[923]: info: Oct 25 22:20:02 volumio volumio[923]: ---------------------------- MPD announces system playlist update Oct 25 22:20:02 volumio volumio[923]: info: Ignoring MPD Status Update Oct 25 22:20:02 volumio volumio[923]: info: sendMpdCommand clear took 1 milliseconds Oct 25 22:20:02 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.9:50002/m/NDLNA/104486.wav" Oct 25 22:20:02 volumio volumio[923]: error: updateQueue error: null Oct 25 22:20:02 volumio volumio[923]: info: ------------------------------ 2ms Oct 25 22:20:02 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.9:50002/m/NDLNA/104486.wav" Oct 25 22:20:02 volumio volumio[923]: info: Oct 25 22:20:02 volumio volumio[923]: ---------------------------- MPD announces system playlist update Oct 25 22:20:02 volumio volumio[923]: info: Ignoring MPD Status Update Oct 25 22:20:02 volumio volumio[923]: info: sendMpdCommand add "http://192.168.0.9:50002/m/NDLNA/104486.wav" took 1 milliseconds Oct 25 22:20:02 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 25 22:20:02 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 25 22:20:02 volumio volumio[923]: info: ------------------------------ 4ms Oct 25 22:20:02 volumio volumio[923]: info: sendMpdCommand play took 2 milliseconds Oct 25 22:20:02 volumio volumio[923]: info: Oct 25 22:20:02 volumio volumio[923]: ---------------------------- MPD announces state update: player Oct 25 22:20:02 volumio volumio[923]: info: ControllerMpd::getState Oct 25 22:20:02 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand status Oct 25 22:20:02 volumio volumio[923]: info: Oct 25 22:20:02 volumio volumio[923]: ---------------------------- MPD announces state update: player Oct 25 22:20:02 volumio volumio[923]: info: sendMpdCommand status took 262 milliseconds Oct 25 22:20:02 volumio volumio[923]: info: ControllerMpd::getState Oct 25 22:20:02 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand status Oct 25 22:20:02 volumio volumio[923]: verbose: ControllerMpd::parseState Oct 25 22:20:02 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 22:20:02 volumio volumio[923]: info: sendMpdCommand status took 2 milliseconds Oct 25 22:20:02 volumio volumio[923]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 25 22:20:02 volumio volumio[923]: verbose: ControllerMpd::parseState Oct 25 22:20:02 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 22:20:02 volumio volumio[923]: verbose: ControllerMpd::parseTrackInfo Oct 25 22:20:02 volumio volumio[923]: info: ControllerMpd::pushState Oct 25 22:20:02 volumio volumio[923]: info: CoreCommandRouter::servicePushState Oct 25 22:20:02 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:20:02 volumio volumio[923]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"104486.wav","artist":null,"album":null,"uri":"http://192.168.0.9:50002/m/NDLNA/104486.wav","trackType":"wav"} Oct 25 22:20:02 volumio volumio[923]: verbose: CURRENT POSITION 0 Oct 25 22:20:02 volumio volumio[923]: info: CoreStateMachine::syncState stateService play Oct 25 22:20:02 volumio volumio[923]: info: CoreStateMachine::syncState currentStatus stop Oct 25 22:20:02 volumio volumio[923]: info: ------------------------------ 270ms Oct 25 22:20:02 volumio volumio[923]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 25 22:20:02 volumio volumio[923]: verbose: ControllerMpd::parseTrackInfo Oct 25 22:20:02 volumio volumio[923]: info: ControllerMpd::pushState Oct 25 22:20:02 volumio volumio[923]: info: CoreCommandRouter::servicePushState Oct 25 22:20:02 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:20:02 volumio volumio[923]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"104486.wav","artist":null,"album":null,"uri":"http://192.168.0.9:50002/m/NDLNA/104486.wav","trackType":"wav"} Oct 25 22:20:02 volumio volumio[923]: verbose: CURRENT POSITION 0 Oct 25 22:20:02 volumio volumio[923]: info: CoreStateMachine::syncState stateService play Oct 25 22:20:02 volumio volumio[923]: info: CoreStateMachine::syncState currentStatus play Oct 25 22:20:02 volumio volumio[923]: info: Received an update from plugin. extracting info from payload Oct 25 22:20:02 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:20:02 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 22:20:02 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:20:02 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:20:02 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:20:02 volumio volumio[923]: info: ------------------------------ 24ms Oct 25 22:20:23 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 25 22:20:23 volumio volumio[923]: info: Preload queue cleared Oct 25 22:20:23 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79413 Oct 25 22:20:23 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79406 Oct 25 22:20:23 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79414 Oct 25 22:20:23 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79415 Oct 25 22:20:23 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79405 Oct 25 22:20:23 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79403 Oct 25 22:20:23 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79409 Oct 25 22:20:23 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79404 Oct 25 22:20:23 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79412 Oct 25 22:20:23 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79411 Oct 25 22:20:23 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79407 Oct 25 22:20:23 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79410 Oct 25 22:20:23 volumio volumio[923]: info: Preloading song: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79408 Oct 25 22:20:23 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79413 in service upnp_browser Oct 25 22:20:23 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79406 in service upnp_browser Oct 25 22:20:23 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79414 in service upnp_browser Oct 25 22:20:23 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79415 in service upnp_browser Oct 25 22:20:23 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79405 in service upnp_browser Oct 25 22:20:23 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79403 in service upnp_browser Oct 25 22:20:23 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79409 in service upnp_browser Oct 25 22:20:23 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79404 in service upnp_browser Oct 25 22:20:23 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79412 in service upnp_browser Oct 25 22:20:24 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79411 in service upnp_browser Oct 25 22:20:24 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79407 in service upnp_browser Oct 25 22:20:24 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79410 in service upnp_browser Oct 25 22:20:24 volumio volumio[923]: info: Exploding uri upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79408 in service upnp_browser Oct 25 22:20:29 volumio volumio[923]: info: Preload queue cleared Oct 25 22:20:29 volumio volumio[923]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 25 22:20:29 volumio volumio[923]: info: CoreStateMachine::ClearQueue Oct 25 22:20:29 volumio volumio[923]: info: CoreStateMachine::stop Oct 25 22:20:29 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:20:29 volumio volumio[923]: info: CoreStateMachine::stPlaybackTimer Oct 25 22:20:29 volumio volumio[923]: info: CoreStateMachine::updateTrackBlock Oct 25 22:20:29 volumio volumio[923]: info: CorePlayQueue::getTrackBlock Oct 25 22:20:29 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:20:29 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:20:29 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 22:20:29 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:20:29 volumio volumio[923]: info: CoreStateMachine::serviceStop Oct 25 22:20:29 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:20:29 volumio volumio[923]: info: CoreCommandRouter::serviceStop Oct 25 22:20:29 volumio volumio[923]: info: [1729894829014] ControllerUPNPBrowser::stop Oct 25 22:20:29 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 22:20:29 volumio volumio[923]: info: CorePlayQueue::clearPlayQueue Oct 25 22:20:29 volumio volumio[923]: info: CorePlayQueue::saveQueue Oct 25 22:20:29 volumio volumio[923]: info: CoreCommandRouter::volumioPushQueue Oct 25 22:20:29 volumio volumio[923]: info: CoreStateMachine::addQueueItems Oct 25 22:20:29 volumio volumio[923]: info: CorePlayQueue::addQueueItems Oct 25 22:20:29 volumio volumio[923]: info: Preload queue cleared Oct 25 22:20:29 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79413 Oct 25 22:20:29 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79413 Oct 25 22:20:29 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79406 Oct 25 22:20:29 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79406 Oct 25 22:20:29 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79414 Oct 25 22:20:29 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79414 Oct 25 22:20:29 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79415 Oct 25 22:20:29 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79415 Oct 25 22:20:29 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79405 Oct 25 22:20:29 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79405 Oct 25 22:20:29 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79403 Oct 25 22:20:29 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79403 Oct 25 22:20:29 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79409 Oct 25 22:20:29 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79409 Oct 25 22:20:29 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79404 Oct 25 22:20:29 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79404 Oct 25 22:20:29 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79412 Oct 25 22:20:29 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79412 Oct 25 22:20:29 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79411 Oct 25 22:20:29 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79411 Oct 25 22:20:29 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79407 Oct 25 22:20:29 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79407 Oct 25 22:20:29 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79410 Oct 25 22:20:29 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79410 Oct 25 22:20:29 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79408 Oct 25 22:20:29 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79408 Oct 25 22:20:29 volumio volumio[923]: info: CoreCommandRouter::volumioPushQueue Oct 25 22:20:29 volumio volumio[923]: info: CorePlayQueue::saveQueue Oct 25 22:20:29 volumio volumio[923]: info: CoreStateMachine::updateTrackBlock Oct 25 22:20:29 volumio volumio[923]: info: CorePlayQueue::getTrackBlock Oct 25 22:20:29 volumio volumio[923]: info: CoreCommandRouter::volumioPlay Oct 25 22:20:29 volumio volumio[923]: info: CoreStateMachine::play index 3 Oct 25 22:20:29 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:20:29 volumio volumio[923]: info: CoreStateMachine::stop Oct 25 22:20:29 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:20:29 volumio volumio[923]: info: CoreStateMachine::play index undefined Oct 25 22:20:29 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:20:29 volumio volumio[923]: info: CorePlayQueue::getTrack 3 Oct 25 22:20:29 volumio volumio[923]: info: sendMpdCommand stop took 90 milliseconds Oct 25 22:20:29 volumio volumio[923]: info: Oct 25 22:20:29 volumio volumio[923]: ---------------------------- MPD announces state update: player Oct 25 22:20:29 volumio volumio[923]: info: ControllerMpd::getState Oct 25 22:20:29 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand status Oct 25 22:20:29 volumio volumio[923]: info: sendMpdCommand status took 1 milliseconds Oct 25 22:20:29 volumio volumio[923]: verbose: ControllerMpd::parseState Oct 25 22:20:29 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 22:20:29 volumio volumio[923]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 25 22:20:29 volumio volumio[923]: verbose: ControllerMpd::parseTrackInfo Oct 25 22:20:29 volumio volumio[923]: info: ControllerMpd::pushState Oct 25 22:20:29 volumio volumio[923]: info: CoreCommandRouter::servicePushState Oct 25 22:20:29 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:20:29 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:20:29 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 22:20:29 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:20:29 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:20:29 volumio volumio[923]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"104486.wav","artist":null,"album":null,"uri":"http://192.168.0.9:50002/m/NDLNA/104486.wav","trackType":"wav"} Oct 25 22:20:29 volumio volumio[923]: verbose: CURRENT POSITION 0 Oct 25 22:20:29 volumio volumio[923]: info: CoreStateMachine::syncState stateService stop Oct 25 22:20:29 volumio volumio[923]: info: CoreStateMachine::syncState currentStatus stop Oct 25 22:20:29 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:20:29 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:20:29 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:20:29 volumio volumio[923]: info: No code Oct 25 22:20:29 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:20:29 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:20:29 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:20:29 volumio volumio[923]: info: ------------------------------ 18ms Oct 25 22:20:36 volumio volumio[923]: info: Preload queue cleared Oct 25 22:20:36 volumio volumio[923]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 25 22:20:36 volumio volumio[923]: info: CoreStateMachine::ClearQueue Oct 25 22:20:36 volumio volumio[923]: info: CoreStateMachine::stop Oct 25 22:20:36 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:20:36 volumio volumio[923]: info: CorePlayQueue::clearPlayQueue Oct 25 22:20:36 volumio volumio[923]: info: CorePlayQueue::saveQueue Oct 25 22:20:36 volumio volumio[923]: info: CoreCommandRouter::volumioPushQueue Oct 25 22:20:36 volumio volumio[923]: info: CoreStateMachine::addQueueItems Oct 25 22:20:36 volumio volumio[923]: info: CorePlayQueue::addQueueItems Oct 25 22:20:36 volumio volumio[923]: info: Preload queue cleared Oct 25 22:20:36 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79413 Oct 25 22:20:36 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79413 Oct 25 22:20:36 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79406 Oct 25 22:20:36 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79406 Oct 25 22:20:36 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79414 Oct 25 22:20:36 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79414 Oct 25 22:20:36 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79415 Oct 25 22:20:36 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79415 Oct 25 22:20:36 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79405 Oct 25 22:20:36 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79405 Oct 25 22:20:36 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79403 Oct 25 22:20:36 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79403 Oct 25 22:20:36 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79409 Oct 25 22:20:36 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79409 Oct 25 22:20:36 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79404 Oct 25 22:20:36 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79404 Oct 25 22:20:36 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79412 Oct 25 22:20:36 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79412 Oct 25 22:20:36 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79411 Oct 25 22:20:36 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79411 Oct 25 22:20:36 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79407 Oct 25 22:20:36 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79407 Oct 25 22:20:36 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79410 Oct 25 22:20:36 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79410 Oct 25 22:20:36 volumio volumio[923]: info: Adding Item to queue: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79408 Oct 25 22:20:36 volumio volumio[923]: info: Using cached record of: upnp/http://192.168.0.9:50001/ContentDirectory/control@22$@79408 Oct 25 22:20:36 volumio volumio[923]: info: CoreCommandRouter::volumioPushQueue Oct 25 22:20:36 volumio volumio[923]: info: CorePlayQueue::saveQueue Oct 25 22:20:36 volumio volumio[923]: info: CoreStateMachine::updateTrackBlock Oct 25 22:20:36 volumio volumio[923]: info: CorePlayQueue::getTrackBlock Oct 25 22:20:36 volumio volumio[923]: info: CoreCommandRouter::volumioPlay Oct 25 22:20:36 volumio volumio[923]: info: CoreStateMachine::play index 2 Oct 25 22:20:36 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:20:36 volumio volumio[923]: info: CoreStateMachine::stop Oct 25 22:20:36 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:20:36 volumio volumio[923]: info: CoreStateMachine::play index undefined Oct 25 22:20:36 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:20:36 volumio volumio[923]: info: CorePlayQueue::getTrack 2 Oct 25 22:20:40 volumio volumio[923]: info: Preload queue cleared Oct 25 22:20:40 volumio volumio[923]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 25 22:20:40 volumio volumio[923]: info: CoreStateMachine::ClearQueue Oct 25 22:20:40 volumio volumio[923]: info: CoreStateMachine::stop Oct 25 22:20:40 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:20:40 volumio volumio[923]: info: CorePlayQueue::clearPlayQueue Oct 25 22:20:40 volumio volumio[923]: info: CorePlayQueue::saveQueue Oct 25 22:20:40 volumio volumio[923]: info: CoreCommandRouter::volumioPushQueue Oct 25 22:20:40 volumio volumio[923]: info: CoreStateMachine::addQueueItems Oct 25 22:20:40 volumio volumio[923]: info: CorePlayQueue::addQueueItems Oct 25 22:20:40 volumio volumio[923]: info: Preload queue cleared Oct 25 22:20:40 volumio volumio[923]: info: Adding Item to queue: upnp/folder/http://192.168.0.9:50001/ContentDirectory/control@22$14008 Oct 25 22:20:40 volumio volumio[923]: info: Exploding uri upnp/folder/http://192.168.0.9:50001/ContentDirectory/control@22$14008 in service upnp_browser Oct 25 22:20:40 volumio volumio[923]: info: CoreCommandRouter::volumioPushQueue Oct 25 22:20:40 volumio volumio[923]: info: CorePlayQueue::saveQueue Oct 25 22:20:40 volumio volumio[923]: info: CoreStateMachine::updateTrackBlock Oct 25 22:20:40 volumio volumio[923]: info: CorePlayQueue::getTrackBlock Oct 25 22:20:40 volumio volumio[923]: info: CoreCommandRouter::volumioPlay Oct 25 22:20:40 volumio volumio[923]: info: CoreStateMachine::play index 0 Oct 25 22:20:40 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:20:40 volumio volumio[923]: info: CoreStateMachine::stop Oct 25 22:20:40 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:20:40 volumio volumio[923]: info: CoreStateMachine::play index undefined Oct 25 22:20:40 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 22:20:40 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:20:40 volumio volumio[923]: info: CoreStateMachine::startPlaybackTimer Oct 25 22:20:40 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:20:40 volumio volumio[923]: info: [1729894840367] ControllerUPNPBrowser::clearAddPlayTrack Oct 25 22:20:40 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 22:20:40 volumio volumio[923]: info: sendMpdCommand stop took 3 milliseconds Oct 25 22:20:40 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand clear Oct 25 22:20:40 volumio volumio[923]: info: Oct 25 22:20:40 volumio volumio[923]: ---------------------------- MPD announces system playlist update Oct 25 22:20:40 volumio volumio[923]: info: Ignoring MPD Status Update Oct 25 22:20:40 volumio volumio[923]: info: sendMpdCommand clear took 1 milliseconds Oct 25 22:20:40 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.9:50002/m/MP3/79413.mp3" Oct 25 22:20:40 volumio volumio[923]: error: updateQueue error: null Oct 25 22:20:40 volumio volumio[923]: info: ------------------------------ 46ms Oct 25 22:20:40 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.9:50002/m/MP3/79413.mp3" Oct 25 22:20:40 volumio volumio[923]: info: Oct 25 22:20:40 volumio volumio[923]: ---------------------------- MPD announces system playlist update Oct 25 22:20:40 volumio volumio[923]: info: Ignoring MPD Status Update Oct 25 22:20:40 volumio volumio[923]: info: sendMpdCommand add "http://192.168.0.9:50002/m/MP3/79413.mp3" took 1 milliseconds Oct 25 22:20:40 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 25 22:20:40 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 25 22:20:40 volumio volumio[923]: info: ------------------------------ 1ms Oct 25 22:20:40 volumio volumio[923]: info: sendMpdCommand play took 1 milliseconds Oct 25 22:20:40 volumio volumio[923]: info: Oct 25 22:20:40 volumio volumio[923]: ---------------------------- MPD announces state update: player Oct 25 22:20:40 volumio volumio[923]: info: ControllerMpd::getState Oct 25 22:20:40 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand status Oct 25 22:20:40 volumio volumio[923]: info: sendMpdCommand status took 269 milliseconds Oct 25 22:20:40 volumio volumio[923]: verbose: ControllerMpd::parseState Oct 25 22:20:40 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 22:20:40 volumio volumio[923]: info: Oct 25 22:20:40 volumio volumio[923]: ---------------------------- MPD announces system playlist update Oct 25 22:20:40 volumio volumio[923]: info: Ignoring MPD Status Update Oct 25 22:20:40 volumio volumio[923]: info: Oct 25 22:20:40 volumio volumio[923]: ---------------------------- MPD announces state update: player Oct 25 22:20:40 volumio volumio[923]: info: ControllerMpd::getState Oct 25 22:20:40 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand status Oct 25 22:20:40 volumio volumio[923]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 25 22:20:40 volumio volumio[923]: verbose: ControllerMpd::parseTrackInfo Oct 25 22:20:40 volumio volumio[923]: info: ControllerMpd::pushState Oct 25 22:20:40 volumio volumio[923]: info: CoreCommandRouter::servicePushState Oct 25 22:20:40 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:20:40 volumio volumio[923]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":340,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"27式太极拳","artist":null,"album":null,"uri":"http://192.168.0.9:50002/m/MP3/79413.mp3","trackType":"mp3"} Oct 25 22:20:40 volumio volumio[923]: verbose: CURRENT POSITION 0 Oct 25 22:20:40 volumio volumio[923]: info: CoreStateMachine::syncState stateService play Oct 25 22:20:40 volumio volumio[923]: info: CoreStateMachine::syncState currentStatus stop Oct 25 22:20:40 volumio volumio[923]: info: ------------------------------ 276ms Oct 25 22:20:40 volumio volumio[923]: info: ------------------------------ 6ms Oct 25 22:20:40 volumio volumio[923]: info: sendMpdCommand status took 5 milliseconds Oct 25 22:20:40 volumio volumio[923]: verbose: ControllerMpd::parseState Oct 25 22:20:40 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 22:20:40 volumio volumio[923]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 25 22:20:40 volumio volumio[923]: verbose: ControllerMpd::parseTrackInfo Oct 25 22:20:40 volumio volumio[923]: info: ControllerMpd::pushState Oct 25 22:20:40 volumio volumio[923]: info: CoreCommandRouter::servicePushState Oct 25 22:20:40 volumio volumio[923]: info: CorePlayQueue::getTrack 0 Oct 25 22:20:40 volumio volumio[923]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":340,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"27式太极拳","artist":null,"album":null,"uri":"http://192.168.0.9:50002/m/MP3/79413.mp3","trackType":"mp3"} Oct 25 22:20:40 volumio volumio[923]: verbose: CURRENT POSITION 0 Oct 25 22:20:40 volumio volumio[923]: info: CoreStateMachine::syncState stateService play Oct 25 22:20:40 volumio volumio[923]: info: CoreStateMachine::syncState currentStatus play Oct 25 22:20:40 volumio volumio[923]: info: Received an update from plugin. extracting info from payload Oct 25 22:20:40 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:20:40 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 22:20:40 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:20:40 volumio volumio[923]: info: CoreStateMachine::pushState Oct 25 22:20:40 volumio volumio[923]: info: CoreCommandRouter::volumioPushState Oct 25 22:20:40 volumio volumio[923]: info: ------------------------------ 19ms Oct 25 22:20:42 volumio volumio[923]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 22:20:42 volumio volumio[923]: Error: connect ETIMEDOUT 31.13.94.49:80 Oct 25 22:20:42 volumio volumio[923]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 25 22:20:42 volumio volumio[923]: errno: -110, Oct 25 22:20:42 volumio volumio[923]: code: 'ETIMEDOUT', Oct 25 22:20:42 volumio volumio[923]: syscall: 'connect', Oct 25 22:20:42 volumio volumio[923]: address: '31.13.94.49', Oct 25 22:20:42 volumio volumio[923]: port: 80 Oct 25 22:20:42 volumio volumio[923]: } Oct 25 22:20:42 volumio volumio[923]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 22:20:43 volumio sudo[2673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-25 22:19 Oct 25 22:20:43 volumio sudo[2673]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"