-- Logs begin at Sat 2024-08-24 23:28:26 CST, end at Sat 2024-08-24 23:47:45 CST. -- Aug 24 23:46:00 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 24 23:46:00 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 24 23:46:00 volumio volumio[1073]: info: Discovery: Getting this device information Aug 24 23:46:00 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Aug 24 23:46:00 volumio volumio[1073]: info: CorePlayQueue::getTrack 11 Aug 24 23:46:00 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 24 23:46:00 volumio volumio[1073]: info: Preload queue cleared Aug 24 23:46:02 volumio volumio[1073]: info: Preload queue cleared Aug 24 23:46:02 volumio volumio[1073]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 24 23:46:02 volumio volumio[1073]: info: CoreStateMachine::ClearQueue Aug 24 23:46:02 volumio volumio[1073]: info: CoreStateMachine::stop Aug 24 23:46:02 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:46:02 volumio volumio[1073]: info: CoreStateMachine::stPlaybackTimer Aug 24 23:46:02 volumio volumio[1073]: info: CoreStateMachine::updateTrackBlock Aug 24 23:46:02 volumio volumio[1073]: info: CorePlayQueue::getTrackBlock Aug 24 23:46:02 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:02 volumio volumio[1073]: info: CorePlayQueue::getTrack 11 Aug 24 23:46:02 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:46:02 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:02 volumio volumio[1073]: info: CoreStateMachine::serviceStop Aug 24 23:46:02 volumio volumio[1073]: info: CorePlayQueue::getTrack 11 Aug 24 23:46:02 volumio volumio[1073]: info: CoreCommandRouter::serviceStop Aug 24 23:46:02 volumio volumio[1073]: info: ControllerMpd::stop Aug 24 23:46:02 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 23:46:02 volumio volumio[1073]: info: CorePlayQueue::clearPlayQueue Aug 24 23:46:02 volumio volumio[1073]: info: CorePlayQueue::saveQueue Aug 24 23:46:02 volumio volumio[1073]: info: CoreCommandRouter::volumioPushQueue Aug 24 23:46:02 volumio volumio[1073]: info: CoreStateMachine::addQueueItems Aug 24 23:46:02 volumio volumio[1073]: info: CorePlayQueue::addQueueItems Aug 24 23:46:02 volumio volumio[1073]: info: Preload queue cleared Aug 24 23:46:02 volumio volumio[1073]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996 Aug 24 23:46:02 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 24 23:46:02 volumio volumio[1073]: info: CoreCommandRouter::volumioPushQueue Aug 24 23:46:02 volumio volumio[1073]: info: CorePlayQueue::saveQueue Aug 24 23:46:02 volumio volumio[1073]: info: CoreStateMachine::updateTrackBlock Aug 24 23:46:02 volumio volumio[1073]: info: CorePlayQueue::getTrackBlock Aug 24 23:46:02 volumio volumio[1073]: info: CoreCommandRouter::volumioPlay Aug 24 23:46:02 volumio volumio[1073]: info: CoreStateMachine::play index 0 Aug 24 23:46:02 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:46:02 volumio volumio[1073]: info: CoreStateMachine::stop Aug 24 23:46:02 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:46:02 volumio volumio[1073]: info: CoreStateMachine::play index undefined Aug 24 23:46:02 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:46:02 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:02 volumio volumio[1073]: info: CoreStateMachine::startPlaybackTimer Aug 24 23:46:02 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:02 volumio volumio[1073]: info: [1724514362283] ControllerWebradio::clearAddPlayTrack Aug 24 23:46:02 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 23:46:02 volumio volumio[1073]: info: Aug 24 23:46:02 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:46:02 volumio volumio[1073]: info: sendMpdCommand stop took 20 milliseconds Aug 24 23:46:02 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:46:02 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:46:02 volumio volumio[1073]: info: sendMpdCommand stop took 7 milliseconds Aug 24 23:46:02 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand clear Aug 24 23:46:02 volumio volumio[1073]: info: Aug 24 23:46:02 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:46:02 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:46:02 volumio volumio[1073]: info: sendMpdCommand status took 2 milliseconds Aug 24 23:46:02 volumio volumio[1073]: info: sendMpdCommand clear took 1 milliseconds Aug 24 23:46:02 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:46:02 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:46:02 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Aug 24 23:46:03 volumio volumio[1073]: error: updateQueue error: null Aug 24 23:46:03 volumio volumio[1073]: info: ------------------------------ 1040ms Aug 24 23:46:03 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 1038 milliseconds Aug 24 23:46:03 volumio volumio[1073]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 1038 milliseconds Aug 24 23:46:03 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:46:03 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 24 23:46:03 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand play Aug 24 23:46:03 volumio volumio[1073]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 24 23:46:03 volumio volumio[1073]: info: ------------------------------ 1045ms Aug 24 23:46:03 volumio volumio[1073]: info: Aug 24 23:46:03 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:46:03 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:46:03 volumio volumio[1073]: info: sendMpdCommand play took 3 milliseconds Aug 24 23:46:03 volumio volumio[1073]: info: ------------------------------ 1ms Aug 24 23:46:06 volumio volumio[1073]: info: Aug 24 23:46:06 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:46:06 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:46:06 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:46:06 volumio volumio[1073]: info: Aug 24 23:46:06 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:46:06 volumio volumio[1073]: info: sendMpdCommand status took 5 milliseconds Aug 24 23:46:06 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:46:06 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:46:06 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:46:06 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:46:06 volumio volumio[1073]: info: sendMpdCommand status took 4 milliseconds Aug 24 23:46:06 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 24 23:46:06 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:46:06 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:46:06 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:46:06 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:46:06 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:46:06 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:06 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 9454/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Aug 24 23:46:06 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:46:06 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:46:06 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus stop Aug 24 23:46:06 volumio volumio[1073]: info: ------------------------------ 13ms Aug 24 23:46:06 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 24 23:46:06 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:46:06 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:46:06 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:46:06 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:06 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 9454/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Aug 24 23:46:06 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:46:06 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:46:06 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus play Aug 24 23:46:06 volumio volumio[1073]: info: Received an update from plugin. extracting info from payload Aug 24 23:46:06 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 24 23:46:06 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:06 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:06 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:46:06 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:06 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:06 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:06 volumio volumio[1073]: info: ------------------------------ 24ms Aug 24 23:46:06 volumio volumio[1073]: info: Aug 24 23:46:06 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:46:06 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:46:06 volumio volumio[1073]: info: Aug 24 23:46:06 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:46:06 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:46:06 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:46:06 volumio volumio[1073]: info: Aug 24 23:46:06 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:46:06 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:46:06 volumio volumio[1073]: info: Aug 24 23:46:06 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:46:06 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:46:06 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:46:06 volumio volumio[1073]: info: ------------------------------ 4ms Aug 24 23:46:06 volumio volumio[1073]: info: sendMpdCommand status took 3 milliseconds Aug 24 23:46:06 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:46:06 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:46:06 volumio volumio[1073]: info: ------------------------------ 4ms Aug 24 23:46:06 volumio volumio[1073]: info: sendMpdCommand status took 4 milliseconds Aug 24 23:46:06 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 24 23:46:06 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:46:06 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:46:06 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:46:06 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:46:06 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:46:06 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:06 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Bayerns bester Musikmix","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Aug 24 23:46:06 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:46:06 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:46:06 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus play Aug 24 23:46:06 volumio volumio[1073]: info: Received an update from plugin. extracting info from payload Aug 24 23:46:06 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 24 23:46:06 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:06 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:46:06 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:06 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:06 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:06 volumio volumio[1073]: info: ------------------------------ 24ms Aug 24 23:46:06 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 17 milliseconds Aug 24 23:46:06 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:46:06 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:46:06 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:46:06 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:06 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Bayerns bester Musikmix","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Aug 24 23:46:06 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:46:06 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:46:06 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus play Aug 24 23:46:06 volumio volumio[1073]: info: Received an update from plugin. extracting info from payload Aug 24 23:46:06 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 24 23:46:06 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:06 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:46:06 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:06 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:06 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:06 volumio volumio[1073]: info: ------------------------------ 34ms Aug 24 23:46:06 volumio volumio[1073]: info: Aug 24 23:46:06 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:46:06 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:46:06 volumio volumio[1073]: info: Aug 24 23:46:06 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:46:06 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:46:06 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:46:06 volumio volumio[1073]: info: ------------------------------ 3ms Aug 24 23:46:07 volumio volumio[1073]: info: sendMpdCommand status took 4 milliseconds Aug 24 23:46:07 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:46:07 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:46:07 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 24 23:46:07 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:46:07 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:46:07 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:46:07 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:07 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1111,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Bayerns bester Musikmix","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Aug 24 23:46:07 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:46:07 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:46:07 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus play Aug 24 23:46:07 volumio volumio[1073]: info: Received an update from plugin. extracting info from payload Aug 24 23:46:07 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 24 23:46:07 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:07 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:46:07 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:07 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:07 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:07 volumio volumio[1073]: info: ------------------------------ 21ms Aug 24 23:46:08 volumio volumio[1073]: info: Aug 24 23:46:08 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:46:08 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:46:08 volumio volumio[1073]: info: Aug 24 23:46:08 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:46:08 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:46:08 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:46:08 volumio volumio[1073]: info: ------------------------------ 1ms Aug 24 23:46:08 volumio volumio[1073]: info: sendMpdCommand status took 2 milliseconds Aug 24 23:46:08 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:46:08 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:46:08 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 24 23:46:08 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:46:08 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:46:08 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:46:08 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:08 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Bayerns bester Musikmix","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Aug 24 23:46:08 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:46:08 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:46:08 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus play Aug 24 23:46:08 volumio volumio[1073]: info: Received an update from plugin. extracting info from payload Aug 24 23:46:08 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 24 23:46:08 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:08 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:46:08 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:08 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:08 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:08 volumio volumio[1073]: info: ------------------------------ 25ms Aug 24 23:46:14 volumio volumio[1073]: info: Preload queue cleared Aug 24 23:46:14 volumio volumio[1073]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 24 23:46:14 volumio volumio[1073]: info: CoreStateMachine::ClearQueue Aug 24 23:46:14 volumio volumio[1073]: info: CoreStateMachine::stop Aug 24 23:46:14 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:46:14 volumio volumio[1073]: info: CoreStateMachine::stPlaybackTimer Aug 24 23:46:14 volumio volumio[1073]: info: CoreStateMachine::updateTrackBlock Aug 24 23:46:14 volumio volumio[1073]: info: CorePlayQueue::getTrackBlock Aug 24 23:46:14 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:14 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:14 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:46:14 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:14 volumio volumio[1073]: info: CoreStateMachine::serviceStop Aug 24 23:46:14 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:14 volumio volumio[1073]: info: CoreCommandRouter::serviceStop Aug 24 23:46:14 volumio volumio[1073]: info: [1724514374973] ControllerWebradio::stop Aug 24 23:46:14 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 23:46:14 volumio volumio[1073]: info: CorePlayQueue::clearPlayQueue Aug 24 23:46:14 volumio volumio[1073]: info: CorePlayQueue::saveQueue Aug 24 23:46:14 volumio volumio[1073]: info: CoreCommandRouter::volumioPushQueue Aug 24 23:46:14 volumio volumio[1073]: info: CoreStateMachine::addQueueItems Aug 24 23:46:14 volumio volumio[1073]: info: CorePlayQueue::addQueueItems Aug 24 23:46:14 volumio volumio[1073]: info: Preload queue cleared Aug 24 23:46:14 volumio volumio[1073]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320 Aug 24 23:46:14 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 24 23:46:14 volumio volumio[1073]: info: CoreCommandRouter::volumioPushQueue Aug 24 23:46:14 volumio volumio[1073]: info: CorePlayQueue::saveQueue Aug 24 23:46:14 volumio volumio[1073]: info: CoreStateMachine::updateTrackBlock Aug 24 23:46:14 volumio volumio[1073]: info: CorePlayQueue::getTrackBlock Aug 24 23:46:14 volumio volumio[1073]: info: CoreCommandRouter::volumioPlay Aug 24 23:46:14 volumio volumio[1073]: info: CoreStateMachine::play index 0 Aug 24 23:46:14 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:46:14 volumio volumio[1073]: info: CoreStateMachine::stop Aug 24 23:46:14 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:46:14 volumio volumio[1073]: info: CoreStateMachine::play index undefined Aug 24 23:46:14 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:46:14 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:14 volumio volumio[1073]: info: CoreStateMachine::startPlaybackTimer Aug 24 23:46:14 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:14 volumio volumio[1073]: info: [1724514374988] ControllerWebradio::clearAddPlayTrack Aug 24 23:46:14 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 23:46:14 volumio volumio[1073]: info: Aug 24 23:46:14 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:46:14 volumio volumio[1073]: info: sendMpdCommand stop took 17 milliseconds Aug 24 23:46:14 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:46:14 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:46:14 volumio volumio[1073]: info: sendMpdCommand stop took 4 milliseconds Aug 24 23:46:14 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand clear Aug 24 23:46:14 volumio volumio[1073]: info: Aug 24 23:46:14 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:46:14 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:46:14 volumio volumio[1073]: info: sendMpdCommand status took 2 milliseconds Aug 24 23:46:14 volumio volumio[1073]: info: sendMpdCommand clear took 1 milliseconds Aug 24 23:46:14 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:46:14 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:46:14 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Aug 24 23:46:14 volumio volumio[1073]: error: updateQueue error: null Aug 24 23:46:14 volumio volumio[1073]: info: ------------------------------ 2ms Aug 24 23:46:14 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 24 23:46:14 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:46:14 volumio volumio[1073]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 24 23:46:14 volumio volumio[1073]: info: ------------------------------ 5ms Aug 24 23:46:15 volumio volumio[1073]: info: Aug 24 23:46:15 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:46:15 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:46:15 volumio volumio[1073]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 252 milliseconds Aug 24 23:46:15 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 24 23:46:15 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand play Aug 24 23:46:15 volumio volumio[1073]: info: ------------------------------ 2ms Aug 24 23:46:15 volumio volumio[1073]: info: sendMpdCommand play took 1 milliseconds Aug 24 23:46:20 volumio volumio[1073]: info: Aug 24 23:46:20 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:46:20 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:46:20 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:46:20 volumio volumio[1073]: info: Aug 24 23:46:20 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:46:20 volumio volumio[1073]: info: sendMpdCommand status took 3 milliseconds Aug 24 23:46:20 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:46:20 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:46:20 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:46:20 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:46:20 volumio volumio[1073]: info: sendMpdCommand status took 1 milliseconds Aug 24 23:46:20 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 24 23:46:20 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:46:20 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:46:20 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:46:20 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:46:20 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:46:20 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:20 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5279/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Aug 24 23:46:20 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:46:20 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:46:20 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus stop Aug 24 23:46:20 volumio volumio[1073]: info: ------------------------------ 7ms Aug 24 23:46:20 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 24 23:46:20 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:46:20 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:46:20 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:46:20 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:20 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5279/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Aug 24 23:46:20 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:46:20 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:46:20 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus play Aug 24 23:46:20 volumio volumio[1073]: info: Received an update from plugin. extracting info from payload Aug 24 23:46:20 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 24 23:46:20 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:20 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:20 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:46:20 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:20 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:20 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:20 volumio volumio[1073]: info: ------------------------------ 12ms Aug 24 23:46:20 volumio volumio[1073]: info: Aug 24 23:46:20 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:46:20 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:46:20 volumio volumio[1073]: info: Aug 24 23:46:20 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:46:20 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:46:20 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:46:20 volumio volumio[1073]: info: ------------------------------ 2ms Aug 24 23:46:20 volumio volumio[1073]: info: sendMpdCommand status took 1 milliseconds Aug 24 23:46:20 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:46:20 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:46:20 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 24 23:46:20 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:46:20 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:46:20 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:46:20 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:20 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":862,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Aug 24 23:46:20 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:46:20 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:46:20 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus play Aug 24 23:46:20 volumio volumio[1073]: info: Received an update from plugin. extracting info from payload Aug 24 23:46:20 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 24 23:46:20 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:20 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:46:20 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:20 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:20 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:20 volumio volumio[1073]: info: ------------------------------ 14ms Aug 24 23:46:30 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 24 23:46:30 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 24 23:46:30 volumio volumio[1073]: info: Discovery: Getting this device information Aug 24 23:46:30 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Aug 24 23:46:30 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 24 23:46:32 volumio volumio[1073]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%BD%91%E7%BB%9C%E6%AD%8C%E6%89%8B/61c92bfc-4611-4d25-a7cd-29f159ac0dd7.jpg' Aug 24 23:46:34 volumio volumio[1073]: info: Aug 24 23:46:34 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:46:34 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:46:34 volumio volumio[1073]: info: Aug 24 23:46:34 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:46:34 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:46:34 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:46:34 volumio volumio[1073]: info: ------------------------------ 13ms Aug 24 23:46:34 volumio volumio[1073]: info: sendMpdCommand status took 4 milliseconds Aug 24 23:46:34 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:46:34 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:46:34 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 24 23:46:34 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:46:34 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:46:34 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:46:34 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:34 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14487,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Aug 24 23:46:34 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:46:34 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:46:34 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus play Aug 24 23:46:34 volumio volumio[1073]: info: Received an update from plugin. extracting info from payload Aug 24 23:46:34 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 24 23:46:34 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:34 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:46:34 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:34 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:34 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:34 volumio volumio[1073]: info: ------------------------------ 38ms Aug 24 23:46:49 volumio volumio[1073]: info: Aug 24 23:46:49 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:46:49 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:46:49 volumio volumio[1073]: info: Aug 24 23:46:49 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:46:49 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:46:49 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:46:49 volumio volumio[1073]: info: ------------------------------ 2ms Aug 24 23:46:49 volumio volumio[1073]: info: sendMpdCommand status took 2 milliseconds Aug 24 23:46:49 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:46:49 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:46:49 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 24 23:46:49 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:46:49 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:46:49 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:46:49 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:49 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Aug 24 23:46:49 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:46:49 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:46:49 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus play Aug 24 23:46:49 volumio volumio[1073]: info: Received an update from plugin. extracting info from payload Aug 24 23:46:49 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 24 23:46:49 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:49 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:46:49 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:49 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:49 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:49 volumio volumio[1073]: info: ------------------------------ 17ms Aug 24 23:46:49 volumio volumio[1073]: info: Preload queue cleared Aug 24 23:46:49 volumio volumio[1073]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 24 23:46:49 volumio volumio[1073]: info: CoreStateMachine::ClearQueue Aug 24 23:46:49 volumio volumio[1073]: info: CoreStateMachine::stop Aug 24 23:46:49 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:46:49 volumio volumio[1073]: info: CoreStateMachine::stPlaybackTimer Aug 24 23:46:49 volumio volumio[1073]: info: CoreStateMachine::updateTrackBlock Aug 24 23:46:49 volumio volumio[1073]: info: CorePlayQueue::getTrackBlock Aug 24 23:46:49 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:49 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:49 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:46:49 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:49 volumio volumio[1073]: info: CoreStateMachine::serviceStop Aug 24 23:46:49 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:49 volumio volumio[1073]: info: CoreCommandRouter::serviceStop Aug 24 23:46:49 volumio volumio[1073]: info: [1724514409822] ControllerWebradio::stop Aug 24 23:46:49 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 23:46:49 volumio volumio[1073]: info: CorePlayQueue::clearPlayQueue Aug 24 23:46:49 volumio volumio[1073]: info: CorePlayQueue::saveQueue Aug 24 23:46:49 volumio volumio[1073]: info: CoreCommandRouter::volumioPushQueue Aug 24 23:46:49 volumio volumio[1073]: info: CoreStateMachine::addQueueItems Aug 24 23:46:49 volumio volumio[1073]: info: CorePlayQueue::addQueueItems Aug 24 23:46:49 volumio volumio[1073]: info: Preload queue cleared Aug 24 23:46:49 volumio volumio[1073]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1760430 Aug 24 23:46:49 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 24 23:46:49 volumio volumio[1073]: info: CoreCommandRouter::volumioPushQueue Aug 24 23:46:49 volumio volumio[1073]: info: CorePlayQueue::saveQueue Aug 24 23:46:49 volumio volumio[1073]: info: CoreStateMachine::updateTrackBlock Aug 24 23:46:49 volumio volumio[1073]: info: CorePlayQueue::getTrackBlock Aug 24 23:46:49 volumio volumio[1073]: info: CoreCommandRouter::volumioPlay Aug 24 23:46:49 volumio volumio[1073]: info: CoreStateMachine::play index 0 Aug 24 23:46:49 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:46:49 volumio volumio[1073]: info: CoreStateMachine::stop Aug 24 23:46:49 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:46:49 volumio volumio[1073]: info: CoreStateMachine::play index undefined Aug 24 23:46:49 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:46:49 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:49 volumio volumio[1073]: info: CoreStateMachine::startPlaybackTimer Aug 24 23:46:49 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:49 volumio volumio[1073]: info: [1724514409845] ControllerWebradio::clearAddPlayTrack Aug 24 23:46:49 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 23:46:49 volumio volumio[1073]: info: Aug 24 23:46:49 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:46:49 volumio volumio[1073]: info: sendMpdCommand stop took 25 milliseconds Aug 24 23:46:49 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:46:49 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:46:49 volumio volumio[1073]: info: sendMpdCommand stop took 3 milliseconds Aug 24 23:46:49 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand clear Aug 24 23:46:49 volumio volumio[1073]: info: Aug 24 23:46:49 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:46:49 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:46:49 volumio volumio[1073]: info: sendMpdCommand status took 1 milliseconds Aug 24 23:46:49 volumio volumio[1073]: info: sendMpdCommand clear took 1 milliseconds Aug 24 23:46:49 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:46:49 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:46:49 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1760430" Aug 24 23:46:49 volumio volumio[1073]: error: updateQueue error: null Aug 24 23:46:49 volumio volumio[1073]: info: ------------------------------ 2ms Aug 24 23:46:49 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 24 23:46:49 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:46:49 volumio volumio[1073]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 24 23:46:49 volumio volumio[1073]: info: ------------------------------ 5ms Aug 24 23:46:50 volumio volumio[1073]: info: Aug 24 23:46:50 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:46:50 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:46:50 volumio volumio[1073]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1760430" took 254 milliseconds Aug 24 23:46:50 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 24 23:46:50 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand play Aug 24 23:46:50 volumio volumio[1073]: info: ------------------------------ 5ms Aug 24 23:46:50 volumio volumio[1073]: info: sendMpdCommand play took 4 milliseconds Aug 24 23:46:51 volumio volumio[1073]: info: Aug 24 23:46:51 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:46:51 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:46:51 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:46:51 volumio volumio[1073]: info: Aug 24 23:46:51 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:46:51 volumio volumio[1073]: info: sendMpdCommand status took 5 milliseconds Aug 24 23:46:51 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:46:51 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:46:51 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:46:51 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:46:51 volumio volumio[1073]: info: sendMpdCommand status took 2 milliseconds Aug 24 23:46:51 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 24 23:46:51 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:46:51 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:46:51 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:46:51 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:46:51 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:46:51 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:51 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"onelive_64","artist":"1.FM - America's Best Ballads (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/onelive_64","trackType":""} Aug 24 23:46:51 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:46:51 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:46:51 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus stop Aug 24 23:46:51 volumio volumio[1073]: info: ------------------------------ 9ms Aug 24 23:46:51 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 24 23:46:51 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:46:51 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:46:51 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:46:51 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:51 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"onelive_64","artist":"1.FM - America's Best Ballads (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/onelive_64","trackType":""} Aug 24 23:46:51 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:46:51 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:46:51 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus play Aug 24 23:46:51 volumio volumio[1073]: info: Received an update from plugin. extracting info from payload Aug 24 23:46:51 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:51 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:51 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:46:51 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:51 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:51 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:51 volumio volumio[1073]: info: ------------------------------ 20ms Aug 24 23:46:51 volumio volumio[1073]: info: Aug 24 23:46:51 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:46:51 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:46:51 volumio volumio[1073]: info: Aug 24 23:46:51 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:46:51 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:46:51 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:46:51 volumio volumio[1073]: info: Aug 24 23:46:51 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:46:51 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:46:51 volumio volumio[1073]: info: Aug 24 23:46:51 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:46:51 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:46:51 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:46:51 volumio volumio[1073]: info: ------------------------------ 2ms Aug 24 23:46:51 volumio volumio[1073]: info: sendMpdCommand status took 5 milliseconds Aug 24 23:46:51 volumio volumio[1073]: info: ------------------------------ 5ms Aug 24 23:46:51 volumio volumio[1073]: info: sendMpdCommand status took 4 milliseconds Aug 24 23:46:51 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:46:51 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:46:51 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:46:51 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:46:51 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 24 23:46:51 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:46:51 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:46:51 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:46:51 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:51 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":545,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Whitney Houston - I Have Nothing","artist":"1.FM - America's Best Ballads Radio","album":null,"uri":"http://185.33.21.112:80/onelive_64","trackType":""} Aug 24 23:46:51 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:46:51 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:46:51 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus play Aug 24 23:46:51 volumio volumio[1073]: info: Received an update from plugin. extracting info from payload Aug 24 23:46:51 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:51 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:46:51 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:51 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:51 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:51 volumio volumio[1073]: info: ------------------------------ 19ms Aug 24 23:46:51 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 24 23:46:51 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:46:51 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:46:51 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:46:51 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:46:51 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":852,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Whitney Houston - I Have Nothing","artist":"1.FM - America's Best Ballads Radio","album":null,"uri":"http://185.33.21.112:80/onelive_64","trackType":""} Aug 24 23:46:51 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:46:51 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:46:51 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus play Aug 24 23:46:51 volumio volumio[1073]: info: Received an update from plugin. extracting info from payload Aug 24 23:46:51 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:51 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:51 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:46:51 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:46:51 volumio volumio[1073]: info: ------------------------------ 27ms Aug 24 23:47:00 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 24 23:47:00 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 24 23:47:00 volumio volumio[1073]: info: Discovery: Getting this device information Aug 24 23:47:00 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Aug 24 23:47:00 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 24 23:47:20 volumio volumio[1073]: info: Preload queue cleared Aug 24 23:47:20 volumio volumio[1073]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 24 23:47:20 volumio volumio[1073]: info: CoreStateMachine::ClearQueue Aug 24 23:47:20 volumio volumio[1073]: info: CoreStateMachine::stop Aug 24 23:47:20 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:47:20 volumio volumio[1073]: info: CoreStateMachine::stPlaybackTimer Aug 24 23:47:20 volumio volumio[1073]: info: CoreStateMachine::updateTrackBlock Aug 24 23:47:20 volumio volumio[1073]: info: CorePlayQueue::getTrackBlock Aug 24 23:47:20 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:47:20 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:20 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:47:20 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:47:20 volumio volumio[1073]: info: CoreStateMachine::serviceStop Aug 24 23:47:20 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:20 volumio volumio[1073]: info: CoreCommandRouter::serviceStop Aug 24 23:47:20 volumio volumio[1073]: info: [1724514440027] ControllerWebradio::stop Aug 24 23:47:20 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 23:47:20 volumio volumio[1073]: info: CorePlayQueue::clearPlayQueue Aug 24 23:47:20 volumio volumio[1073]: info: CorePlayQueue::saveQueue Aug 24 23:47:20 volumio volumio[1073]: info: CoreCommandRouter::volumioPushQueue Aug 24 23:47:20 volumio volumio[1073]: info: CoreStateMachine::addQueueItems Aug 24 23:47:20 volumio volumio[1073]: info: CorePlayQueue::addQueueItems Aug 24 23:47:20 volumio volumio[1073]: info: Preload queue cleared Aug 24 23:47:20 volumio volumio[1073]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1770054 Aug 24 23:47:20 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 24 23:47:20 volumio volumio[1073]: info: CoreCommandRouter::volumioPushQueue Aug 24 23:47:20 volumio volumio[1073]: info: CorePlayQueue::saveQueue Aug 24 23:47:20 volumio volumio[1073]: info: CoreStateMachine::updateTrackBlock Aug 24 23:47:20 volumio volumio[1073]: info: CorePlayQueue::getTrackBlock Aug 24 23:47:20 volumio volumio[1073]: info: CoreCommandRouter::volumioPlay Aug 24 23:47:20 volumio volumio[1073]: info: CoreStateMachine::play index 0 Aug 24 23:47:20 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:47:20 volumio volumio[1073]: info: CoreStateMachine::stop Aug 24 23:47:20 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:47:20 volumio volumio[1073]: info: CoreStateMachine::play index undefined Aug 24 23:47:20 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:47:20 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:20 volumio volumio[1073]: info: CoreStateMachine::startPlaybackTimer Aug 24 23:47:20 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:20 volumio volumio[1073]: info: [1724514440036] ControllerWebradio::clearAddPlayTrack Aug 24 23:47:20 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 23:47:20 volumio volumio[1073]: info: Aug 24 23:47:20 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:47:20 volumio volumio[1073]: info: sendMpdCommand stop took 19 milliseconds Aug 24 23:47:20 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:47:20 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:47:20 volumio volumio[1073]: info: sendMpdCommand stop took 10 milliseconds Aug 24 23:47:20 volumio volumio[1073]: info: sendMpdCommand status took 1 milliseconds Aug 24 23:47:20 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand clear Aug 24 23:47:20 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:47:20 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:47:20 volumio volumio[1073]: info: Aug 24 23:47:20 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:47:20 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:47:20 volumio volumio[1073]: info: sendMpdCommand clear took 1 milliseconds Aug 24 23:47:20 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 24 23:47:20 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1770054" Aug 24 23:47:20 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:47:20 volumio volumio[1073]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 24 23:47:20 volumio volumio[1073]: info: ------------------------------ 5ms Aug 24 23:47:20 volumio volumio[1073]: error: updateQueue error: null Aug 24 23:47:20 volumio volumio[1073]: info: ------------------------------ 3ms Aug 24 23:47:20 volumio volumio[1073]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1770054" took 264 milliseconds Aug 24 23:47:20 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 24 23:47:20 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand play Aug 24 23:47:20 volumio volumio[1073]: info: Aug 24 23:47:20 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:47:20 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:47:20 volumio volumio[1073]: info: sendMpdCommand play took 1 milliseconds Aug 24 23:47:20 volumio volumio[1073]: info: ------------------------------ 5ms Aug 24 23:47:22 volumio volumio[1073]: info: Aug 24 23:47:22 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:47:22 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:47:22 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:47:22 volumio volumio[1073]: info: Aug 24 23:47:22 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:47:22 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:47:22 volumio volumio[1073]: info: Aug 24 23:47:22 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:47:22 volumio volumio[1073]: info: sendMpdCommand status took 5 milliseconds Aug 24 23:47:22 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:47:22 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:47:22 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:47:22 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:47:22 volumio volumio[1073]: info: ------------------------------ 6ms Aug 24 23:47:22 volumio volumio[1073]: info: sendMpdCommand status took 5 milliseconds Aug 24 23:47:22 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 24 23:47:22 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:47:22 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:47:22 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:47:22 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:47:22 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:47:22 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:22 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"113.164.229.226:8035","artist":"VOV1_TNVN1","album":null,"uri":"http://113.164.229.226:8035","trackType":"226:8035"} Aug 24 23:47:22 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:47:22 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:47:22 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus stop Aug 24 23:47:22 volumio volumio[1073]: info: ------------------------------ 12ms Aug 24 23:47:22 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 24 23:47:22 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:47:22 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:47:22 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:47:22 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:22 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"51 Kbps","isStreaming":false,"title":"113.164.229.226:8035","artist":"VOV1_TNVN1","album":null,"uri":"http://113.164.229.226:8035","trackType":"226:8035"} Aug 24 23:47:22 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:47:22 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:47:22 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus play Aug 24 23:47:22 volumio volumio[1073]: info: Received an update from plugin. extracting info from payload Aug 24 23:47:22 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:47:22 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:22 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:47:22 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:47:22 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:47:22 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:47:22 volumio volumio[1073]: info: ------------------------------ 25ms Aug 24 23:47:24 volumio volumio[1073]: info: Preload queue cleared Aug 24 23:47:24 volumio volumio[1073]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 24 23:47:24 volumio volumio[1073]: info: CoreStateMachine::ClearQueue Aug 24 23:47:24 volumio volumio[1073]: info: CoreStateMachine::stop Aug 24 23:47:24 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:47:24 volumio volumio[1073]: info: CoreStateMachine::stPlaybackTimer Aug 24 23:47:24 volumio volumio[1073]: info: CoreStateMachine::updateTrackBlock Aug 24 23:47:24 volumio volumio[1073]: info: CorePlayQueue::getTrackBlock Aug 24 23:47:24 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:47:24 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:24 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:47:24 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:47:24 volumio volumio[1073]: info: CoreStateMachine::serviceStop Aug 24 23:47:24 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:24 volumio volumio[1073]: info: CoreCommandRouter::serviceStop Aug 24 23:47:24 volumio volumio[1073]: info: [1724514444931] ControllerWebradio::stop Aug 24 23:47:24 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 23:47:24 volumio volumio[1073]: info: CorePlayQueue::clearPlayQueue Aug 24 23:47:24 volumio volumio[1073]: info: CorePlayQueue::saveQueue Aug 24 23:47:24 volumio volumio[1073]: info: CoreCommandRouter::volumioPushQueue Aug 24 23:47:24 volumio volumio[1073]: info: CoreStateMachine::addQueueItems Aug 24 23:47:24 volumio volumio[1073]: info: CorePlayQueue::addQueueItems Aug 24 23:47:24 volumio volumio[1073]: info: Preload queue cleared Aug 24 23:47:24 volumio volumio[1073]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99563397 Aug 24 23:47:24 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 24 23:47:24 volumio volumio[1073]: info: CoreCommandRouter::volumioPushQueue Aug 24 23:47:24 volumio volumio[1073]: info: CorePlayQueue::saveQueue Aug 24 23:47:24 volumio volumio[1073]: info: CoreStateMachine::updateTrackBlock Aug 24 23:47:24 volumio volumio[1073]: info: CorePlayQueue::getTrackBlock Aug 24 23:47:24 volumio volumio[1073]: info: CoreCommandRouter::volumioPlay Aug 24 23:47:24 volumio volumio[1073]: info: CoreStateMachine::play index 0 Aug 24 23:47:24 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:47:24 volumio volumio[1073]: info: CoreStateMachine::stop Aug 24 23:47:24 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:47:24 volumio volumio[1073]: info: CoreStateMachine::play index undefined Aug 24 23:47:24 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:47:24 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:24 volumio volumio[1073]: info: CoreStateMachine::startPlaybackTimer Aug 24 23:47:24 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:24 volumio volumio[1073]: info: [1724514444953] ControllerWebradio::clearAddPlayTrack Aug 24 23:47:24 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 23:47:24 volumio volumio[1073]: info: Aug 24 23:47:24 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:47:24 volumio volumio[1073]: info: sendMpdCommand stop took 25 milliseconds Aug 24 23:47:24 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:47:24 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:47:24 volumio volumio[1073]: info: sendMpdCommand stop took 4 milliseconds Aug 24 23:47:24 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand clear Aug 24 23:47:24 volumio volumio[1073]: info: sendMpdCommand status took 1 milliseconds Aug 24 23:47:24 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:47:24 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:47:24 volumio volumio[1073]: info: Aug 24 23:47:24 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:47:24 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:47:24 volumio volumio[1073]: info: sendMpdCommand clear took 2 milliseconds Aug 24 23:47:24 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 24 23:47:24 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99563397" Aug 24 23:47:24 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:47:24 volumio volumio[1073]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 24 23:47:24 volumio volumio[1073]: info: ------------------------------ 5ms Aug 24 23:47:24 volumio volumio[1073]: error: updateQueue error: null Aug 24 23:47:24 volumio volumio[1073]: info: ------------------------------ 3ms Aug 24 23:47:25 volumio volumio[1073]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99563397" took 250 milliseconds Aug 24 23:47:25 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 24 23:47:25 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand play Aug 24 23:47:25 volumio volumio[1073]: info: Aug 24 23:47:25 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:47:25 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:47:25 volumio volumio[1073]: info: sendMpdCommand play took 1 milliseconds Aug 24 23:47:25 volumio volumio[1073]: info: ------------------------------ 1ms Aug 24 23:47:25 volumio volumio[1073]: info: Aug 24 23:47:25 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:47:25 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:47:25 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:47:25 volumio volumio[1073]: info: Aug 24 23:47:25 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:47:25 volumio volumio[1073]: info: sendMpdCommand status took 6 milliseconds Aug 24 23:47:25 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:47:25 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:47:25 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:47:25 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:47:25 volumio volumio[1073]: info: sendMpdCommand status took 1 milliseconds Aug 24 23:47:25 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 24 23:47:25 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:47:25 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:47:25 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:47:25 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:47:25 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:47:25 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:25 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 81/500) DownSouthRadio.net","album":null,"uri":"http://23.95.73.78:8036/stream","trackType":""} Aug 24 23:47:25 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:47:25 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:47:25 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus stop Aug 24 23:47:25 volumio volumio[1073]: info: ------------------------------ 11ms Aug 24 23:47:25 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 24 23:47:25 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:47:25 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:47:25 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:47:25 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:25 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 81/500) DownSouthRadio.net","album":null,"uri":"http://23.95.73.78:8036/stream","trackType":""} Aug 24 23:47:25 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:47:25 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:47:25 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus play Aug 24 23:47:25 volumio volumio[1073]: info: Received an update from plugin. extracting info from payload Aug 24 23:47:25 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 24 23:47:25 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:47:25 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:25 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:47:25 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:47:25 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:47:25 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:47:25 volumio volumio[1073]: info: ------------------------------ 13ms Aug 24 23:47:25 volumio volumio[1073]: info: Aug 24 23:47:25 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:47:25 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:47:25 volumio volumio[1073]: info: Aug 24 23:47:25 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:47:25 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:47:25 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:47:25 volumio volumio[1073]: info: Aug 24 23:47:25 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:47:25 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:47:25 volumio volumio[1073]: info: Aug 24 23:47:25 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:47:25 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:47:25 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:47:25 volumio volumio[1073]: info: ------------------------------ 3ms Aug 24 23:47:25 volumio volumio[1073]: info: sendMpdCommand status took 2 milliseconds Aug 24 23:47:25 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:47:25 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:47:25 volumio volumio[1073]: info: ------------------------------ 3ms Aug 24 23:47:25 volumio volumio[1073]: info: sendMpdCommand status took 3 milliseconds Aug 24 23:47:25 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 24 23:47:25 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:47:25 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:47:25 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:47:25 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:47:25 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:47:25 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:25 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Lacee - Where The Party At","artist":"DownSouthRadio.net","album":null,"uri":"http://23.95.73.78:8036/stream","trackType":""} Aug 24 23:47:25 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:47:25 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:47:25 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus play Aug 24 23:47:25 volumio volumio[1073]: info: Received an update from plugin. extracting info from payload Aug 24 23:47:25 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 24 23:47:25 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:47:25 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:47:25 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:47:25 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:47:25 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:47:25 volumio volumio[1073]: info: ------------------------------ 14ms Aug 24 23:47:25 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 24 23:47:25 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:47:25 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:47:25 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:47:25 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:25 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Lacee - Where The Party At","artist":"DownSouthRadio.net","album":null,"uri":"http://23.95.73.78:8036/stream","trackType":""} Aug 24 23:47:25 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:47:25 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:47:25 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus play Aug 24 23:47:25 volumio volumio[1073]: info: Received an update from plugin. extracting info from payload Aug 24 23:47:25 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 24 23:47:25 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:47:25 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:47:25 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:47:25 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:47:25 volumio volumio[1073]: info: ------------------------------ 27ms Aug 24 23:47:30 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 24 23:47:30 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 24 23:47:30 volumio volumio[1073]: info: Discovery: Getting this device information Aug 24 23:47:30 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Aug 24 23:47:30 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 24 23:47:42 volumio volumio[1073]: info: Preload queue cleared Aug 24 23:47:42 volumio volumio[1073]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 24 23:47:42 volumio volumio[1073]: info: CoreStateMachine::ClearQueue Aug 24 23:47:42 volumio volumio[1073]: info: CoreStateMachine::stop Aug 24 23:47:42 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:47:42 volumio volumio[1073]: info: CoreStateMachine::stPlaybackTimer Aug 24 23:47:42 volumio volumio[1073]: info: CoreStateMachine::updateTrackBlock Aug 24 23:47:42 volumio volumio[1073]: info: CorePlayQueue::getTrackBlock Aug 24 23:47:42 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:47:42 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:42 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:47:42 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:47:42 volumio volumio[1073]: info: CoreStateMachine::serviceStop Aug 24 23:47:42 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:42 volumio volumio[1073]: info: CoreCommandRouter::serviceStop Aug 24 23:47:42 volumio volumio[1073]: info: [1724514462181] ControllerWebradio::stop Aug 24 23:47:42 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 23:47:42 volumio volumio[1073]: info: CorePlayQueue::clearPlayQueue Aug 24 23:47:42 volumio volumio[1073]: info: CorePlayQueue::saveQueue Aug 24 23:47:42 volumio volumio[1073]: info: CoreCommandRouter::volumioPushQueue Aug 24 23:47:42 volumio volumio[1073]: info: CoreStateMachine::addQueueItems Aug 24 23:47:42 volumio volumio[1073]: info: CorePlayQueue::addQueueItems Aug 24 23:47:42 volumio volumio[1073]: info: Preload queue cleared Aug 24 23:47:42 volumio volumio[1073]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99495526 Aug 24 23:47:42 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 24 23:47:42 volumio volumio[1073]: info: CoreCommandRouter::volumioPushQueue Aug 24 23:47:42 volumio volumio[1073]: info: CorePlayQueue::saveQueue Aug 24 23:47:42 volumio volumio[1073]: info: CoreStateMachine::updateTrackBlock Aug 24 23:47:42 volumio volumio[1073]: info: CorePlayQueue::getTrackBlock Aug 24 23:47:42 volumio volumio[1073]: info: CoreCommandRouter::volumioPlay Aug 24 23:47:42 volumio volumio[1073]: info: CoreStateMachine::play index 0 Aug 24 23:47:42 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:47:42 volumio volumio[1073]: info: CoreStateMachine::stop Aug 24 23:47:42 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:47:42 volumio volumio[1073]: info: CoreStateMachine::play index undefined Aug 24 23:47:42 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:47:42 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:42 volumio volumio[1073]: info: CoreStateMachine::startPlaybackTimer Aug 24 23:47:42 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:42 volumio volumio[1073]: info: [1724514462198] ControllerWebradio::clearAddPlayTrack Aug 24 23:47:42 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 23:47:42 volumio volumio[1073]: info: Aug 24 23:47:42 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:47:42 volumio volumio[1073]: info: sendMpdCommand stop took 25 milliseconds Aug 24 23:47:42 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:47:42 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:47:42 volumio volumio[1073]: info: sendMpdCommand stop took 8 milliseconds Aug 24 23:47:42 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand clear Aug 24 23:47:42 volumio volumio[1073]: info: Aug 24 23:47:42 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:47:42 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:47:42 volumio volumio[1073]: info: sendMpdCommand status took 2 milliseconds Aug 24 23:47:42 volumio volumio[1073]: info: sendMpdCommand clear took 1 milliseconds Aug 24 23:47:42 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:47:42 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:47:42 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99495526" Aug 24 23:47:42 volumio volumio[1073]: error: updateQueue error: null Aug 24 23:47:42 volumio volumio[1073]: info: ------------------------------ 3ms Aug 24 23:47:42 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 24 23:47:42 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:47:42 volumio volumio[1073]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 24 23:47:42 volumio volumio[1073]: info: ------------------------------ 7ms Aug 24 23:47:42 volumio volumio[1073]: info: Aug 24 23:47:42 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:47:42 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:47:42 volumio volumio[1073]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99495526" took 236 milliseconds Aug 24 23:47:42 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 24 23:47:42 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand play Aug 24 23:47:42 volumio volumio[1073]: info: ------------------------------ 1ms Aug 24 23:47:42 volumio volumio[1073]: info: sendMpdCommand play took 1 milliseconds Aug 24 23:47:42 volumio volumio[1073]: info: Aug 24 23:47:42 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:47:42 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:47:42 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:47:42 volumio volumio[1073]: info: Aug 24 23:47:42 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:47:42 volumio volumio[1073]: info: sendMpdCommand status took 5 milliseconds Aug 24 23:47:42 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:47:42 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:47:42 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:47:42 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:47:42 volumio volumio[1073]: info: sendMpdCommand status took 1 milliseconds Aug 24 23:47:42 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 24 23:47:42 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:47:42 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:47:42 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:47:42 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:47:42 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:47:42 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:42 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"cjpx.mp3","artist":"Wknd radio","album":null,"uri":"http://54.39.106.84:8000/cjpx.mp3","trackType":"mp3"} Aug 24 23:47:42 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:47:42 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:47:42 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus stop Aug 24 23:47:42 volumio volumio[1073]: info: ------------------------------ 9ms Aug 24 23:47:42 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 24 23:47:42 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:47:42 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:47:42 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:47:42 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:42 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"cjpx.mp3","artist":"Wknd radio","album":null,"uri":"http://54.39.106.84:8000/cjpx.mp3","trackType":"mp3"} Aug 24 23:47:42 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:47:42 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:47:42 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus play Aug 24 23:47:42 volumio volumio[1073]: info: Received an update from plugin. extracting info from payload Aug 24 23:47:42 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 24 23:47:42 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:47:42 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:42 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:47:42 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:47:42 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:47:42 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:47:42 volumio volumio[1073]: info: ------------------------------ 16ms Aug 24 23:47:43 volumio volumio[1073]: info: Aug 24 23:47:43 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Aug 24 23:47:43 volumio volumio[1073]: info: Ignoring MPD Status Update Aug 24 23:47:43 volumio volumio[1073]: info: Aug 24 23:47:43 volumio volumio[1073]: ---------------------------- MPD announces state update: player Aug 24 23:47:43 volumio volumio[1073]: info: ControllerMpd::getState Aug 24 23:47:43 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:47:43 volumio volumio[1073]: info: ------------------------------ 2ms Aug 24 23:47:43 volumio volumio[1073]: info: sendMpdCommand status took 1 milliseconds Aug 24 23:47:43 volumio volumio[1073]: verbose: ControllerMpd::parseState Aug 24 23:47:43 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:47:43 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 24 23:47:43 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:47:43 volumio volumio[1073]: info: ControllerMpd::pushState Aug 24 23:47:43 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Aug 24 23:47:43 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:43 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":364,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"cjpx.mp3","artist":"Wknd radio","album":null,"uri":"http://54.39.106.84:8000/cjpx.mp3","trackType":"mp3"} Aug 24 23:47:43 volumio volumio[1073]: verbose: CURRENT POSITION 0 Aug 24 23:47:43 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Aug 24 23:47:43 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus play Aug 24 23:47:43 volumio volumio[1073]: info: Received an update from plugin. extracting info from payload Aug 24 23:47:43 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 24 23:47:43 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:47:43 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Aug 24 23:47:43 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:47:43 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:47:43 volumio volumio[1073]: info: CoreStateMachine::pushState Aug 24 23:47:43 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Aug 24 23:47:43 volumio volumio[1073]: info: ------------------------------ 12ms Aug 24 23:47:45 volumio volumio[1073]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 24 23:47:45 volumio volumio[1073]: Error: connect ETIMEDOUT 199.59.148.209:80 Aug 24 23:47:45 volumio volumio[1073]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Aug 24 23:47:45 volumio volumio[1073]: errno: -110, Aug 24 23:47:45 volumio volumio[1073]: code: 'ETIMEDOUT', Aug 24 23:47:45 volumio volumio[1073]: syscall: 'connect', Aug 24 23:47:45 volumio volumio[1073]: address: '199.59.148.209', Aug 24 23:47:45 volumio volumio[1073]: port: 80 Aug 24 23:47:45 volumio volumio[1073]: } Aug 24 23:47:45 volumio volumio[1073]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 24 23:47:45 volumio sudo[5129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-24 23:46 Aug 24 23:47:45 volumio sudo[5129]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"