-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2024-11-21 23:33:59 CET. -- Nov 21 23:32:02 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 21 23:32:02 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 21 23:32:02 volumioandrew volumio[1130]: info: Discovery: Getting this device information Nov 21 23:32:02 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioGetState Nov 21 23:32:02 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 21 23:32:08 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 21 23:32:08 volumioandrew volumio[1130]: info: Preload queue cleared Nov 21 23:32:12 volumioandrew volumio[1130]: info: Preload queue cleared Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::ClearQueue Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::stop Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:12 volumioandrew volumio[1130]: info: CorePlayQueue::clearPlayQueue Nov 21 23:32:12 volumioandrew volumio[1130]: info: CorePlayQueue::saveQueue Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushQueue Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::addQueueItems Nov 21 23:32:12 volumioandrew volumio[1130]: info: CorePlayQueue::addQueueItems Nov 21 23:32:12 volumioandrew volumio[1130]: info: Preload queue cleared Nov 21 23:32:12 volumioandrew volumio[1130]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497960 Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushQueue Nov 21 23:32:12 volumioandrew volumio[1130]: info: CorePlayQueue::saveQueue Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::updateTrackBlock Nov 21 23:32:12 volumioandrew volumio[1130]: info: CorePlayQueue::getTrackBlock Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPlay Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::play index 0 Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::stop Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::play index undefined Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:12 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::startPlaybackTimer Nov 21 23:32:12 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:12 volumioandrew volumio[1130]: info: [1732228332059] ControllerWebradio::clearAddPlayTrack Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 23:32:12 volumioandrew volumio[1130]: info: Nov 21 23:32:12 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:12 volumioandrew volumio[1130]: info: sendMpdCommand stop took 2 milliseconds Nov 21 23:32:12 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear Nov 21 23:32:12 volumioandrew volumio[1130]: info: Nov 21 23:32:12 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:12 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:12 volumioandrew volumio[1130]: info: sendMpdCommand status took 1 milliseconds Nov 21 23:32:12 volumioandrew volumio[1130]: info: sendMpdCommand clear took 2 milliseconds Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497960" Nov 21 23:32:12 volumioandrew volumio[1130]: info: Nov 21 23:32:12 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:12 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:12 volumioandrew volumio[1130]: info: Nov 21 23:32:12 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:12 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:12 volumioandrew volumio[1130]: error: updateQueue error: null Nov 21 23:32:12 volumioandrew volumio[1130]: info: ------------------------------ 9ms Nov 21 23:32:12 volumioandrew volumio[1130]: info: sendMpdCommand playlistinfo took 8 milliseconds Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:12 volumioandrew volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 21 23:32:12 volumioandrew volumio[1130]: info: ------------------------------ 11ms Nov 21 23:32:12 volumioandrew volumio[1130]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497960" took 105 milliseconds Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand play Nov 21 23:32:12 volumioandrew volumio[1130]: info: Nov 21 23:32:12 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:12 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:12 volumioandrew volumio[1130]: info: sendMpdCommand status took 106 milliseconds Nov 21 23:32:12 volumioandrew volumio[1130]: info: sendMpdCommand status took 105 milliseconds Nov 21 23:32:12 volumioandrew volumio[1130]: info: sendMpdCommand play took 1 milliseconds Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:12 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService stop Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: No code Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService stop Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: No code Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: ------------------------------ 160ms Nov 21 23:32:12 volumioandrew volumio[1130]: info: ------------------------------ 161ms Nov 21 23:32:12 volumioandrew volumio[1130]: info: Nov 21 23:32:12 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:12 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:12 volumioandrew volumio[1130]: info: Nov 21 23:32:12 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:12 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:12 volumioandrew volumio[1130]: info: ------------------------------ 65ms Nov 21 23:32:12 volumioandrew volumio[1130]: info: ------------------------------ 5ms Nov 21 23:32:12 volumioandrew volumio[1130]: info: ------------------------------ 4ms Nov 21 23:32:12 volumioandrew volumio[1130]: info: Nov 21 23:32:12 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:12 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:12 volumioandrew volumio[1130]: info: Nov 21 23:32:12 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:12 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:12 volumioandrew volumio[1130]: info: Nov 21 23:32:12 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:12 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 21 23:32:12 volumioandrew volumio[1130]: info: sendMpdCommand status took 7 milliseconds Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:12 volumioandrew volumio[1130]: info: Nov 21 23:32:12 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:12 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 21 23:32:12 volumioandrew volumio[1130]: info: sendMpdCommand status took 15 milliseconds Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 21 23:32:12 volumioandrew volumio[1130]: info: sendMpdCommand status took 15 milliseconds Nov 21 23:32:12 volumioandrew volumio[1130]: info: sendMpdCommand clearerror took 11 milliseconds Nov 21 23:32:12 volumioandrew volumio[1130]: info: sendMpdCommand playlistinfo took 9 milliseconds Nov 21 23:32:12 volumioandrew volumio[1130]: info: sendMpdCommand status took 7 milliseconds Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:12 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"chillout","artist":"(#1 - 980/500000) ANTENNE BAYERN Chillout (Germany)","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService pause Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: ------------------------------ 42ms Nov 21 23:32:12 volumioandrew volumio[1130]: info: Nov 21 23:32:12 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:12 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:12 volumioandrew volumio[1130]: info: Nov 21 23:32:12 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:12 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:12 volumioandrew volumio[1130]: info: sendMpdCommand clearerror took 34 milliseconds Nov 21 23:32:12 volumioandrew volumio[1130]: info: sendMpdCommand clearerror took 34 milliseconds Nov 21 23:32:12 volumioandrew volumio[1130]: info: sendMpdCommand playlistinfo took 28 milliseconds Nov 21 23:32:12 volumioandrew volumio[1130]: info: sendMpdCommand playlistinfo took 28 milliseconds Nov 21 23:32:12 volumioandrew volumio[1130]: info: sendMpdCommand playlistinfo took 27 milliseconds Nov 21 23:32:12 volumioandrew volumio[1130]: info: sendMpdCommand status took 11 milliseconds Nov 21 23:32:12 volumioandrew volumio[1130]: info: sendMpdCommand status took 9 milliseconds Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:12 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"chillout","artist":"(#1 - 980/500000) ANTENNE BAYERN Chillout (Germany)","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService pause Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"chillout","artist":"(#1 - 980/500000) ANTENNE BAYERN Chillout (Germany)","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService pause Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"chillout","artist":"(#1 - 980/500000) ANTENNE BAYERN Chillout (Germany)","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService pause Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: ------------------------------ 104ms Nov 21 23:32:12 volumioandrew volumio[1130]: info: ------------------------------ 103ms Nov 21 23:32:12 volumioandrew volumio[1130]: info: ------------------------------ 97ms Nov 21 23:32:12 volumioandrew volumio[1130]: info: sendMpdCommand playlistinfo took 52 milliseconds Nov 21 23:32:12 volumioandrew volumio[1130]: info: sendMpdCommand playlistinfo took 52 milliseconds Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:12 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"chillout","artist":"(#1 - 980/500000) ANTENNE BAYERN Chillout (Germany)","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService pause Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"chillout","artist":"(#1 - 980/500000) ANTENNE BAYERN Chillout (Germany)","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Nov 21 23:32:12 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService pause Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:12 volumioandrew volumio[1130]: info: ------------------------------ 89ms Nov 21 23:32:12 volumioandrew volumio[1130]: info: ------------------------------ 90ms Nov 21 23:32:14 volumioandrew volumio[1130]: info: Preload queue cleared Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::ClearQueue Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::stop Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:14 volumioandrew volumio[1130]: info: CorePlayQueue::clearPlayQueue Nov 21 23:32:14 volumioandrew volumio[1130]: info: CorePlayQueue::saveQueue Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushQueue Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::addQueueItems Nov 21 23:32:14 volumioandrew volumio[1130]: info: CorePlayQueue::addQueueItems Nov 21 23:32:14 volumioandrew volumio[1130]: info: Preload queue cleared Nov 21 23:32:14 volumioandrew volumio[1130]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99598119 Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushQueue Nov 21 23:32:14 volumioandrew volumio[1130]: info: CorePlayQueue::saveQueue Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::updateTrackBlock Nov 21 23:32:14 volumioandrew volumio[1130]: info: CorePlayQueue::getTrackBlock Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPlay Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::play index 0 Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::stop Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::play index undefined Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:14 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::startPlaybackTimer Nov 21 23:32:14 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:14 volumioandrew volumio[1130]: info: [1732228334362] ControllerWebradio::clearAddPlayTrack Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 23:32:14 volumioandrew volumio[1130]: info: Nov 21 23:32:14 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:14 volumioandrew volumio[1130]: info: sendMpdCommand stop took 3 milliseconds Nov 21 23:32:14 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear Nov 21 23:32:14 volumioandrew volumio[1130]: info: Nov 21 23:32:14 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:14 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:14 volumioandrew volumio[1130]: info: sendMpdCommand status took 1 milliseconds Nov 21 23:32:14 volumioandrew volumio[1130]: info: sendMpdCommand clear took 1 milliseconds Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99598119" Nov 21 23:32:14 volumioandrew volumio[1130]: info: Nov 21 23:32:14 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:14 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:14 volumioandrew volumio[1130]: info: Nov 21 23:32:14 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:14 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:14 volumioandrew volumio[1130]: error: updateQueue error: null Nov 21 23:32:14 volumioandrew volumio[1130]: info: ------------------------------ 3ms Nov 21 23:32:14 volumioandrew volumio[1130]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:14 volumioandrew volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 21 23:32:14 volumioandrew volumio[1130]: info: ------------------------------ 6ms Nov 21 23:32:14 volumioandrew volumio[1130]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99598119" took 105 milliseconds Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand play Nov 21 23:32:14 volumioandrew volumio[1130]: info: Nov 21 23:32:14 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:14 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:14 volumioandrew volumio[1130]: info: sendMpdCommand status took 106 milliseconds Nov 21 23:32:14 volumioandrew volumio[1130]: info: sendMpdCommand status took 105 milliseconds Nov 21 23:32:14 volumioandrew volumio[1130]: info: sendMpdCommand play took 2 milliseconds Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:14 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService stop Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: No code Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService stop Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: No code Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: ------------------------------ 154ms Nov 21 23:32:14 volumioandrew volumio[1130]: info: ------------------------------ 155ms Nov 21 23:32:14 volumioandrew volumio[1130]: info: Nov 21 23:32:14 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:14 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:14 volumioandrew volumio[1130]: info: Nov 21 23:32:14 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:14 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:14 volumioandrew volumio[1130]: info: ------------------------------ 56ms Nov 21 23:32:14 volumioandrew volumio[1130]: info: ------------------------------ 3ms Nov 21 23:32:14 volumioandrew volumio[1130]: info: ------------------------------ 2ms Nov 21 23:32:14 volumioandrew volumio[1130]: info: Nov 21 23:32:14 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:14 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:14 volumioandrew volumio[1130]: info: Nov 21 23:32:14 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:14 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:14 volumioandrew volumio[1130]: info: Nov 21 23:32:14 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:14 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 21 23:32:14 volumioandrew volumio[1130]: info: sendMpdCommand status took 8 milliseconds Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:14 volumioandrew volumio[1130]: info: Nov 21 23:32:14 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:14 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:14 volumioandrew volumio[1130]: info: Nov 21 23:32:14 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:14 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 21 23:32:14 volumioandrew volumio[1130]: info: sendMpdCommand status took 17 milliseconds Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 21 23:32:14 volumioandrew volumio[1130]: info: sendMpdCommand status took 16 milliseconds Nov 21 23:32:14 volumioandrew volumio[1130]: info: sendMpdCommand clearerror took 11 milliseconds Nov 21 23:32:14 volumioandrew volumio[1130]: info: sendMpdCommand playlistinfo took 10 milliseconds Nov 21 23:32:14 volumioandrew volumio[1130]: info: sendMpdCommand status took 9 milliseconds Nov 21 23:32:14 volumioandrew volumio[1130]: info: sendMpdCommand status took 7 milliseconds Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:14 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 1142/5000) VCR Auditorium Hi-Fi | Venice Classic Radio Italia","album":null,"uri":"http://116.202.241.212:8010/stream","trackType":""} Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService pause Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: ------------------------------ 43ms Nov 21 23:32:14 volumioandrew volumio[1130]: info: Nov 21 23:32:14 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:14 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:14 volumioandrew volumio[1130]: info: sendMpdCommand clearerror took 29 milliseconds Nov 21 23:32:14 volumioandrew volumio[1130]: info: sendMpdCommand clearerror took 28 milliseconds Nov 21 23:32:14 volumioandrew volumio[1130]: info: sendMpdCommand playlistinfo took 22 milliseconds Nov 21 23:32:14 volumioandrew volumio[1130]: info: sendMpdCommand playlistinfo took 22 milliseconds Nov 21 23:32:14 volumioandrew volumio[1130]: info: sendMpdCommand playlistinfo took 20 milliseconds Nov 21 23:32:14 volumioandrew volumio[1130]: info: sendMpdCommand playlistinfo took 20 milliseconds Nov 21 23:32:14 volumioandrew volumio[1130]: info: sendMpdCommand status took 5 milliseconds Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:14 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 1142/5000) VCR Auditorium Hi-Fi | Venice Classic Radio Italia","album":null,"uri":"http://116.202.241.212:8010/stream","trackType":""} Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService pause Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 1142/5000) VCR Auditorium Hi-Fi | Venice Classic Radio Italia","album":null,"uri":"http://116.202.241.212:8010/stream","trackType":""} Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService pause Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 1142/5000) VCR Auditorium Hi-Fi | Venice Classic Radio Italia","album":null,"uri":"http://116.202.241.212:8010/stream","trackType":""} Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService pause Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 1142/5000) VCR Auditorium Hi-Fi | Venice Classic Radio Italia","album":null,"uri":"http://116.202.241.212:8010/stream","trackType":""} Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService pause Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: ------------------------------ 89ms Nov 21 23:32:14 volumioandrew volumio[1130]: info: ------------------------------ 88ms Nov 21 23:32:14 volumioandrew volumio[1130]: info: ------------------------------ 80ms Nov 21 23:32:14 volumioandrew volumio[1130]: info: ------------------------------ 79ms Nov 21 23:32:14 volumioandrew volumio[1130]: info: sendMpdCommand playlistinfo took 41 milliseconds Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:14 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 1142/5000) VCR Auditorium Hi-Fi | Venice Classic Radio Italia","album":null,"uri":"http://116.202.241.212:8010/stream","trackType":""} Nov 21 23:32:14 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService pause Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 23:32:14 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:14 volumioandrew volumio[1130]: info: ------------------------------ 62ms Nov 21 23:32:20 volumioandrew volumio[1130]: info: Preload queue cleared Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreStateMachine::ClearQueue Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreStateMachine::stop Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:20 volumioandrew volumio[1130]: info: CorePlayQueue::clearPlayQueue Nov 21 23:32:20 volumioandrew volumio[1130]: info: CorePlayQueue::saveQueue Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushQueue Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreStateMachine::addQueueItems Nov 21 23:32:20 volumioandrew volumio[1130]: info: CorePlayQueue::addQueueItems Nov 21 23:32:20 volumioandrew volumio[1130]: info: Preload queue cleared Nov 21 23:32:20 volumioandrew volumio[1130]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1340450 Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushQueue Nov 21 23:32:20 volumioandrew volumio[1130]: info: CorePlayQueue::saveQueue Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreStateMachine::updateTrackBlock Nov 21 23:32:20 volumioandrew volumio[1130]: info: CorePlayQueue::getTrackBlock Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPlay Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreStateMachine::play index 0 Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreStateMachine::stop Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreStateMachine::play index undefined Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:20 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreStateMachine::startPlaybackTimer Nov 21 23:32:20 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:20 volumioandrew volumio[1130]: info: [1732228340782] ControllerWebradio::clearAddPlayTrack Nov 21 23:32:20 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 23:32:20 volumioandrew volumio[1130]: info: Nov 21 23:32:20 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:20 volumioandrew volumio[1130]: info: sendMpdCommand stop took 2 milliseconds Nov 21 23:32:20 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:20 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:20 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear Nov 21 23:32:20 volumioandrew volumio[1130]: info: Nov 21 23:32:20 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:20 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:20 volumioandrew volumio[1130]: info: sendMpdCommand status took 1 milliseconds Nov 21 23:32:20 volumioandrew volumio[1130]: info: sendMpdCommand clear took 1 milliseconds Nov 21 23:32:20 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:20 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:20 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1340450" Nov 21 23:32:20 volumioandrew volumio[1130]: info: Nov 21 23:32:20 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:20 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:20 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:20 volumioandrew volumio[1130]: info: Nov 21 23:32:20 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:20 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:20 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:20 volumioandrew volumio[1130]: error: updateQueue error: null Nov 21 23:32:20 volumioandrew volumio[1130]: info: ------------------------------ 3ms Nov 21 23:32:20 volumioandrew volumio[1130]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 21 23:32:20 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:20 volumioandrew volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 21 23:32:20 volumioandrew volumio[1130]: info: ------------------------------ 5ms Nov 21 23:32:20 volumioandrew volumio[1130]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1340450" took 106 milliseconds Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 21 23:32:20 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand play Nov 21 23:32:20 volumioandrew volumio[1130]: info: sendMpdCommand status took 106 milliseconds Nov 21 23:32:20 volumioandrew volumio[1130]: info: sendMpdCommand status took 105 milliseconds Nov 21 23:32:20 volumioandrew volumio[1130]: info: sendMpdCommand play took 1 milliseconds Nov 21 23:32:20 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:20 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:20 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:20 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:20 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 23:32:20 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService stop Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:20 volumioandrew volumio[1130]: info: No code Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:20 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:20 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:20 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 23:32:20 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService stop Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:20 volumioandrew volumio[1130]: info: No code Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:20 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:20 volumioandrew volumio[1130]: info: ------------------------------ 165ms Nov 21 23:32:20 volumioandrew volumio[1130]: info: ------------------------------ 166ms Nov 21 23:32:20 volumioandrew volumio[1130]: info: Nov 21 23:32:20 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:20 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:20 volumioandrew volumio[1130]: info: Nov 21 23:32:20 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:20 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:20 volumioandrew volumio[1130]: error: updateQueue error: null Nov 21 23:32:20 volumioandrew volumio[1130]: error: updateQueue error: null Nov 21 23:32:20 volumioandrew volumio[1130]: info: ------------------------------ 3ms Nov 21 23:32:20 volumioandrew volumio[1130]: info: ------------------------------ 2ms Nov 21 23:32:23 volumioandrew volumio[1130]: info: Preload queue cleared Nov 21 23:32:23 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 21 23:32:23 volumioandrew volumio[1130]: info: CoreStateMachine::ClearQueue Nov 21 23:32:23 volumioandrew volumio[1130]: info: CoreStateMachine::stop Nov 21 23:32:23 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:23 volumioandrew volumio[1130]: info: CorePlayQueue::clearPlayQueue Nov 21 23:32:23 volumioandrew volumio[1130]: info: CorePlayQueue::saveQueue Nov 21 23:32:23 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushQueue Nov 21 23:32:23 volumioandrew volumio[1130]: info: CoreStateMachine::addQueueItems Nov 21 23:32:23 volumioandrew volumio[1130]: info: CorePlayQueue::addQueueItems Nov 21 23:32:23 volumioandrew volumio[1130]: info: Preload queue cleared Nov 21 23:32:23 volumioandrew volumio[1130]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1340450 Nov 21 23:32:23 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 21 23:32:23 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushQueue Nov 21 23:32:23 volumioandrew volumio[1130]: info: CorePlayQueue::saveQueue Nov 21 23:32:23 volumioandrew volumio[1130]: info: CoreStateMachine::updateTrackBlock Nov 21 23:32:23 volumioandrew volumio[1130]: info: CorePlayQueue::getTrackBlock Nov 21 23:32:23 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPlay Nov 21 23:32:23 volumioandrew volumio[1130]: info: CoreStateMachine::play index 0 Nov 21 23:32:23 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:23 volumioandrew volumio[1130]: info: CoreStateMachine::stop Nov 21 23:32:23 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:23 volumioandrew volumio[1130]: info: CoreStateMachine::play index undefined Nov 21 23:32:23 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:23 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:23 volumioandrew volumio[1130]: info: CoreStateMachine::startPlaybackTimer Nov 21 23:32:23 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:23 volumioandrew volumio[1130]: info: [1732228343068] ControllerWebradio::clearAddPlayTrack Nov 21 23:32:23 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 23:32:23 volumioandrew volumio[1130]: info: sendMpdCommand stop took 1 milliseconds Nov 21 23:32:23 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear Nov 21 23:32:23 volumioandrew volumio[1130]: info: Nov 21 23:32:23 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:23 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:23 volumioandrew volumio[1130]: info: sendMpdCommand clear took 1 milliseconds Nov 21 23:32:23 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1340450" Nov 21 23:32:23 volumioandrew volumio[1130]: info: Nov 21 23:32:23 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:23 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:23 volumioandrew volumio[1130]: info: Nov 21 23:32:23 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:23 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:23 volumioandrew volumio[1130]: error: updateQueue error: null Nov 21 23:32:23 volumioandrew volumio[1130]: info: ------------------------------ 3ms Nov 21 23:32:23 volumioandrew volumio[1130]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1340450" took 106 milliseconds Nov 21 23:32:23 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 21 23:32:23 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand play Nov 21 23:32:23 volumioandrew volumio[1130]: error: updateQueue error: null Nov 21 23:32:23 volumioandrew volumio[1130]: error: updateQueue error: null Nov 21 23:32:23 volumioandrew volumio[1130]: info: ------------------------------ 106ms Nov 21 23:32:23 volumioandrew volumio[1130]: info: ------------------------------ 106ms Nov 21 23:32:23 volumioandrew volumio[1130]: info: sendMpdCommand play took 1 milliseconds Nov 21 23:32:25 volumioandrew volumio[1130]: info: Preload queue cleared Nov 21 23:32:25 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 21 23:32:25 volumioandrew volumio[1130]: info: CoreStateMachine::ClearQueue Nov 21 23:32:25 volumioandrew volumio[1130]: info: CoreStateMachine::stop Nov 21 23:32:25 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:25 volumioandrew volumio[1130]: info: CorePlayQueue::clearPlayQueue Nov 21 23:32:25 volumioandrew volumio[1130]: info: CorePlayQueue::saveQueue Nov 21 23:32:25 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushQueue Nov 21 23:32:25 volumioandrew volumio[1130]: info: CoreStateMachine::addQueueItems Nov 21 23:32:25 volumioandrew volumio[1130]: info: CorePlayQueue::addQueueItems Nov 21 23:32:25 volumioandrew volumio[1130]: info: Preload queue cleared Nov 21 23:32:25 volumioandrew volumio[1130]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99441647 Nov 21 23:32:25 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 21 23:32:25 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushQueue Nov 21 23:32:25 volumioandrew volumio[1130]: info: CorePlayQueue::saveQueue Nov 21 23:32:25 volumioandrew volumio[1130]: info: CoreStateMachine::updateTrackBlock Nov 21 23:32:25 volumioandrew volumio[1130]: info: CorePlayQueue::getTrackBlock Nov 21 23:32:25 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPlay Nov 21 23:32:25 volumioandrew volumio[1130]: info: CoreStateMachine::play index 0 Nov 21 23:32:25 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:25 volumioandrew volumio[1130]: info: CoreStateMachine::stop Nov 21 23:32:25 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:25 volumioandrew volumio[1130]: info: CoreStateMachine::play index undefined Nov 21 23:32:25 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:25 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:25 volumioandrew volumio[1130]: info: CoreStateMachine::startPlaybackTimer Nov 21 23:32:25 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:25 volumioandrew volumio[1130]: info: [1732228345253] ControllerWebradio::clearAddPlayTrack Nov 21 23:32:25 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 23:32:25 volumioandrew volumio[1130]: info: sendMpdCommand stop took 3 milliseconds Nov 21 23:32:25 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear Nov 21 23:32:25 volumioandrew volumio[1130]: info: sendMpdCommand clear took 0 milliseconds Nov 21 23:32:25 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99441647" Nov 21 23:32:25 volumioandrew volumio[1130]: info: Nov 21 23:32:25 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:25 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:25 volumioandrew volumio[1130]: info: Nov 21 23:32:25 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:25 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:25 volumioandrew volumio[1130]: info: Nov 21 23:32:25 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:25 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:25 volumioandrew volumio[1130]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99441647" took 105 milliseconds Nov 21 23:32:25 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 21 23:32:25 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand play Nov 21 23:32:25 volumioandrew volumio[1130]: info: Nov 21 23:32:25 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:25 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:25 volumioandrew volumio[1130]: info: ------------------------------ 107ms Nov 21 23:32:25 volumioandrew volumio[1130]: info: ------------------------------ 106ms Nov 21 23:32:25 volumioandrew volumio[1130]: info: ------------------------------ 107ms Nov 21 23:32:25 volumioandrew volumio[1130]: info: Nov 21 23:32:25 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:25 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:25 volumioandrew volumio[1130]: info: Nov 21 23:32:25 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:25 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:25 volumioandrew volumio[1130]: info: sendMpdCommand play took 7 milliseconds Nov 21 23:32:25 volumioandrew volumio[1130]: info: ------------------------------ 6ms Nov 21 23:32:25 volumioandrew volumio[1130]: info: ------------------------------ 4ms Nov 21 23:32:25 volumioandrew volumio[1130]: info: ------------------------------ 4ms Nov 21 23:32:26 volumioandrew volumio[1130]: info: Nov 21 23:32:26 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:26 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:26 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:26 volumioandrew volumio[1130]: info: Nov 21 23:32:26 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:26 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:26 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:26 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 21 23:32:26 volumioandrew volumio[1130]: info: sendMpdCommand status took 6 milliseconds Nov 21 23:32:26 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:26 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:26 volumioandrew volumio[1130]: info: Nov 21 23:32:26 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:26 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:26 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:26 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 21 23:32:26 volumioandrew volumio[1130]: info: sendMpdCommand status took 12 milliseconds Nov 21 23:32:26 volumioandrew volumio[1130]: info: sendMpdCommand clearerror took 8 milliseconds Nov 21 23:32:26 volumioandrew volumio[1130]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 21 23:32:26 volumioandrew volumio[1130]: info: sendMpdCommand status took 6 milliseconds Nov 21 23:32:26 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:26 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:26 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:26 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:26 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:26 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:26 volumioandrew volumio[1130]: 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":"stream","artist":"(#1 - 455/2000) Blues Radio","album":null,"uri":"https://i4.streams.ovh:8352/stream","trackType":""} Nov 21 23:32:26 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService stop Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: No code Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: ------------------------------ 46ms Nov 21 23:32:26 volumioandrew volumio[1130]: info: sendMpdCommand clearerror took 37 milliseconds Nov 21 23:32:26 volumioandrew volumio[1130]: info: sendMpdCommand playlistinfo took 36 milliseconds Nov 21 23:32:26 volumioandrew volumio[1130]: info: sendMpdCommand playlistinfo took 36 milliseconds Nov 21 23:32:26 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:26 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:26 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:26 volumioandrew volumio[1130]: 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":"stream","artist":"(#1 - 455/2000) Blues Radio","album":null,"uri":"https://i4.streams.ovh:8352/stream","trackType":""} Nov 21 23:32:26 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService stop Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: No code Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:26 volumioandrew volumio[1130]: 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":"stream","artist":"(#1 - 455/2000) Blues Radio","album":null,"uri":"https://i4.streams.ovh:8352/stream","trackType":""} Nov 21 23:32:26 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService stop Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: No code Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:26 volumioandrew volumio[1130]: info: ------------------------------ 111ms Nov 21 23:32:26 volumioandrew volumio[1130]: info: ------------------------------ 106ms Nov 21 23:32:29 volumioandrew volumio[1130]: info: Preload queue cleared Nov 21 23:32:29 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 21 23:32:29 volumioandrew volumio[1130]: info: CoreStateMachine::ClearQueue Nov 21 23:32:29 volumioandrew volumio[1130]: info: CoreStateMachine::stop Nov 21 23:32:29 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:29 volumioandrew volumio[1130]: info: CorePlayQueue::clearPlayQueue Nov 21 23:32:29 volumioandrew volumio[1130]: info: CorePlayQueue::saveQueue Nov 21 23:32:29 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushQueue Nov 21 23:32:29 volumioandrew volumio[1130]: info: CoreStateMachine::addQueueItems Nov 21 23:32:29 volumioandrew volumio[1130]: info: CorePlayQueue::addQueueItems Nov 21 23:32:29 volumioandrew volumio[1130]: info: Preload queue cleared Nov 21 23:32:29 volumioandrew volumio[1130]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1821355 Nov 21 23:32:29 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 21 23:32:29 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushQueue Nov 21 23:32:29 volumioandrew volumio[1130]: info: CorePlayQueue::saveQueue Nov 21 23:32:29 volumioandrew volumio[1130]: info: CoreStateMachine::updateTrackBlock Nov 21 23:32:29 volumioandrew volumio[1130]: info: CorePlayQueue::getTrackBlock Nov 21 23:32:29 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPlay Nov 21 23:32:29 volumioandrew volumio[1130]: info: CoreStateMachine::play index 0 Nov 21 23:32:29 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:29 volumioandrew volumio[1130]: info: CoreStateMachine::stop Nov 21 23:32:29 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:29 volumioandrew volumio[1130]: info: CoreStateMachine::play index undefined Nov 21 23:32:29 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 23:32:29 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:29 volumioandrew volumio[1130]: info: CoreStateMachine::startPlaybackTimer Nov 21 23:32:29 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:29 volumioandrew volumio[1130]: info: [1732228349399] ControllerWebradio::clearAddPlayTrack Nov 21 23:32:29 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 23:32:29 volumioandrew volumio[1130]: info: sendMpdCommand stop took 2 milliseconds Nov 21 23:32:29 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear Nov 21 23:32:29 volumioandrew volumio[1130]: info: Nov 21 23:32:29 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:29 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:29 volumioandrew volumio[1130]: info: sendMpdCommand clear took 1 milliseconds Nov 21 23:32:29 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1821355" Nov 21 23:32:29 volumioandrew volumio[1130]: info: Nov 21 23:32:29 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:29 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:29 volumioandrew volumio[1130]: info: Nov 21 23:32:29 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:29 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:29 volumioandrew volumio[1130]: error: updateQueue error: null Nov 21 23:32:29 volumioandrew volumio[1130]: info: ------------------------------ 2ms Nov 21 23:32:29 volumioandrew volumio[1130]: info: Nov 21 23:32:29 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:29 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:29 volumioandrew volumio[1130]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1821355" took 108 milliseconds Nov 21 23:32:29 volumioandrew volumio[1130]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 21 23:32:29 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand play Nov 21 23:32:29 volumioandrew volumio[1130]: info: ------------------------------ 108ms Nov 21 23:32:29 volumioandrew volumio[1130]: info: ------------------------------ 108ms Nov 21 23:32:29 volumioandrew volumio[1130]: info: ------------------------------ 2ms Nov 21 23:32:29 volumioandrew volumio[1130]: info: Nov 21 23:32:29 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:29 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:29 volumioandrew volumio[1130]: info: Nov 21 23:32:29 volumioandrew volumio[1130]: ---------------------------- MPD announces system playlist update Nov 21 23:32:29 volumioandrew volumio[1130]: info: Ignoring MPD Status Update Nov 21 23:32:29 volumioandrew volumio[1130]: info: sendMpdCommand play took 5 milliseconds Nov 21 23:32:29 volumioandrew volumio[1130]: info: ------------------------------ 3ms Nov 21 23:32:29 volumioandrew volumio[1130]: info: ------------------------------ 1ms Nov 21 23:32:30 volumioandrew volumio[1130]: info: Nov 21 23:32:30 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:30 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:30 volumioandrew volumio[1130]: info: Nov 21 23:32:30 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:30 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:30 volumioandrew volumio[1130]: info: Nov 21 23:32:30 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:30 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 21 23:32:30 volumioandrew volumio[1130]: info: sendMpdCommand status took 7 milliseconds Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:30 volumioandrew volumio[1130]: info: Nov 21 23:32:30 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:30 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:30 volumioandrew volumio[1130]: info: Nov 21 23:32:30 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:30 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 21 23:32:30 volumioandrew volumio[1130]: info: sendMpdCommand status took 14 milliseconds Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 21 23:32:30 volumioandrew volumio[1130]: info: sendMpdCommand status took 14 milliseconds Nov 21 23:32:30 volumioandrew volumio[1130]: info: sendMpdCommand clearerror took 10 milliseconds Nov 21 23:32:30 volumioandrew volumio[1130]: info: sendMpdCommand playlistinfo took 9 milliseconds Nov 21 23:32:30 volumioandrew volumio[1130]: info: sendMpdCommand status took 8 milliseconds Nov 21 23:32:30 volumioandrew volumio[1130]: info: sendMpdCommand status took 7 milliseconds Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:30 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"atr_128","artist":"1.FM - Amsterdam Trance (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/atr_128","trackType":""} Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService pause Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: ------------------------------ 53ms Nov 21 23:32:30 volumioandrew volumio[1130]: info: Nov 21 23:32:30 volumioandrew volumio[1130]: ---------------------------- MPD announces state update: player Nov 21 23:32:30 volumioandrew volumio[1130]: info: ControllerMpd::getState Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Nov 21 23:32:30 volumioandrew volumio[1130]: info: sendMpdCommand clearerror took 40 milliseconds Nov 21 23:32:30 volumioandrew volumio[1130]: info: sendMpdCommand clearerror took 39 milliseconds Nov 21 23:32:30 volumioandrew volumio[1130]: info: sendMpdCommand playlistinfo took 28 milliseconds Nov 21 23:32:30 volumioandrew volumio[1130]: info: sendMpdCommand playlistinfo took 28 milliseconds Nov 21 23:32:30 volumioandrew volumio[1130]: info: sendMpdCommand playlistinfo took 27 milliseconds Nov 21 23:32:30 volumioandrew volumio[1130]: info: sendMpdCommand status took 4 milliseconds Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::parseState Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 23:32:30 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"atr_128","artist":"1.FM - Amsterdam Trance (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/atr_128","trackType":""} Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService pause Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"atr_128","artist":"1.FM - Amsterdam Trance (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/atr_128","trackType":""} Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService pause Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"atr_128","artist":"1.FM - Amsterdam Trance (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/atr_128","trackType":""} Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService pause Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"atr_128","artist":"1.FM - Amsterdam Trance (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/atr_128","trackType":""} Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService pause Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: ------------------------------ 90ms Nov 21 23:32:30 volumioandrew volumio[1130]: info: ------------------------------ 89ms Nov 21 23:32:30 volumioandrew volumio[1130]: info: ------------------------------ 85ms Nov 21 23:32:30 volumioandrew volumio[1130]: info: ------------------------------ 87ms Nov 21 23:32:30 volumioandrew volumio[1130]: info: sendMpdCommand playlistinfo took 39 milliseconds Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: ControllerMpd::parseTrackInfo Nov 21 23:32:30 volumioandrew volumio[1130]: info: ControllerMpd::pushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreCommandRouter::servicePushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: CorePlayQueue::getTrack 0 Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"atr_128","artist":"1.FM - Amsterdam Trance (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/atr_128","trackType":""} Nov 21 23:32:30 volumioandrew volumio[1130]: verbose: CURRENT POSITION 0 Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreStateMachine::syncState stateService pause Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreStateMachine::pushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 23:32:30 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioPushState Nov 21 23:32:30 volumioandrew volumio[1130]: info: ------------------------------ 57ms Nov 21 23:32:32 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 21 23:32:32 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 21 23:32:32 volumioandrew volumio[1130]: info: Discovery: Getting this device information Nov 21 23:32:32 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioGetState Nov 21 23:32:32 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 21 23:32:43 volumioandrew volumio[1130]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 23:32:44 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 21 23:32:44 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 23:32:44 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 21 23:32:44 volumioandrew volumio[1130]: Invalid card number. Nov 21 23:32:44 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 23:32:44 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 23:32:44 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 23:32:44 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 23:32:44 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 23:32:44 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 23:32:44 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 23:32:44 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 21 23:32:44 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 21 23:33:02 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 21 23:33:02 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 21 23:33:02 volumioandrew volumio[1130]: info: Discovery: Getting this device information Nov 21 23:33:02 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioGetState Nov 21 23:33:02 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 21 23:33:14 volumioandrew ntpd[1928]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Nov 21 23:33:32 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 21 23:33:32 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 21 23:33:32 volumioandrew volumio[1130]: info: Discovery: Getting this device information Nov 21 23:33:32 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioGetState Nov 21 23:33:32 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 21 23:33:32 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioGetState Nov 21 23:33:41 volumioandrew volumio[1130]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 23:33:41 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 21 23:33:41 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 21 23:33:41 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 21 23:33:41 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Nov 21 23:33:41 volumioandrew volumio[1130]: info: Received Get System Version Nov 21 23:33:41 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 23:33:54 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard Nov 21 23:33:54 volumioandrew volumio[1130]: info: CoreCommandRouter::Reload Ui Nov 21 23:33:55 volumioandrew sudo[3272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 21 23:33:55 volumioandrew sudo[3275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 21 23:33:55 volumioandrew sudo[3272]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 23:33:55 volumioandrew sudo[3275]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 23:33:55 volumioandrew sudo[3272]: pam_unix(sudo:session): session closed for user root Nov 21 23:33:55 volumioandrew sudo[3275]: pam_unix(sudo:session): session closed for user root Nov 21 23:33:55 volumioandrew volumio[1130]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.2 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 5 Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioGetVisibleSources Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Nov 21 23:33:55 volumioandrew volumio[1130]: info: Received Get System Info Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 21 23:33:55 volumioandrew volumio[1130]: info: Discovery: Getting this device information Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioGetState Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 21 23:33:55 volumioandrew volumio-remote-updater[567]: No test mode Nov 21 23:33:55 volumioandrew volumio-remote-updater[567]: No alpha test mode Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 23:33:55 volumioandrew volumio[1130]: info: Retrieving Cloud Streaming UI Nov 21 23:33:55 volumioandrew volumio[1130]: info: Getting Tidal Cloud Configuration Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 23:33:55 volumioandrew volumio[1130]: info: Getting Qobuz Cloud Configuration Nov 21 23:33:55 volumioandrew volumio[1130]: info: Asking plugin for UI Config Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 23:33:55 volumioandrew volumio[1130]: info: Getting Spotify Cloud Configuration Nov 21 23:33:55 volumioandrew volumio[1130]: info: Asking plugin for UI Config Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 23:33:55 volumioandrew volumio[1130]: info: Saving Spotify Acccount Nov 21 23:33:55 volumioandrew volumio[1130]: info: Got Tidal Cloud Configuration Nov 21 23:33:55 volumioandrew volumio[1130]: info: Got it Nov 21 23:33:55 volumioandrew volumio[1130]: info: Got it Nov 21 23:33:55 volumioandrew volumio[1130]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 23:33:55 volumioandrew volumio[1130]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Nov 21 23:33:55 volumioandrew volumio[1130]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 21 23:33:55 volumioandrew volumio[1130]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 6 Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioGetVisibleSources Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Nov 21 23:33:55 volumioandrew volumio[1130]: info: Received Get System Info Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 21 23:33:55 volumioandrew volumio[1130]: info: Discovery: Getting this device information Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::volumioGetState Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 21 23:33:55 volumioandrew volumio-remote-updater[567]: No test mode Nov 21 23:33:55 volumioandrew volumio-remote-updater[567]: No alpha test mode Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 21 23:33:55 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 21 23:33:56 volumioandrew volumio[1130]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 23:33:56 volumioandrew volumio[1130]: info: Retrieving Cloud Streaming UI Nov 21 23:33:56 volumioandrew volumio[1130]: info: Getting Tidal Cloud Configuration Nov 21 23:33:56 volumioandrew volumio[1130]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 23:33:56 volumioandrew volumio[1130]: info: Getting Qobuz Cloud Configuration Nov 21 23:33:56 volumioandrew volumio[1130]: info: Asking plugin for UI Config Nov 21 23:33:56 volumioandrew volumio[1130]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 23:33:56 volumioandrew volumio[1130]: info: Getting Spotify Cloud Configuration Nov 21 23:33:56 volumioandrew volumio[1130]: info: Asking plugin for UI Config Nov 21 23:33:56 volumioandrew volumio[1130]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 23:33:56 volumioandrew volumio[1130]: info: Saving Spotify Acccount Nov 21 23:33:56 volumioandrew volumio[1130]: info: Got Tidal Cloud Configuration Nov 21 23:33:56 volumioandrew volumio[1130]: info: Got it Nov 21 23:33:56 volumioandrew volumio[1130]: info: Got it Nov 21 23:33:56 volumioandrew volumio[1130]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 21 23:33:56 volumioandrew volumio[1130]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 23:33:56 volumioandrew volumio[1130]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Nov 21 23:33:56 volumioandrew volumio[1130]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 21 23:33:56 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 21 23:33:56 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Nov 21 23:33:56 volumioandrew volumio[1130]: info: Loading i18n strings for locale it Nov 21 23:33:56 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Nov 21 23:33:56 volumioandrew volumio[1130]: info: Setting timezone to Europe/Rome Nov 21 23:33:56 volumioandrew sudo[3313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Nov 21 23:33:56 volumioandrew sudo[3313]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 23:33:56 volumioandrew sudo[3313]: pam_unix(sudo:session): session closed for user root Nov 21 23:33:56 volumioandrew sudo[3323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Rome /etc/localtime Nov 21 23:33:56 volumioandrew sudo[3323]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 23:33:56 volumioandrew sudo[3323]: pam_unix(sudo:session): session closed for user root Nov 21 23:33:56 volumioandrew sudo[3333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Nov 21 23:33:56 volumioandrew sudo[3333]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 23:33:56 volumioandrew sudo[3333]: pam_unix(sudo:session): session closed for user root Nov 21 23:33:56 volumioandrew sudo[3343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Rome Nov 21 23:33:56 volumioandrew sudo[3343]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 23:33:56 volumioandrew dbus-daemon[543]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.18' (uid=0 pid=3348 comm="/usr/bin/timedatectl set-timezone Europe/Rome ") Nov 21 23:33:56 volumioandrew systemd[1]: Starting Time & Date Service... Nov 21 23:33:56 volumioandrew dbus-daemon[543]: [system] Successfully activated service 'org.freedesktop.timedate1' Nov 21 23:33:56 volumioandrew systemd[1]: Started Time & Date Service. Nov 21 23:33:56 volumioandrew sudo[3343]: pam_unix(sudo:session): session closed for user root Nov 21 23:33:56 volumioandrew volumio[1130]: Updating browse sources language Nov 21 23:33:56 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 23:33:56 volumioandrew volumio[1130]: info: Fetching Streaming Services browse cache Nov 21 23:33:57 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 21 23:33:57 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Nov 21 23:33:57 volumioandrew volumio[1130]: info: Loading i18n strings for locale en Nov 21 23:33:57 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Nov 21 23:33:57 volumioandrew volumio[1130]: info: Setting timezone to UTC Nov 21 23:33:57 volumioandrew sudo[3354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Nov 21 23:33:57 volumioandrew sudo[3354]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 23:33:57 volumioandrew sudo[3354]: pam_unix(sudo:session): session closed for user root Nov 21 23:33:57 volumioandrew sudo[3364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Nov 21 23:33:57 volumioandrew sudo[3364]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 23:33:57 volumioandrew sudo[3364]: pam_unix(sudo:session): session closed for user root Nov 21 23:33:57 volumioandrew sudo[3374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Nov 21 23:33:57 volumioandrew sudo[3374]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 23:33:57 volumioandrew sudo[3374]: pam_unix(sudo:session): session closed for user root Nov 21 23:33:57 volumioandrew sudo[3384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Nov 21 23:33:57 volumioandrew sudo[3384]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 23:33:57 volumioandrew systemd-timedated[3349]: Failed to set time zone: Permission denied Nov 21 23:33:57 volumioandrew sudo[3384]: pam_unix(sudo:session): session closed for user root Nov 21 23:33:57 volumioandrew volumio[1130]: Failed to set time zone: Access denied Nov 21 23:33:57 volumioandrew volumio[1130]: info: Could not set timezone, retrying Nov 21 23:33:57 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 21 23:33:57 volumioandrew volumio[1130]: Updating browse sources language Nov 21 23:33:57 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 23:33:57 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 21 23:33:57 volumioandrew volumio[1130]: info: Fetching Streaming Services browse cache Nov 21 23:33:58 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Nov 21 23:33:58 volumioandrew volumio[1130]: info: Loading i18n strings for locale it Nov 21 23:33:58 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Nov 21 23:33:58 volumioandrew volumio[1130]: info: Setting timezone to Europe/Rome Nov 21 23:33:58 volumioandrew sudo[3394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Nov 21 23:33:58 volumioandrew sudo[3394]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 23:33:58 volumioandrew sudo[3394]: pam_unix(sudo:session): session closed for user root Nov 21 23:33:58 volumioandrew sudo[3404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Rome /etc/localtime Nov 21 23:33:58 volumioandrew sudo[3404]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 23:33:58 volumioandrew sudo[3404]: pam_unix(sudo:session): session closed for user root Nov 21 23:33:58 volumioandrew sudo[3414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Nov 21 23:33:58 volumioandrew sudo[3414]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 23:33:58 volumioandrew sudo[3414]: pam_unix(sudo:session): session closed for user root Nov 21 23:33:58 volumioandrew sudo[3424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Rome Nov 21 23:33:58 volumioandrew sudo[3424]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 23:33:58 volumioandrew systemd-timedated[3349]: Failed to set time zone: Permission denied Nov 21 23:33:58 volumioandrew sudo[3424]: pam_unix(sudo:session): session closed for user root Nov 21 23:33:58 volumioandrew volumio[1130]: Failed to set time zone: Access denied Nov 21 23:33:58 volumioandrew volumio[1130]: info: Could not set timezone, retrying Nov 21 23:33:58 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Nov 21 23:33:58 volumioandrew volumio[1130]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Nov 21 23:33:58 volumioandrew sudo[3434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 21 23:33:58 volumioandrew sudo[3434]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 23:33:58 volumioandrew sudo[3438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Nov 21 23:33:58 volumioandrew sudo[3438]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 23:33:58 volumioandrew systemd-timedated[3349]: Failed to set time zone: Permission denied Nov 21 23:33:58 volumioandrew sudo[3438]: pam_unix(sudo:session): session closed for user root Nov 21 23:33:58 volumioandrew volumio[1130]: Failed to set time zone: Access denied Nov 21 23:33:58 volumioandrew volumio[1130]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 23:33:58 volumioandrew volumio[1130]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'UTC' Nov 21 23:33:58 volumioandrew volumio[1130]: Failed to set time zone: Access denied Nov 21 23:33:58 volumioandrew volumio[1130]: at checkExecSyncError (child_process.js:790:11) Nov 21 23:33:58 volumioandrew volumio[1130]: at execSync (child_process.js:863:15) Nov 21 23:33:58 volumioandrew volumio[1130]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1575:9) Nov 21 23:33:58 volumioandrew volumio[1130]: at listOnTimeout (internal/timers.js:557:17) Nov 21 23:33:58 volumioandrew volumio[1130]: at processTimers (internal/timers.js:500:7) { Nov 21 23:33:58 volumioandrew volumio[1130]: status: 1, Nov 21 23:33:58 volumioandrew volumio[1130]: signal: null, Nov 21 23:33:58 volumioandrew volumio[1130]: output: [ null, '', 'Failed to set time zone: Access denied\n' ], Nov 21 23:33:58 volumioandrew volumio[1130]: pid: 3436, Nov 21 23:33:58 volumioandrew volumio[1130]: stdout: '', Nov 21 23:33:58 volumioandrew volumio[1130]: stderr: 'Failed to set time zone: Access denied\n' Nov 21 23:33:58 volumioandrew volumio[1130]: } Nov 21 23:33:58 volumioandrew volumio[1130]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 23:33:59 volumioandrew sudo[3434]: pam_unix(sudo:session): session closed for user root Nov 21 23:33:59 volumioandrew sudo[3462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-21 23:32 Nov 21 23:33:59 volumioandrew sudo[3462]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:11:38 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="932fc3546f7cc8faa2b4d349036752dd"