-- Logs begin at Mon 2025-12-29 15:10:51 UTC, end at Mon 2025-12-29 21:22:49 UTC. -- Dec 29 21:21:26 volumio volumio[935]: info: CoreCommandRouter::volumioGetVisibleSources Dec 29 21:21:26 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 29 21:21:26 volumio volumio[935]: info: CoreCommandRouter::volumioGetState Dec 29 21:21:26 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:26 volumio volumio[935]: info: CoreCommandRouter::volumioGetQueue Dec 29 21:21:26 volumio volumio[935]: info: CoreStateMachine::getQueue Dec 29 21:21:26 volumio volumio[935]: info: CorePlayQueue::getQueue Dec 29 21:21:26 volumio volumio[935]: info: Listing playlists Dec 29 21:21:26 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 29 21:21:26 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 29 21:21:26 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 29 21:21:26 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 29 21:21:26 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 29 21:21:29 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 29 21:21:30 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 29 21:21:32 volumio volumio[935]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 29 21:21:32 volumio volumio[935]: info: CoreStateMachine::ClearQueue Dec 29 21:21:32 volumio volumio[935]: info: CoreStateMachine::stop Dec 29 21:21:32 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:32 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:21:32 volumio volumio[935]: info: CorePlayQueue::clearPlayQueue Dec 29 21:21:32 volumio volumio[935]: info: CorePlayQueue::saveQueue Dec 29 21:21:32 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:32 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:32 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Dec 29 21:21:32 volumio volumio[935]: info: CoreStateMachine::addQueueItems Dec 29 21:21:32 volumio volumio[935]: info: CorePlayQueue::addQueueItems Dec 29 21:21:32 volumio volumio[935]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s112796 Dec 29 21:21:32 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 29 21:21:32 volumio volumio[935]: info: CorePlayQueue::saveQueue Dec 29 21:21:32 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Dec 29 21:21:32 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Dec 29 21:21:32 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Dec 29 21:21:32 volumio volumio[935]: info: CoreCommandRouter::volumioPlay Dec 29 21:21:32 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:21:32 volumio volumio[935]: info: CoreStateMachine::play index 0 Dec 29 21:21:32 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:32 volumio volumio[935]: info: CoreStateMachine::stop Dec 29 21:21:32 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:32 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:21:32 volumio volumio[935]: info: CoreStateMachine::play index undefined Dec 29 21:21:32 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:32 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:32 volumio volumio[935]: info: CoreStateMachine::startPlaybackTimer Dec 29 21:21:32 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:32 volumio volumio[935]: info: [1767043292326] ControllerWebradio::clearAddPlayTrack Dec 29 21:21:32 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 21:21:32 volumio volumio[935]: info: sendMpdCommand stop took 5 milliseconds Dec 29 21:21:32 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand clear Dec 29 21:21:32 volumio volumio[935]: info: Dec 29 21:21:32 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:21:32 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:21:32 volumio volumio[935]: info: sendMpdCommand clear took 4 milliseconds Dec 29 21:21:32 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand load "https://solid24.streamupsolutions.com/proxy/nilxzqeg?mp=/;type=mp3" Dec 29 21:21:32 volumio volumio[935]: error: updateQueue error: null Dec 29 21:21:32 volumio volumio[935]: info: ------------------------------ 8ms Dec 29 21:21:32 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand add "https://solid24.streamupsolutions.com/proxy/nilxzqeg?mp=/;type=mp3" Dec 29 21:21:32 volumio volumio[935]: info: Dec 29 21:21:32 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:21:32 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:21:32 volumio volumio[935]: info: sendMpdCommand add "https://solid24.streamupsolutions.com/proxy/nilxzqeg?mp=/;type=mp3" took 2 milliseconds Dec 29 21:21:32 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 29 21:21:32 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand play Dec 29 21:21:32 volumio volumio[935]: info: ------------------------------ 5ms Dec 29 21:21:32 volumio volumio[935]: info: sendMpdCommand play took 3 milliseconds Dec 29 21:21:33 volumio volumio[935]: info: Dec 29 21:21:33 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:21:33 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:21:33 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:21:33 volumio volumio[935]: info: Dec 29 21:21:33 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:21:33 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:21:33 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:21:33 volumio volumio[935]: info: Dec 29 21:21:33 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:21:33 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:21:33 volumio volumio[935]: info: sendMpdCommand status took 6 milliseconds Dec 29 21:21:33 volumio volumio[935]: info: sendMpdCommand status took 3 milliseconds Dec 29 21:21:33 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:21:33 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:21:33 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:21:33 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:21:33 volumio volumio[935]: info: ------------------------------ 5ms Dec 29 21:21:33 volumio volumio[935]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 29 21:21:33 volumio volumio[935]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 29 21:21:33 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:21:33 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:21:33 volumio volumio[935]: info: ControllerMpd::pushState Dec 29 21:21:33 volumio volumio[935]: info: CoreCommandRouter::servicePushState Dec 29 21:21:33 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:33 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":481,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Barry Levenson - Riley's Shuffle / Blue Tears","artist":"Bar Legend Radio","album":null,"uri":"https://solid24.streamupsolutions.com/proxy/nilxzqeg?mp=/;type=mp3","trackType":""} Dec 29 21:21:33 volumio volumio[935]: verbose: CURRENT POSITION 0 Dec 29 21:21:33 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Dec 29 21:21:33 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus stop Dec 29 21:21:33 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:33 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:33 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:33 volumio volumio[935]: info: ControllerMpd::pushState Dec 29 21:21:33 volumio volumio[935]: info: CoreCommandRouter::servicePushState Dec 29 21:21:33 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:33 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":481,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Barry Levenson - Riley's Shuffle / Blue Tears","artist":"Bar Legend Radio","album":null,"uri":"https://solid24.streamupsolutions.com/proxy/nilxzqeg?mp=/;type=mp3","trackType":""} Dec 29 21:21:33 volumio volumio[935]: verbose: CURRENT POSITION 0 Dec 29 21:21:33 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Dec 29 21:21:33 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus play Dec 29 21:21:33 volumio volumio[935]: info: Received an update from plugin. extracting info from payload Dec 29 21:21:33 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:33 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:33 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:33 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:33 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:33 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:33 volumio volumio[935]: info: ------------------------------ 35ms Dec 29 21:21:33 volumio volumio[935]: info: ------------------------------ 32ms Dec 29 21:21:35 volumio volumio[935]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 29 21:21:35 volumio volumio[935]: info: CoreStateMachine::ClearQueue Dec 29 21:21:35 volumio volumio[935]: info: CoreStateMachine::stop Dec 29 21:21:35 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:35 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:21:35 volumio volumio[935]: info: CoreStateMachine::stPlaybackTimer Dec 29 21:21:35 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Dec 29 21:21:35 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Dec 29 21:21:35 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:35 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:35 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:35 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:35 volumio volumio[935]: info: CoreStateMachine::serviceStop Dec 29 21:21:35 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:35 volumio volumio[935]: info: CoreCommandRouter::serviceStop Dec 29 21:21:35 volumio volumio[935]: info: [1767043295915] ControllerWebradio::stop Dec 29 21:21:35 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 21:21:35 volumio volumio[935]: info: CorePlayQueue::clearPlayQueue Dec 29 21:21:35 volumio volumio[935]: info: CorePlayQueue::saveQueue Dec 29 21:21:35 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:35 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:35 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Dec 29 21:21:35 volumio volumio[935]: info: CoreStateMachine::addQueueItems Dec 29 21:21:35 volumio volumio[935]: info: CorePlayQueue::addQueueItems Dec 29 21:21:35 volumio volumio[935]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99441647 Dec 29 21:21:35 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 29 21:21:35 volumio volumio[935]: info: CorePlayQueue::saveQueue Dec 29 21:21:35 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Dec 29 21:21:35 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Dec 29 21:21:35 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Dec 29 21:21:35 volumio volumio[935]: info: CoreCommandRouter::volumioPlay Dec 29 21:21:35 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:21:35 volumio volumio[935]: info: CoreStateMachine::play index 0 Dec 29 21:21:35 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:35 volumio volumio[935]: info: CoreStateMachine::stop Dec 29 21:21:35 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:35 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:21:35 volumio volumio[935]: info: CoreStateMachine::play index undefined Dec 29 21:21:35 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:35 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:35 volumio volumio[935]: info: CoreStateMachine::startPlaybackTimer Dec 29 21:21:35 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:35 volumio volumio[935]: info: [1767043295940] ControllerWebradio::clearAddPlayTrack Dec 29 21:21:35 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 21:21:35 volumio volumio[935]: info: sendMpdCommand stop took 53 milliseconds Dec 29 21:21:35 volumio volumio[935]: info: Dec 29 21:21:35 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:21:35 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:21:35 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:21:35 volumio volumio[935]: info: sendMpdCommand stop took 29 milliseconds Dec 29 21:21:35 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand clear Dec 29 21:21:35 volumio volumio[935]: info: Dec 29 21:21:35 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:21:35 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:21:35 volumio volumio[935]: info: sendMpdCommand status took 2 milliseconds Dec 29 21:21:35 volumio volumio[935]: info: sendMpdCommand clear took 2 milliseconds Dec 29 21:21:35 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:21:35 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:21:35 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99441647" Dec 29 21:21:35 volumio volumio[935]: error: updateQueue error: null Dec 29 21:21:35 volumio volumio[935]: info: ------------------------------ 4ms Dec 29 21:21:35 volumio volumio[935]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 29 21:21:35 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:21:35 volumio volumio[935]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 29 21:21:35 volumio volumio[935]: info: ------------------------------ 9ms Dec 29 21:21:36 volumio volumio[935]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99441647" took 275 milliseconds Dec 29 21:21:36 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 29 21:21:36 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand play Dec 29 21:21:36 volumio volumio[935]: info: Dec 29 21:21:36 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:21:36 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:21:36 volumio volumio[935]: info: sendMpdCommand play took 2 milliseconds Dec 29 21:21:36 volumio volumio[935]: info: ------------------------------ 2ms Dec 29 21:21:36 volumio volumio[935]: info: Dec 29 21:21:36 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:21:36 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:21:36 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:21:36 volumio volumio[935]: info: Dec 29 21:21:36 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:21:36 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:21:36 volumio volumio[935]: info: Dec 29 21:21:36 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:21:36 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:21:36 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:21:36 volumio volumio[935]: info: sendMpdCommand status took 3 milliseconds Dec 29 21:21:36 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:21:36 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:21:36 volumio volumio[935]: info: ------------------------------ 5ms Dec 29 21:21:36 volumio volumio[935]: info: sendMpdCommand status took 3 milliseconds Dec 29 21:21:36 volumio volumio[935]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 29 21:21:36 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:21:36 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:21:36 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:21:36 volumio volumio[935]: info: ControllerMpd::pushState Dec 29 21:21:36 volumio volumio[935]: info: CoreCommandRouter::servicePushState Dec 29 21:21:36 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:36 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":473,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Eliza Neals - Bitten By The Blues (Feat. Popa Chubby)","artist":"Blues Radio","album":null,"uri":"https://i4.streams.ovh:8352/stream","trackType":""} Dec 29 21:21:36 volumio volumio[935]: verbose: CURRENT POSITION 0 Dec 29 21:21:36 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Dec 29 21:21:36 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus stop Dec 29 21:21:36 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:36 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:36 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:36 volumio volumio[935]: info: ------------------------------ 18ms Dec 29 21:21:36 volumio volumio[935]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 29 21:21:36 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:21:36 volumio volumio[935]: info: ControllerMpd::pushState Dec 29 21:21:36 volumio volumio[935]: info: CoreCommandRouter::servicePushState Dec 29 21:21:36 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:36 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":484,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Eliza Neals - Bitten By The Blues (Feat. Popa Chubby)","artist":"Blues Radio","album":null,"uri":"https://i4.streams.ovh:8352/stream","trackType":""} Dec 29 21:21:36 volumio volumio[935]: verbose: CURRENT POSITION 0 Dec 29 21:21:36 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Dec 29 21:21:36 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus play Dec 29 21:21:36 volumio volumio[935]: info: Received an update from plugin. extracting info from payload Dec 29 21:21:36 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 29 21:21:36 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:36 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:36 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:36 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:36 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:36 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:36 volumio volumio[935]: info: ------------------------------ 40ms Dec 29 21:21:39 volumio volumio[935]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 29 21:21:39 volumio volumio[935]: info: CoreStateMachine::ClearQueue Dec 29 21:21:39 volumio volumio[935]: info: CoreStateMachine::stop Dec 29 21:21:39 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:39 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:21:39 volumio volumio[935]: info: CoreStateMachine::stPlaybackTimer Dec 29 21:21:39 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Dec 29 21:21:39 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Dec 29 21:21:39 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:39 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:39 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:39 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:39 volumio volumio[935]: info: CoreStateMachine::serviceStop Dec 29 21:21:39 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:39 volumio volumio[935]: info: CoreCommandRouter::serviceStop Dec 29 21:21:39 volumio volumio[935]: info: [1767043299837] ControllerWebradio::stop Dec 29 21:21:39 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 21:21:39 volumio volumio[935]: info: CorePlayQueue::clearPlayQueue Dec 29 21:21:39 volumio volumio[935]: info: CorePlayQueue::saveQueue Dec 29 21:21:39 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:39 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:39 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Dec 29 21:21:39 volumio volumio[935]: info: CoreStateMachine::addQueueItems Dec 29 21:21:39 volumio volumio[935]: info: CorePlayQueue::addQueueItems Dec 29 21:21:39 volumio volumio[935]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99502708 Dec 29 21:21:39 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 29 21:21:39 volumio volumio[935]: info: CorePlayQueue::saveQueue Dec 29 21:21:39 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Dec 29 21:21:39 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Dec 29 21:21:39 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Dec 29 21:21:39 volumio volumio[935]: info: CoreCommandRouter::volumioPlay Dec 29 21:21:39 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:21:39 volumio volumio[935]: info: CoreStateMachine::play index 0 Dec 29 21:21:39 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:39 volumio volumio[935]: info: CoreStateMachine::stop Dec 29 21:21:39 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:39 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:21:39 volumio volumio[935]: info: CoreStateMachine::play index undefined Dec 29 21:21:39 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:39 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:39 volumio volumio[935]: info: CoreStateMachine::startPlaybackTimer Dec 29 21:21:39 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:39 volumio volumio[935]: info: [1767043299856] ControllerWebradio::clearAddPlayTrack Dec 29 21:21:39 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 21:21:39 volumio volumio[935]: info: Dec 29 21:21:39 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:21:39 volumio volumio[935]: info: sendMpdCommand stop took 41 milliseconds Dec 29 21:21:39 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:21:39 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:21:39 volumio volumio[935]: info: sendMpdCommand stop took 23 milliseconds Dec 29 21:21:39 volumio volumio[935]: info: sendMpdCommand status took 2 milliseconds Dec 29 21:21:39 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand clear Dec 29 21:21:39 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:21:39 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:21:39 volumio volumio[935]: info: Dec 29 21:21:39 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:21:39 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:21:39 volumio volumio[935]: info: sendMpdCommand clear took 2 milliseconds Dec 29 21:21:39 volumio volumio[935]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 29 21:21:39 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99502708" Dec 29 21:21:39 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:21:39 volumio volumio[935]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 29 21:21:39 volumio volumio[935]: info: ------------------------------ 8ms Dec 29 21:21:39 volumio volumio[935]: error: updateQueue error: null Dec 29 21:21:39 volumio volumio[935]: info: ------------------------------ 4ms Dec 29 21:21:39 volumio volumio[935]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99502708" took 111 milliseconds Dec 29 21:21:39 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 29 21:21:40 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand play Dec 29 21:21:40 volumio volumio[935]: info: Dec 29 21:21:40 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:21:40 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:21:40 volumio volumio[935]: info: sendMpdCommand play took 2 milliseconds Dec 29 21:21:40 volumio volumio[935]: info: ------------------------------ 2ms Dec 29 21:21:40 volumio volumio[935]: info: Dec 29 21:21:40 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:21:40 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:21:40 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:21:40 volumio volumio[935]: info: Dec 29 21:21:40 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:21:40 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:21:40 volumio volumio[935]: info: Dec 29 21:21:40 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:21:40 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:21:40 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:21:40 volumio volumio[935]: info: sendMpdCommand status took 5 milliseconds Dec 29 21:21:40 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:21:40 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:21:40 volumio volumio[935]: info: ------------------------------ 4ms Dec 29 21:21:40 volumio volumio[935]: info: sendMpdCommand status took 4 milliseconds Dec 29 21:21:40 volumio volumio[935]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 29 21:21:40 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:21:40 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:21:40 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:21:40 volumio volumio[935]: info: ControllerMpd::pushState Dec 29 21:21:40 volumio volumio[935]: info: CoreCommandRouter::servicePushState Dec 29 21:21:40 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:40 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Dr. Hook - Only Sixteen","artist":"Classic Hits 109 - 70s Hits!","album":null,"uri":"http://144.217.79.175:6980/stream","trackType":""} Dec 29 21:21:40 volumio volumio[935]: verbose: CURRENT POSITION 0 Dec 29 21:21:40 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Dec 29 21:21:40 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus stop Dec 29 21:21:40 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:40 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:40 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:40 volumio volumio[935]: info: ------------------------------ 20ms Dec 29 21:21:40 volumio volumio[935]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 29 21:21:40 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:21:40 volumio volumio[935]: info: ControllerMpd::pushState Dec 29 21:21:40 volumio volumio[935]: info: CoreCommandRouter::servicePushState Dec 29 21:21:40 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:40 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Dr. Hook - Only Sixteen","artist":"Classic Hits 109 - 70s Hits!","album":null,"uri":"http://144.217.79.175:6980/stream","trackType":""} Dec 29 21:21:40 volumio volumio[935]: verbose: CURRENT POSITION 0 Dec 29 21:21:40 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Dec 29 21:21:40 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus play Dec 29 21:21:40 volumio volumio[935]: info: Received an update from plugin. extracting info from payload Dec 29 21:21:40 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 29 21:21:40 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:40 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:40 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:40 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:40 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:40 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:40 volumio volumio[935]: info: ------------------------------ 28ms Dec 29 21:21:43 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 29 21:21:43 volumio volumio[935]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 29 21:21:43 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 29 21:21:43 volumio volumio[935]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Dec 29 21:21:43 volumio volumio[935]: info: Received Get System Version Dec 29 21:21:43 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 29 21:21:44 volumio volumio[935]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 29 21:21:44 volumio volumio[935]: info: CoreStateMachine::ClearQueue Dec 29 21:21:44 volumio volumio[935]: info: CoreStateMachine::stop Dec 29 21:21:44 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:44 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:21:44 volumio volumio[935]: info: CoreStateMachine::stPlaybackTimer Dec 29 21:21:44 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Dec 29 21:21:44 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Dec 29 21:21:44 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:44 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:44 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:44 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:44 volumio volumio[935]: info: CoreStateMachine::serviceStop Dec 29 21:21:44 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:44 volumio volumio[935]: info: CoreCommandRouter::serviceStop Dec 29 21:21:44 volumio volumio[935]: info: [1767043304120] ControllerWebradio::stop Dec 29 21:21:44 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 21:21:44 volumio volumio[935]: info: CorePlayQueue::clearPlayQueue Dec 29 21:21:44 volumio volumio[935]: info: CorePlayQueue::saveQueue Dec 29 21:21:44 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:44 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:44 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Dec 29 21:21:44 volumio volumio[935]: info: CoreStateMachine::addQueueItems Dec 29 21:21:44 volumio volumio[935]: info: CorePlayQueue::addQueueItems Dec 29 21:21:44 volumio volumio[935]: info: Adding Item to queue: http://cdn.gotradio.com/itunes/bit_o_blues.pls Dec 29 21:21:44 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 29 21:21:44 volumio volumio[935]: info: CorePlayQueue::saveQueue Dec 29 21:21:44 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Dec 29 21:21:44 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Dec 29 21:21:44 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Dec 29 21:21:44 volumio volumio[935]: info: CoreCommandRouter::volumioPlay Dec 29 21:21:44 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:21:44 volumio volumio[935]: info: CoreStateMachine::play index 0 Dec 29 21:21:44 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:44 volumio volumio[935]: info: CoreStateMachine::stop Dec 29 21:21:44 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:44 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:21:44 volumio volumio[935]: info: CoreStateMachine::play index undefined Dec 29 21:21:44 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:44 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:44 volumio volumio[935]: info: CoreStateMachine::startPlaybackTimer Dec 29 21:21:44 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:44 volumio volumio[935]: info: [1767043304141] ControllerWebradio::clearAddPlayTrack Dec 29 21:21:44 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 21:21:44 volumio volumio[935]: info: Dec 29 21:21:44 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:21:44 volumio volumio[935]: info: sendMpdCommand stop took 140 milliseconds Dec 29 21:21:44 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:21:44 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:21:44 volumio volumio[935]: info: sendMpdCommand stop took 120 milliseconds Dec 29 21:21:44 volumio volumio[935]: info: sendMpdCommand status took 1 milliseconds Dec 29 21:21:44 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand clear Dec 29 21:21:44 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:21:44 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:21:44 volumio volumio[935]: info: Dec 29 21:21:44 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:21:44 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:21:44 volumio volumio[935]: info: sendMpdCommand clear took 2 milliseconds Dec 29 21:21:44 volumio volumio[935]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 29 21:21:44 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand load "http://cdn.gotradio.com/itunes/bit_o_blues.pls" Dec 29 21:21:44 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:21:44 volumio volumio[935]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 29 21:21:44 volumio volumio[935]: info: ------------------------------ 7ms Dec 29 21:21:44 volumio volumio[935]: error: updateQueue error: null Dec 29 21:21:44 volumio volumio[935]: info: ------------------------------ 4ms Dec 29 21:21:44 volumio volumio[935]: info: sendMpdCommand load "http://cdn.gotradio.com/itunes/bit_o_blues.pls" took 99 milliseconds Dec 29 21:21:44 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 29 21:21:44 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand play Dec 29 21:21:44 volumio volumio[935]: info: Dec 29 21:21:44 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:21:44 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:21:44 volumio volumio[935]: info: sendMpdCommand play took 3 milliseconds Dec 29 21:21:44 volumio volumio[935]: info: ------------------------------ 2ms Dec 29 21:21:45 volumio volumio[935]: info: Dec 29 21:21:45 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:21:45 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:21:45 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:21:45 volumio volumio[935]: info: Dec 29 21:21:45 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:21:45 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:21:45 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:21:45 volumio volumio[935]: info: Dec 29 21:21:45 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:21:45 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:21:45 volumio volumio[935]: info: Dec 29 21:21:45 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:21:45 volumio volumio[935]: info: sendMpdCommand status took 5 milliseconds Dec 29 21:21:45 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:21:45 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:21:45 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:21:45 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:21:45 volumio volumio[935]: info: sendMpdCommand status took 5 milliseconds Dec 29 21:21:45 volumio volumio[935]: info: ------------------------------ 5ms Dec 29 21:21:45 volumio volumio[935]: info: sendMpdCommand status took 3 milliseconds Dec 29 21:21:45 volumio volumio[935]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 29 21:21:45 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:21:45 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:21:45 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:21:45 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:21:45 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:21:45 volumio volumio[935]: info: ControllerMpd::pushState Dec 29 21:21:45 volumio volumio[935]: info: CoreCommandRouter::servicePushState Dec 29 21:21:45 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:45 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"6019_128.mp3","artist":"GotRadio - Bit O' Blues","album":null,"uri":"http://pureplay.cdnstream1.com/6019_128.mp3","trackType":"mp3"} Dec 29 21:21:45 volumio volumio[935]: verbose: CURRENT POSITION 0 Dec 29 21:21:45 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Dec 29 21:21:45 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus stop Dec 29 21:21:45 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:45 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:45 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:45 volumio volumio[935]: info: ------------------------------ 28ms Dec 29 21:21:45 volumio volumio[935]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 29 21:21:45 volumio volumio[935]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 29 21:21:45 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:21:45 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:21:45 volumio volumio[935]: info: ControllerMpd::pushState Dec 29 21:21:45 volumio volumio[935]: info: CoreCommandRouter::servicePushState Dec 29 21:21:45 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:45 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"6019_128.mp3","artist":"GotRadio - Bit O' Blues","album":null,"uri":"http://pureplay.cdnstream1.com/6019_128.mp3","trackType":"mp3"} Dec 29 21:21:45 volumio volumio[935]: verbose: CURRENT POSITION 0 Dec 29 21:21:45 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Dec 29 21:21:45 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus play Dec 29 21:21:45 volumio volumio[935]: info: Received an update from plugin. extracting info from payload Dec 29 21:21:45 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 29 21:21:45 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:45 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:45 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:45 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:45 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:45 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:45 volumio volumio[935]: info: ControllerMpd::pushState Dec 29 21:21:45 volumio volumio[935]: info: CoreCommandRouter::servicePushState Dec 29 21:21:45 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:45 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"6019_128.mp3","artist":"GotRadio - Bit O' Blues","album":null,"uri":"http://pureplay.cdnstream1.com/6019_128.mp3","trackType":"mp3"} Dec 29 21:21:45 volumio volumio[935]: verbose: CURRENT POSITION 0 Dec 29 21:21:45 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Dec 29 21:21:45 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus play Dec 29 21:21:45 volumio volumio[935]: info: Received an update from plugin. extracting info from payload Dec 29 21:21:45 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 29 21:21:45 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:45 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:45 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:45 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:45 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:45 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:45 volumio volumio[935]: info: ------------------------------ 50ms Dec 29 21:21:45 volumio volumio[935]: info: ------------------------------ 48ms Dec 29 21:21:45 volumio volumio[935]: info: Dec 29 21:21:45 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:21:45 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:21:45 volumio volumio[935]: info: Dec 29 21:21:45 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:21:45 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:21:45 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:21:45 volumio volumio[935]: info: ------------------------------ 4ms Dec 29 21:21:45 volumio volumio[935]: info: sendMpdCommand status took 3 milliseconds Dec 29 21:21:45 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:21:45 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:21:45 volumio volumio[935]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 29 21:21:45 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:21:45 volumio volumio[935]: info: ControllerMpd::pushState Dec 29 21:21:45 volumio volumio[935]: info: CoreCommandRouter::servicePushState Dec 29 21:21:45 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:45 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Eddie Cotton - Delivered By The Blues","artist":"GotRadio - Bit O' Blues","album":null,"uri":"http://pureplay.cdnstream1.com/6019_128.mp3","trackType":"mp3"} Dec 29 21:21:45 volumio volumio[935]: verbose: CURRENT POSITION 0 Dec 29 21:21:45 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Dec 29 21:21:45 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus play Dec 29 21:21:45 volumio volumio[935]: info: Received an update from plugin. extracting info from payload Dec 29 21:21:45 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 29 21:21:45 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:45 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:45 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:45 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:45 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:45 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:45 volumio volumio[935]: info: ------------------------------ 24ms Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::ClearQueue Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::stop Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:50 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::stPlaybackTimer Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Dec 29 21:21:50 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:50 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::serviceStop Dec 29 21:21:50 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::serviceStop Dec 29 21:21:50 volumio volumio[935]: info: [1767043310517] ControllerWebradio::stop Dec 29 21:21:50 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 21:21:50 volumio volumio[935]: info: CorePlayQueue::clearPlayQueue Dec 29 21:21:50 volumio volumio[935]: info: CorePlayQueue::saveQueue Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::addQueueItems Dec 29 21:21:50 volumio volumio[935]: info: CorePlayQueue::addQueueItems Dec 29 21:21:50 volumio volumio[935]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s232698 Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 29 21:21:50 volumio volumio[935]: info: Dec 29 21:21:50 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:21:50 volumio volumio[935]: info: sendMpdCommand stop took 133 milliseconds Dec 29 21:21:50 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:21:50 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:21:50 volumio volumio[935]: info: sendMpdCommand status took 1 milliseconds Dec 29 21:21:50 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:21:50 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:21:50 volumio volumio[935]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 29 21:21:50 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:21:50 volumio volumio[935]: info: ControllerMpd::pushState Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::servicePushState Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:50 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:50 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:50 volumio volumio[935]: 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":"Eddie Cotton - Delivered By The Blues","artist":"GotRadio - Bit O' Blues","album":null,"uri":"http://pureplay.cdnstream1.com/6019_128.mp3","trackType":"mp3"} Dec 29 21:21:50 volumio volumio[935]: verbose: CURRENT POSITION 0 Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::syncState stateService stop Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus stop Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:50 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:50 volumio volumio[935]: info: No code Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:50 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:50 volumio volumio[935]: info: ------------------------------ 27ms Dec 29 21:21:50 volumio volumio[935]: info: CorePlayQueue::saveQueue Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Dec 29 21:21:50 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::volumioPlay Dec 29 21:21:50 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::play index 0 Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::stop Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:50 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::play index undefined Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:50 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::startPlaybackTimer Dec 29 21:21:50 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:50 volumio volumio[935]: info: [1767043310765] ControllerWebradio::clearAddPlayTrack Dec 29 21:21:50 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 21:21:50 volumio volumio[935]: info: sendMpdCommand stop took 7 milliseconds Dec 29 21:21:50 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand clear Dec 29 21:21:50 volumio volumio[935]: info: Dec 29 21:21:50 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:21:50 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:21:50 volumio volumio[935]: info: sendMpdCommand clear took 2 milliseconds Dec 29 21:21:50 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand load "http://cdn-cms.tunein.com/service/Audio/georestricted.enUS.mp3" Dec 29 21:21:50 volumio volumio[935]: error: updateQueue error: null Dec 29 21:21:50 volumio volumio[935]: info: ------------------------------ 3ms Dec 29 21:21:50 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand add "http://cdn-cms.tunein.com/service/Audio/georestricted.enUS.mp3" Dec 29 21:21:50 volumio volumio[935]: info: Dec 29 21:21:50 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:21:50 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:21:50 volumio volumio[935]: info: sendMpdCommand add "http://cdn-cms.tunein.com/service/Audio/georestricted.enUS.mp3" took 1 milliseconds Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 29 21:21:50 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand play Dec 29 21:21:50 volumio volumio[935]: info: ------------------------------ 3ms Dec 29 21:21:50 volumio volumio[935]: info: sendMpdCommand play took 1 milliseconds Dec 29 21:21:50 volumio volumio[935]: info: Dec 29 21:21:50 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:21:50 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:21:50 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:21:50 volumio volumio[935]: info: Dec 29 21:21:50 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:21:50 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:21:50 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:21:50 volumio volumio[935]: info: sendMpdCommand status took 3 milliseconds Dec 29 21:21:50 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:21:50 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:21:50 volumio volumio[935]: info: sendMpdCommand status took 3 milliseconds Dec 29 21:21:50 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:21:50 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:21:50 volumio volumio[935]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 29 21:21:50 volumio volumio[935]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 29 21:21:50 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:21:50 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:21:50 volumio volumio[935]: info: ControllerMpd::pushState Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::servicePushState Dec 29 21:21:50 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:50 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":384,"duration":6,"samplerate":"16 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"24 Kbps","isStreaming":false,"title":"georestricted.enUS.mp3","artist":null,"album":null,"uri":"http://cdn-cms.tunein.com/service/Audio/georestricted.enUS.mp3","trackType":"mp3"} Dec 29 21:21:50 volumio volumio[935]: verbose: CURRENT POSITION 0 Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus stop Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:50 volumio volumio[935]: info: ControllerMpd::pushState Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::servicePushState Dec 29 21:21:50 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:50 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":384,"duration":6,"samplerate":"16 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"24 Kbps","isStreaming":false,"title":"georestricted.enUS.mp3","artist":null,"album":null,"uri":"http://cdn-cms.tunein.com/service/Audio/georestricted.enUS.mp3","trackType":"mp3"} Dec 29 21:21:50 volumio volumio[935]: verbose: CURRENT POSITION 0 Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus play Dec 29 21:21:50 volumio volumio[935]: info: Received an update from plugin. extracting info from payload Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:50 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:50 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:50 volumio volumio[935]: info: ------------------------------ 33ms Dec 29 21:21:50 volumio volumio[935]: info: ------------------------------ 29ms Dec 29 21:21:54 volumio volumio[935]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 29 21:21:54 volumio volumio[935]: info: CoreStateMachine::ClearQueue Dec 29 21:21:54 volumio volumio[935]: info: CoreStateMachine::stop Dec 29 21:21:54 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:54 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:21:54 volumio volumio[935]: info: CoreStateMachine::stPlaybackTimer Dec 29 21:21:54 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Dec 29 21:21:54 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Dec 29 21:21:54 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:54 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:54 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:54 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:54 volumio volumio[935]: info: CoreStateMachine::serviceStop Dec 29 21:21:54 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:54 volumio volumio[935]: info: CoreCommandRouter::serviceStop Dec 29 21:21:54 volumio volumio[935]: info: [1767043314023] ControllerWebradio::stop Dec 29 21:21:54 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 21:21:54 volumio volumio[935]: info: CorePlayQueue::clearPlayQueue Dec 29 21:21:54 volumio volumio[935]: info: CorePlayQueue::saveQueue Dec 29 21:21:54 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:54 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:54 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Dec 29 21:21:54 volumio volumio[935]: info: CoreStateMachine::addQueueItems Dec 29 21:21:54 volumio volumio[935]: info: CorePlayQueue::addQueueItems Dec 29 21:21:54 volumio volumio[935]: info: Adding Item to queue: http://cdn.gotradio.com/itunes/bit_o_blues.pls Dec 29 21:21:54 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 29 21:21:54 volumio volumio[935]: info: CorePlayQueue::saveQueue Dec 29 21:21:54 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Dec 29 21:21:54 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Dec 29 21:21:54 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Dec 29 21:21:54 volumio volumio[935]: info: CoreCommandRouter::volumioPlay Dec 29 21:21:54 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:21:54 volumio volumio[935]: info: CoreStateMachine::play index 0 Dec 29 21:21:54 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:54 volumio volumio[935]: info: CoreStateMachine::stop Dec 29 21:21:54 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:54 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:21:54 volumio volumio[935]: info: CoreStateMachine::play index undefined Dec 29 21:21:54 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:21:54 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:54 volumio volumio[935]: info: CoreStateMachine::startPlaybackTimer Dec 29 21:21:54 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:54 volumio volumio[935]: info: [1767043314041] ControllerWebradio::clearAddPlayTrack Dec 29 21:21:54 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 21:21:54 volumio volumio[935]: info: sendMpdCommand stop took 55 milliseconds Dec 29 21:21:54 volumio volumio[935]: info: Dec 29 21:21:54 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:21:54 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:21:54 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:21:54 volumio volumio[935]: info: sendMpdCommand stop took 38 milliseconds Dec 29 21:21:54 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand clear Dec 29 21:21:54 volumio volumio[935]: info: Dec 29 21:21:54 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:21:54 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:21:54 volumio volumio[935]: info: sendMpdCommand status took 3 milliseconds Dec 29 21:21:54 volumio volumio[935]: info: sendMpdCommand clear took 3 milliseconds Dec 29 21:21:54 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:21:54 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:21:54 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand load "http://cdn.gotradio.com/itunes/bit_o_blues.pls" Dec 29 21:21:54 volumio volumio[935]: error: updateQueue error: null Dec 29 21:21:54 volumio volumio[935]: info: ------------------------------ 14ms Dec 29 21:21:54 volumio volumio[935]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 29 21:21:54 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:21:54 volumio volumio[935]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 29 21:21:54 volumio volumio[935]: info: ------------------------------ 18ms Dec 29 21:21:54 volumio volumio[935]: info: sendMpdCommand load "http://cdn.gotradio.com/itunes/bit_o_blues.pls" took 24 milliseconds Dec 29 21:21:54 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 29 21:21:54 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand play Dec 29 21:21:54 volumio volumio[935]: info: Dec 29 21:21:54 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:21:54 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:21:54 volumio volumio[935]: info: sendMpdCommand play took 1 milliseconds Dec 29 21:21:54 volumio volumio[935]: info: ------------------------------ 3ms Dec 29 21:21:55 volumio volumio[935]: info: Dec 29 21:21:55 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:21:55 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:21:55 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:21:55 volumio volumio[935]: info: Dec 29 21:21:55 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:21:55 volumio volumio[935]: info: sendMpdCommand status took 1 milliseconds Dec 29 21:21:55 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:21:55 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:21:55 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:21:55 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:21:55 volumio volumio[935]: info: Dec 29 21:21:55 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:21:55 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:21:55 volumio volumio[935]: info: sendMpdCommand status took 4 milliseconds Dec 29 21:21:55 volumio volumio[935]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 29 21:21:55 volumio volumio[935]: info: ------------------------------ 3ms Dec 29 21:21:55 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:21:55 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:21:55 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:21:55 volumio volumio[935]: info: ControllerMpd::pushState Dec 29 21:21:55 volumio volumio[935]: info: CoreCommandRouter::servicePushState Dec 29 21:21:55 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:55 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":278,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"6019_128.mp3","artist":"GotRadio - Bit O' Blues","album":null,"uri":"http://pureplay.cdnstream1.com/6019_128.mp3","trackType":"mp3"} Dec 29 21:21:55 volumio volumio[935]: verbose: CURRENT POSITION 0 Dec 29 21:21:55 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Dec 29 21:21:55 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus stop Dec 29 21:21:55 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:55 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:55 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:55 volumio volumio[935]: info: ------------------------------ 17ms Dec 29 21:21:55 volumio volumio[935]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 29 21:21:55 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:21:55 volumio volumio[935]: info: ControllerMpd::pushState Dec 29 21:21:55 volumio volumio[935]: info: CoreCommandRouter::servicePushState Dec 29 21:21:55 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:55 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"6019_128.mp3","artist":"GotRadio - Bit O' Blues","album":null,"uri":"http://pureplay.cdnstream1.com/6019_128.mp3","trackType":"mp3"} Dec 29 21:21:55 volumio volumio[935]: verbose: CURRENT POSITION 0 Dec 29 21:21:55 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Dec 29 21:21:55 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus play Dec 29 21:21:55 volumio volumio[935]: info: Received an update from plugin. extracting info from payload Dec 29 21:21:55 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 29 21:21:55 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:55 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:55 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:55 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:55 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:55 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:55 volumio volumio[935]: info: ------------------------------ 29ms Dec 29 21:21:55 volumio volumio[935]: info: Dec 29 21:21:55 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:21:55 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:21:55 volumio volumio[935]: info: Dec 29 21:21:55 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:21:55 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:21:55 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:21:55 volumio volumio[935]: info: ------------------------------ 3ms Dec 29 21:21:55 volumio volumio[935]: info: sendMpdCommand status took 2 milliseconds Dec 29 21:21:55 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:21:55 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:21:55 volumio volumio[935]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 29 21:21:55 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:21:55 volumio volumio[935]: info: ControllerMpd::pushState Dec 29 21:21:55 volumio volumio[935]: info: CoreCommandRouter::servicePushState Dec 29 21:21:55 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:21:55 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Eddie Cotton - Delivered By The Blues","artist":"GotRadio - Bit O' Blues","album":null,"uri":"http://pureplay.cdnstream1.com/6019_128.mp3","trackType":"mp3"} Dec 29 21:21:55 volumio volumio[935]: verbose: CURRENT POSITION 0 Dec 29 21:21:55 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Dec 29 21:21:55 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus play Dec 29 21:21:55 volumio volumio[935]: info: Received an update from plugin. extracting info from payload Dec 29 21:21:55 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 29 21:21:55 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:55 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:55 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:55 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:21:55 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:21:55 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:21:55 volumio volumio[935]: info: ------------------------------ 20ms Dec 29 21:22:00 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 29 21:22:00 volumio volumio[935]: info: CURURI: music-library Dec 29 21:22:01 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 29 21:22:01 volumio volumio[935]: info: CURURI: music-library/NAS Dec 29 21:22:02 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 29 21:22:02 volumio volumio[935]: info: CURURI: music-library/NAS/NAS-Server Dec 29 21:22:03 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 29 21:22:03 volumio volumio[935]: info: CURURI: music-library/NAS/NAS-Server/Interpreten Dec 29 21:22:05 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 29 21:22:05 volumio volumio[935]: info: CURURI: music-library/NAS/NAS-Server/Interpreten/ACDC Dec 29 21:22:05 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 29 21:22:05 volumio volumio[935]: info: CURURI: music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live Dec 29 21:22:06 volumio volumio[935]: info: Dec 29 21:22:06 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:22:06 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:22:06 volumio volumio[935]: info: Dec 29 21:22:06 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:22:06 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:22:06 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:22:06 volumio volumio[935]: info: ------------------------------ 3ms Dec 29 21:22:06 volumio volumio[935]: info: sendMpdCommand status took 2 milliseconds Dec 29 21:22:06 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:22:06 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:22:06 volumio volumio[935]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 29 21:22:06 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:22:06 volumio volumio[935]: info: ControllerMpd::pushState Dec 29 21:22:06 volumio volumio[935]: info: CoreCommandRouter::servicePushState Dec 29 21:22:06 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:06 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11232,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"John Mayall - Greeny","artist":"GotRadio - Bit O' Blues","album":null,"uri":"http://pureplay.cdnstream1.com/6019_128.mp3","trackType":"mp3"} Dec 29 21:22:06 volumio volumio[935]: verbose: CURRENT POSITION 0 Dec 29 21:22:06 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Dec 29 21:22:06 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus play Dec 29 21:22:06 volumio volumio[935]: info: Received an update from plugin. extracting info from payload Dec 29 21:22:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 29 21:22:06 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:22:06 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:22:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:22:06 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:22:06 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:22:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:22:06 volumio volumio[935]: info: ------------------------------ 19ms Dec 29 21:22:06 volumio volumio[935]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 29 21:22:06 volumio volumio[935]: info: CoreStateMachine::ClearQueue Dec 29 21:22:06 volumio volumio[935]: info: CoreStateMachine::stop Dec 29 21:22:06 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:22:06 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:22:06 volumio volumio[935]: info: CoreStateMachine::stPlaybackTimer Dec 29 21:22:06 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Dec 29 21:22:06 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Dec 29 21:22:06 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:22:06 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:06 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:22:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:22:06 volumio volumio[935]: info: CoreStateMachine::serviceStop Dec 29 21:22:06 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:06 volumio volumio[935]: info: CoreCommandRouter::serviceStop Dec 29 21:22:06 volumio volumio[935]: info: [1767043326868] ControllerWebradio::stop Dec 29 21:22:06 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 21:22:06 volumio volumio[935]: info: CorePlayQueue::clearPlayQueue Dec 29 21:22:06 volumio volumio[935]: info: CorePlayQueue::saveQueue Dec 29 21:22:06 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:22:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:22:06 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Dec 29 21:22:06 volumio volumio[935]: info: CoreStateMachine::addQueueItems Dec 29 21:22:06 volumio volumio[935]: info: CorePlayQueue::addQueueItems Dec 29 21:22:06 volumio volumio[935]: info: Adding Item to queue: music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Thunderstruck - AC_DC.mp3 Dec 29 21:22:06 volumio volumio[935]: info: Exploding uri music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Thunderstruck - AC_DC.mp3 in service mpd Dec 29 21:22:06 volumio volumio[935]: scanFolder - failure to stat '/mnt/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Thunderstruck - AC_DC.mp3' Dec 29 21:22:06 volumio volumio[935]: info: Adding Item to queue: music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Shoot to Thrill - AC_DC.mp3 Dec 29 21:22:06 volumio volumio[935]: info: Exploding uri music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Shoot to Thrill - AC_DC.mp3 in service mpd Dec 29 21:22:06 volumio volumio[935]: scanFolder - failure to stat '/mnt/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Shoot to Thrill - AC_DC.mp3' Dec 29 21:22:06 volumio volumio[935]: info: Adding Item to queue: music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Back in Black - AC_DC.mp3 Dec 29 21:22:06 volumio volumio[935]: info: Exploding uri music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Back in Black - AC_DC.mp3 in service mpd Dec 29 21:22:06 volumio volumio[935]: scanFolder - failure to stat '/mnt/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Back in Black - AC_DC.mp3' Dec 29 21:22:06 volumio volumio[935]: info: Adding Item to queue: music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Who Made Who - AC_DC.mp3 Dec 29 21:22:06 volumio volumio[935]: info: Exploding uri music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Who Made Who - AC_DC.mp3 in service mpd Dec 29 21:22:06 volumio volumio[935]: scanFolder - failure to stat '/mnt/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Who Made Who - AC_DC.mp3' Dec 29 21:22:06 volumio volumio[935]: info: Adding Item to queue: music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Heatseeker - AC_DC.mp3 Dec 29 21:22:06 volumio volumio[935]: info: Exploding uri music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Heatseeker - AC_DC.mp3 in service mpd Dec 29 21:22:06 volumio volumio[935]: scanFolder - failure to stat '/mnt/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Heatseeker - AC_DC.mp3' Dec 29 21:22:06 volumio volumio[935]: info: Adding Item to queue: music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/The Jack - AC_DC.mp3 Dec 29 21:22:06 volumio volumio[935]: info: Exploding uri music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/The Jack - AC_DC.mp3 in service mpd Dec 29 21:22:06 volumio volumio[935]: scanFolder - failure to stat '/mnt/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/The Jack - AC_DC.mp3' Dec 29 21:22:06 volumio volumio[935]: info: Adding Item to queue: music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Moneytalks - AC_DC.mp3 Dec 29 21:22:06 volumio volumio[935]: info: Exploding uri music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Moneytalks - AC_DC.mp3 in service mpd Dec 29 21:22:06 volumio volumio[935]: scanFolder - failure to stat '/mnt/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Moneytalks - AC_DC.mp3' Dec 29 21:22:06 volumio volumio[935]: info: Adding Item to queue: music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Hells Bells [Live] - AC_DC.mp3 Dec 29 21:22:06 volumio volumio[935]: info: Exploding uri music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Hells Bells [Live] - AC_DC.mp3 in service mpd Dec 29 21:22:06 volumio volumio[935]: scanFolder - failure to stat '/mnt/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Hells Bells [Live] - AC_DC.mp3' Dec 29 21:22:06 volumio volumio[935]: info: Adding Item to queue: music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Dirty Deeds Done Dirt Cheap - AC_DC.mp3 Dec 29 21:22:06 volumio volumio[935]: info: Exploding uri music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Dirty Deeds Done Dirt Cheap - AC_DC.mp3 in service mpd Dec 29 21:22:06 volumio volumio[935]: scanFolder - failure to stat '/mnt/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Dirty Deeds Done Dirt Cheap - AC_DC.mp3' Dec 29 21:22:06 volumio volumio[935]: info: Adding Item to queue: music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Whole Lotta Rosie - AC_DC.mp3 Dec 29 21:22:06 volumio volumio[935]: info: Exploding uri music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Whole Lotta Rosie - AC_DC.mp3 in service mpd Dec 29 21:22:06 volumio volumio[935]: scanFolder - failure to stat '/mnt/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Whole Lotta Rosie - AC_DC.mp3' Dec 29 21:22:06 volumio volumio[935]: info: Adding Item to queue: music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/You Shook Me All Night Long - AC_DC.mp3 Dec 29 21:22:06 volumio volumio[935]: info: Exploding uri music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/You Shook Me All Night Long - AC_DC.mp3 in service mpd Dec 29 21:22:06 volumio volumio[935]: scanFolder - failure to stat '/mnt/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/You Shook Me All Night Long - AC_DC.mp3' Dec 29 21:22:06 volumio volumio[935]: info: Adding Item to queue: music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Highway to Hell - AC_DC.mp3 Dec 29 21:22:06 volumio volumio[935]: info: Exploding uri music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Highway to Hell - AC_DC.mp3 in service mpd Dec 29 21:22:06 volumio volumio[935]: scanFolder - failure to stat '/mnt/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/Highway to Hell - AC_DC.mp3' Dec 29 21:22:06 volumio volumio[935]: info: Adding Item to queue: music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/T.N.T. - AC_DC.mp3 Dec 29 21:22:06 volumio volumio[935]: info: Exploding uri music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/T.N.T. - AC_DC.mp3 in service mpd Dec 29 21:22:06 volumio volumio[935]: scanFolder - failure to stat '/mnt/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/T.N.T. - AC_DC.mp3' Dec 29 21:22:06 volumio volumio[935]: info: Adding Item to queue: music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/For Those About to Rock (We Sa - AC_DC.mp3 Dec 29 21:22:06 volumio volumio[935]: info: Exploding uri music-library/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/For Those About to Rock (We Sa - AC_DC.mp3 in service mpd Dec 29 21:22:06 volumio volumio[935]: scanFolder - failure to stat '/mnt/NAS/NAS-Server/Interpreten/ACDC/ACDC Live/For Those About to Rock (We Sa - AC_DC.mp3' Dec 29 21:22:06 volumio volumio[935]: info: CorePlayQueue::saveQueue Dec 29 21:22:06 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Dec 29 21:22:06 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Dec 29 21:22:06 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Dec 29 21:22:06 volumio volumio[935]: info: CoreCommandRouter::volumioPlay Dec 29 21:22:06 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:22:06 volumio volumio[935]: info: CoreStateMachine::play index 3 Dec 29 21:22:06 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:22:06 volumio volumio[935]: info: CoreStateMachine::stop Dec 29 21:22:06 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:22:06 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:22:06 volumio volumio[935]: info: CoreStateMachine::play index undefined Dec 29 21:22:06 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:22:06 volumio volumio[935]: info: CorePlayQueue::getTrack 3 Dec 29 21:22:07 volumio volumio[935]: info: sendMpdCommand stop took 132 milliseconds Dec 29 21:22:07 volumio volumio[935]: info: Dec 29 21:22:07 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:22:07 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:22:07 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:22:07 volumio volumio[935]: info: sendMpdCommand status took 1 milliseconds Dec 29 21:22:07 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:22:07 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:22:07 volumio volumio[935]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 29 21:22:07 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:22:07 volumio volumio[935]: info: ControllerMpd::pushState Dec 29 21:22:07 volumio volumio[935]: info: CoreCommandRouter::servicePushState Dec 29 21:22:07 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:22:07 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:07 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:22:07 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:22:07 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:07 volumio volumio[935]: 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":"John Mayall - Greeny","artist":"GotRadio - Bit O' Blues","album":null,"uri":"http://pureplay.cdnstream1.com/6019_128.mp3","trackType":"mp3"} Dec 29 21:22:07 volumio volumio[935]: verbose: CURRENT POSITION 0 Dec 29 21:22:07 volumio volumio[935]: info: CoreStateMachine::syncState stateService stop Dec 29 21:22:07 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus stop Dec 29 21:22:07 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:22:07 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:07 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:22:07 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:22:07 volumio volumio[935]: info: No code Dec 29 21:22:07 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:22:07 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:07 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:22:07 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:22:07 volumio volumio[935]: info: ------------------------------ 21ms Dec 29 21:22:10 volumio volumio[935]: info: CoreCommandRouter::volumioGetState Dec 29 21:22:10 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 29 21:22:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 29 21:22:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 29 21:22:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 29 21:22:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 29 21:22:12 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 29 21:22:13 volumio volumio[935]: info: CoreCommandRouter::volumioGetVisibleSources Dec 29 21:22:13 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 29 21:22:13 volumio volumio[935]: info: CoreCommandRouter::volumioGetState Dec 29 21:22:13 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:13 volumio volumio[935]: info: CoreCommandRouter::volumioGetQueue Dec 29 21:22:13 volumio volumio[935]: info: CoreStateMachine::getQueue Dec 29 21:22:13 volumio volumio[935]: info: CorePlayQueue::getQueue Dec 29 21:22:13 volumio volumio[935]: info: Listing playlists Dec 29 21:22:13 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 29 21:22:13 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 29 21:22:13 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 29 21:22:13 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 29 21:22:13 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 29 21:22:16 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 29 21:22:16 volumio volumio[935]: info: CURURI: artists:// Dec 29 21:22:17 volumio volumio[935]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/01/2a283c72-0430-4abd-86c0-5c9d38513585.jpg' Dec 29 21:22:19 volumio volumio[935]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/04/4cea83d2-7758-4e77-a014-cd57b2a9a3ca.jpg' Dec 29 21:22:20 volumio volumio[935]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/07/ff972bf6-3a47-46fc-9b44-d9578955fd8c.jpg' Dec 29 21:22:21 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 29 21:22:21 volumio volumio[935]: info: CURURI: artists://018.%20Johny%20Winter Dec 29 21:22:24 volumio volumio[935]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/A%20Perfect%20Circle/fbc7ea25-32d3-4262-b72f-723f2494bea5.jpg' Dec 29 21:22:24 volumio volumio[935]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 29 21:22:24 volumio volumio[935]: info: CoreStateMachine::ClearQueue Dec 29 21:22:24 volumio volumio[935]: info: CoreStateMachine::stop Dec 29 21:22:24 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:22:24 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:22:24 volumio volumio[935]: info: CorePlayQueue::clearPlayQueue Dec 29 21:22:24 volumio volumio[935]: info: CorePlayQueue::saveQueue Dec 29 21:22:24 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:22:24 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:22:24 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Dec 29 21:22:24 volumio volumio[935]: info: CoreStateMachine::addQueueItems Dec 29 21:22:24 volumio volumio[935]: info: CorePlayQueue::addQueueItems Dec 29 21:22:24 volumio volumio[935]: info: Adding Item to queue: artists://018.%20Johny%20Winter Dec 29 21:22:24 volumio volumio[935]: info: Exploding uri artists://018.%20Johny%20Winter in service mpd Dec 29 21:22:24 volumio volumio[935]: info: CorePlayQueue::saveQueue Dec 29 21:22:24 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Dec 29 21:22:24 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Dec 29 21:22:24 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Dec 29 21:22:24 volumio volumio[935]: info: CoreCommandRouter::volumioPlay Dec 29 21:22:24 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:22:24 volumio volumio[935]: info: CoreStateMachine::play index 0 Dec 29 21:22:24 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:22:24 volumio volumio[935]: info: CoreStateMachine::stop Dec 29 21:22:24 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:22:24 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:22:24 volumio volumio[935]: info: CoreStateMachine::play index undefined Dec 29 21:22:24 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:22:24 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:24 volumio volumio[935]: info: CoreStateMachine::startPlaybackTimer Dec 29 21:22:24 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:24 volumio volumio[935]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS-Server/StreamWriter/Joint Radio Blues/018. Johny Winter - Life Is Hard.mp3 Dec 29 21:22:24 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 21:22:24 volumio volumio[935]: info: sendMpdCommand stop took 5 milliseconds Dec 29 21:22:24 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand clear Dec 29 21:22:24 volumio volumio[935]: info: Dec 29 21:22:24 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:22:24 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:22:24 volumio volumio[935]: info: sendMpdCommand clear took 22 milliseconds Dec 29 21:22:24 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS-Server/StreamWriter/Joint Radio Blues/018. Johny Winter - Life Is Hard.mp3" Dec 29 21:22:24 volumio volumio[935]: error: updateQueue error: null Dec 29 21:22:24 volumio volumio[935]: info: Dec 29 21:22:24 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:22:24 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:22:24 volumio volumio[935]: info: ------------------------------ 13ms Dec 29 21:22:24 volumio volumio[935]: info: sendMpdCommand add "NAS/NAS-Server/StreamWriter/Joint Radio Blues/018. Johny Winter - Life Is Hard.mp3" took 8 milliseconds Dec 29 21:22:24 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand play Dec 29 21:22:24 volumio volumio[935]: info: Dec 29 21:22:24 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:22:24 volumio volumio[935]: info: ------------------------------ 11ms Dec 29 21:22:24 volumio volumio[935]: info: sendMpdCommand play took 6 milliseconds Dec 29 21:22:24 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:22:24 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:22:24 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 29 21:22:24 volumio volumio[935]: info: sendMpdCommand status took 6 milliseconds Dec 29 21:22:24 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:22:24 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:22:24 volumio volumio[935]: info: sendMpdCommand clearerror took 7 milliseconds Dec 29 21:22:24 volumio volumio[935]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 29 21:22:24 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:22:24 volumio volumio[935]: info: ControllerMpd::pushState Dec 29 21:22:24 volumio volumio[935]: info: CoreCommandRouter::servicePushState Dec 29 21:22:24 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:22:24 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:24 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:22:24 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:22:24 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:24 volumio volumio[935]: 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":"Life Is Hard","artist":"018. Johny Winter","album":null,"uri":"NAS/NAS-Server/StreamWriter/Joint Radio Blues/018. Johny Winter - Life Is Hard.mp3","trackType":"mp3"} Dec 29 21:22:24 volumio volumio[935]: verbose: CURRENT POSITION 0 Dec 29 21:22:24 volumio volumio[935]: info: CoreStateMachine::syncState stateService stop Dec 29 21:22:24 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus stop Dec 29 21:22:24 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:22:24 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:24 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:22:24 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:22:24 volumio volumio[935]: info: No code Dec 29 21:22:24 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:22:24 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:24 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:22:24 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:22:24 volumio volumio[935]: info: ------------------------------ 43ms Dec 29 21:22:30 volumio volumio[935]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 29 21:22:30 volumio volumio[935]: info: CoreStateMachine::ClearQueue Dec 29 21:22:30 volumio volumio[935]: info: CoreStateMachine::stop Dec 29 21:22:30 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:22:30 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:22:30 volumio volumio[935]: info: CorePlayQueue::clearPlayQueue Dec 29 21:22:30 volumio volumio[935]: info: CorePlayQueue::saveQueue Dec 29 21:22:30 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:22:30 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:22:30 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Dec 29 21:22:30 volumio volumio[935]: info: CoreStateMachine::addQueueItems Dec 29 21:22:30 volumio volumio[935]: info: CorePlayQueue::addQueueItems Dec 29 21:22:30 volumio volumio[935]: info: Adding Item to queue: artists://018.%20Johny%20Winter Dec 29 21:22:30 volumio volumio[935]: info: Exploding uri artists://018.%20Johny%20Winter in service mpd Dec 29 21:22:30 volumio volumio[935]: info: CorePlayQueue::saveQueue Dec 29 21:22:30 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Dec 29 21:22:30 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Dec 29 21:22:30 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Dec 29 21:22:30 volumio volumio[935]: info: CoreCommandRouter::volumioPlay Dec 29 21:22:30 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:22:30 volumio volumio[935]: info: CoreStateMachine::play index 0 Dec 29 21:22:30 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:22:30 volumio volumio[935]: info: CoreStateMachine::stop Dec 29 21:22:30 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:22:30 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:22:30 volumio volumio[935]: info: CoreStateMachine::play index undefined Dec 29 21:22:30 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:22:30 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:30 volumio volumio[935]: info: CoreStateMachine::startPlaybackTimer Dec 29 21:22:30 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:30 volumio volumio[935]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS-Server/StreamWriter/Joint Radio Blues/018. Johny Winter - Life Is Hard.mp3 Dec 29 21:22:30 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 21:22:30 volumio volumio[935]: info: sendMpdCommand stop took 6 milliseconds Dec 29 21:22:30 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand clear Dec 29 21:22:30 volumio volumio[935]: info: Dec 29 21:22:30 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:22:30 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:22:30 volumio volumio[935]: info: sendMpdCommand clear took 2 milliseconds Dec 29 21:22:30 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS-Server/StreamWriter/Joint Radio Blues/018. Johny Winter - Life Is Hard.mp3" Dec 29 21:22:30 volumio volumio[935]: error: updateQueue error: null Dec 29 21:22:30 volumio volumio[935]: info: Dec 29 21:22:30 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:22:30 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:22:30 volumio volumio[935]: info: ------------------------------ 6ms Dec 29 21:22:30 volumio volumio[935]: info: sendMpdCommand add "NAS/NAS-Server/StreamWriter/Joint Radio Blues/018. Johny Winter - Life Is Hard.mp3" took 4 milliseconds Dec 29 21:22:30 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand play Dec 29 21:22:30 volumio volumio[935]: info: Dec 29 21:22:30 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:22:30 volumio volumio[935]: info: ------------------------------ 6ms Dec 29 21:22:30 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:22:30 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:22:30 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 29 21:22:30 volumio volumio[935]: info: sendMpdCommand status took 5 milliseconds Dec 29 21:22:30 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:22:30 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:22:30 volumio volumio[935]: info: sendMpdCommand clearerror took 2 milliseconds Dec 29 21:22:30 volumio volumio[935]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 29 21:22:30 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:22:30 volumio volumio[935]: info: ControllerMpd::pushState Dec 29 21:22:30 volumio volumio[935]: info: CoreCommandRouter::servicePushState Dec 29 21:22:30 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:22:30 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:30 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:22:30 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:22:30 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:30 volumio volumio[935]: 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":"Life Is Hard","artist":"018. Johny Winter","album":null,"uri":"NAS/NAS-Server/StreamWriter/Joint Radio Blues/018. Johny Winter - Life Is Hard.mp3","trackType":"mp3"} Dec 29 21:22:30 volumio volumio[935]: verbose: CURRENT POSITION 0 Dec 29 21:22:30 volumio volumio[935]: info: CoreStateMachine::syncState stateService stop Dec 29 21:22:30 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus stop Dec 29 21:22:30 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:22:30 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:30 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:22:30 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:22:30 volumio volumio[935]: info: No code Dec 29 21:22:30 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:22:30 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:30 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:22:30 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:22:30 volumio volumio[935]: info: ------------------------------ 32ms Dec 29 21:22:36 volumio volumio[935]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Bernd%20Cl%C3%BCver/f36bdd58-3345-42d5-aa45-72b308f1ac1d.jpg' Dec 29 21:22:36 volumio volumio[935]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 29 21:22:36 volumio volumio[935]: info: CoreStateMachine::ClearQueue Dec 29 21:22:36 volumio volumio[935]: info: CoreStateMachine::stop Dec 29 21:22:36 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:22:36 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:22:36 volumio volumio[935]: info: CorePlayQueue::clearPlayQueue Dec 29 21:22:36 volumio volumio[935]: info: CorePlayQueue::saveQueue Dec 29 21:22:36 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:22:36 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:22:36 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Dec 29 21:22:36 volumio volumio[935]: info: CoreStateMachine::addQueueItems Dec 29 21:22:36 volumio volumio[935]: info: CorePlayQueue::addQueueItems Dec 29 21:22:36 volumio volumio[935]: info: Adding Item to queue: artists://018.%20Johny%20Winter Dec 29 21:22:36 volumio volumio[935]: info: Exploding uri artists://018.%20Johny%20Winter in service mpd Dec 29 21:22:36 volumio volumio[935]: info: CorePlayQueue::saveQueue Dec 29 21:22:36 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Dec 29 21:22:36 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Dec 29 21:22:36 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Dec 29 21:22:36 volumio volumio[935]: info: CoreCommandRouter::volumioPlay Dec 29 21:22:36 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:22:36 volumio volumio[935]: info: CoreStateMachine::play index 0 Dec 29 21:22:36 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:22:36 volumio volumio[935]: info: CoreStateMachine::stop Dec 29 21:22:36 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:22:36 volumio volumio[935]: verbose: UNSET VOLATILE Dec 29 21:22:36 volumio volumio[935]: info: CoreStateMachine::play index undefined Dec 29 21:22:36 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 21:22:36 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:36 volumio volumio[935]: info: CoreStateMachine::startPlaybackTimer Dec 29 21:22:36 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:36 volumio volumio[935]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS-Server/StreamWriter/Joint Radio Blues/018. Johny Winter - Life Is Hard.mp3 Dec 29 21:22:36 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 21:22:36 volumio volumio[935]: info: sendMpdCommand stop took 11 milliseconds Dec 29 21:22:36 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand clear Dec 29 21:22:36 volumio volumio[935]: info: Dec 29 21:22:36 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:22:36 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:22:37 volumio volumio[935]: info: sendMpdCommand clear took 1 milliseconds Dec 29 21:22:37 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS-Server/StreamWriter/Joint Radio Blues/018. Johny Winter - Life Is Hard.mp3" Dec 29 21:22:37 volumio volumio[935]: error: updateQueue error: null Dec 29 21:22:37 volumio volumio[935]: info: Dec 29 21:22:37 volumio volumio[935]: ---------------------------- MPD announces system playlist update Dec 29 21:22:37 volumio volumio[935]: info: Ignoring MPD Status Update Dec 29 21:22:37 volumio volumio[935]: info: ------------------------------ 4ms Dec 29 21:22:37 volumio volumio[935]: info: sendMpdCommand add "NAS/NAS-Server/StreamWriter/Joint Radio Blues/018. Johny Winter - Life Is Hard.mp3" took 3 milliseconds Dec 29 21:22:37 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand play Dec 29 21:22:37 volumio volumio[935]: info: ------------------------------ 3ms Dec 29 21:22:37 volumio volumio[935]: info: Dec 29 21:22:37 volumio volumio[935]: ---------------------------- MPD announces state update: player Dec 29 21:22:37 volumio volumio[935]: info: ControllerMpd::getState Dec 29 21:22:37 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Dec 29 21:22:37 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 29 21:22:37 volumio volumio[935]: info: sendMpdCommand status took 5 milliseconds Dec 29 21:22:37 volumio volumio[935]: verbose: ControllerMpd::parseState Dec 29 21:22:37 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 21:22:37 volumio volumio[935]: info: sendMpdCommand clearerror took 3 milliseconds Dec 29 21:22:37 volumio volumio[935]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 29 21:22:37 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Dec 29 21:22:37 volumio volumio[935]: info: ControllerMpd::pushState Dec 29 21:22:37 volumio volumio[935]: info: CoreCommandRouter::servicePushState Dec 29 21:22:37 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:22:37 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:37 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:22:37 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:22:37 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:37 volumio volumio[935]: 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":"Life Is Hard","artist":"018. Johny Winter","album":null,"uri":"NAS/NAS-Server/StreamWriter/Joint Radio Blues/018. Johny Winter - Life Is Hard.mp3","trackType":"mp3"} Dec 29 21:22:37 volumio volumio[935]: verbose: CURRENT POSITION 0 Dec 29 21:22:37 volumio volumio[935]: info: CoreStateMachine::syncState stateService stop Dec 29 21:22:37 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus stop Dec 29 21:22:37 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:22:37 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:37 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:22:37 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:22:37 volumio volumio[935]: info: No code Dec 29 21:22:37 volumio volumio[935]: info: CoreStateMachine::pushState Dec 29 21:22:37 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Dec 29 21:22:37 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Dec 29 21:22:37 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 21:22:37 volumio volumio[935]: info: ------------------------------ 37ms Dec 29 21:22:42 volumio volumio[935]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Boomtown%20Rats/ed4c9359-b71b-4ae4-86a8-25b48cbcd321.jpg' Dec 29 21:22:43 volumio volumio[935]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Boys%20II%20Men/c2ca220a-dc77-4930-94f0-aef29f2240ef.jpg' Dec 29 21:22:44 volumio volumio[935]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Buck69/a3ec9161-84f2-406a-9ba7-82bce1a787bb.jpg' Dec 29 21:22:46 volumio volumio[935]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Carey%2C%20Mariah/eacb69e6-3d4e-489a-b6e5-ff13b28173c7.jpg' Dec 29 21:22:48 volumio volumio[935]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 21:22:48 volumio volumio[935]: TypeError: Cannot read property 'slice' of null Dec 29 21:22:48 volumio volumio[935]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:714:41) Dec 29 21:22:48 volumio volumio[935]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:724:7) Dec 29 21:22:48 volumio volumio[935]: at Object.onceWrapper (events.js:315:30) Dec 29 21:22:48 volumio volumio[935]: at emitOne (events.js:116:13) Dec 29 21:22:48 volumio volumio[935]: at ClientRequest.emit (events.js:211:7) Dec 29 21:22:48 volumio volumio[935]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:551:21) Dec 29 21:22:48 volumio volumio[935]: at HTTPParser.parserOnHeadersComplete (_http_common.js:115:23) Dec 29 21:22:48 volumio volumio[935]: at TLSSocket.socketOnData (_http_client.js:440:20) Dec 29 21:22:48 volumio volumio[935]: at emitOne (events.js:116:13) Dec 29 21:22:48 volumio volumio[935]: at TLSSocket.emit (events.js:211:7) Dec 29 21:22:48 volumio volumio[935]: at addChunk (_stream_readable.js:263:12) Dec 29 21:22:48 volumio volumio[935]: at readableAddChunk (_stream_readable.js:250:11) Dec 29 21:22:48 volumio volumio[935]: at TLSSocket.Readable.push (_stream_readable.js:208:10) Dec 29 21:22:48 volumio volumio[935]: at TLSWrap.onread (net.js:607:20) Dec 29 21:22:48 volumio volumio[935]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 21:22:49 volumio sudo[21186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-29 21:21 Dec 29 21:22:49 volumio sudo[21186]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"