-- Logs begin at Mon 2025-06-23 20:41:39 +08, end at Mon 2025-06-23 21:00:51 +08. -- Jun 23 20:59:09 volumio volumio[990]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 23 20:59:09 volumio volumio[990]: info: Retrying Login Due to Network Error Jun 23 20:59:17 volumio volumio[990]: info: Preload queue cleared Jun 23 20:59:17 volumio volumio[990]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 23 20:59:17 volumio volumio[990]: info: CoreStateMachine::ClearQueue Jun 23 20:59:17 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 20:59:17 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:17 volumio volumio[990]: info: CoreStateMachine::stPlaybackTimer Jun 23 20:59:17 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 20:59:17 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 20:59:17 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:17 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:17 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:17 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:17 volumio volumio[990]: info: CoreStateMachine::serviceStop Jun 23 20:59:17 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:17 volumio volumio[990]: info: CoreCommandRouter::serviceStop Jun 23 20:59:17 volumio volumio[990]: info: [1750683557223] ControllerWebradio::stop Jun 23 20:59:17 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Jun 23 20:59:17 volumio volumio[990]: info: CorePlayQueue::clearPlayQueue Jun 23 20:59:17 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 20:59:17 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 20:59:17 volumio volumio[990]: info: CoreStateMachine::addQueueItems Jun 23 20:59:17 volumio volumio[990]: info: CorePlayQueue::addQueueItems Jun 23 20:59:17 volumio volumio[990]: info: Preload queue cleared Jun 23 20:59:17 volumio volumio[990]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1867429 Jun 23 20:59:17 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 23 20:59:17 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 20:59:17 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 20:59:17 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 20:59:17 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 20:59:17 volumio volumio[990]: info: CoreCommandRouter::volumioPlay Jun 23 20:59:17 volumio volumio[990]: info: CoreStateMachine::play index 0 Jun 23 20:59:17 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:17 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 20:59:17 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:17 volumio volumio[990]: info: CoreStateMachine::play index undefined Jun 23 20:59:17 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:17 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:17 volumio volumio[990]: info: CoreStateMachine::startPlaybackTimer Jun 23 20:59:17 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:17 volumio volumio[990]: info: [1750683557258] ControllerWebradio::clearAddPlayTrack Jun 23 20:59:17 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Jun 23 20:59:17 volumio volumio[990]: info: sendMpdCommand stop took 156 milliseconds Jun 23 20:59:17 volumio volumio[990]: info: Jun 23 20:59:17 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:17 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:17 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:17 volumio volumio[990]: info: sendMpdCommand stop took 123 milliseconds Jun 23 20:59:17 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand clear Jun 23 20:59:17 volumio volumio[990]: info: Jun 23 20:59:17 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:17 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:17 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:17 volumio volumio[990]: info: Jun 23 20:59:17 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:17 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:17 volumio volumio[990]: info: sendMpdCommand status took 5 milliseconds Jun 23 20:59:17 volumio volumio[990]: info: sendMpdCommand clear took 5 milliseconds Jun 23 20:59:17 volumio volumio[990]: info: sendMpdCommand status took 3 milliseconds Jun 23 20:59:17 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:17 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:17 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1867429" Jun 23 20:59:17 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:17 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:17 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:17 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:17 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:17 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:17 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:17 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:17 volumio volumio[990]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 23 20:59:17 volumio volumio[990]: info: ------------------------------ 34ms Jun 23 20:59:17 volumio volumio[990]: info: Jun 23 20:59:17 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:17 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:17 volumio volumio[990]: error: updateQueue error: null Jun 23 20:59:17 volumio volumio[990]: info: ------------------------------ 34ms Jun 23 20:59:17 volumio sudo[5419]: pam_unix(sudo:session): session closed for user root Jun 23 20:59:17 volumio volumio[990]: info: Jun 23 20:59:17 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:17 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:17 volumio volumio[990]: info: sendMpdCommand playlistinfo took 259 milliseconds Jun 23 20:59:17 volumio volumio[990]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1867429" took 259 milliseconds Jun 23 20:59:17 volumio volumio[990]: info: ------------------------------ 231ms Jun 23 20:59:17 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:17 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 23 20:59:17 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand play Jun 23 20:59:17 volumio volumio[990]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 23 20:59:17 volumio volumio[990]: info: ------------------------------ 272ms Jun 23 20:59:17 volumio volumio[990]: info: Jun 23 20:59:17 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:17 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:17 volumio volumio[990]: info: ------------------------------ 11ms Jun 23 20:59:17 volumio volumio[990]: info: sendMpdCommand play took 6 milliseconds Jun 23 20:59:17 volumio volumio[990]: info: ------------------------------ 4ms Jun 23 20:59:18 volumio volumio[990]: info: Jun 23 20:59:18 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:18 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:18 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:18 volumio volumio[990]: info: Jun 23 20:59:18 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:18 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:18 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:19 volumio volumio[990]: info: sendMpdCommand status took 167 milliseconds Jun 23 20:59:19 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:19 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:19 volumio volumio[990]: info: Jun 23 20:59:19 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:19 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:19 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:19 volumio volumio[990]: info: sendMpdCommand status took 170 milliseconds Jun 23 20:59:19 volumio volumio[990]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 23 20:59:19 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:19 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:19 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:19 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:19 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:19 volumio volumio[990]: 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":"1022","artist":"113.fm Hits 1978","album":null,"uri":"http://50.31.185.139:80/1022","trackType":""} Jun 23 20:59:19 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:19 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:19 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus stop Jun 23 20:59:19 volumio volumio[990]: info: ------------------------------ 184ms Jun 23 20:59:19 volumio volumio[990]: info: Jun 23 20:59:19 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:19 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:19 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:19 volumio volumio[990]: info: sendMpdCommand status took 14 milliseconds Jun 23 20:59:19 volumio volumio[990]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 23 20:59:19 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:19 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:19 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:19 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:19 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:19 volumio volumio[990]: 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":"1022","artist":"113.fm Hits 1978","album":null,"uri":"http://50.31.185.139:80/1022","trackType":""} Jun 23 20:59:19 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:19 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:19 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:19 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 23 20:59:19 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:19 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:19 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:19 volumio volumio[990]: info: ------------------------------ 237ms Jun 23 20:59:19 volumio volumio[990]: info: Jun 23 20:59:19 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:19 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:19 volumio volumio[990]: info: Jun 23 20:59:19 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:19 volumio volumio[990]: info: sendMpdCommand status took 55 milliseconds Jun 23 20:59:19 volumio volumio[990]: info: sendMpdCommand playlistinfo took 51 milliseconds Jun 23 20:59:19 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:19 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:19 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:19 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:19 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:19 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:19 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:19 volumio volumio[990]: 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":"1022","artist":"113.fm Hits 1978","album":null,"uri":"http://50.31.185.139:80/1022","trackType":""} Jun 23 20:59:19 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:19 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:19 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:19 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 23 20:59:19 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:19 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:19 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:19 volumio volumio[990]: info: ------------------------------ 108ms Jun 23 20:59:19 volumio volumio[990]: info: Jun 23 20:59:19 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:19 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:19 volumio volumio[990]: info: Jun 23 20:59:19 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:19 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:19 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:19 volumio volumio[990]: info: ------------------------------ 53ms Jun 23 20:59:19 volumio volumio[990]: info: sendMpdCommand status took 51 milliseconds Jun 23 20:59:19 volumio volumio[990]: info: sendMpdCommand playlistinfo took 52 milliseconds Jun 23 20:59:19 volumio volumio[990]: info: ------------------------------ 7ms Jun 23 20:59:19 volumio volumio[990]: info: sendMpdCommand status took 6 milliseconds Jun 23 20:59:19 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:19 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:19 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:19 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:19 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:19 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:19 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:19 volumio volumio[990]: 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":"O'Jays - Used To Be My Girl","artist":"113.fm Hits 1978","album":null,"uri":"http://50.31.185.139:80/1022","trackType":""} Jun 23 20:59:19 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:19 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:19 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:19 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 23 20:59:19 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:19 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:19 volumio volumio[990]: info: ------------------------------ 142ms Jun 23 20:59:19 volumio volumio[990]: info: sendMpdCommand playlistinfo took 37 milliseconds Jun 23 20:59:19 volumio volumio[990]: info: sendMpdCommand playlistinfo took 37 milliseconds Jun 23 20:59:19 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:19 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:19 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:19 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:19 volumio volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"O'Jays - Used To Be My Girl","artist":"113.fm Hits 1978","album":null,"uri":"http://50.31.185.139:80/1022","trackType":""} Jun 23 20:59:19 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:19 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:19 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:19 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 23 20:59:19 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:19 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:19 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:19 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:19 volumio volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"O'Jays - Used To Be My Girl","artist":"113.fm Hits 1978","album":null,"uri":"http://50.31.185.139:80/1022","trackType":""} Jun 23 20:59:19 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:19 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:19 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:19 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 23 20:59:19 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:19 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:19 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:19 volumio volumio[990]: info: ------------------------------ 143ms Jun 23 20:59:19 volumio volumio[990]: info: ------------------------------ 96ms Jun 23 20:59:19 volumio volumio[990]: info: MyVolumio login type: Token Jun 23 20:59:21 volumio volumio[990]: info: Preload queue cleared Jun 23 20:59:21 volumio volumio[990]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 23 20:59:21 volumio volumio[990]: info: CoreStateMachine::ClearQueue Jun 23 20:59:21 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 20:59:21 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:21 volumio volumio[990]: info: CoreStateMachine::stPlaybackTimer Jun 23 20:59:21 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 20:59:21 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 20:59:21 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:21 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:21 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:21 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:21 volumio volumio[990]: info: CoreStateMachine::serviceStop Jun 23 20:59:21 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:21 volumio volumio[990]: info: CoreCommandRouter::serviceStop Jun 23 20:59:21 volumio volumio[990]: info: [1750683561329] ControllerWebradio::stop Jun 23 20:59:21 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Jun 23 20:59:21 volumio volumio[990]: info: CorePlayQueue::clearPlayQueue Jun 23 20:59:21 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 20:59:21 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 20:59:21 volumio volumio[990]: info: CoreStateMachine::addQueueItems Jun 23 20:59:21 volumio volumio[990]: info: CorePlayQueue::addQueueItems Jun 23 20:59:21 volumio volumio[990]: info: Preload queue cleared Jun 23 20:59:21 volumio volumio[990]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1821969 Jun 23 20:59:21 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 23 20:59:21 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 20:59:21 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 20:59:21 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 20:59:21 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 20:59:21 volumio volumio[990]: info: CoreCommandRouter::volumioPlay Jun 23 20:59:21 volumio volumio[990]: info: CoreStateMachine::play index 0 Jun 23 20:59:21 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:21 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 20:59:21 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:21 volumio volumio[990]: info: CoreStateMachine::play index undefined Jun 23 20:59:21 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:21 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:21 volumio volumio[990]: info: CoreStateMachine::startPlaybackTimer Jun 23 20:59:21 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:21 volumio volumio[990]: info: [1750683561357] ControllerWebradio::clearAddPlayTrack Jun 23 20:59:21 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Jun 23 20:59:21 volumio volumio[990]: info: Jun 23 20:59:21 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:21 volumio volumio[990]: info: sendMpdCommand stop took 167 milliseconds Jun 23 20:59:21 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:21 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:21 volumio volumio[990]: info: sendMpdCommand stop took 141 milliseconds Jun 23 20:59:21 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand clear Jun 23 20:59:21 volumio volumio[990]: info: Jun 23 20:59:21 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:21 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:21 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:21 volumio volumio[990]: info: Jun 23 20:59:21 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:21 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:21 volumio volumio[990]: info: sendMpdCommand status took 2 milliseconds Jun 23 20:59:21 volumio volumio[990]: info: sendMpdCommand clear took 3 milliseconds Jun 23 20:59:21 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:21 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:21 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1821969" Jun 23 20:59:21 volumio volumio[990]: info: Jun 23 20:59:21 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:21 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:21 volumio volumio[990]: error: updateQueue error: null Jun 23 20:59:21 volumio volumio[990]: info: sendMpdCommand status took 4 milliseconds Jun 23 20:59:21 volumio volumio[990]: info: ------------------------------ 3ms Jun 23 20:59:21 volumio volumio[990]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 23 20:59:21 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:21 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:21 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:21 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:21 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:21 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:21 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:21 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:21 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:21 volumio volumio[990]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 23 20:59:21 volumio volumio[990]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 23 20:59:21 volumio volumio[990]: info: ------------------------------ 19ms Jun 23 20:59:21 volumio volumio[990]: info: ------------------------------ 21ms Jun 23 20:59:21 volumio volumio[990]: info: Jun 23 20:59:21 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:21 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:21 volumio volumio[990]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1821969" took 249 milliseconds Jun 23 20:59:21 volumio volumio[990]: info: ------------------------------ 248ms Jun 23 20:59:21 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 23 20:59:21 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand play Jun 23 20:59:21 volumio volumio[990]: info: Jun 23 20:59:21 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:21 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:21 volumio volumio[990]: info: ------------------------------ 4ms Jun 23 20:59:21 volumio volumio[990]: info: sendMpdCommand play took 4 milliseconds Jun 23 20:59:21 volumio volumio[990]: info: ------------------------------ 5ms Jun 23 20:59:22 volumio volumio[990]: info: Jun 23 20:59:22 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:22 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:22 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:22 volumio volumio[990]: info: Jun 23 20:59:22 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:22 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:22 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:23 volumio volumio[990]: info: sendMpdCommand status took 140 milliseconds Jun 23 20:59:23 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:23 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:23 volumio volumio[990]: info: Jun 23 20:59:23 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:23 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:23 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:23 volumio volumio[990]: info: sendMpdCommand status took 144 milliseconds Jun 23 20:59:23 volumio volumio[990]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 23 20:59:23 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:23 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:23 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:23 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:23 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:23 volumio volumio[990]: 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":"1001","artist":"113.fm Alt-X","album":null,"uri":"http://50.31.185.139:80/1001","trackType":""} Jun 23 20:59:23 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:23 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:23 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus stop Jun 23 20:59:23 volumio volumio[990]: info: ------------------------------ 154ms Jun 23 20:59:23 volumio volumio[990]: info: Jun 23 20:59:23 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:23 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:23 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:23 volumio volumio[990]: info: sendMpdCommand status took 12 milliseconds Jun 23 20:59:23 volumio volumio[990]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 23 20:59:23 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:23 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:23 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:23 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:23 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:23 volumio volumio[990]: 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":"1001","artist":"113.fm Alt-X","album":null,"uri":"http://50.31.185.139:80/1001","trackType":""} Jun 23 20:59:23 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:23 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:23 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:23 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 23 20:59:23 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:23 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:23 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:23 volumio volumio[990]: info: ------------------------------ 212ms Jun 23 20:59:23 volumio volumio[990]: info: sendMpdCommand status took 59 milliseconds Jun 23 20:59:23 volumio volumio[990]: info: sendMpdCommand playlistinfo took 56 milliseconds Jun 23 20:59:23 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:23 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:23 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:23 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:23 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:23 volumio volumio[990]: 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":"1001","artist":"113.fm Alt-X","album":null,"uri":"http://50.31.185.139:80/1001","trackType":""} Jun 23 20:59:23 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:23 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:23 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:23 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 23 20:59:23 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:23 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:23 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:23 volumio volumio[990]: info: ------------------------------ 114ms Jun 23 20:59:23 volumio volumio[990]: info: Jun 23 20:59:23 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:23 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:23 volumio volumio[990]: info: Jun 23 20:59:23 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:23 volumio volumio[990]: info: sendMpdCommand playlistinfo took 52 milliseconds Jun 23 20:59:23 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:23 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:23 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:23 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:23 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:23 volumio volumio[990]: 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":"1001","artist":"113.fm Alt-X","album":null,"uri":"http://50.31.185.139:80/1001","trackType":""} Jun 23 20:59:23 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:23 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:23 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:23 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 23 20:59:23 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:23 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:23 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:23 volumio volumio[990]: info: ------------------------------ 143ms Jun 23 20:59:23 volumio volumio[990]: info: Jun 23 20:59:23 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:23 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:23 volumio volumio[990]: info: Jun 23 20:59:23 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:23 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:23 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:23 volumio volumio[990]: info: ------------------------------ 40ms Jun 23 20:59:23 volumio volumio[990]: info: sendMpdCommand status took 38 milliseconds Jun 23 20:59:23 volumio volumio[990]: info: ------------------------------ 5ms Jun 23 20:59:23 volumio volumio[990]: info: sendMpdCommand status took 4 milliseconds Jun 23 20:59:23 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:23 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:23 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:23 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:23 volumio volumio[990]: info: sendMpdCommand playlistinfo took 51 milliseconds Jun 23 20:59:23 volumio volumio[990]: info: sendMpdCommand playlistinfo took 52 milliseconds Jun 23 20:59:23 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:23 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:23 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:23 volumio volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"311 - All Mixed Up","artist":"113.fm Alt-X","album":null,"uri":"http://50.31.185.139:80/1001","trackType":""} Jun 23 20:59:23 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:23 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:23 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:23 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 23 20:59:23 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:23 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:23 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:23 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:23 volumio volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"311 - All Mixed Up","artist":"113.fm Alt-X","album":null,"uri":"http://50.31.185.139:80/1001","trackType":""} Jun 23 20:59:23 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:23 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:23 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:23 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 23 20:59:23 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:23 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:23 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:23 volumio volumio[990]: info: ------------------------------ 142ms Jun 23 20:59:23 volumio volumio[990]: info: ------------------------------ 108ms Jun 23 20:59:26 volumio volumio[990]: info: Preload queue cleared Jun 23 20:59:26 volumio volumio[990]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 23 20:59:26 volumio volumio[990]: info: CoreStateMachine::ClearQueue Jun 23 20:59:26 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 20:59:26 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:26 volumio volumio[990]: info: CoreStateMachine::stPlaybackTimer Jun 23 20:59:26 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 20:59:26 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 20:59:26 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:26 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:26 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:26 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:26 volumio volumio[990]: info: CoreStateMachine::serviceStop Jun 23 20:59:26 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:26 volumio volumio[990]: info: CoreCommandRouter::serviceStop Jun 23 20:59:26 volumio volumio[990]: info: [1750683566200] ControllerWebradio::stop Jun 23 20:59:26 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Jun 23 20:59:26 volumio volumio[990]: info: CorePlayQueue::clearPlayQueue Jun 23 20:59:26 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 20:59:26 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 20:59:26 volumio volumio[990]: info: CoreStateMachine::addQueueItems Jun 23 20:59:26 volumio volumio[990]: info: CorePlayQueue::addQueueItems Jun 23 20:59:26 volumio volumio[990]: info: Preload queue cleared Jun 23 20:59:26 volumio volumio[990]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1766540 Jun 23 20:59:26 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 23 20:59:26 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 20:59:26 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 20:59:26 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 20:59:26 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 20:59:26 volumio volumio[990]: info: CoreCommandRouter::volumioPlay Jun 23 20:59:26 volumio volumio[990]: info: CoreStateMachine::play index 0 Jun 23 20:59:26 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:26 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 20:59:26 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:26 volumio volumio[990]: info: CoreStateMachine::play index undefined Jun 23 20:59:26 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:26 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:26 volumio volumio[990]: info: CoreStateMachine::startPlaybackTimer Jun 23 20:59:26 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:26 volumio volumio[990]: info: [1750683566221] ControllerWebradio::clearAddPlayTrack Jun 23 20:59:26 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Jun 23 20:59:26 volumio volumio[990]: info: Jun 23 20:59:26 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:26 volumio volumio[990]: info: sendMpdCommand stop took 171 milliseconds Jun 23 20:59:26 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:26 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:26 volumio volumio[990]: info: sendMpdCommand stop took 149 milliseconds Jun 23 20:59:26 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand clear Jun 23 20:59:26 volumio volumio[990]: info: Jun 23 20:59:26 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:26 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:26 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:26 volumio volumio[990]: info: Jun 23 20:59:26 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:26 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:26 volumio volumio[990]: info: sendMpdCommand status took 6 milliseconds Jun 23 20:59:26 volumio volumio[990]: info: sendMpdCommand clear took 6 milliseconds Jun 23 20:59:26 volumio volumio[990]: info: sendMpdCommand status took 5 milliseconds Jun 23 20:59:26 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:26 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:26 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1766540" Jun 23 20:59:26 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:26 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:26 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:26 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:26 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:26 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:26 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:26 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:26 volumio volumio[990]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 23 20:59:26 volumio volumio[990]: info: ------------------------------ 23ms Jun 23 20:59:26 volumio volumio[990]: info: Jun 23 20:59:26 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:26 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:26 volumio volumio[990]: error: updateQueue error: null Jun 23 20:59:26 volumio volumio[990]: info: ------------------------------ 25ms Jun 23 20:59:26 volumio volumio[990]: info: sendMpdCommand playlistinfo took 20 milliseconds Jun 23 20:59:26 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:26 volumio volumio[990]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 23 20:59:26 volumio volumio[990]: info: ------------------------------ 29ms Jun 23 20:59:26 volumio volumio[990]: info: Jun 23 20:59:26 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:26 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:26 volumio volumio[990]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1766540" took 252 milliseconds Jun 23 20:59:26 volumio volumio[990]: info: ------------------------------ 234ms Jun 23 20:59:26 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 23 20:59:26 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand play Jun 23 20:59:26 volumio volumio[990]: info: Jun 23 20:59:26 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:26 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:26 volumio volumio[990]: info: ------------------------------ 5ms Jun 23 20:59:26 volumio volumio[990]: info: sendMpdCommand play took 4 milliseconds Jun 23 20:59:26 volumio volumio[990]: info: ------------------------------ 4ms Jun 23 20:59:27 volumio volumio[990]: info: Jun 23 20:59:27 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:27 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:27 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:27 volumio volumio[990]: info: Jun 23 20:59:27 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:27 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:27 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:27 volumio volumio[990]: info: Jun 23 20:59:27 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:27 volumio volumio[990]: info: sendMpdCommand status took 139 milliseconds Jun 23 20:59:27 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:27 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:27 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:27 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:27 volumio volumio[990]: info: sendMpdCommand status took 139 milliseconds Jun 23 20:59:27 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:27 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:27 volumio volumio[990]: info: Jun 23 20:59:27 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:27 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:27 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:27 volumio volumio[990]: info: sendMpdCommand status took 3 milliseconds Jun 23 20:59:27 volumio volumio[990]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 23 20:59:27 volumio volumio[990]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 23 20:59:27 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:27 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:27 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:27 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:27 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:27 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:27 volumio volumio[990]: 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":"178.172.150.248:2530","artist":"(#1 - 80/512) SKIF","album":null,"uri":"http://178.172.150.248:2530","trackType":"248:2530"} Jun 23 20:59:27 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:27 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:27 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus stop Jun 23 20:59:27 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:27 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:27 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:27 volumio volumio[990]: 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":"178.172.150.248:2530","artist":"(#1 - 80/512) SKIF","album":null,"uri":"http://178.172.150.248:2530","trackType":"248:2530"} Jun 23 20:59:27 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:27 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:27 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:27 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:27 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:27 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:27 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:27 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:27 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:27 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:27 volumio volumio[990]: info: ------------------------------ 155ms Jun 23 20:59:27 volumio volumio[990]: info: ------------------------------ 175ms Jun 23 20:59:27 volumio volumio[990]: info: sendMpdCommand status took 35 milliseconds Jun 23 20:59:27 volumio volumio[990]: info: sendMpdCommand playlistinfo took 34 milliseconds Jun 23 20:59:27 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:27 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:27 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:27 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:27 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:27 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:27 volumio volumio[990]: 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":"178.172.150.248:2530","artist":"(#1 - 80/512) SKIF","album":null,"uri":"http://178.172.150.248:2530","trackType":"248:2530"} Jun 23 20:59:27 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:27 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:27 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:27 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:27 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:27 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:27 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:27 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:27 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:27 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:27 volumio volumio[990]: info: ------------------------------ 86ms Jun 23 20:59:27 volumio volumio[990]: info: sendMpdCommand playlistinfo took 57 milliseconds Jun 23 20:59:27 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:27 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:27 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:27 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:27 volumio volumio[990]: 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":"178.172.150.248:2530","artist":"(#1 - 80/512) SKIF","album":null,"uri":"http://178.172.150.248:2530","trackType":"248:2530"} Jun 23 20:59:27 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:27 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:27 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:27 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:27 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:27 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:27 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:27 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:27 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:27 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:27 volumio volumio[990]: info: ------------------------------ 113ms Jun 23 20:59:28 volumio volumio[990]: info: Jun 23 20:59:28 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:28 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:28 volumio volumio[990]: info: Jun 23 20:59:28 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:28 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:28 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:28 volumio volumio[990]: info: Jun 23 20:59:28 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:28 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:28 volumio volumio[990]: info: Jun 23 20:59:28 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:28 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:28 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:28 volumio volumio[990]: info: ------------------------------ 6ms Jun 23 20:59:28 volumio volumio[990]: info: sendMpdCommand status took 5 milliseconds Jun 23 20:59:28 volumio volumio[990]: info: ------------------------------ 5ms Jun 23 20:59:28 volumio volumio[990]: info: sendMpdCommand status took 4 milliseconds Jun 23 20:59:28 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:28 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:28 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:28 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:28 volumio volumio[990]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 23 20:59:28 volumio volumio[990]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 23 20:59:28 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:28 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:28 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:28 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:28 volumio volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":352,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"178.172.150.248:2530","artist":"SKIF","album":null,"uri":"http://178.172.150.248:2530","trackType":"248:2530"} Jun 23 20:59:28 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:28 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:28 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:28 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:28 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:28 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:28 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:28 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:28 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:28 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:28 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:28 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:28 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:28 volumio volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":352,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"178.172.150.248:2530","artist":"SKIF","album":null,"uri":"http://178.172.150.248:2530","trackType":"248:2530"} Jun 23 20:59:28 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:28 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:28 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:28 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:28 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:28 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:28 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:28 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:28 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:28 volumio volumio[990]: info: ------------------------------ 73ms Jun 23 20:59:28 volumio volumio[990]: info: ------------------------------ 71ms Jun 23 20:59:35 volumio volumio[990]: info: Preload queue cleared Jun 23 20:59:35 volumio volumio[990]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 23 20:59:35 volumio volumio[990]: info: CoreStateMachine::ClearQueue Jun 23 20:59:35 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 20:59:35 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:35 volumio volumio[990]: info: CoreStateMachine::stPlaybackTimer Jun 23 20:59:35 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 20:59:35 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 20:59:35 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:35 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:35 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:35 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:35 volumio volumio[990]: info: CoreStateMachine::serviceStop Jun 23 20:59:35 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:35 volumio volumio[990]: info: CoreCommandRouter::serviceStop Jun 23 20:59:35 volumio volumio[990]: info: [1750683575219] ControllerWebradio::stop Jun 23 20:59:35 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Jun 23 20:59:35 volumio volumio[990]: info: CorePlayQueue::clearPlayQueue Jun 23 20:59:35 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 20:59:35 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 20:59:35 volumio volumio[990]: info: CoreStateMachine::addQueueItems Jun 23 20:59:35 volumio volumio[990]: info: CorePlayQueue::addQueueItems Jun 23 20:59:35 volumio volumio[990]: info: Preload queue cleared Jun 23 20:59:35 volumio volumio[990]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99533427 Jun 23 20:59:35 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 23 20:59:35 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 20:59:35 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 20:59:35 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 20:59:35 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 20:59:35 volumio volumio[990]: info: CoreCommandRouter::volumioPlay Jun 23 20:59:35 volumio volumio[990]: info: CoreStateMachine::play index 0 Jun 23 20:59:35 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:35 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 20:59:35 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:35 volumio volumio[990]: info: CoreStateMachine::play index undefined Jun 23 20:59:35 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:35 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:35 volumio volumio[990]: info: CoreStateMachine::startPlaybackTimer Jun 23 20:59:35 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:35 volumio volumio[990]: info: [1750683575243] ControllerWebradio::clearAddPlayTrack Jun 23 20:59:35 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Jun 23 20:59:35 volumio volumio[990]: info: Jun 23 20:59:35 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:35 volumio volumio[990]: info: sendMpdCommand stop took 152 milliseconds Jun 23 20:59:35 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:35 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:35 volumio volumio[990]: info: sendMpdCommand stop took 128 milliseconds Jun 23 20:59:35 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand clear Jun 23 20:59:35 volumio volumio[990]: info: Jun 23 20:59:35 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:35 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:35 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:35 volumio volumio[990]: info: Jun 23 20:59:35 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:35 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:35 volumio volumio[990]: info: sendMpdCommand status took 4 milliseconds Jun 23 20:59:35 volumio volumio[990]: info: sendMpdCommand clear took 3 milliseconds Jun 23 20:59:35 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:35 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:35 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99533427" Jun 23 20:59:35 volumio volumio[990]: info: Jun 23 20:59:35 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:35 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:35 volumio volumio[990]: error: updateQueue error: null Jun 23 20:59:35 volumio volumio[990]: info: sendMpdCommand status took 5 milliseconds Jun 23 20:59:35 volumio volumio[990]: info: ------------------------------ 5ms Jun 23 20:59:35 volumio volumio[990]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 23 20:59:35 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:35 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:35 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:35 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:35 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:35 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:35 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:35 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:35 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:35 volumio volumio[990]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 23 20:59:35 volumio volumio[990]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 23 20:59:35 volumio volumio[990]: info: ------------------------------ 27ms Jun 23 20:59:35 volumio volumio[990]: info: ------------------------------ 31ms Jun 23 20:59:35 volumio volumio[990]: info: Jun 23 20:59:35 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:35 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:35 volumio volumio[990]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99533427" took 245 milliseconds Jun 23 20:59:35 volumio volumio[990]: info: ------------------------------ 245ms Jun 23 20:59:35 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 23 20:59:35 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand play Jun 23 20:59:35 volumio volumio[990]: info: ------------------------------ 2ms Jun 23 20:59:35 volumio volumio[990]: info: Jun 23 20:59:35 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:35 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:35 volumio volumio[990]: info: sendMpdCommand play took 3 milliseconds Jun 23 20:59:35 volumio volumio[990]: info: ------------------------------ 1ms Jun 23 20:59:37 volumio volumio[990]: info: Jun 23 20:59:37 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:37 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:37 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:37 volumio volumio[990]: info: Jun 23 20:59:37 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:37 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:37 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:37 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 23 20:59:37 volumio volumio[990]: info: sendMpdCommand status took 10 milliseconds Jun 23 20:59:37 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 23 20:59:37 volumio volumio[990]: info: sendMpdCommand status took 9 milliseconds Jun 23 20:59:37 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:37 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:37 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:37 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:37 volumio volumio[990]: info: sendMpdCommand clearerror took 13 milliseconds Jun 23 20:59:37 volumio volumio[990]: info: sendMpdCommand clearerror took 11 milliseconds Jun 23 20:59:37 volumio volumio[990]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 23 20:59:37 volumio volumio[990]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 23 20:59:37 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:37 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:37 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:37 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:37 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:37 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:37 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:37 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:37 volumio volumio[990]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 66/990) Radio1 Rodos","album":null,"uri":"https://cast2.radiohost.ovh:8000/stream","trackType":""} Jun 23 20:59:37 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:37 volumio volumio[990]: info: CoreStateMachine::syncState stateService stop Jun 23 20:59:37 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus stop Jun 23 20:59:37 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:37 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:37 volumio volumio[990]: info: No code Jun 23 20:59:37 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:37 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:37 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:37 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:37 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:37 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:37 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:37 volumio volumio[990]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 66/990) Radio1 Rodos","album":null,"uri":"https://cast2.radiohost.ovh:8000/stream","trackType":""} Jun 23 20:59:37 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:37 volumio volumio[990]: info: CoreStateMachine::syncState stateService stop Jun 23 20:59:37 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus stop Jun 23 20:59:37 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:37 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:37 volumio volumio[990]: info: No code Jun 23 20:59:37 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:37 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:37 volumio volumio[990]: info: ------------------------------ 98ms Jun 23 20:59:37 volumio volumio[990]: info: ------------------------------ 100ms Jun 23 20:59:38 volumio volumio[990]: info: CoreCommandRouter::volumioGetState Jun 23 20:59:38 volumio sudo[5560]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jun 23 20:59:38 volumio sudo[5560]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 20:59:40 volumio volumio[990]: info: Preload queue cleared Jun 23 20:59:40 volumio volumio[990]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 23 20:59:40 volumio volumio[990]: info: CoreStateMachine::ClearQueue Jun 23 20:59:40 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 20:59:40 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:40 volumio volumio[990]: info: CorePlayQueue::clearPlayQueue Jun 23 20:59:40 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 20:59:40 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 20:59:40 volumio volumio[990]: info: CoreStateMachine::addQueueItems Jun 23 20:59:40 volumio volumio[990]: info: CorePlayQueue::addQueueItems Jun 23 20:59:40 volumio volumio[990]: info: Preload queue cleared Jun 23 20:59:40 volumio volumio[990]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1616567 Jun 23 20:59:40 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 23 20:59:40 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 20:59:40 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 20:59:40 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 20:59:40 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 20:59:40 volumio volumio[990]: info: CoreCommandRouter::volumioPlay Jun 23 20:59:40 volumio volumio[990]: info: CoreStateMachine::play index 0 Jun 23 20:59:40 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:40 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 20:59:40 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:40 volumio volumio[990]: info: CoreStateMachine::play index undefined Jun 23 20:59:40 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:40 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:40 volumio volumio[990]: info: CoreStateMachine::startPlaybackTimer Jun 23 20:59:40 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:40 volumio volumio[990]: info: [1750683580197] ControllerWebradio::clearAddPlayTrack Jun 23 20:59:40 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Jun 23 20:59:40 volumio volumio[990]: info: sendMpdCommand stop took 5 milliseconds Jun 23 20:59:40 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand clear Jun 23 20:59:40 volumio volumio[990]: info: Jun 23 20:59:40 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:40 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:40 volumio volumio[990]: info: sendMpdCommand clear took 1 milliseconds Jun 23 20:59:40 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1616567" Jun 23 20:59:40 volumio volumio[990]: info: Jun 23 20:59:40 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:40 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:40 volumio volumio[990]: error: updateQueue error: null Jun 23 20:59:40 volumio volumio[990]: info: ------------------------------ 3ms Jun 23 20:59:40 volumio volumio[990]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1616567" took 246 milliseconds Jun 23 20:59:40 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 23 20:59:40 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand play Jun 23 20:59:40 volumio volumio[990]: info: Jun 23 20:59:40 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:40 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:40 volumio volumio[990]: info: ------------------------------ 248ms Jun 23 20:59:40 volumio volumio[990]: info: Jun 23 20:59:40 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:40 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:40 volumio volumio[990]: info: sendMpdCommand play took 5 milliseconds Jun 23 20:59:40 volumio volumio[990]: info: ------------------------------ 4ms Jun 23 20:59:40 volumio volumio[990]: info: ------------------------------ 2ms Jun 23 20:59:41 volumio volumio[990]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 10 Jun 23 20:59:41 volumio volumio[990]: info: CoreCommandRouter::volumioGetState Jun 23 20:59:42 volumio volumio[990]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 11 Jun 23 20:59:42 volumio volumio[990]: info: CoreCommandRouter::volumioGetState Jun 23 20:59:44 volumio volumio[990]: info: Preload queue cleared Jun 23 20:59:44 volumio volumio[990]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 23 20:59:44 volumio volumio[990]: info: CoreStateMachine::ClearQueue Jun 23 20:59:44 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 20:59:44 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:44 volumio volumio[990]: info: CorePlayQueue::clearPlayQueue Jun 23 20:59:44 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 20:59:44 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 20:59:44 volumio volumio[990]: info: CoreStateMachine::addQueueItems Jun 23 20:59:44 volumio volumio[990]: info: CorePlayQueue::addQueueItems Jun 23 20:59:44 volumio volumio[990]: info: Preload queue cleared Jun 23 20:59:44 volumio volumio[990]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1472177 Jun 23 20:59:44 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 23 20:59:44 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 20:59:44 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 20:59:44 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 20:59:44 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 20:59:44 volumio volumio[990]: info: CoreCommandRouter::volumioPlay Jun 23 20:59:44 volumio volumio[990]: info: CoreStateMachine::play index 0 Jun 23 20:59:44 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:44 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 20:59:44 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:44 volumio volumio[990]: info: CoreStateMachine::play index undefined Jun 23 20:59:44 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:44 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:44 volumio volumio[990]: info: CoreStateMachine::startPlaybackTimer Jun 23 20:59:44 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:44 volumio volumio[990]: info: [1750683584759] ControllerWebradio::clearAddPlayTrack Jun 23 20:59:44 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Jun 23 20:59:44 volumio volumio[990]: info: Jun 23 20:59:44 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:44 volumio volumio[990]: info: sendMpdCommand stop took 6 milliseconds Jun 23 20:59:44 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:44 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:44 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand clear Jun 23 20:59:44 volumio volumio[990]: info: Jun 23 20:59:44 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:44 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:44 volumio volumio[990]: info: sendMpdCommand status took 3 milliseconds Jun 23 20:59:44 volumio volumio[990]: info: sendMpdCommand clear took 4 milliseconds Jun 23 20:59:44 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:44 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:44 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1472177" Jun 23 20:59:44 volumio volumio[990]: info: Jun 23 20:59:44 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:44 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:44 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:44 volumio volumio[990]: error: updateQueue error: null Jun 23 20:59:44 volumio volumio[990]: info: ------------------------------ 8ms Jun 23 20:59:44 volumio volumio[990]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 23 20:59:44 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:44 volumio volumio[990]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 23 20:59:44 volumio volumio[990]: info: ------------------------------ 14ms Jun 23 20:59:45 volumio volumio[990]: info: Jun 23 20:59:45 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:45 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:45 volumio volumio[990]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1472177" took 249 milliseconds Jun 23 20:59:45 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 23 20:59:45 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand play Jun 23 20:59:45 volumio volumio[990]: info: sendMpdCommand status took 250 milliseconds Jun 23 20:59:45 volumio volumio[990]: info: ------------------------------ 5ms Jun 23 20:59:45 volumio volumio[990]: info: sendMpdCommand play took 4 milliseconds Jun 23 20:59:45 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:45 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:45 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:45 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:45 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:45 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:45 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:45 volumio volumio[990]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 23 20:59:45 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:45 volumio volumio[990]: info: CoreStateMachine::syncState stateService stop Jun 23 20:59:45 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus stop Jun 23 20:59:45 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:45 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:45 volumio volumio[990]: info: No code Jun 23 20:59:45 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:45 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:45 volumio volumio[990]: info: ------------------------------ 326ms Jun 23 20:59:45 volumio volumio[990]: info: Jun 23 20:59:45 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:45 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:45 volumio volumio[990]: info: ------------------------------ 8ms Jun 23 20:59:45 volumio volumio[990]: info: Jun 23 20:59:45 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:45 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:45 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:45 volumio volumio[990]: info: Jun 23 20:59:45 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:45 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:45 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:45 volumio volumio[990]: info: Jun 23 20:59:45 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:45 volumio volumio[990]: info: sendMpdCommand status took 243 milliseconds Jun 23 20:59:45 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:45 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:45 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:45 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:45 volumio volumio[990]: info: sendMpdCommand status took 243 milliseconds Jun 23 20:59:45 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:45 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:45 volumio volumio[990]: info: Jun 23 20:59:45 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:45 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:45 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:45 volumio volumio[990]: info: sendMpdCommand status took 3 milliseconds Jun 23 20:59:45 volumio volumio[990]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 23 20:59:45 volumio volumio[990]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 23 20:59:45 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:45 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:45 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:45 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:45 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:45 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:45 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:45 volumio volumio[990]: 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":"50.78.91.214:8804","artist":"(#1 - 69/512) WHYL Carlisle, PA","album":null,"uri":"http://50.78.91.214:8804","trackType":"214:8804"} Jun 23 20:59:45 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:45 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:45 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus stop Jun 23 20:59:45 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:45 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:45 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:45 volumio volumio[990]: 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":"50.78.91.214:8804","artist":"(#1 - 69/512) WHYL Carlisle, PA","album":null,"uri":"http://50.78.91.214:8804","trackType":"214:8804"} Jun 23 20:59:45 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:45 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:45 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:45 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:45 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:45 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:45 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:45 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:45 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:45 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:45 volumio volumio[990]: info: ------------------------------ 264ms Jun 23 20:59:45 volumio volumio[990]: info: ------------------------------ 282ms Jun 23 20:59:45 volumio volumio[990]: info: Jun 23 20:59:45 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:45 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:45 volumio volumio[990]: info: Jun 23 20:59:45 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:45 volumio volumio[990]: info: sendMpdCommand status took 39 milliseconds Jun 23 20:59:45 volumio volumio[990]: info: sendMpdCommand playlistinfo took 38 milliseconds Jun 23 20:59:45 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:45 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:45 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:45 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:45 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:45 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:45 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:45 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:45 volumio volumio[990]: 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":"50.78.91.214:8804","artist":"(#1 - 69/512) WHYL Carlisle, PA","album":null,"uri":"http://50.78.91.214:8804","trackType":"214:8804"} Jun 23 20:59:45 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:45 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:45 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:45 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:45 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:45 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:45 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:45 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:45 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:46 volumio volumio[990]: info: ------------------------------ 92ms Jun 23 20:59:46 volumio volumio[990]: info: Jun 23 20:59:46 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:46 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:46 volumio volumio[990]: info: Jun 23 20:59:46 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:46 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:46 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:46 volumio volumio[990]: info: ------------------------------ 62ms Jun 23 20:59:46 volumio volumio[990]: info: sendMpdCommand status took 62 milliseconds Jun 23 20:59:46 volumio volumio[990]: info: sendMpdCommand playlistinfo took 61 milliseconds Jun 23 20:59:46 volumio volumio[990]: info: ------------------------------ 3ms Jun 23 20:59:46 volumio volumio[990]: info: sendMpdCommand status took 2 milliseconds Jun 23 20:59:46 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:46 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:46 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:46 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:46 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:46 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:46 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:46 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:46 volumio volumio[990]: 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":"50.78.91.214:8804","artist":"WHYL Carlisle, PA","album":null,"uri":"http://50.78.91.214:8804","trackType":"214:8804"} Jun 23 20:59:46 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:46 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:46 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:46 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:46 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:46 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:46 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:46 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:46 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:46 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:46 volumio volumio[990]: info: ------------------------------ 137ms Jun 23 20:59:46 volumio volumio[990]: info: sendMpdCommand playlistinfo took 35 milliseconds Jun 23 20:59:46 volumio volumio[990]: info: sendMpdCommand playlistinfo took 36 milliseconds Jun 23 20:59:46 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:46 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:46 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:46 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:46 volumio volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":535,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"50.78.91.214:8804","artist":"WHYL Carlisle, PA","album":null,"uri":"http://50.78.91.214:8804","trackType":"214:8804"} Jun 23 20:59:46 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:46 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:46 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:46 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:46 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:46 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:46 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:46 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:46 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:46 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:46 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:46 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:46 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:46 volumio volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"50.78.91.214:8804","artist":"WHYL Carlisle, PA","album":null,"uri":"http://50.78.91.214:8804","trackType":"214:8804"} Jun 23 20:59:46 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:46 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:46 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:46 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:46 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:46 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:46 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:46 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:46 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:46 volumio volumio[990]: info: ------------------------------ 126ms Jun 23 20:59:46 volumio volumio[990]: info: ------------------------------ 65ms Jun 23 20:59:47 volumio volumio[990]: info: Preload queue cleared Jun 23 20:59:47 volumio volumio[990]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 23 20:59:47 volumio volumio[990]: info: CoreStateMachine::ClearQueue Jun 23 20:59:47 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 20:59:47 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:47 volumio volumio[990]: info: CoreStateMachine::stPlaybackTimer Jun 23 20:59:47 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 20:59:47 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 20:59:47 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:47 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:47 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:47 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:47 volumio volumio[990]: info: CoreStateMachine::serviceStop Jun 23 20:59:47 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:47 volumio volumio[990]: info: CoreCommandRouter::serviceStop Jun 23 20:59:47 volumio volumio[990]: info: [1750683587660] ControllerWebradio::stop Jun 23 20:59:47 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Jun 23 20:59:47 volumio volumio[990]: info: CorePlayQueue::clearPlayQueue Jun 23 20:59:47 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 20:59:47 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 20:59:47 volumio volumio[990]: info: CoreStateMachine::addQueueItems Jun 23 20:59:47 volumio volumio[990]: info: CorePlayQueue::addQueueItems Jun 23 20:59:47 volumio volumio[990]: info: Preload queue cleared Jun 23 20:59:47 volumio volumio[990]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1768504 Jun 23 20:59:47 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 23 20:59:47 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 20:59:47 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 20:59:47 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 20:59:47 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 20:59:47 volumio volumio[990]: info: CoreCommandRouter::volumioPlay Jun 23 20:59:47 volumio volumio[990]: info: CoreStateMachine::play index 0 Jun 23 20:59:47 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:47 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 20:59:47 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:47 volumio volumio[990]: info: CoreStateMachine::play index undefined Jun 23 20:59:47 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:47 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:47 volumio volumio[990]: info: CoreStateMachine::startPlaybackTimer Jun 23 20:59:47 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:47 volumio volumio[990]: info: [1750683587679] ControllerWebradio::clearAddPlayTrack Jun 23 20:59:47 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Jun 23 20:59:47 volumio sudo[5560]: pam_unix(sudo:session): session closed for user root Jun 23 20:59:47 volumio volumio[990]: info: Jun 23 20:59:47 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:47 volumio volumio[990]: info: sendMpdCommand stop took 142 milliseconds Jun 23 20:59:47 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:47 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:47 volumio volumio[990]: info: sendMpdCommand stop took 123 milliseconds Jun 23 20:59:47 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand clear Jun 23 20:59:47 volumio volumio[990]: info: Jun 23 20:59:47 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:47 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:47 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:47 volumio volumio[990]: info: Jun 23 20:59:47 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:47 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:47 volumio volumio[990]: info: sendMpdCommand status took 2 milliseconds Jun 23 20:59:47 volumio volumio[990]: info: sendMpdCommand clear took 2 milliseconds Jun 23 20:59:47 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:47 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:47 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1768504" Jun 23 20:59:47 volumio volumio[990]: info: Jun 23 20:59:47 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:47 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:47 volumio volumio[990]: error: updateQueue error: null Jun 23 20:59:47 volumio volumio[990]: info: sendMpdCommand status took 4 milliseconds Jun 23 20:59:47 volumio volumio[990]: info: ------------------------------ 3ms Jun 23 20:59:47 volumio volumio[990]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 23 20:59:47 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:47 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:47 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:47 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:47 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:47 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:47 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:47 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:47 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:47 volumio volumio[990]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 23 20:59:47 volumio volumio[990]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 23 20:59:47 volumio volumio[990]: info: ------------------------------ 11ms Jun 23 20:59:47 volumio volumio[990]: info: ------------------------------ 13ms Jun 23 20:59:48 volumio volumio[990]: info: Jun 23 20:59:48 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:48 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:48 volumio volumio[990]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1768504" took 250 milliseconds Jun 23 20:59:48 volumio volumio[990]: info: ------------------------------ 250ms Jun 23 20:59:48 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 23 20:59:48 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand play Jun 23 20:59:48 volumio volumio[990]: info: ------------------------------ 7ms Jun 23 20:59:48 volumio volumio[990]: info: Jun 23 20:59:48 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:48 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:48 volumio volumio[990]: info: sendMpdCommand play took 5 milliseconds Jun 23 20:59:48 volumio volumio[990]: info: ------------------------------ 3ms Jun 23 20:59:49 volumio volumio[990]: info: Jun 23 20:59:49 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:49 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:49 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:49 volumio volumio[990]: info: Jun 23 20:59:49 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:49 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:49 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:49 volumio volumio[990]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 23 20:59:49 volumio volumio[990]: info: Retrying Login Due to Network Error Jun 23 20:59:49 volumio volumio[990]: info: Jun 23 20:59:49 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:49 volumio volumio[990]: info: sendMpdCommand status took 152 milliseconds Jun 23 20:59:49 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:49 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:49 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:49 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:49 volumio volumio[990]: info: Jun 23 20:59:49 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:49 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:49 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:49 volumio volumio[990]: info: sendMpdCommand status took 154 milliseconds Jun 23 20:59:49 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:49 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:49 volumio volumio[990]: info: sendMpdCommand status took 7 milliseconds Jun 23 20:59:49 volumio volumio[990]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 23 20:59:49 volumio volumio[990]: info: sendMpdCommand status took 6 milliseconds Jun 23 20:59:49 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:49 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:49 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:49 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:49 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:49 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:49 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:49 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:49 volumio volumio[990]: 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":"1017","artist":"113.fm Hits 1973","album":null,"uri":"http://50.31.185.139:80/1017","trackType":""} Jun 23 20:59:49 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:49 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:49 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus stop Jun 23 20:59:49 volumio volumio[990]: info: ------------------------------ 163ms Jun 23 20:59:49 volumio volumio[990]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 23 20:59:49 volumio volumio[990]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 23 20:59:49 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:49 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:49 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:49 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:49 volumio volumio[990]: 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":"1017","artist":"113.fm Hits 1973","album":null,"uri":"http://50.31.185.139:80/1017","trackType":""} Jun 23 20:59:49 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:49 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:49 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:49 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:49 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 23 20:59:49 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:49 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:49 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:49 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:49 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:49 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:49 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:49 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:49 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:49 volumio volumio[990]: 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":"1017","artist":"113.fm Hits 1973","album":null,"uri":"http://50.31.185.139:80/1017","trackType":""} Jun 23 20:59:49 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:49 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:49 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:49 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:49 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 23 20:59:49 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:49 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:49 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:49 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:49 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:49 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:49 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:49 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:49 volumio volumio[990]: 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":"1017","artist":"113.fm Hits 1973","album":null,"uri":"http://50.31.185.139:80/1017","trackType":""} Jun 23 20:59:49 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:49 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:49 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:49 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:49 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 23 20:59:49 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:49 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:49 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:49 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:49 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:49 volumio volumio[990]: info: ------------------------------ 192ms Jun 23 20:59:49 volumio volumio[990]: info: ------------------------------ 42ms Jun 23 20:59:49 volumio volumio[990]: info: ------------------------------ 41ms Jun 23 20:59:49 volumio volumio[990]: info: Jun 23 20:59:49 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:49 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:49 volumio volumio[990]: info: Jun 23 20:59:49 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:49 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:49 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:49 volumio volumio[990]: info: Jun 23 20:59:49 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:49 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:49 volumio volumio[990]: info: Jun 23 20:59:49 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:49 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:49 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:49 volumio volumio[990]: info: ------------------------------ 20ms Jun 23 20:59:49 volumio volumio[990]: info: sendMpdCommand status took 19 milliseconds Jun 23 20:59:49 volumio volumio[990]: info: ------------------------------ 18ms Jun 23 20:59:49 volumio volumio[990]: info: sendMpdCommand status took 17 milliseconds Jun 23 20:59:49 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:49 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:49 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:49 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:49 volumio volumio[990]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 23 20:59:49 volumio volumio[990]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 23 20:59:49 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:49 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:49 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:49 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:49 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:49 volumio volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":535,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Stealers Wheel - Stuck In The Middle With You","artist":"113.fm Hits 1973","album":null,"uri":"http://50.31.185.139:80/1017","trackType":""} Jun 23 20:59:49 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:49 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:49 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:49 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:49 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 23 20:59:49 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:49 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:49 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:49 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:49 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:49 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:49 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:49 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:49 volumio volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":637,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Stealers Wheel - Stuck In The Middle With You","artist":"113.fm Hits 1973","album":null,"uri":"http://50.31.185.139:80/1017","trackType":""} Jun 23 20:59:49 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:49 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:49 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:49 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:49 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 23 20:59:49 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:49 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:49 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:49 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:49 volumio volumio[990]: info: ------------------------------ 45ms Jun 23 20:59:49 volumio volumio[990]: info: ------------------------------ 45ms Jun 23 20:59:51 volumio volumio[990]: info: Preload queue cleared Jun 23 20:59:51 volumio volumio[990]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 23 20:59:51 volumio volumio[990]: info: CoreStateMachine::ClearQueue Jun 23 20:59:51 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 20:59:51 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:51 volumio volumio[990]: info: CoreStateMachine::stPlaybackTimer Jun 23 20:59:51 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 20:59:51 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 20:59:51 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:51 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:51 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:51 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:51 volumio volumio[990]: info: CoreStateMachine::serviceStop Jun 23 20:59:51 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:51 volumio volumio[990]: info: CoreCommandRouter::serviceStop Jun 23 20:59:51 volumio volumio[990]: info: [1750683591260] ControllerWebradio::stop Jun 23 20:59:51 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Jun 23 20:59:51 volumio volumio[990]: info: CorePlayQueue::clearPlayQueue Jun 23 20:59:51 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 20:59:51 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 20:59:51 volumio volumio[990]: info: CoreStateMachine::addQueueItems Jun 23 20:59:51 volumio volumio[990]: info: CorePlayQueue::addQueueItems Jun 23 20:59:51 volumio volumio[990]: info: Preload queue cleared Jun 23 20:59:51 volumio volumio[990]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99493376 Jun 23 20:59:51 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 23 20:59:51 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 20:59:51 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 20:59:51 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 20:59:51 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 20:59:51 volumio volumio[990]: info: CoreCommandRouter::volumioPlay Jun 23 20:59:51 volumio volumio[990]: info: CoreStateMachine::play index 0 Jun 23 20:59:51 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:51 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 20:59:51 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:51 volumio volumio[990]: info: CoreStateMachine::play index undefined Jun 23 20:59:51 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 20:59:51 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:51 volumio volumio[990]: info: CoreStateMachine::startPlaybackTimer Jun 23 20:59:51 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:51 volumio volumio[990]: info: [1750683591274] ControllerWebradio::clearAddPlayTrack Jun 23 20:59:51 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Jun 23 20:59:51 volumio volumio[990]: info: Jun 23 20:59:51 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:51 volumio volumio[990]: info: sendMpdCommand stop took 141 milliseconds Jun 23 20:59:51 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:51 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:51 volumio volumio[990]: info: sendMpdCommand stop took 128 milliseconds Jun 23 20:59:51 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand clear Jun 23 20:59:51 volumio volumio[990]: info: Jun 23 20:59:51 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:51 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:51 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:51 volumio volumio[990]: info: Jun 23 20:59:51 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:51 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:51 volumio volumio[990]: info: sendMpdCommand status took 8 milliseconds Jun 23 20:59:51 volumio volumio[990]: info: sendMpdCommand clear took 8 milliseconds Jun 23 20:59:51 volumio volumio[990]: info: sendMpdCommand status took 2 milliseconds Jun 23 20:59:51 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:51 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:51 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99493376" Jun 23 20:59:51 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:51 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:51 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:51 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:51 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:51 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:51 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:51 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:51 volumio volumio[990]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 23 20:59:51 volumio volumio[990]: info: ------------------------------ 29ms Jun 23 20:59:51 volumio volumio[990]: info: Jun 23 20:59:51 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:51 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:51 volumio volumio[990]: error: updateQueue error: null Jun 23 20:59:51 volumio volumio[990]: info: ------------------------------ 36ms Jun 23 20:59:51 volumio volumio[990]: info: sendMpdCommand playlistinfo took 30 milliseconds Jun 23 20:59:51 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:51 volumio volumio[990]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 23 20:59:51 volumio volumio[990]: info: ------------------------------ 45ms Jun 23 20:59:51 volumio volumio[990]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99493376" took 247 milliseconds Jun 23 20:59:51 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 23 20:59:51 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand play Jun 23 20:59:51 volumio volumio[990]: info: Jun 23 20:59:51 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:51 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:51 volumio volumio[990]: info: ------------------------------ 225ms Jun 23 20:59:51 volumio volumio[990]: info: Jun 23 20:59:51 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:51 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:51 volumio volumio[990]: info: sendMpdCommand play took 8 milliseconds Jun 23 20:59:51 volumio volumio[990]: info: ------------------------------ 7ms Jun 23 20:59:51 volumio volumio[990]: info: ------------------------------ 4ms Jun 23 20:59:55 volumio volumio[990]: info: Jun 23 20:59:55 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:55 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:55 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:55 volumio volumio[990]: info: Jun 23 20:59:55 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:55 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:55 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:55 volumio volumio[990]: info: Jun 23 20:59:55 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:55 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:55 volumio volumio[990]: info: Jun 23 20:59:55 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:55 volumio volumio[990]: info: sendMpdCommand status took 240 milliseconds Jun 23 20:59:55 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:55 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:55 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:55 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:55 volumio volumio[990]: info: Jun 23 20:59:55 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 20:59:55 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 20:59:55 volumio volumio[990]: info: Jun 23 20:59:55 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 20:59:55 volumio volumio[990]: info: ControllerMpd::getState Jun 23 20:59:55 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 20:59:55 volumio volumio[990]: info: sendMpdCommand status took 243 milliseconds Jun 23 20:59:55 volumio volumio[990]: info: ------------------------------ 7ms Jun 23 20:59:55 volumio volumio[990]: info: sendMpdCommand status took 5 milliseconds Jun 23 20:59:55 volumio volumio[990]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 23 20:59:55 volumio volumio[990]: info: ------------------------------ 4ms Jun 23 20:59:55 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:55 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:55 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:55 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:55 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:55 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:55 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:55 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:55 volumio volumio[990]: 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":"96 Kbps","isStreaming":false,"title":"localhost","artist":"University Of Oregon - KWAX","album":null,"uri":"http://streaming.kwax.com:8000/localhost","trackType":""} Jun 23 20:59:55 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:55 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:55 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus stop Jun 23 20:59:55 volumio volumio[990]: info: ------------------------------ 251ms Jun 23 20:59:55 volumio volumio[990]: info: sendMpdCommand status took 8 milliseconds Jun 23 20:59:55 volumio volumio[990]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 23 20:59:55 volumio volumio[990]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 23 20:59:55 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 20:59:55 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 20:59:55 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:55 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:55 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:55 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:55 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:55 volumio volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":352,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"localhost","artist":"University Of Oregon - KWAX","album":null,"uri":"http://streaming.kwax.com:8000/localhost","trackType":""} Jun 23 20:59:55 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:55 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:55 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:55 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:55 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 23 20:59:55 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:55 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:55 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:55 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:55 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:55 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:55 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:55 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:55 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:55 volumio volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":603,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"localhost","artist":"University Of Oregon - KWAX","album":null,"uri":"http://streaming.kwax.com:8000/localhost","trackType":""} Jun 23 20:59:55 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:55 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:55 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:55 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:55 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 23 20:59:55 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:55 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:55 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:55 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:55 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:55 volumio volumio[990]: info: ------------------------------ 286ms Jun 23 20:59:55 volumio volumio[990]: info: ------------------------------ 48ms Jun 23 20:59:55 volumio volumio[990]: info: sendMpdCommand playlistinfo took 37 milliseconds Jun 23 20:59:55 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 20:59:55 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 20:59:55 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 20:59:55 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:55 volumio volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":603,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"localhost","artist":"University Of Oregon - KWAX","album":null,"uri":"http://streaming.kwax.com:8000/localhost","trackType":""} Jun 23 20:59:55 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 20:59:55 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 20:59:55 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 20:59:55 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 20:59:55 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 23 20:59:55 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:55 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 20:59:55 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 20:59:55 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:55 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 20:59:55 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 20:59:55 volumio volumio[990]: info: ------------------------------ 59ms Jun 23 20:59:59 volumio volumio[990]: info: MyVolumio login type: Token Jun 23 21:00:01 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 23 21:00:01 volumio volumio[990]: info: CURURI: music-library Jun 23 21:00:02 volumio volumio[990]: info: Preload queue cleared Jun 23 21:00:02 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 23 21:00:02 volumio volumio[990]: info: CURURI: music-library/INTERNAL Jun 23 21:00:02 volumio volumio[990]: info: Preload queue cleared Jun 23 21:00:08 volumio volumio[990]: info: CoreCommandRouter::volumioGetState Jun 23 21:00:08 volumio sudo[5759]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jun 23 21:00:08 volumio sudo[5759]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 21:00:08 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Jun 23 21:00:08 volumio volumio[990]: info: Preload queue cleared Jun 23 21:00:08 volumio volumio[990]: info: Preloading song: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 03.情系半生.flac Jun 23 21:00:08 volumio volumio[990]: info: Preloading song: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 02.期待.flac Jun 23 21:00:08 volumio volumio[990]: info: Preloading song: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 01.春风秋雨.flac Jun 23 21:00:08 volumio volumio[990]: info: Preloading song: mnt/INTERNAL/musics/2008再低一次/赵鹏&柏菲音乐-Wulala.flac Jun 23 21:00:08 volumio volumio[990]: info: Preloading song: mnt/INTERNAL/musics/风筝（先听版）/孙燕姿-风筝(先听版).flac Jun 23 21:00:08 volumio volumio[990]: info: Preloading song: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-把悲伤留给自己.flac Jun 23 21:00:08 volumio volumio[990]: info: Preloading song: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-我的家乡.flac Jun 23 21:00:08 volumio volumio[990]: info: Preloading song: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-山茶花.flac Jun 23 21:00:08 volumio volumio[990]: info: Preloading song: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-风和花的爱情.flac Jun 23 21:00:08 volumio volumio[990]: info: Preloading song: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-知道不知道.flac Jun 23 21:00:08 volumio volumio[990]: info: Preloading song: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-殇.flac Jun 23 21:00:08 volumio volumio[990]: info: Preloading song: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-春花秋月.flac Jun 23 21:00:08 volumio volumio[990]: info: Preloading song: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-明月千里寄相思.flac Jun 23 21:00:08 volumio volumio[990]: info: Preloading song: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-情人的眼泪.flac Jun 23 21:00:08 volumio volumio[990]: info: Preloading song: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-Wulala.flac Jun 23 21:00:09 volumio volumio[990]: info: Exploding uri mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 03.情系半生.flac in service mpd Jun 23 21:00:09 volumio volumio[990]: info: ALBUMART /albumart?cacheid=373&web=%E5%BC%A0%E5%AD%A6%E5%8F%8B/%E7%BB%9D%E5%AF%B9%E7%8F%8D%E8%97%8FDISC1/extralarge&path=%2Fmnt%2FUSB%2F6F6B1E859BE1152C%2F%E5%BC%A0%E5%AD%A6%E5%8F%8B%20%E7%BB%9D%E5%AF%B9%E7%8F%8D%E8%97%8FCD1%20%5BFLAC%E5%88%86%E8%BD%A8%5D%2F%E5%BC%A0%E5%AD%A6%E5%8F%8B%20-%2003.%E6%83%85%E7%B3%BB%E5%8D%8A%E7%94%9F.flac&metadata=false Jun 23 21:00:09 volumio volumio[990]: info: URI /mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 03.情系半生.flac Jun 23 21:00:09 volumio volumio[990]: info: Exploding uri mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 02.期待.flac in service mpd Jun 23 21:00:09 volumio volumio[990]: info: ALBUMART /albumart?cacheid=373&web=%E5%BC%A0%E5%AD%A6%E5%8F%8B/%E7%BB%9D%E5%AF%B9%E7%8F%8D%E8%97%8FDISC1/extralarge&path=%2Fmnt%2FUSB%2F6F6B1E859BE1152C%2F%E5%BC%A0%E5%AD%A6%E5%8F%8B%20%E7%BB%9D%E5%AF%B9%E7%8F%8D%E8%97%8FCD1%20%5BFLAC%E5%88%86%E8%BD%A8%5D%2F%E5%BC%A0%E5%AD%A6%E5%8F%8B%20-%2002.%E6%9C%9F%E5%BE%85.flac&metadata=false Jun 23 21:00:09 volumio volumio[990]: info: URI /mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 02.期待.flac Jun 23 21:00:09 volumio volumio[990]: info: Exploding uri mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 01.春风秋雨.flac in service mpd Jun 23 21:00:09 volumio volumio[990]: info: ALBUMART /albumart?cacheid=373&web=%E5%BC%A0%E5%AD%A6%E5%8F%8B/%E7%BB%9D%E5%AF%B9%E7%8F%8D%E8%97%8FDISC1/extralarge&path=%2Fmnt%2FUSB%2F6F6B1E859BE1152C%2F%E5%BC%A0%E5%AD%A6%E5%8F%8B%20%E7%BB%9D%E5%AF%B9%E7%8F%8D%E8%97%8FCD1%20%5BFLAC%E5%88%86%E8%BD%A8%5D%2F%E5%BC%A0%E5%AD%A6%E5%8F%8B%20-%2001.%E6%98%A5%E9%A3%8E%E7%A7%8B%E9%9B%A8.flac&metadata=false Jun 23 21:00:09 volumio volumio[990]: info: URI /mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 01.春风秋雨.flac Jun 23 21:00:09 volumio volumio[990]: info: Exploding uri mnt/INTERNAL/musics/2008再低一次/赵鹏&柏菲音乐-Wulala.flac in service mpd Jun 23 21:00:09 volumio volumio[990]: info: ALBUMART /albumart?cacheid=373&web=%E8%B5%B5%E9%B9%8F%26%E6%9F%8F%E8%8F%B2%E9%9F%B3%E4%B9%90/2008%E5%86%8D%E4%BD%8E%E4%B8%80%E6%AC%A1/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F2008%E5%86%8D%E4%BD%8E%E4%B8%80%E6%AC%A1%2F%E8%B5%B5%E9%B9%8F%26%E6%9F%8F%E8%8F%B2%E9%9F%B3%E4%B9%90-Wulala.flac&metadata=false Jun 23 21:00:09 volumio volumio[990]: info: URI /mnt/INTERNAL/musics/2008再低一次/赵鹏&柏菲音乐-Wulala.flac Jun 23 21:00:09 volumio volumio[990]: info: Exploding uri mnt/INTERNAL/musics/风筝（先听版）/孙燕姿-风筝(先听版).flac in service mpd Jun 23 21:00:09 volumio volumio[990]: info: ALBUMART /albumart?cacheid=373&web=%E5%AD%99%E7%87%95%E5%A7%BF/%E9%A3%8E%E7%AD%9D/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E9%A3%8E%E7%AD%9D%EF%BC%88%E5%85%88%E5%90%AC%E7%89%88%EF%BC%89%2F%E5%AD%99%E7%87%95%E5%A7%BF-%E9%A3%8E%E7%AD%9D(%E5%85%88%E5%90%AC%E7%89%88).flac&metadata=false Jun 23 21:00:09 volumio volumio[990]: info: URI /mnt/INTERNAL/musics/风筝（先听版）/孙燕姿-风筝(先听版).flac Jun 23 21:00:09 volumio volumio[990]: info: Exploding uri mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-把悲伤留给自己.flac in service mpd Jun 23 21:00:09 volumio volumio[990]: error: scanFolder - failure to stat '/mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-把悲伤留给自己.flac' Jun 23 21:00:09 volumio volumio[990]: info: Exploding uri mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-我的家乡.flac in service mpd Jun 23 21:00:09 volumio volumio[990]: error: scanFolder - failure to stat '/mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-我的家乡.flac' Jun 23 21:00:09 volumio volumio[990]: info: Exploding uri mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-山茶花.flac in service mpd Jun 23 21:00:09 volumio volumio[990]: error: scanFolder - failure to stat '/mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-山茶花.flac' Jun 23 21:00:09 volumio volumio[990]: info: Exploding uri mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-风和花的爱情.flac in service mpd Jun 23 21:00:09 volumio volumio[990]: error: scanFolder - failure to stat '/mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-风和花的爱情.flac' Jun 23 21:00:09 volumio volumio[990]: info: Exploding uri mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-知道不知道.flac in service mpd Jun 23 21:00:09 volumio volumio[990]: error: scanFolder - failure to stat '/mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-知道不知道.flac' Jun 23 21:00:09 volumio volumio[990]: info: Exploding uri mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-殇.flac in service mpd Jun 23 21:00:09 volumio volumio[990]: error: scanFolder - failure to stat '/mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-殇.flac' Jun 23 21:00:09 volumio volumio[990]: info: Exploding uri mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-春花秋月.flac in service mpd Jun 23 21:00:09 volumio volumio[990]: error: scanFolder - failure to stat '/mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-春花秋月.flac' Jun 23 21:00:09 volumio volumio[990]: info: Exploding uri mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-明月千里寄相思.flac in service mpd Jun 23 21:00:09 volumio volumio[990]: error: scanFolder - failure to stat '/mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-明月千里寄相思.flac' Jun 23 21:00:09 volumio volumio[990]: info: Exploding uri mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-情人的眼泪.flac in service mpd Jun 23 21:00:09 volumio volumio[990]: error: scanFolder - failure to stat '/mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-情人的眼泪.flac' Jun 23 21:00:09 volumio volumio[990]: info: Exploding uri mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-Wulala.flac in service mpd Jun 23 21:00:09 volumio volumio[990]: error: scanFolder - failure to stat '/mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-Wulala.flac' Jun 23 21:00:11 volumio volumio[990]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 10 Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::volumioGetState Jun 23 21:00:11 volumio volumio[990]: info: Preload queue cleared Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::ClearQueue Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::stPlaybackTimer Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::serviceStop Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::serviceStop Jun 23 21:00:11 volumio volumio[990]: info: [1750683611430] ControllerWebradio::stop Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::clearPlayQueue Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::addQueueItems Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::addQueueItems Jun 23 21:00:11 volumio volumio[990]: info: Preload queue cleared Jun 23 21:00:11 volumio volumio[990]: info: Adding Item to queue: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 03.情系半生.flac Jun 23 21:00:11 volumio volumio[990]: info: Using cached record of: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 03.情系半生.flac Jun 23 21:00:11 volumio volumio[990]: info: Adding Item to queue: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 02.期待.flac Jun 23 21:00:11 volumio volumio[990]: info: Using cached record of: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 02.期待.flac Jun 23 21:00:11 volumio volumio[990]: info: Adding Item to queue: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 01.春风秋雨.flac Jun 23 21:00:11 volumio volumio[990]: info: Using cached record of: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 01.春风秋雨.flac Jun 23 21:00:11 volumio volumio[990]: info: Adding Item to queue: mnt/INTERNAL/musics/2008再低一次/赵鹏&柏菲音乐-Wulala.flac Jun 23 21:00:11 volumio volumio[990]: info: Using cached record of: mnt/INTERNAL/musics/2008再低一次/赵鹏&柏菲音乐-Wulala.flac Jun 23 21:00:11 volumio volumio[990]: info: Adding Item to queue: mnt/INTERNAL/musics/风筝（先听版）/孙燕姿-风筝(先听版).flac Jun 23 21:00:11 volumio volumio[990]: info: Using cached record of: mnt/INTERNAL/musics/风筝（先听版）/孙燕姿-风筝(先听版).flac Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::volumioPlay Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::play index 4 Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::addQueueItems Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::addQueueItems Jun 23 21:00:11 volumio volumio[990]: info: Preload queue cleared Jun 23 21:00:11 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-把悲伤留给自己.flac Jun 23 21:00:11 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-把悲伤留给自己.flac Jun 23 21:00:11 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-我的家乡.flac Jun 23 21:00:11 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-我的家乡.flac Jun 23 21:00:11 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-山茶花.flac Jun 23 21:00:11 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-山茶花.flac Jun 23 21:00:11 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-风和花的爱情.flac Jun 23 21:00:11 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-风和花的爱情.flac Jun 23 21:00:11 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-知道不知道.flac Jun 23 21:00:11 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-知道不知道.flac Jun 23 21:00:11 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-殇.flac Jun 23 21:00:11 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-殇.flac Jun 23 21:00:11 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-春花秋月.flac Jun 23 21:00:11 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-春花秋月.flac Jun 23 21:00:11 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-明月千里寄相思.flac Jun 23 21:00:11 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-明月千里寄相思.flac Jun 23 21:00:11 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-情人的眼泪.flac Jun 23 21:00:11 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-情人的眼泪.flac Jun 23 21:00:11 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-Wulala.flac Jun 23 21:00:11 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-Wulala.flac Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::play index undefined Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::getTrack 4 Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::startPlaybackTimer Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::getTrack 4 Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/musics/风筝（先听版）/孙燕姿-风筝(先听版).flac Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Jun 23 21:00:11 volumio volumio[990]: info: Jun 23 21:00:11 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 21:00:11 volumio volumio[990]: info: sendMpdCommand stop took 147 milliseconds Jun 23 21:00:11 volumio volumio[990]: info: ControllerMpd::getState Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 21:00:11 volumio volumio[990]: info: sendMpdCommand stop took 75 milliseconds Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand clear Jun 23 21:00:11 volumio volumio[990]: info: Jun 23 21:00:11 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 21:00:11 volumio volumio[990]: info: ControllerMpd::getState Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 21:00:11 volumio volumio[990]: info: Jun 23 21:00:11 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 21:00:11 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 21:00:11 volumio volumio[990]: info: sendMpdCommand status took 4 milliseconds Jun 23 21:00:11 volumio volumio[990]: info: sendMpdCommand clear took 3 milliseconds Jun 23 21:00:11 volumio volumio[990]: info: sendMpdCommand status took 2 milliseconds Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/musics/风筝（先听版）/孙燕姿-风筝(先听版).flac" Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 21:00:11 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::getTrack 4 Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::getTrack 4 Jun 23 21:00:11 volumio volumio[990]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 23 21:00:11 volumio volumio[990]: verbose: CURRENT POSITION 4 Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::syncState stateService stop Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus stop Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::getTrack 4 Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:11 volumio volumio[990]: info: No code Jun 23 21:00:11 volumio volumio[990]: info: ------------------------------ 28ms Jun 23 21:00:11 volumio volumio[990]: info: Jun 23 21:00:11 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 21:00:11 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 21:00:11 volumio volumio[990]: error: updateQueue error: null Jun 23 21:00:11 volumio volumio[990]: info: Jun 23 21:00:11 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 21:00:11 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 21:00:11 volumio volumio[990]: info: ------------------------------ 35ms Jun 23 21:00:11 volumio volumio[990]: info: sendMpdCommand playlistinfo took 34 milliseconds Jun 23 21:00:11 volumio volumio[990]: info: sendMpdCommand add "INTERNAL/musics/风筝（先听版）/孙燕姿-风筝(先听版).flac" took 34 milliseconds Jun 23 21:00:11 volumio volumio[990]: info: ------------------------------ 7ms Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand play Jun 23 21:00:11 volumio volumio[990]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 23 21:00:11 volumio volumio[990]: info: ------------------------------ 42ms Jun 23 21:00:11 volumio volumio[990]: info: Jun 23 21:00:11 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 21:00:11 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 21:00:11 volumio volumio[990]: info: ------------------------------ 15ms Jun 23 21:00:11 volumio volumio[990]: info: sendMpdCommand play took 13 milliseconds Jun 23 21:00:11 volumio volumio[990]: info: ------------------------------ 12ms Jun 23 21:00:11 volumio volumio[990]: info: Jun 23 21:00:11 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 21:00:11 volumio volumio[990]: info: ControllerMpd::getState Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 21:00:11 volumio volumio[990]: info: Jun 23 21:00:11 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 21:00:11 volumio volumio[990]: info: ControllerMpd::getState Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 21:00:11 volumio volumio[990]: info: Jun 23 21:00:11 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 21:00:11 volumio volumio[990]: info: sendMpdCommand status took 135 milliseconds Jun 23 21:00:11 volumio volumio[990]: info: sendMpdCommand status took 134 milliseconds Jun 23 21:00:11 volumio volumio[990]: info: ControllerMpd::getState Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 21:00:11 volumio volumio[990]: info: Jun 23 21:00:11 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 21:00:11 volumio volumio[990]: info: ControllerMpd::getState Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 21:00:11 volumio volumio[990]: info: sendMpdCommand status took 6 milliseconds Jun 23 21:00:11 volumio volumio[990]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 23 21:00:11 volumio volumio[990]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 23 21:00:11 volumio volumio[990]: info: sendMpdCommand status took 3 milliseconds Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 21:00:11 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::getTrack 4 Jun 23 21:00:11 volumio volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"风筝(先听版)","artist":"孙燕姿","album":"风筝","uri":"INTERNAL/musics/风筝（先听版）/孙燕姿-风筝(先听版).flac","trackType":"flac"} Jun 23 21:00:11 volumio volumio[990]: verbose: CURRENT POSITION 4 Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus stop Jun 23 21:00:11 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::getTrack 4 Jun 23 21:00:11 volumio volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"风筝(先听版)","artist":"孙燕姿","album":"风筝","uri":"INTERNAL/musics/风筝（先听版）/孙燕姿-风筝(先听版).flac","trackType":"flac"} Jun 23 21:00:11 volumio volumio[990]: verbose: CURRENT POSITION 4 Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 21:00:11 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::getTrack 4 Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::getTrack 4 Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:11 volumio volumio[990]: info: ------------------------------ 164ms Jun 23 21:00:11 volumio volumio[990]: info: ------------------------------ 168ms Jun 23 21:00:11 volumio volumio[990]: info: sendMpdCommand playlistinfo took 24 milliseconds Jun 23 21:00:11 volumio volumio[990]: info: sendMpdCommand playlistinfo took 25 milliseconds Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 21:00:11 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 21:00:11 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::getTrack 4 Jun 23 21:00:11 volumio volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":591,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"408 Kbps","isStreaming":false,"title":"风筝(先听版)","artist":"孙燕姿","album":"风筝","uri":"INTERNAL/musics/风筝（先听版）/孙燕姿-风筝(先听版).flac","trackType":"flac"} Jun 23 21:00:11 volumio volumio[990]: verbose: CURRENT POSITION 4 Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 21:00:11 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::getTrack 4 Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::getTrack 4 Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:11 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::getTrack 4 Jun 23 21:00:11 volumio volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"408 Kbps","isStreaming":false,"title":"风筝(先听版)","artist":"孙燕姿","album":"风筝","uri":"INTERNAL/musics/风筝（先听版）/孙燕姿-风筝(先听版).flac","trackType":"flac"} Jun 23 21:00:11 volumio volumio[990]: verbose: CURRENT POSITION 4 Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 21:00:11 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::getTrack 4 Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:11 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:11 volumio volumio[990]: info: CorePlayQueue::getTrack 4 Jun 23 21:00:11 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:11 volumio volumio[990]: info: ------------------------------ 66ms Jun 23 21:00:11 volumio volumio[990]: info: ------------------------------ 62ms Jun 23 21:00:11 volumio volumio[990]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 11 Jun 23 21:00:12 volumio volumio[990]: info: CoreCommandRouter::volumioGetState Jun 23 21:00:12 volumio volumio[990]: info: CorePlayQueue::getTrack 4 Jun 23 21:00:13 volumio volumio[990]: info: Preload queue cleared Jun 23 21:00:13 volumio volumio[990]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::ClearQueue Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::stPlaybackTimer Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 21:00:13 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:13 volumio volumio[990]: info: CorePlayQueue::getTrack 4 Jun 23 21:00:13 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 21:00:13 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::serviceStop Jun 23 21:00:13 volumio volumio[990]: info: CorePlayQueue::getTrack 4 Jun 23 21:00:13 volumio volumio[990]: info: CoreCommandRouter::serviceStop Jun 23 21:00:13 volumio volumio[990]: info: ControllerMpd::stop Jun 23 21:00:13 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Jun 23 21:00:13 volumio volumio[990]: info: CorePlayQueue::clearPlayQueue Jun 23 21:00:13 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 21:00:13 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::addQueueItems Jun 23 21:00:13 volumio volumio[990]: info: CorePlayQueue::addQueueItems Jun 23 21:00:13 volumio volumio[990]: info: Preload queue cleared Jun 23 21:00:13 volumio volumio[990]: info: Adding Item to queue: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 03.情系半生.flac Jun 23 21:00:13 volumio volumio[990]: info: Using cached record of: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 03.情系半生.flac Jun 23 21:00:13 volumio volumio[990]: info: Adding Item to queue: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 02.期待.flac Jun 23 21:00:13 volumio volumio[990]: info: Using cached record of: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 02.期待.flac Jun 23 21:00:13 volumio volumio[990]: info: Adding Item to queue: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 01.春风秋雨.flac Jun 23 21:00:13 volumio volumio[990]: info: Using cached record of: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 01.春风秋雨.flac Jun 23 21:00:13 volumio volumio[990]: info: Adding Item to queue: mnt/INTERNAL/musics/2008再低一次/赵鹏&柏菲音乐-Wulala.flac Jun 23 21:00:13 volumio volumio[990]: info: Using cached record of: mnt/INTERNAL/musics/2008再低一次/赵鹏&柏菲音乐-Wulala.flac Jun 23 21:00:13 volumio volumio[990]: info: Adding Item to queue: mnt/INTERNAL/musics/风筝（先听版）/孙燕姿-风筝(先听版).flac Jun 23 21:00:13 volumio volumio[990]: info: Using cached record of: mnt/INTERNAL/musics/风筝（先听版）/孙燕姿-风筝(先听版).flac Jun 23 21:00:13 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-把悲伤留给自己.flac Jun 23 21:00:13 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-把悲伤留给自己.flac Jun 23 21:00:13 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 21:00:13 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 21:00:13 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 21:00:13 volumio volumio[990]: info: CoreCommandRouter::volumioPlay Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::play index 5 Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::addQueueItems Jun 23 21:00:13 volumio volumio[990]: info: CorePlayQueue::addQueueItems Jun 23 21:00:13 volumio volumio[990]: info: Preload queue cleared Jun 23 21:00:13 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-我的家乡.flac Jun 23 21:00:13 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-我的家乡.flac Jun 23 21:00:13 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-山茶花.flac Jun 23 21:00:13 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-山茶花.flac Jun 23 21:00:13 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-风和花的爱情.flac Jun 23 21:00:13 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-风和花的爱情.flac Jun 23 21:00:13 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-知道不知道.flac Jun 23 21:00:13 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-知道不知道.flac Jun 23 21:00:13 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-殇.flac Jun 23 21:00:13 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-殇.flac Jun 23 21:00:13 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-春花秋月.flac Jun 23 21:00:13 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-春花秋月.flac Jun 23 21:00:13 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-明月千里寄相思.flac Jun 23 21:00:13 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-明月千里寄相思.flac Jun 23 21:00:13 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-情人的眼泪.flac Jun 23 21:00:13 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-情人的眼泪.flac Jun 23 21:00:13 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-Wulala.flac Jun 23 21:00:13 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-Wulala.flac Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 21:00:13 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 21:00:13 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::play index undefined Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 21:00:13 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 21:00:13 volumio volumio[990]: info: CorePlayQueue::getTrack 5 Jun 23 21:00:13 volumio volumio[990]: info: Jun 23 21:00:13 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 21:00:13 volumio volumio[990]: info: sendMpdCommand stop took 137 milliseconds Jun 23 21:00:13 volumio volumio[990]: info: ControllerMpd::getState Jun 23 21:00:13 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 21:00:13 volumio volumio[990]: info: Jun 23 21:00:13 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 21:00:13 volumio volumio[990]: info: ControllerMpd::getState Jun 23 21:00:13 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 21:00:13 volumio volumio[990]: info: sendMpdCommand status took 1 milliseconds Jun 23 21:00:13 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 21:00:13 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 21:00:13 volumio volumio[990]: info: sendMpdCommand status took 2 milliseconds Jun 23 21:00:13 volumio volumio[990]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 23 21:00:13 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 21:00:13 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 21:00:13 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 21:00:13 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 21:00:13 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:13 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:13 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 21:00:13 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:13 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:13 volumio volumio[990]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"风筝(先听版)","artist":"孙燕姿","album":"风筝","uri":"INTERNAL/musics/风筝（先听版）/孙燕姿-风筝(先听版).flac","trackType":"flac"} Jun 23 21:00:13 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::syncState stateService stop Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus stop Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:13 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:13 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:13 volumio volumio[990]: info: No code Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:13 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:13 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:13 volumio volumio[990]: info: ------------------------------ 20ms Jun 23 21:00:13 volumio volumio[990]: info: sendMpdCommand playlistinfo took 16 milliseconds Jun 23 21:00:13 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 21:00:13 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 21:00:13 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:13 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:13 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:13 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:13 volumio volumio[990]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"风筝(先听版)","artist":"孙燕姿","album":"风筝","uri":"INTERNAL/musics/风筝（先听版）/孙燕姿-风筝(先听版).flac","trackType":"flac"} Jun 23 21:00:13 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::syncState stateService stop Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus stop Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:13 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:13 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:13 volumio volumio[990]: info: No code Jun 23 21:00:13 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:13 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:13 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:13 volumio volumio[990]: info: ------------------------------ 42ms Jun 23 21:00:14 volumio sudo[5759]: pam_unix(sudo:session): session closed for user root Jun 23 21:00:27 volumio volumio[990]: info: Preload queue cleared Jun 23 21:00:27 volumio volumio[990]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 23 21:00:27 volumio volumio[990]: info: CoreStateMachine::ClearQueue Jun 23 21:00:27 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 21:00:27 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 21:00:27 volumio volumio[990]: info: CorePlayQueue::clearPlayQueue Jun 23 21:00:27 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 21:00:27 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 21:00:27 volumio volumio[990]: info: CoreStateMachine::addQueueItems Jun 23 21:00:27 volumio volumio[990]: info: CorePlayQueue::addQueueItems Jun 23 21:00:27 volumio volumio[990]: info: Preload queue cleared Jun 23 21:00:27 volumio volumio[990]: info: Adding Item to queue: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 03.情系半生.flac Jun 23 21:00:27 volumio volumio[990]: info: Using cached record of: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 03.情系半生.flac Jun 23 21:00:27 volumio volumio[990]: info: Adding Item to queue: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 02.期待.flac Jun 23 21:00:27 volumio volumio[990]: info: Using cached record of: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 02.期待.flac Jun 23 21:00:27 volumio volumio[990]: info: Adding Item to queue: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 01.春风秋雨.flac Jun 23 21:00:27 volumio volumio[990]: info: Using cached record of: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 01.春风秋雨.flac Jun 23 21:00:27 volumio volumio[990]: info: Adding Item to queue: mnt/INTERNAL/musics/2008再低一次/赵鹏&柏菲音乐-Wulala.flac Jun 23 21:00:27 volumio volumio[990]: info: Using cached record of: mnt/INTERNAL/musics/2008再低一次/赵鹏&柏菲音乐-Wulala.flac Jun 23 21:00:27 volumio volumio[990]: info: Adding Item to queue: mnt/INTERNAL/musics/风筝（先听版）/孙燕姿-风筝(先听版).flac Jun 23 21:00:27 volumio volumio[990]: info: Using cached record of: mnt/INTERNAL/musics/风筝（先听版）/孙燕姿-风筝(先听版).flac Jun 23 21:00:27 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-把悲伤留给自己.flac Jun 23 21:00:27 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-把悲伤留给自己.flac Jun 23 21:00:27 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 21:00:27 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 21:00:27 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 21:00:27 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 21:00:27 volumio volumio[990]: info: CoreCommandRouter::volumioPlay Jun 23 21:00:27 volumio volumio[990]: info: CoreStateMachine::play index 5 Jun 23 21:00:27 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 21:00:27 volumio volumio[990]: info: CoreStateMachine::addQueueItems Jun 23 21:00:27 volumio volumio[990]: info: CorePlayQueue::addQueueItems Jun 23 21:00:27 volumio volumio[990]: info: Preload queue cleared Jun 23 21:00:27 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-我的家乡.flac Jun 23 21:00:27 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-我的家乡.flac Jun 23 21:00:27 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-山茶花.flac Jun 23 21:00:27 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-山茶花.flac Jun 23 21:00:27 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-风和花的爱情.flac Jun 23 21:00:27 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-风和花的爱情.flac Jun 23 21:00:27 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-知道不知道.flac Jun 23 21:00:27 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-知道不知道.flac Jun 23 21:00:27 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-殇.flac Jun 23 21:00:27 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-殇.flac Jun 23 21:00:27 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-春花秋月.flac Jun 23 21:00:27 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-春花秋月.flac Jun 23 21:00:27 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-明月千里寄相思.flac Jun 23 21:00:27 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-明月千里寄相思.flac Jun 23 21:00:27 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-情人的眼泪.flac Jun 23 21:00:27 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-情人的眼泪.flac Jun 23 21:00:27 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-Wulala.flac Jun 23 21:00:27 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-Wulala.flac Jun 23 21:00:27 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 21:00:27 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 21:00:27 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 21:00:27 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 21:00:27 volumio volumio[990]: info: CoreStateMachine::play index undefined Jun 23 21:00:27 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 21:00:27 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 21:00:27 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 21:00:27 volumio volumio[990]: info: CorePlayQueue::getTrack 5 Jun 23 21:00:29 volumio volumio[990]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 23 21:00:29 volumio volumio[990]: info: Retrying Login Due to Network Error Jun 23 21:00:34 volumio volumio[990]: info: CoreCommandRouter::volumioPlay Jun 23 21:00:34 volumio volumio[990]: info: CoreStateMachine::play index undefined Jun 23 21:00:34 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 21:00:34 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:34 volumio volumio[990]: info: CoreStateMachine::startPlaybackTimer Jun 23 21:00:34 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:34 volumio volumio[990]: verbose: ControllerMpd::clearAddPlayTracks USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 03.情系半生.flac Jun 23 21:00:34 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Jun 23 21:00:34 volumio volumio[990]: info: sendMpdCommand stop took 2 milliseconds Jun 23 21:00:34 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand clear Jun 23 21:00:34 volumio volumio[990]: info: Jun 23 21:00:34 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 21:00:34 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 21:00:34 volumio volumio[990]: info: sendMpdCommand clear took 2 milliseconds Jun 23 21:00:34 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand add "USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 03.情系半生.flac" Jun 23 21:00:34 volumio volumio[990]: error: updateQueue error: null Jun 23 21:00:34 volumio volumio[990]: info: Jun 23 21:00:34 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 21:00:34 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 21:00:34 volumio volumio[990]: info: ------------------------------ 4ms Jun 23 21:00:34 volumio volumio[990]: info: sendMpdCommand add "USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 03.情系半生.flac" took 4 milliseconds Jun 23 21:00:34 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand play Jun 23 21:00:34 volumio volumio[990]: info: Jun 23 21:00:34 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 21:00:34 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 21:00:34 volumio volumio[990]: info: ------------------------------ 9ms Jun 23 21:00:34 volumio volumio[990]: info: Jun 23 21:00:34 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 21:00:34 volumio volumio[990]: info: sendMpdCommand play took 12 milliseconds Jun 23 21:00:34 volumio volumio[990]: info: ControllerMpd::getState Jun 23 21:00:34 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 21:00:34 volumio volumio[990]: info: ------------------------------ 10ms Jun 23 21:00:34 volumio volumio[990]: info: Jun 23 21:00:34 volumio volumio[990]: ---------------------------- MPD announces system playlist update Jun 23 21:00:34 volumio volumio[990]: info: Ignoring MPD Status Update Jun 23 21:00:34 volumio volumio[990]: info: Jun 23 21:00:34 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 21:00:34 volumio volumio[990]: info: ControllerMpd::getState Jun 23 21:00:34 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 21:00:34 volumio volumio[990]: info: Jun 23 21:00:34 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 21:00:34 volumio volumio[990]: info: sendMpdCommand status took 243 milliseconds Jun 23 21:00:34 volumio volumio[990]: info: ControllerMpd::getState Jun 23 21:00:34 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 21:00:34 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 21:00:34 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 21:00:34 volumio volumio[990]: info: ------------------------------ 243ms Jun 23 21:00:34 volumio volumio[990]: info: sendMpdCommand status took 243 milliseconds Jun 23 21:00:34 volumio volumio[990]: info: sendMpdCommand status took 2 milliseconds Jun 23 21:00:34 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 21:00:34 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 21:00:34 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 21:00:34 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 21:00:34 volumio volumio[990]: info: Jun 23 21:00:34 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 21:00:34 volumio volumio[990]: info: ControllerMpd::getState Jun 23 21:00:34 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 21:00:34 volumio volumio[990]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 23 21:00:34 volumio volumio[990]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 23 21:00:34 volumio volumio[990]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 23 21:00:34 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 21:00:34 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 21:00:34 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 21:00:34 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 21:00:34 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 21:00:34 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:34 volumio volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"情系半生","artist":"张学友","album":"绝对珍藏DISC1","uri":"USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 03.情系半生.flac","trackType":"flac"} Jun 23 21:00:34 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 21:00:34 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 21:00:34 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus stop Jun 23 21:00:34 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 21:00:34 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 21:00:34 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:34 volumio volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":204,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"399 Kbps","isStreaming":false,"title":"情系半生","artist":"张学友","album":"绝对珍藏DISC1","uri":"USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 03.情系半生.flac","trackType":"flac"} Jun 23 21:00:34 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 21:00:34 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 21:00:34 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 21:00:34 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 21:00:34 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:34 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:34 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 21:00:34 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:34 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:34 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:34 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:34 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 21:00:34 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 21:00:34 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:34 volumio volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":523,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1049 Kbps","isStreaming":false,"title":"情系半生","artist":"张学友","album":"绝对珍藏DISC1","uri":"USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 03.情系半生.flac","trackType":"flac"} Jun 23 21:00:34 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 21:00:34 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 21:00:34 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 21:00:34 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 21:00:34 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:34 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:34 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 21:00:34 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:34 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:34 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:34 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:34 volumio volumio[990]: info: ------------------------------ 267ms Jun 23 21:00:34 volumio volumio[990]: info: ------------------------------ 271ms Jun 23 21:00:34 volumio volumio[990]: info: ------------------------------ 30ms Jun 23 21:00:34 volumio volumio[990]: info: sendMpdCommand status took 28 milliseconds Jun 23 21:00:34 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 21:00:34 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 21:00:34 volumio volumio[990]: info: sendMpdCommand playlistinfo took 43 milliseconds Jun 23 21:00:34 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 21:00:34 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 21:00:34 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 21:00:34 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:34 volumio volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":523,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1049 Kbps","isStreaming":false,"title":"情系半生","artist":"张学友","album":"绝对珍藏DISC1","uri":"USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 03.情系半生.flac","trackType":"flac"} Jun 23 21:00:34 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 21:00:34 volumio volumio[990]: info: CoreStateMachine::syncState stateService play Jun 23 21:00:34 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus play Jun 23 21:00:34 volumio volumio[990]: info: Received an update from plugin. extracting info from payload Jun 23 21:00:34 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:34 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:34 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:34 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:34 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:34 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:34 volumio volumio[990]: info: ------------------------------ 89ms Jun 23 21:00:35 volumio volumio[990]: info: CoreCommandRouter::volumioGetState Jun 23 21:00:35 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:35 volumio sudo[5903]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jun 23 21:00:35 volumio sudo[5903]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 21:00:37 volumio volumio[990]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 10 Jun 23 21:00:37 volumio volumio[990]: info: CoreCommandRouter::volumioGetState Jun 23 21:00:37 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:38 volumio volumio[990]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 11 Jun 23 21:00:38 volumio volumio[990]: info: CoreCommandRouter::volumioGetState Jun 23 21:00:38 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:39 volumio volumio[990]: info: MyVolumio login type: Token Jun 23 21:00:41 volumio volumio[990]: info: Preload queue cleared Jun 23 21:00:41 volumio volumio[990]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::ClearQueue Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::stPlaybackTimer Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 21:00:41 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:41 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:41 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 21:00:41 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::serviceStop Jun 23 21:00:41 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:41 volumio volumio[990]: info: CoreCommandRouter::serviceStop Jun 23 21:00:41 volumio volumio[990]: info: ControllerMpd::stop Jun 23 21:00:41 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Jun 23 21:00:41 volumio volumio[990]: info: CorePlayQueue::clearPlayQueue Jun 23 21:00:41 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 21:00:41 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::addQueueItems Jun 23 21:00:41 volumio volumio[990]: info: CorePlayQueue::addQueueItems Jun 23 21:00:41 volumio volumio[990]: info: Preload queue cleared Jun 23 21:00:41 volumio volumio[990]: info: Adding Item to queue: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 03.情系半生.flac Jun 23 21:00:41 volumio volumio[990]: info: Using cached record of: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 03.情系半生.flac Jun 23 21:00:41 volumio volumio[990]: info: Adding Item to queue: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 02.期待.flac Jun 23 21:00:41 volumio volumio[990]: info: Using cached record of: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 02.期待.flac Jun 23 21:00:41 volumio volumio[990]: info: Adding Item to queue: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 01.春风秋雨.flac Jun 23 21:00:41 volumio volumio[990]: info: Using cached record of: mnt/USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 01.春风秋雨.flac Jun 23 21:00:41 volumio volumio[990]: info: Adding Item to queue: mnt/INTERNAL/musics/2008再低一次/赵鹏&柏菲音乐-Wulala.flac Jun 23 21:00:41 volumio volumio[990]: info: Using cached record of: mnt/INTERNAL/musics/2008再低一次/赵鹏&柏菲音乐-Wulala.flac Jun 23 21:00:41 volumio volumio[990]: info: Adding Item to queue: mnt/INTERNAL/musics/风筝（先听版）/孙燕姿-风筝(先听版).flac Jun 23 21:00:41 volumio volumio[990]: info: Using cached record of: mnt/INTERNAL/musics/风筝（先听版）/孙燕姿-风筝(先听版).flac Jun 23 21:00:41 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-把悲伤留给自己.flac Jun 23 21:00:41 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-把悲伤留给自己.flac Jun 23 21:00:41 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 21:00:41 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 21:00:41 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 21:00:41 volumio volumio[990]: info: CoreCommandRouter::volumioPlay Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::play index 5 Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::addQueueItems Jun 23 21:00:41 volumio volumio[990]: info: CorePlayQueue::addQueueItems Jun 23 21:00:41 volumio volumio[990]: info: Preload queue cleared Jun 23 21:00:41 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-我的家乡.flac Jun 23 21:00:41 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-我的家乡.flac Jun 23 21:00:41 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-山茶花.flac Jun 23 21:00:41 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-山茶花.flac Jun 23 21:00:41 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-风和花的爱情.flac Jun 23 21:00:41 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-风和花的爱情.flac Jun 23 21:00:41 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-知道不知道.flac Jun 23 21:00:41 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-知道不知道.flac Jun 23 21:00:41 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-殇.flac Jun 23 21:00:41 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-殇.flac Jun 23 21:00:41 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-春花秋月.flac Jun 23 21:00:41 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-春花秋月.flac Jun 23 21:00:41 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-明月千里寄相思.flac Jun 23 21:00:41 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-明月千里寄相思.flac Jun 23 21:00:41 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-情人的眼泪.flac Jun 23 21:00:41 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-情人的眼泪.flac Jun 23 21:00:41 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-Wulala.flac Jun 23 21:00:41 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-Wulala.flac Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 21:00:41 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 21:00:41 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::play index undefined Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 21:00:41 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 21:00:41 volumio volumio[990]: info: CorePlayQueue::getTrack 5 Jun 23 21:00:41 volumio volumio[990]: info: Jun 23 21:00:41 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 21:00:41 volumio volumio[990]: info: sendMpdCommand stop took 165 milliseconds Jun 23 21:00:41 volumio volumio[990]: info: ControllerMpd::getState Jun 23 21:00:41 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 21:00:41 volumio volumio[990]: info: Jun 23 21:00:41 volumio volumio[990]: ---------------------------- MPD announces state update: player Jun 23 21:00:41 volumio volumio[990]: info: ControllerMpd::getState Jun 23 21:00:41 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand status Jun 23 21:00:41 volumio volumio[990]: info: sendMpdCommand status took 2 milliseconds Jun 23 21:00:41 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 21:00:41 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 21:00:41 volumio volumio[990]: info: sendMpdCommand status took 2 milliseconds Jun 23 21:00:41 volumio volumio[990]: verbose: ControllerMpd::parseState Jun 23 21:00:41 volumio volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 21:00:41 volumio volumio[990]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 23 21:00:41 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 21:00:41 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 21:00:41 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:41 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:41 volumio volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 21:00:41 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:41 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:41 volumio volumio[990]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"情系半生","artist":"张学友","album":"绝对珍藏DISC1","uri":"USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 03.情系半生.flac","trackType":"flac"} Jun 23 21:00:41 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::syncState stateService stop Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus stop Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:41 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:41 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:41 volumio volumio[990]: info: No code Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:41 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:41 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:41 volumio volumio[990]: info: ------------------------------ 23ms Jun 23 21:00:41 volumio volumio[990]: info: sendMpdCommand playlistinfo took 20 milliseconds Jun 23 21:00:41 volumio volumio[990]: verbose: ControllerMpd::parseTrackInfo Jun 23 21:00:41 volumio volumio[990]: info: ControllerMpd::pushState Jun 23 21:00:41 volumio volumio[990]: info: CoreCommandRouter::servicePushState Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:41 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:41 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:41 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:41 volumio volumio[990]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"情系半生","artist":"张学友","album":"绝对珍藏DISC1","uri":"USB/6F6B1E859BE1152C/张学友 绝对珍藏CD1 [FLAC分轨]/张学友 - 03.情系半生.flac","trackType":"flac"} Jun 23 21:00:41 volumio volumio[990]: verbose: CURRENT POSITION 0 Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::syncState stateService stop Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::syncState currentStatus stop Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:41 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:41 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:41 volumio volumio[990]: info: No code Jun 23 21:00:41 volumio volumio[990]: info: CoreStateMachine::pushState Jun 23 21:00:41 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:41 volumio volumio[990]: info: CoreCommandRouter::volumioPushState Jun 23 21:00:41 volumio volumio[990]: info: ------------------------------ 39ms Jun 23 21:00:42 volumio sudo[5903]: pam_unix(sudo:session): session closed for user root Jun 23 21:00:46 volumio volumio[990]: info: Preload queue cleared Jun 23 21:00:46 volumio volumio[990]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 23 21:00:46 volumio volumio[990]: info: CoreStateMachine::ClearQueue Jun 23 21:00:46 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 21:00:46 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 21:00:46 volumio volumio[990]: info: CorePlayQueue::clearPlayQueue Jun 23 21:00:46 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 21:00:46 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 21:00:46 volumio volumio[990]: info: CoreStateMachine::addQueueItems Jun 23 21:00:46 volumio volumio[990]: info: CorePlayQueue::addQueueItems Jun 23 21:00:46 volumio volumio[990]: info: Preload queue cleared Jun 23 21:00:46 volumio volumio[990]: info: Adding Item to queue: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-把悲伤留给自己.flac Jun 23 21:00:46 volumio volumio[990]: info: Using cached record of: mnt/USB/Music/2008再低一次/赵鹏&柏菲音乐-把悲伤留给自己.flac Jun 23 21:00:46 volumio volumio[990]: info: CoreCommandRouter::volumioPushQueue Jun 23 21:00:46 volumio volumio[990]: info: CorePlayQueue::saveQueue Jun 23 21:00:46 volumio volumio[990]: info: CoreStateMachine::updateTrackBlock Jun 23 21:00:46 volumio volumio[990]: info: CorePlayQueue::getTrackBlock Jun 23 21:00:46 volumio volumio[990]: info: CoreCommandRouter::volumioPlay Jun 23 21:00:46 volumio volumio[990]: info: CoreStateMachine::play index 0 Jun 23 21:00:46 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 21:00:46 volumio volumio[990]: info: CoreStateMachine::stop Jun 23 21:00:46 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 21:00:46 volumio volumio[990]: info: CoreStateMachine::play index undefined Jun 23 21:00:46 volumio volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 21:00:46 volumio volumio[990]: info: CorePlayQueue::getTrack 0 Jun 23 21:00:51 volumio volumio[990]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 23 21:00:51 volumio volumio[990]: Error: connect ETIMEDOUT 67.228.235.91:80 Jun 23 21:00:51 volumio volumio[990]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 23 21:00:51 volumio volumio[990]: errno: -110, Jun 23 21:00:51 volumio volumio[990]: code: 'ETIMEDOUT', Jun 23 21:00:51 volumio volumio[990]: syscall: 'connect', Jun 23 21:00:51 volumio volumio[990]: address: '67.228.235.91', Jun 23 21:00:51 volumio volumio[990]: port: 80 Jun 23 21:00:51 volumio volumio[990]: } Jun 23 21:00:51 volumio volumio[990]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 23 21:00:51 volumio sudo[6060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-23 20:59 Jun 23 21:00:51 volumio sudo[6060]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"