-- Logs begin at Thu 2019-02-14 13:11:58 MSK, end at Mon 2026-02-09 20:48:48 MSK. -- Feb 09 20:47:00 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Feb 09 20:47:00 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioStop Feb 09 20:47:00 volumio-agm volumio[1025]: info: CoreStateMachine::stop Feb 09 20:47:00 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:00 volumio-agm volumio[1025]: info: CoreStateMachine::stPlaybackTimer Feb 09 20:47:00 volumio-agm volumio[1025]: info: CoreStateMachine::updateTrackBlock Feb 09 20:47:00 volumio-agm volumio[1025]: info: CorePlayQueue::getTrackBlock Feb 09 20:47:00 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:00 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:00 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:47:00 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:00 volumio-agm volumio[1025]: info: CoreStateMachine::serviceStop Feb 09 20:47:00 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:00 volumio-agm volumio[1025]: info: CoreCommandRouter::serviceStop Feb 09 20:47:00 volumio-agm volumio[1025]: info: [1770659220925] ControllerWebradio::stop Feb 09 20:47:00 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 20:47:00 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Feb 09 20:47:00 volumio-agm volumio[1025]: info: Feb 09 20:47:00 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:47:00 volumio-agm volumio[1025]: info: sendMpdCommand stop took 41 milliseconds Feb 09 20:47:00 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:47:00 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:47:00 volumio-agm volumio[1025]: info: sendMpdCommand status took 10 milliseconds Feb 09 20:47:00 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:47:00 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:47:00 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 09 20:47:00 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:47:00 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:47:00 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:47:00 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:00 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:00 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:01 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:01 volumio-agm volumio[1025]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 09 20:47:01 volumio-agm volumio[1025]: info: ------------------------------ 66ms Feb 09 20:47:01 volumio-agm volumio[1025]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 09 20:47:01 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Feb 09 20:47:01 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Feb 09 20:47:02 volumio-agm wpa_supplicant[855]: wlan0: Failed to initiate sched scan Feb 09 20:47:02 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Feb 09 20:47:02 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Feb 09 20:47:02 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Feb 09 20:47:02 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Feb 09 20:47:02 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Feb 09 20:47:02 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Feb 09 20:47:02 volumio-agm volumio[1025]: info: Preload queue cleared Feb 09 20:47:02 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 09 20:47:02 volumio-agm volumio[1025]: info: CoreStateMachine::ClearQueue Feb 09 20:47:02 volumio-agm volumio[1025]: info: CoreStateMachine::stop Feb 09 20:47:02 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:02 volumio-agm volumio[1025]: info: CorePlayQueue::clearPlayQueue Feb 09 20:47:02 volumio-agm volumio[1025]: info: CorePlayQueue::saveQueue Feb 09 20:47:02 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushQueue Feb 09 20:47:02 volumio-agm volumio[1025]: info: CoreStateMachine::addQueueItems Feb 09 20:47:02 volumio-agm volumio[1025]: info: CorePlayQueue::addQueueItems Feb 09 20:47:02 volumio-agm volumio[1025]: info: Preload queue cleared Feb 09 20:47:02 volumio-agm volumio[1025]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99611385 Feb 09 20:47:02 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 09 20:47:02 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushQueue Feb 09 20:47:02 volumio-agm volumio[1025]: info: CorePlayQueue::saveQueue Feb 09 20:47:02 volumio-agm volumio[1025]: info: CoreStateMachine::updateTrackBlock Feb 09 20:47:02 volumio-agm volumio[1025]: info: CorePlayQueue::getTrackBlock Feb 09 20:47:02 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPlay Feb 09 20:47:02 volumio-agm volumio[1025]: info: CoreStateMachine::play index 0 Feb 09 20:47:02 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:02 volumio-agm volumio[1025]: info: CoreStateMachine::stop Feb 09 20:47:02 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:02 volumio-agm volumio[1025]: info: CoreStateMachine::play index undefined Feb 09 20:47:02 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:02 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:02 volumio-agm volumio[1025]: info: CoreStateMachine::startPlaybackTimer Feb 09 20:47:02 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:02 volumio-agm volumio[1025]: info: [1770659222748] ControllerWebradio::clearAddPlayTrack Feb 09 20:47:02 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 20:47:02 volumio-agm volumio[1025]: info: sendMpdCommand stop took 6 milliseconds Feb 09 20:47:02 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand clear Feb 09 20:47:02 volumio-agm volumio[1025]: info: Feb 09 20:47:02 volumio-agm volumio[1025]: ---------------------------- MPD announces system playlist update Feb 09 20:47:02 volumio-agm volumio[1025]: info: Ignoring MPD Status Update Feb 09 20:47:02 volumio-agm volumio[1025]: info: sendMpdCommand clear took 1 milliseconds Feb 09 20:47:02 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99611385" Feb 09 20:47:02 volumio-agm volumio[1025]: error: updateQueue error: null Feb 09 20:47:02 volumio-agm volumio[1025]: info: ------------------------------ 2ms Feb 09 20:47:02 volumio-agm volumio[1025]: info: Feb 09 20:47:02 volumio-agm volumio[1025]: ---------------------------- MPD announces system playlist update Feb 09 20:47:02 volumio-agm volumio[1025]: info: Ignoring MPD Status Update Feb 09 20:47:02 volumio-agm volumio[1025]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99611385" took 122 milliseconds Feb 09 20:47:02 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 09 20:47:02 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand play Feb 09 20:47:02 volumio-agm volumio[1025]: info: ------------------------------ 2ms Feb 09 20:47:02 volumio-agm volumio[1025]: info: sendMpdCommand play took 2 milliseconds Feb 09 20:47:04 volumio-agm volumio[1025]: info: Feb 09 20:47:04 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:47:04 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:47:04 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:47:04 volumio-agm volumio[1025]: info: Feb 09 20:47:04 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:47:04 volumio-agm volumio[1025]: info: sendMpdCommand status took 24 milliseconds Feb 09 20:47:04 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:47:04 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:47:04 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:47:04 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:47:04 volumio-agm volumio[1025]: info: sendMpdCommand status took 1 milliseconds Feb 09 20:47:04 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 09 20:47:04 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:47:04 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:47:04 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:47:04 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:47:04 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:47:04 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:04 volumio-agm volumio[1025]: 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":"","artist":"(#1 - 67/400) SMOOTH JAZZ DELUXE","album":null,"uri":"https://cast1.torontocast.com:4490/","trackType":"com:4490/"} Feb 09 20:47:04 volumio-agm volumio[1025]: verbose: CURRENT POSITION 0 Feb 09 20:47:04 volumio-agm volumio[1025]: info: CoreStateMachine::syncState stateService play Feb 09 20:47:04 volumio-agm volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Feb 09 20:47:04 volumio-agm volumio[1025]: info: ------------------------------ 30ms Feb 09 20:47:04 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 09 20:47:04 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:47:04 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:47:04 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:47:04 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:04 volumio-agm volumio[1025]: 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":"","artist":"(#1 - 67/400) SMOOTH JAZZ DELUXE","album":null,"uri":"https://cast1.torontocast.com:4490/","trackType":"com:4490/"} Feb 09 20:47:04 volumio-agm volumio[1025]: verbose: CURRENT POSITION 0 Feb 09 20:47:04 volumio-agm volumio[1025]: info: CoreStateMachine::syncState stateService play Feb 09 20:47:04 volumio-agm volumio[1025]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:47:04 volumio-agm volumio[1025]: info: Received an update from plugin. extracting info from payload Feb 09 20:47:04 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 09 20:47:04 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:04 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:47:04 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:04 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:04 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:04 volumio-agm volumio[1025]: info: ------------------------------ 65ms Feb 09 20:47:05 volumio-agm volumio[1025]: 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: 9 Feb 09 20:47:05 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioGetState Feb 09 20:47:05 volumio-agm volumio[1025]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%2067%2F400)%20SMOOTH%20JAZZ%20DELUXE/4862ee38-4c15-4e18-b709-24935c3cfbae.jpg' Feb 09 20:47:08 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioStop Feb 09 20:47:08 volumio-agm volumio[1025]: info: CoreStateMachine::stop Feb 09 20:47:08 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:08 volumio-agm volumio[1025]: info: CoreStateMachine::stPlaybackTimer Feb 09 20:47:08 volumio-agm volumio[1025]: info: CoreStateMachine::updateTrackBlock Feb 09 20:47:08 volumio-agm volumio[1025]: info: CorePlayQueue::getTrackBlock Feb 09 20:47:08 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:08 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:08 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:47:08 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:08 volumio-agm volumio[1025]: info: CoreStateMachine::serviceStop Feb 09 20:47:08 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:08 volumio-agm volumio[1025]: info: CoreCommandRouter::serviceStop Feb 09 20:47:08 volumio-agm volumio[1025]: info: [1770659228522] ControllerWebradio::stop Feb 09 20:47:08 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 20:47:08 volumio-agm volumio[1025]: info: Feb 09 20:47:08 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:47:08 volumio-agm volumio[1025]: info: sendMpdCommand stop took 46 milliseconds Feb 09 20:47:08 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:47:08 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:47:08 volumio-agm volumio[1025]: info: sendMpdCommand status took 2 milliseconds Feb 09 20:47:08 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:47:08 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:47:08 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 09 20:47:08 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:47:08 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:47:08 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:47:08 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:08 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:08 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:47:08 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:08 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:08 volumio-agm volumio[1025]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 09 20:47:08 volumio-agm volumio[1025]: info: ------------------------------ 42ms Feb 09 20:47:08 volumio-agm volumio[1025]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 09 20:47:09 volumio-agm volumio[1025]: info: peppy_screensaver: Start PeppyMeter Feb 09 20:47:11 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Feb 09 20:47:11 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Feb 09 20:47:11 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Feb 09 20:47:11 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Feb 09 20:47:12 volumio-agm wpa_supplicant[855]: wlan0: Failed to initiate sched scan Feb 09 20:47:12 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Feb 09 20:47:12 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Feb 09 20:47:12 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Feb 09 20:47:12 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Feb 09 20:47:12 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Feb 09 20:47:12 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Feb 09 20:47:15 volumio-agm volumio[1025]: info: Preload queue cleared Feb 09 20:47:15 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 09 20:47:15 volumio-agm volumio[1025]: info: CoreStateMachine::ClearQueue Feb 09 20:47:15 volumio-agm volumio[1025]: info: CoreStateMachine::stop Feb 09 20:47:15 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:15 volumio-agm volumio[1025]: info: CorePlayQueue::clearPlayQueue Feb 09 20:47:15 volumio-agm volumio[1025]: info: CorePlayQueue::saveQueue Feb 09 20:47:15 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushQueue Feb 09 20:47:15 volumio-agm volumio[1025]: info: CoreStateMachine::addQueueItems Feb 09 20:47:15 volumio-agm volumio[1025]: info: CorePlayQueue::addQueueItems Feb 09 20:47:15 volumio-agm volumio[1025]: info: Preload queue cleared Feb 09 20:47:15 volumio-agm volumio[1025]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1760270 Feb 09 20:47:15 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 09 20:47:15 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushQueue Feb 09 20:47:15 volumio-agm volumio[1025]: info: CorePlayQueue::saveQueue Feb 09 20:47:15 volumio-agm volumio[1025]: info: CoreStateMachine::updateTrackBlock Feb 09 20:47:15 volumio-agm volumio[1025]: info: CorePlayQueue::getTrackBlock Feb 09 20:47:15 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPlay Feb 09 20:47:15 volumio-agm volumio[1025]: info: CoreStateMachine::play index 0 Feb 09 20:47:15 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:15 volumio-agm volumio[1025]: info: CoreStateMachine::stop Feb 09 20:47:15 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:15 volumio-agm volumio[1025]: info: CoreStateMachine::play index undefined Feb 09 20:47:15 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:15 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:15 volumio-agm volumio[1025]: info: CoreStateMachine::startPlaybackTimer Feb 09 20:47:15 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:15 volumio-agm volumio[1025]: info: [1770659235444] ControllerWebradio::clearAddPlayTrack Feb 09 20:47:15 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 20:47:15 volumio-agm volumio[1025]: info: sendMpdCommand stop took 2 milliseconds Feb 09 20:47:15 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand clear Feb 09 20:47:15 volumio-agm volumio[1025]: info: Feb 09 20:47:15 volumio-agm volumio[1025]: ---------------------------- MPD announces system playlist update Feb 09 20:47:15 volumio-agm volumio[1025]: info: Ignoring MPD Status Update Feb 09 20:47:15 volumio-agm volumio[1025]: info: sendMpdCommand clear took 0 milliseconds Feb 09 20:47:15 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1760270" Feb 09 20:47:15 volumio-agm volumio[1025]: error: updateQueue error: null Feb 09 20:47:15 volumio-agm volumio[1025]: info: ------------------------------ 2ms Feb 09 20:47:15 volumio-agm volumio[1025]: info: Feb 09 20:47:15 volumio-agm volumio[1025]: ---------------------------- MPD announces system playlist update Feb 09 20:47:15 volumio-agm volumio[1025]: info: Ignoring MPD Status Update Feb 09 20:47:15 volumio-agm volumio[1025]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1760270" took 123 milliseconds Feb 09 20:47:15 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 09 20:47:15 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand play Feb 09 20:47:15 volumio-agm volumio[1025]: info: ------------------------------ 1ms Feb 09 20:47:15 volumio-agm volumio[1025]: info: sendMpdCommand play took 2 milliseconds Feb 09 20:47:16 volumio-agm volumio[1025]: info: Feb 09 20:47:16 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:47:16 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:47:16 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:47:16 volumio-agm volumio[1025]: info: Feb 09 20:47:16 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:47:16 volumio-agm volumio[1025]: info: sendMpdCommand status took 19 milliseconds Feb 09 20:47:16 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:47:16 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:47:16 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:47:16 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:47:16 volumio-agm volumio[1025]: info: sendMpdCommand status took 1 milliseconds Feb 09 20:47:16 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 09 20:47:16 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:47:16 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:47:16 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:47:16 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:47:16 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:47:16 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:16 volumio-agm volumio[1025]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 28/1000) Smooth Jazz Florida Plus HD","album":null,"uri":"http://server.webnetradio.net:5350/stream","trackType":""} Feb 09 20:47:16 volumio-agm volumio[1025]: verbose: CURRENT POSITION 0 Feb 09 20:47:16 volumio-agm volumio[1025]: info: CoreStateMachine::syncState stateService play Feb 09 20:47:16 volumio-agm volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Feb 09 20:47:16 volumio-agm volumio[1025]: info: ------------------------------ 25ms Feb 09 20:47:16 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 09 20:47:16 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:47:16 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:47:16 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:47:16 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:16 volumio-agm volumio[1025]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 28/1000) Smooth Jazz Florida Plus HD","album":null,"uri":"http://server.webnetradio.net:5350/stream","trackType":""} Feb 09 20:47:16 volumio-agm volumio[1025]: verbose: CURRENT POSITION 0 Feb 09 20:47:16 volumio-agm volumio[1025]: info: CoreStateMachine::syncState stateService play Feb 09 20:47:16 volumio-agm volumio[1025]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:47:16 volumio-agm volumio[1025]: info: Received an update from plugin. extracting info from payload Feb 09 20:47:16 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 09 20:47:16 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:16 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:16 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:47:16 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:16 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:16 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:16 volumio-agm volumio[1025]: info: ------------------------------ 33ms Feb 09 20:47:17 volumio-agm volumio[1025]: info: Feb 09 20:47:17 volumio-agm volumio[1025]: ---------------------------- MPD announces system playlist update Feb 09 20:47:17 volumio-agm volumio[1025]: info: Ignoring MPD Status Update Feb 09 20:47:17 volumio-agm volumio[1025]: info: Feb 09 20:47:17 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:47:17 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:47:17 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:47:17 volumio-agm volumio[1025]: info: Feb 09 20:47:17 volumio-agm volumio[1025]: ---------------------------- MPD announces system playlist update Feb 09 20:47:17 volumio-agm volumio[1025]: info: Ignoring MPD Status Update Feb 09 20:47:17 volumio-agm volumio[1025]: info: Feb 09 20:47:17 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:47:17 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:47:17 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:47:17 volumio-agm volumio[1025]: info: ------------------------------ 5ms Feb 09 20:47:17 volumio-agm volumio[1025]: info: sendMpdCommand status took 3 milliseconds Feb 09 20:47:17 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:47:17 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:47:17 volumio-agm volumio[1025]: info: ------------------------------ 3ms Feb 09 20:47:17 volumio-agm volumio[1025]: info: sendMpdCommand status took 1 milliseconds Feb 09 20:47:17 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 09 20:47:17 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:47:17 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:47:17 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:47:17 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:47:17 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:47:17 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:17 volumio-agm volumio[1025]: verbose: STATE SERVICE {"status":"play","position":0,"seek":633,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Bach A Killian - Night And Day","artist":"Smooth Jazz Florida Plus HD","album":null,"uri":"http://server.webnetradio.net:5350/stream","trackType":""} Feb 09 20:47:17 volumio-agm volumio[1025]: verbose: CURRENT POSITION 0 Feb 09 20:47:17 volumio-agm volumio[1025]: info: CoreStateMachine::syncState stateService play Feb 09 20:47:17 volumio-agm volumio[1025]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:47:17 volumio-agm volumio[1025]: info: Received an update from plugin. extracting info from payload Feb 09 20:47:17 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 09 20:47:17 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:17 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:47:17 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:17 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:17 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:17 volumio-agm volumio[1025]: info: ------------------------------ 35ms Feb 09 20:47:17 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 30 milliseconds Feb 09 20:47:17 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:47:17 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:47:17 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:47:17 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:17 volumio-agm volumio[1025]: verbose: STATE SERVICE {"status":"play","position":0,"seek":724,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Bach A Killian - Night And Day","artist":"Smooth Jazz Florida Plus HD","album":null,"uri":"http://server.webnetradio.net:5350/stream","trackType":""} Feb 09 20:47:17 volumio-agm volumio[1025]: verbose: CURRENT POSITION 0 Feb 09 20:47:17 volumio-agm volumio[1025]: info: CoreStateMachine::syncState stateService play Feb 09 20:47:17 volumio-agm volumio[1025]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:47:17 volumio-agm volumio[1025]: info: Received an update from plugin. extracting info from payload Feb 09 20:47:17 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 09 20:47:17 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:17 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:17 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:17 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:17 volumio-agm volumio[1025]: info: ------------------------------ 75ms Feb 09 20:47:21 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Feb 09 20:47:21 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Feb 09 20:47:21 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Feb 09 20:47:21 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Feb 09 20:47:22 volumio-agm wpa_supplicant[855]: wlan0: Failed to initiate sched scan Feb 09 20:47:22 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Feb 09 20:47:22 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Feb 09 20:47:22 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Feb 09 20:47:22 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Feb 09 20:47:22 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Feb 09 20:47:22 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Feb 09 20:47:27 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioStop Feb 09 20:47:27 volumio-agm volumio[1025]: info: CoreStateMachine::stop Feb 09 20:47:27 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:27 volumio-agm volumio[1025]: info: CoreStateMachine::stPlaybackTimer Feb 09 20:47:27 volumio-agm volumio[1025]: info: CoreStateMachine::updateTrackBlock Feb 09 20:47:27 volumio-agm volumio[1025]: info: CorePlayQueue::getTrackBlock Feb 09 20:47:27 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:27 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:27 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:47:27 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:27 volumio-agm volumio[1025]: info: CoreStateMachine::serviceStop Feb 09 20:47:27 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:27 volumio-agm volumio[1025]: info: CoreCommandRouter::serviceStop Feb 09 20:47:27 volumio-agm volumio[1025]: info: [1770659247724] ControllerWebradio::stop Feb 09 20:47:27 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 20:47:27 volumio-agm volumio[1025]: info: Feb 09 20:47:27 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:47:27 volumio-agm volumio[1025]: info: sendMpdCommand stop took 45 milliseconds Feb 09 20:47:27 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:47:27 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:47:27 volumio-agm volumio[1025]: info: sendMpdCommand status took 2 milliseconds Feb 09 20:47:27 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:47:27 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:47:27 volumio-agm volumio[1025]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 09 20:47:27 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 09 20:47:27 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:47:27 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:47:27 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:47:27 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:27 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:27 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:47:27 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:27 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:27 volumio-agm volumio[1025]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 09 20:47:27 volumio-agm volumio[1025]: info: ------------------------------ 45ms Feb 09 20:47:30 volumio-agm volumio[1025]: info: Preload queue cleared Feb 09 20:47:30 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 09 20:47:30 volumio-agm volumio[1025]: info: CoreStateMachine::ClearQueue Feb 09 20:47:30 volumio-agm volumio[1025]: info: CoreStateMachine::stop Feb 09 20:47:30 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:30 volumio-agm volumio[1025]: info: CorePlayQueue::clearPlayQueue Feb 09 20:47:30 volumio-agm volumio[1025]: info: CorePlayQueue::saveQueue Feb 09 20:47:30 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushQueue Feb 09 20:47:30 volumio-agm volumio[1025]: info: CoreStateMachine::addQueueItems Feb 09 20:47:30 volumio-agm volumio[1025]: info: CorePlayQueue::addQueueItems Feb 09 20:47:30 volumio-agm volumio[1025]: info: Preload queue cleared Feb 09 20:47:30 volumio-agm volumio[1025]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1649462 Feb 09 20:47:30 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 09 20:47:30 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushQueue Feb 09 20:47:30 volumio-agm volumio[1025]: info: CorePlayQueue::saveQueue Feb 09 20:47:30 volumio-agm volumio[1025]: info: CoreStateMachine::updateTrackBlock Feb 09 20:47:30 volumio-agm volumio[1025]: info: CorePlayQueue::getTrackBlock Feb 09 20:47:30 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPlay Feb 09 20:47:30 volumio-agm volumio[1025]: info: CoreStateMachine::play index 0 Feb 09 20:47:30 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:30 volumio-agm volumio[1025]: info: CoreStateMachine::stop Feb 09 20:47:30 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:30 volumio-agm volumio[1025]: info: CoreStateMachine::play index undefined Feb 09 20:47:30 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:30 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:30 volumio-agm volumio[1025]: info: CoreStateMachine::startPlaybackTimer Feb 09 20:47:30 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:30 volumio-agm volumio[1025]: info: [1770659250587] ControllerWebradio::clearAddPlayTrack Feb 09 20:47:30 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 20:47:30 volumio-agm volumio[1025]: info: sendMpdCommand stop took 13 milliseconds Feb 09 20:47:30 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand clear Feb 09 20:47:30 volumio-agm volumio[1025]: info: Feb 09 20:47:30 volumio-agm volumio[1025]: ---------------------------- MPD announces system playlist update Feb 09 20:47:30 volumio-agm volumio[1025]: info: Ignoring MPD Status Update Feb 09 20:47:30 volumio-agm volumio[1025]: info: sendMpdCommand clear took 1 milliseconds Feb 09 20:47:30 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1649462" Feb 09 20:47:30 volumio-agm volumio[1025]: error: updateQueue error: null Feb 09 20:47:30 volumio-agm volumio[1025]: info: ------------------------------ 2ms Feb 09 20:47:30 volumio-agm volumio[1025]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1649462" took 124 milliseconds Feb 09 20:47:30 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 09 20:47:30 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand play Feb 09 20:47:30 volumio-agm volumio[1025]: info: Feb 09 20:47:30 volumio-agm volumio[1025]: ---------------------------- MPD announces system playlist update Feb 09 20:47:30 volumio-agm volumio[1025]: info: Ignoring MPD Status Update Feb 09 20:47:30 volumio-agm volumio[1025]: info: sendMpdCommand play took 5 milliseconds Feb 09 20:47:30 volumio-agm volumio[1025]: info: ------------------------------ 4ms Feb 09 20:47:31 volumio-agm volumio[1025]: info: Feb 09 20:47:31 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:47:31 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:47:31 volumio-agm volumio[1025]: info: Feb 09 20:47:31 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:47:31 volumio-agm volumio[1025]: info: sendMpdCommand status took 18 milliseconds Feb 09 20:47:31 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:47:31 volumio-agm volumio[1025]: info: sendMpdCommand status took 13 milliseconds Feb 09 20:47:31 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:47:31 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:47:31 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 62/1000) 96.9 The Oasis - The World's Smooth Jazz Place","album":null,"uri":"http://192.211.51.158:5014/stream","trackType":""} Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: CURRENT POSITION 0 Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreStateMachine::syncState stateService play Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Feb 09 20:47:31 volumio-agm volumio[1025]: info: ------------------------------ 34ms Feb 09 20:47:31 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:47:31 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:47:31 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 62/1000) 96.9 The Oasis - The World's Smooth Jazz Place","album":null,"uri":"http://192.211.51.158:5014/stream","trackType":""} Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: CURRENT POSITION 0 Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreStateMachine::syncState stateService play Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:47:31 volumio-agm volumio[1025]: info: Received an update from plugin. extracting info from payload Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:31 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:31 volumio-agm volumio[1025]: info: ------------------------------ 52ms Feb 09 20:47:31 volumio-agm volumio[1025]: info: Feb 09 20:47:31 volumio-agm volumio[1025]: ---------------------------- MPD announces system playlist update Feb 09 20:47:31 volumio-agm volumio[1025]: info: Ignoring MPD Status Update Feb 09 20:47:31 volumio-agm volumio[1025]: info: Feb 09 20:47:31 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:47:31 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:47:31 volumio-agm volumio[1025]: info: ------------------------------ 3ms Feb 09 20:47:31 volumio-agm volumio[1025]: info: sendMpdCommand status took 5 milliseconds Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:47:31 volumio-agm volumio[1025]: info: Feb 09 20:47:31 volumio-agm volumio[1025]: ---------------------------- MPD announces system playlist update Feb 09 20:47:31 volumio-agm volumio[1025]: info: Ignoring MPD Status Update Feb 09 20:47:31 volumio-agm volumio[1025]: info: Feb 09 20:47:31 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:47:31 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:47:31 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:47:31 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:47:31 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: STATE SERVICE {"status":"play","position":0,"seek":160,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Michael Silverman - In these Times (radio edit)","artist":"96.9 The Oasis - The World's Smooth Jazz Place","album":null,"uri":"http://192.211.51.158:5014/stream","trackType":""} Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: CURRENT POSITION 0 Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreStateMachine::syncState stateService play Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:47:31 volumio-agm volumio[1025]: info: Received an update from plugin. extracting info from payload Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:31 volumio-agm volumio[1025]: info: ------------------------------ 45ms Feb 09 20:47:31 volumio-agm volumio[1025]: info: ------------------------------ 40ms Feb 09 20:47:31 volumio-agm volumio[1025]: info: sendMpdCommand status took 40 milliseconds Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:47:31 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 17 milliseconds Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:47:31 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:47:31 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: STATE SERVICE {"status":"play","position":0,"seek":721,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Michael Silverman - In these Times (radio edit)","artist":"96.9 The Oasis - The World's Smooth Jazz Place","album":null,"uri":"http://192.211.51.158:5014/stream","trackType":""} Feb 09 20:47:31 volumio-agm volumio[1025]: verbose: CURRENT POSITION 0 Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreStateMachine::syncState stateService play Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:47:31 volumio-agm volumio[1025]: info: Received an update from plugin. extracting info from payload Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:31 volumio-agm volumio[1025]: info: ------------------------------ 127ms Feb 09 20:47:31 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Feb 09 20:47:31 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Feb 09 20:47:31 volumio-agm volumio[1025]: 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: 9 Feb 09 20:47:31 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioGetState Feb 09 20:47:31 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Feb 09 20:47:32 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Feb 09 20:47:32 volumio-agm wpa_supplicant[855]: wlan0: Failed to initiate sched scan Feb 09 20:47:32 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Feb 09 20:47:32 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Feb 09 20:47:32 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Feb 09 20:47:32 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Feb 09 20:47:32 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Feb 09 20:47:32 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Feb 09 20:47:41 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Feb 09 20:47:42 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Feb 09 20:47:42 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Feb 09 20:47:42 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Feb 09 20:47:43 volumio-agm wpa_supplicant[855]: wlan0: Failed to initiate sched scan Feb 09 20:47:43 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Feb 09 20:47:43 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Feb 09 20:47:43 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Feb 09 20:47:43 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Feb 09 20:47:43 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Feb 09 20:47:43 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Feb 09 20:47:43 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioStop Feb 09 20:47:43 volumio-agm volumio[1025]: info: CoreStateMachine::stop Feb 09 20:47:43 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:43 volumio-agm volumio[1025]: info: CoreStateMachine::stPlaybackTimer Feb 09 20:47:43 volumio-agm volumio[1025]: info: CoreStateMachine::updateTrackBlock Feb 09 20:47:43 volumio-agm volumio[1025]: info: CorePlayQueue::getTrackBlock Feb 09 20:47:43 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:43 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:43 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:47:43 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:43 volumio-agm volumio[1025]: info: CoreStateMachine::serviceStop Feb 09 20:47:43 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:43 volumio-agm volumio[1025]: info: CoreCommandRouter::serviceStop Feb 09 20:47:43 volumio-agm volumio[1025]: info: [1770659263702] ControllerWebradio::stop Feb 09 20:47:43 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 20:47:43 volumio-agm volumio[1025]: info: Feb 09 20:47:43 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:47:43 volumio-agm volumio[1025]: info: sendMpdCommand stop took 49 milliseconds Feb 09 20:47:43 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:47:43 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:47:43 volumio-agm volumio[1025]: info: sendMpdCommand status took 3 milliseconds Feb 09 20:47:43 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:47:43 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:47:43 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 09 20:47:43 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:47:43 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:47:43 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:47:43 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:43 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:43 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:43 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:43 volumio-agm volumio[1025]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 09 20:47:43 volumio-agm volumio[1025]: info: ------------------------------ 45ms Feb 09 20:47:43 volumio-agm volumio[1025]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 09 20:47:44 volumio-agm volumio[1025]: info: peppy_screensaver: Start PeppyMeter Feb 09 20:47:44 volumio-agm volumio[1025]: info: Preload queue cleared Feb 09 20:47:44 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 09 20:47:44 volumio-agm volumio[1025]: info: CoreStateMachine::ClearQueue Feb 09 20:47:44 volumio-agm volumio[1025]: info: CoreStateMachine::stop Feb 09 20:47:44 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:44 volumio-agm volumio[1025]: info: CorePlayQueue::clearPlayQueue Feb 09 20:47:44 volumio-agm volumio[1025]: info: CorePlayQueue::saveQueue Feb 09 20:47:44 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushQueue Feb 09 20:47:44 volumio-agm volumio[1025]: info: CoreStateMachine::addQueueItems Feb 09 20:47:44 volumio-agm volumio[1025]: info: CorePlayQueue::addQueueItems Feb 09 20:47:44 volumio-agm volumio[1025]: info: Preload queue cleared Feb 09 20:47:44 volumio-agm volumio[1025]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99527901 Feb 09 20:47:44 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 09 20:47:44 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushQueue Feb 09 20:47:44 volumio-agm volumio[1025]: info: CorePlayQueue::saveQueue Feb 09 20:47:44 volumio-agm volumio[1025]: info: CoreStateMachine::updateTrackBlock Feb 09 20:47:44 volumio-agm volumio[1025]: info: CorePlayQueue::getTrackBlock Feb 09 20:47:44 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPlay Feb 09 20:47:44 volumio-agm volumio[1025]: info: CoreStateMachine::play index 0 Feb 09 20:47:44 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:44 volumio-agm volumio[1025]: info: CoreStateMachine::stop Feb 09 20:47:44 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:44 volumio-agm volumio[1025]: info: CoreStateMachine::play index undefined Feb 09 20:47:44 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:44 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:44 volumio-agm volumio[1025]: info: CoreStateMachine::startPlaybackTimer Feb 09 20:47:44 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:44 volumio-agm volumio[1025]: info: [1770659264990] ControllerWebradio::clearAddPlayTrack Feb 09 20:47:44 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 20:47:44 volumio-agm volumio[1025]: info: sendMpdCommand stop took 2 milliseconds Feb 09 20:47:44 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand clear Feb 09 20:47:44 volumio-agm volumio[1025]: info: Feb 09 20:47:44 volumio-agm volumio[1025]: ---------------------------- MPD announces system playlist update Feb 09 20:47:44 volumio-agm volumio[1025]: info: Ignoring MPD Status Update Feb 09 20:47:44 volumio-agm volumio[1025]: info: sendMpdCommand clear took 1 milliseconds Feb 09 20:47:44 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99527901" Feb 09 20:47:44 volumio-agm volumio[1025]: error: updateQueue error: null Feb 09 20:47:44 volumio-agm volumio[1025]: info: ------------------------------ 2ms Feb 09 20:47:45 volumio-agm volumio[1025]: info: Feb 09 20:47:45 volumio-agm volumio[1025]: ---------------------------- MPD announces system playlist update Feb 09 20:47:45 volumio-agm volumio[1025]: info: Ignoring MPD Status Update Feb 09 20:47:45 volumio-agm volumio[1025]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99527901" took 124 milliseconds Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand play Feb 09 20:47:45 volumio-agm volumio[1025]: info: ------------------------------ 2ms Feb 09 20:47:45 volumio-agm volumio[1025]: info: sendMpdCommand play took 1 milliseconds Feb 09 20:47:45 volumio-agm volumio[1025]: info: Feb 09 20:47:45 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:47:45 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:47:45 volumio-agm volumio[1025]: info: sendMpdCommand status took 17 milliseconds Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:47:45 volumio-agm volumio[1025]: info: Feb 09 20:47:45 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:47:45 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:47:45 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:47:45 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:47:45 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 32/1000) Smooth Jazz Wave Sarasota HD","album":null,"uri":"http://server.webnetradio.net:5012/stream","trackType":""} Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: CURRENT POSITION 0 Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreStateMachine::syncState stateService play Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Feb 09 20:47:45 volumio-agm volumio[1025]: info: ------------------------------ 20ms Feb 09 20:47:45 volumio-agm volumio[1025]: info: sendMpdCommand status took 5 milliseconds Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:47:45 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:47:45 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:47:45 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 32/1000) Smooth Jazz Wave Sarasota HD","album":null,"uri":"http://server.webnetradio.net:5012/stream","trackType":""} Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: CURRENT POSITION 0 Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreStateMachine::syncState stateService play Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:47:45 volumio-agm volumio[1025]: info: Received an update from plugin. extracting info from payload Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:45 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:45 volumio-agm volumio[1025]: info: ------------------------------ 32ms Feb 09 20:47:45 volumio-agm volumio[1025]: info: Feb 09 20:47:45 volumio-agm volumio[1025]: ---------------------------- MPD announces system playlist update Feb 09 20:47:45 volumio-agm volumio[1025]: info: Ignoring MPD Status Update Feb 09 20:47:45 volumio-agm volumio[1025]: info: Feb 09 20:47:45 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:47:45 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:47:45 volumio-agm volumio[1025]: info: ------------------------------ 1ms Feb 09 20:47:45 volumio-agm volumio[1025]: info: sendMpdCommand status took 6 milliseconds Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:47:45 volumio-agm volumio[1025]: info: Feb 09 20:47:45 volumio-agm volumio[1025]: ---------------------------- MPD announces system playlist update Feb 09 20:47:45 volumio-agm volumio[1025]: info: Ignoring MPD Status Update Feb 09 20:47:45 volumio-agm volumio[1025]: info: Feb 09 20:47:45 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:47:45 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:47:45 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:47:45 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:47:45 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"En Vogue - What Is Love","artist":"Smooth Jazz Wave Sarasota HD","album":null,"uri":"http://server.webnetradio.net:5012/stream","trackType":""} Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: CURRENT POSITION 0 Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreStateMachine::syncState stateService play Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:47:45 volumio-agm volumio[1025]: info: Received an update from plugin. extracting info from payload Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:45 volumio-agm volumio[1025]: info: ------------------------------ 32ms Feb 09 20:47:45 volumio-agm volumio[1025]: info: ------------------------------ 25ms Feb 09 20:47:45 volumio-agm volumio[1025]: info: sendMpdCommand status took 24 milliseconds Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:47:45 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:47:45 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:47:45 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: STATE SERVICE {"status":"play","position":0,"seek":729,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"En Vogue - What Is Love","artist":"Smooth Jazz Wave Sarasota HD","album":null,"uri":"http://server.webnetradio.net:5012/stream","trackType":""} Feb 09 20:47:45 volumio-agm volumio[1025]: verbose: CURRENT POSITION 0 Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreStateMachine::syncState stateService play Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:47:45 volumio-agm volumio[1025]: info: Received an update from plugin. extracting info from payload Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:45 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:45 volumio-agm volumio[1025]: info: ------------------------------ 77ms Feb 09 20:47:49 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioStop Feb 09 20:47:49 volumio-agm volumio[1025]: info: CoreStateMachine::stop Feb 09 20:47:49 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:49 volumio-agm volumio[1025]: info: CoreStateMachine::stPlaybackTimer Feb 09 20:47:49 volumio-agm volumio[1025]: info: CoreStateMachine::updateTrackBlock Feb 09 20:47:49 volumio-agm volumio[1025]: info: CorePlayQueue::getTrackBlock Feb 09 20:47:49 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:49 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:49 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:47:49 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:49 volumio-agm volumio[1025]: info: CoreStateMachine::serviceStop Feb 09 20:47:49 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:49 volumio-agm volumio[1025]: info: CoreCommandRouter::serviceStop Feb 09 20:47:49 volumio-agm volumio[1025]: info: [1770659269894] ControllerWebradio::stop Feb 09 20:47:49 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 20:47:49 volumio-agm volumio[1025]: info: Feb 09 20:47:49 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:47:49 volumio-agm volumio[1025]: info: sendMpdCommand stop took 14 milliseconds Feb 09 20:47:49 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:47:49 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:47:49 volumio-agm volumio[1025]: info: sendMpdCommand status took 1 milliseconds Feb 09 20:47:49 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:47:49 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:47:49 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 24 milliseconds Feb 09 20:47:49 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:47:49 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:47:49 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:47:49 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:49 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:49 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:49 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:49 volumio-agm volumio[1025]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 09 20:47:49 volumio-agm volumio[1025]: info: ------------------------------ 55ms Feb 09 20:47:49 volumio-agm volumio[1025]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 09 20:47:52 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Feb 09 20:47:52 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Feb 09 20:47:52 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Feb 09 20:47:52 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Feb 09 20:47:53 volumio-agm volumiologrotate[602]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Feb 09 20:47:53 volumio-agm volumiologrotate[602]: ls: cannot access 'AGM': No such file or directory Feb 09 20:47:53 volumio-agm wpa_supplicant[855]: wlan0: Failed to initiate sched scan Feb 09 20:47:53 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Feb 09 20:47:53 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Feb 09 20:47:53 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Feb 09 20:47:53 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Feb 09 20:47:53 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Feb 09 20:47:53 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Feb 09 20:47:56 volumio-agm volumio[1025]: info: Preload queue cleared Feb 09 20:47:56 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 09 20:47:56 volumio-agm volumio[1025]: info: CoreStateMachine::ClearQueue Feb 09 20:47:56 volumio-agm volumio[1025]: info: CoreStateMachine::stop Feb 09 20:47:56 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:56 volumio-agm volumio[1025]: info: CorePlayQueue::clearPlayQueue Feb 09 20:47:56 volumio-agm volumio[1025]: info: CorePlayQueue::saveQueue Feb 09 20:47:56 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushQueue Feb 09 20:47:56 volumio-agm volumio[1025]: info: CoreStateMachine::addQueueItems Feb 09 20:47:56 volumio-agm volumio[1025]: info: CorePlayQueue::addQueueItems Feb 09 20:47:56 volumio-agm volumio[1025]: info: Preload queue cleared Feb 09 20:47:56 volumio-agm volumio[1025]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99510334 Feb 09 20:47:56 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 09 20:47:56 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushQueue Feb 09 20:47:56 volumio-agm volumio[1025]: info: CorePlayQueue::saveQueue Feb 09 20:47:56 volumio-agm volumio[1025]: info: CoreStateMachine::updateTrackBlock Feb 09 20:47:56 volumio-agm volumio[1025]: info: CorePlayQueue::getTrackBlock Feb 09 20:47:56 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPlay Feb 09 20:47:56 volumio-agm volumio[1025]: info: CoreStateMachine::play index 0 Feb 09 20:47:56 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:56 volumio-agm volumio[1025]: info: CoreStateMachine::stop Feb 09 20:47:56 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:56 volumio-agm volumio[1025]: info: CoreStateMachine::play index undefined Feb 09 20:47:56 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:47:56 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:56 volumio-agm volumio[1025]: info: CoreStateMachine::startPlaybackTimer Feb 09 20:47:56 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:56 volumio-agm volumio[1025]: info: [1770659276098] ControllerWebradio::clearAddPlayTrack Feb 09 20:47:56 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 20:47:56 volumio-agm volumio[1025]: info: sendMpdCommand stop took 2 milliseconds Feb 09 20:47:56 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand clear Feb 09 20:47:56 volumio-agm volumio[1025]: info: Feb 09 20:47:56 volumio-agm volumio[1025]: ---------------------------- MPD announces system playlist update Feb 09 20:47:56 volumio-agm volumio[1025]: info: Ignoring MPD Status Update Feb 09 20:47:56 volumio-agm volumio[1025]: info: sendMpdCommand clear took 1 milliseconds Feb 09 20:47:56 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99510334" Feb 09 20:47:56 volumio-agm volumio[1025]: error: updateQueue error: null Feb 09 20:47:56 volumio-agm volumio[1025]: info: ------------------------------ 2ms Feb 09 20:47:56 volumio-agm volumio[1025]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99510334" took 122 milliseconds Feb 09 20:47:56 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 09 20:47:56 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand play Feb 09 20:47:56 volumio-agm volumio[1025]: info: Feb 09 20:47:56 volumio-agm volumio[1025]: ---------------------------- MPD announces system playlist update Feb 09 20:47:56 volumio-agm volumio[1025]: info: Ignoring MPD Status Update Feb 09 20:47:56 volumio-agm volumio[1025]: info: sendMpdCommand play took 5 milliseconds Feb 09 20:47:56 volumio-agm volumio[1025]: info: ------------------------------ 4ms Feb 09 20:47:57 volumio-agm volumio[1025]: info: Feb 09 20:47:57 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:47:57 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:47:57 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:47:57 volumio-agm volumio[1025]: info: Feb 09 20:47:57 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:47:57 volumio-agm volumio[1025]: info: sendMpdCommand status took 14 milliseconds Feb 09 20:47:57 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:47:57 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:47:57 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:47:57 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:47:57 volumio-agm volumio[1025]: info: sendMpdCommand status took 1 milliseconds Feb 09 20:47:57 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 09 20:47:57 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:47:57 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:47:57 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:47:57 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:47:57 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:47:57 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:57 volumio-agm volumio[1025]: 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":"jazzradiospain.mp3","artist":"(#1 - 16/250) Jazz Radio Spain","album":null,"uri":"https://server5.mediasector.es:8020/jazzradiospain.mp3","trackType":"mp3"} Feb 09 20:47:57 volumio-agm volumio[1025]: verbose: CURRENT POSITION 0 Feb 09 20:47:57 volumio-agm volumio[1025]: info: CoreStateMachine::syncState stateService play Feb 09 20:47:57 volumio-agm volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Feb 09 20:47:57 volumio-agm volumio[1025]: info: ------------------------------ 20ms Feb 09 20:47:57 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 09 20:47:57 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:47:57 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:47:57 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:47:57 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:57 volumio-agm volumio[1025]: 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":"jazzradiospain.mp3","artist":"(#1 - 16/250) Jazz Radio Spain","album":null,"uri":"https://server5.mediasector.es:8020/jazzradiospain.mp3","trackType":"mp3"} Feb 09 20:47:57 volumio-agm volumio[1025]: verbose: CURRENT POSITION 0 Feb 09 20:47:57 volumio-agm volumio[1025]: info: CoreStateMachine::syncState stateService play Feb 09 20:47:57 volumio-agm volumio[1025]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:47:57 volumio-agm volumio[1025]: info: Received an update from plugin. extracting info from payload Feb 09 20:47:57 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 09 20:47:57 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:57 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:57 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:47:57 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:57 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:57 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:57 volumio-agm volumio[1025]: info: ------------------------------ 33ms Feb 09 20:47:58 volumio-agm volumio[1025]: info: Feb 09 20:47:58 volumio-agm volumio[1025]: ---------------------------- MPD announces system playlist update Feb 09 20:47:58 volumio-agm volumio[1025]: info: Ignoring MPD Status Update Feb 09 20:47:58 volumio-agm volumio[1025]: info: Feb 09 20:47:58 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:47:58 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:47:58 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:47:58 volumio-agm volumio[1025]: info: ------------------------------ 5ms Feb 09 20:47:58 volumio-agm volumio[1025]: info: sendMpdCommand status took 3 milliseconds Feb 09 20:47:58 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:47:58 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:47:58 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 09 20:47:58 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:47:58 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:47:58 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:47:58 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:47:58 volumio-agm volumio[1025]: verbose: STATE SERVICE {"status":"play","position":0,"seek":721,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Eddie K Jr., Iosf Jones, Frank Javois - Throwback","artist":"Jazz Radio Spain","album":null,"uri":"https://server5.mediasector.es:8020/jazzradiospain.mp3","trackType":"mp3"} Feb 09 20:47:58 volumio-agm volumio[1025]: verbose: CURRENT POSITION 0 Feb 09 20:47:58 volumio-agm volumio[1025]: info: CoreStateMachine::syncState stateService play Feb 09 20:47:58 volumio-agm volumio[1025]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:47:58 volumio-agm volumio[1025]: info: Received an update from plugin. extracting info from payload Feb 09 20:47:58 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 09 20:47:58 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:58 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:47:58 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:58 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:47:58 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:47:58 volumio-agm volumio[1025]: info: ------------------------------ 55ms Feb 09 20:48:02 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Feb 09 20:48:02 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Feb 09 20:48:02 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Feb 09 20:48:02 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Feb 09 20:48:03 volumio-agm wpa_supplicant[855]: wlan0: Failed to initiate sched scan Feb 09 20:48:03 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Feb 09 20:48:03 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Feb 09 20:48:03 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Feb 09 20:48:03 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Feb 09 20:48:03 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Feb 09 20:48:03 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Feb 09 20:48:12 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Feb 09 20:48:12 volumio-agm volumio[1025]: 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: 9 Feb 09 20:48:12 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioGetState Feb 09 20:48:12 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Feb 09 20:48:13 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Feb 09 20:48:13 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Feb 09 20:48:13 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioStop Feb 09 20:48:13 volumio-agm volumio[1025]: info: CoreStateMachine::stop Feb 09 20:48:13 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:48:13 volumio-agm volumio[1025]: info: CoreStateMachine::stPlaybackTimer Feb 09 20:48:13 volumio-agm volumio[1025]: info: CoreStateMachine::updateTrackBlock Feb 09 20:48:13 volumio-agm volumio[1025]: info: CorePlayQueue::getTrackBlock Feb 09 20:48:13 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:48:13 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:48:13 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:48:13 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:48:13 volumio-agm volumio[1025]: info: CoreStateMachine::serviceStop Feb 09 20:48:13 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:48:13 volumio-agm volumio[1025]: info: CoreCommandRouter::serviceStop Feb 09 20:48:13 volumio-agm volumio[1025]: info: [1770659293780] ControllerWebradio::stop Feb 09 20:48:13 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 20:48:13 volumio-agm volumio[1025]: info: Feb 09 20:48:13 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:48:13 volumio-agm volumio[1025]: info: sendMpdCommand stop took 56 milliseconds Feb 09 20:48:13 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:48:13 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:48:13 volumio-agm volumio[1025]: info: sendMpdCommand status took 5 milliseconds Feb 09 20:48:13 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:48:13 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:48:13 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 09 20:48:13 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:48:13 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:48:13 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:48:13 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:48:13 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:48:13 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:48:13 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:48:13 volumio-agm volumio[1025]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 09 20:48:13 volumio-agm volumio[1025]: info: ------------------------------ 43ms Feb 09 20:48:13 volumio-agm volumio[1025]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 09 20:48:14 volumio-agm wpa_supplicant[855]: wlan0: Failed to initiate sched scan Feb 09 20:48:14 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Feb 09 20:48:14 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Feb 09 20:48:14 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Feb 09 20:48:14 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Feb 09 20:48:14 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Feb 09 20:48:14 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Feb 09 20:48:14 volumio-agm volumio[1025]: info: peppy_screensaver: Start PeppyMeter Feb 09 20:48:21 volumio-agm volumio[1025]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 09 20:48:21 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 09 20:48:21 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 20:48:21 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 09 20:48:21 volumio-agm volumio[1025]: info: Getting Alsa Cards List without I2S DAC Feb 09 20:48:21 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 09 20:48:21 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 20:48:21 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 20:48:21 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 20:48:21 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 20:48:21 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 20:48:21 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 20:48:21 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 20:48:21 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 20:48:21 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 09 20:48:21 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 09 20:48:23 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Feb 09 20:48:23 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Feb 09 20:48:23 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Feb 09 20:48:23 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Feb 09 20:48:24 volumio-agm wpa_supplicant[855]: wlan0: Failed to initiate sched scan Feb 09 20:48:24 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Feb 09 20:48:24 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Feb 09 20:48:24 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Feb 09 20:48:24 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Feb 09 20:48:24 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Feb 09 20:48:24 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Feb 09 20:48:33 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Feb 09 20:48:33 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Feb 09 20:48:33 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Feb 09 20:48:33 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Feb 09 20:48:34 volumio-agm wpa_supplicant[855]: wlan0: Failed to initiate sched scan Feb 09 20:48:34 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Feb 09 20:48:34 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Feb 09 20:48:34 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Feb 09 20:48:34 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Feb 09 20:48:34 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Feb 09 20:48:34 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Feb 09 20:48:35 volumio-agm volumio[1025]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Feb 09 20:48:35 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Feb 09 20:48:35 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 20:48:35 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 20:48:35 volumio-agm sudo[5325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 09 20:48:35 volumio-agm sudo[5325]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 20:48:35 volumio-agm sudo[5325]: pam_unix(sudo:session): session closed for user root Feb 09 20:48:35 volumio-agm sudo[5327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 09 20:48:35 volumio-agm sudo[5327]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 20:48:35 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 20:48:35 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 20:48:35 volumio-agm volumio[1025]: info: MPD Permissions set Feb 09 20:48:35 volumio-agm systemd[1]: Stopping Music Player Daemon... Feb 09 20:48:35 volumio-agm volumio[1025]: error: Upnp client error: Error: This socket has been ended by the other party Feb 09 20:48:35 volumio-agm systemd[1]: mpd.service: Succeeded. Feb 09 20:48:35 volumio-agm systemd[1]: Stopped Music Player Daemon. Feb 09 20:48:35 volumio-agm systemd[1]: Starting Music Player Daemon... Feb 09 20:48:36 volumio-agm sudo[5336]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 09 20:48:36 volumio-agm sudo[5336]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 20:48:36 volumio-agm sudo[5336]: pam_unix(sudo:session): session closed for user root Feb 09 20:48:36 volumio-agm mpd[5339]: Feb 09 20:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 09 20:48:36 volumio-agm systemd[1]: Started Music Player Daemon. Feb 09 20:48:36 volumio-agm sudo[5327]: pam_unix(sudo:session): session closed for user root Feb 09 20:48:36 volumio-agm volumio[1025]: error: updateQueue error: null Feb 09 20:48:39 volumio-agm sudo[5345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 09 20:48:39 volumio-agm sudo[5345]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 20:48:39 volumio-agm systemd[1]: Stopping MPD Monitor Service... Feb 09 20:48:39 volumio-agm systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Feb 09 20:48:39 volumio-agm systemd[1]: mpd_monitor.service: Succeeded. Feb 09 20:48:39 volumio-agm systemd[1]: Stopped MPD Monitor Service. Feb 09 20:48:39 volumio-agm systemd[1]: Started MPD Monitor Service. Feb 09 20:48:39 volumio-agm sudo[5345]: pam_unix(sudo:session): session closed for user root Feb 09 20:48:39 volumio-agm mpd_monitor.sh[5347]: MPD Monitor Service: Starting MPD Monitor Service Feb 09 20:48:39 volumio-agm volumio[1025]: info: Successfully started MPD Monitor Feb 09 20:48:43 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Feb 09 20:48:43 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Feb 09 20:48:43 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Feb 09 20:48:43 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Feb 09 20:48:44 volumio-agm wpa_supplicant[855]: wlan0: Failed to initiate sched scan Feb 09 20:48:44 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Feb 09 20:48:44 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Feb 09 20:48:44 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Feb 09 20:48:44 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Feb 09 20:48:44 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Feb 09 20:48:44 volumio-agm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Feb 09 20:48:45 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPlay Feb 09 20:48:45 volumio-agm volumio[1025]: info: CoreStateMachine::play index undefined Feb 09 20:48:45 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:48:45 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:48:45 volumio-agm volumio[1025]: info: CoreStateMachine::startPlaybackTimer Feb 09 20:48:45 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:48:45 volumio-agm volumio[1025]: info: [1770659325411] ControllerWebradio::clearAddPlayTrack Feb 09 20:48:45 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 20:48:45 volumio-agm volumio[1025]: info: sendMpdCommand stop took 0 milliseconds Feb 09 20:48:45 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand clear Feb 09 20:48:45 volumio-agm volumio[1025]: info: Feb 09 20:48:45 volumio-agm volumio[1025]: ---------------------------- MPD announces system playlist update Feb 09 20:48:45 volumio-agm volumio[1025]: info: Ignoring MPD Status Update Feb 09 20:48:45 volumio-agm volumio[1025]: info: sendMpdCommand clear took 0 milliseconds Feb 09 20:48:45 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99510334" Feb 09 20:48:45 volumio-agm volumio[1025]: error: updateQueue error: null Feb 09 20:48:45 volumio-agm volumio[1025]: info: ------------------------------ 1ms Feb 09 20:48:46 volumio-agm volumio[1025]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99510334" took 1184 milliseconds Feb 09 20:48:46 volumio-agm volumio[1025]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 09 20:48:46 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand play Feb 09 20:48:46 volumio-agm volumio[1025]: info: Feb 09 20:48:46 volumio-agm volumio[1025]: ---------------------------- MPD announces system playlist update Feb 09 20:48:46 volumio-agm volumio[1025]: info: Ignoring MPD Status Update Feb 09 20:48:46 volumio-agm volumio[1025]: info: sendMpdCommand play took 5 milliseconds Feb 09 20:48:46 volumio-agm volumio[1025]: info: ------------------------------ 4ms Feb 09 20:48:47 volumio-agm volumio[1025]: info: Feb 09 20:48:47 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:48:47 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:48:47 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:48:47 volumio-agm volumio[1025]: info: Feb 09 20:48:47 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:48:47 volumio-agm volumio[1025]: info: sendMpdCommand status took 16 milliseconds Feb 09 20:48:47 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:48:47 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:48:47 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:48:47 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:48:47 volumio-agm volumio[1025]: info: sendMpdCommand status took 1 milliseconds Feb 09 20:48:47 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 09 20:48:47 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:48:47 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:48:47 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:48:47 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:48:47 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:48:47 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:48:47 volumio-agm volumio[1025]: 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":"jazzradiospain.mp3","artist":"(#1 - 16/250) Jazz Radio Spain","album":null,"uri":"https://server5.mediasector.es:8020/jazzradiospain.mp3","trackType":"mp3"} Feb 09 20:48:47 volumio-agm volumio[1025]: verbose: CURRENT POSITION 0 Feb 09 20:48:47 volumio-agm volumio[1025]: info: CoreStateMachine::syncState stateService play Feb 09 20:48:47 volumio-agm volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Feb 09 20:48:47 volumio-agm volumio[1025]: info: ------------------------------ 19ms Feb 09 20:48:47 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 09 20:48:47 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:48:47 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:48:47 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:48:47 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:48:47 volumio-agm volumio[1025]: 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":"jazzradiospain.mp3","artist":"(#1 - 16/250) Jazz Radio Spain","album":null,"uri":"https://server5.mediasector.es:8020/jazzradiospain.mp3","trackType":"mp3"} Feb 09 20:48:47 volumio-agm volumio[1025]: verbose: CURRENT POSITION 0 Feb 09 20:48:47 volumio-agm volumio[1025]: info: CoreStateMachine::syncState stateService play Feb 09 20:48:47 volumio-agm volumio[1025]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:48:47 volumio-agm volumio[1025]: info: Received an update from plugin. extracting info from payload Feb 09 20:48:47 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 09 20:48:47 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:48:47 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:48:47 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:48:47 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:48:47 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:48:47 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:48:47 volumio-agm volumio[1025]: info: ------------------------------ 30ms Feb 09 20:48:48 volumio-agm volumio[1025]: info: Feb 09 20:48:48 volumio-agm volumio[1025]: ---------------------------- MPD announces system playlist update Feb 09 20:48:48 volumio-agm volumio[1025]: info: Ignoring MPD Status Update Feb 09 20:48:48 volumio-agm volumio[1025]: info: Feb 09 20:48:48 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:48:48 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:48:48 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:48:48 volumio-agm volumio[1025]: info: Feb 09 20:48:48 volumio-agm volumio[1025]: ---------------------------- MPD announces system playlist update Feb 09 20:48:48 volumio-agm volumio[1025]: info: Ignoring MPD Status Update Feb 09 20:48:48 volumio-agm volumio[1025]: info: Feb 09 20:48:48 volumio-agm volumio[1025]: ---------------------------- MPD announces state update: player Feb 09 20:48:48 volumio-agm volumio[1025]: info: ControllerMpd::getState Feb 09 20:48:48 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:48:48 volumio-agm volumio[1025]: info: ------------------------------ 3ms Feb 09 20:48:48 volumio-agm volumio[1025]: info: sendMpdCommand status took 2 milliseconds Feb 09 20:48:48 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:48:48 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:48:48 volumio-agm volumio[1025]: info: ------------------------------ 6ms Feb 09 20:48:48 volumio-agm volumio[1025]: info: sendMpdCommand status took 5 milliseconds Feb 09 20:48:48 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 09 20:48:48 volumio-agm volumio[1025]: verbose: ControllerMpd::parseState Feb 09 20:48:48 volumio-agm volumio[1025]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:48:48 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:48:48 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:48:48 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:48:48 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:48:48 volumio-agm volumio[1025]: verbose: STATE SERVICE {"status":"play","position":0,"seek":630,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Eddie K Jr., Iosf Jones, Frank Javois - Throwback","artist":"Jazz Radio Spain","album":null,"uri":"https://server5.mediasector.es:8020/jazzradiospain.mp3","trackType":"mp3"} Feb 09 20:48:48 volumio-agm volumio[1025]: verbose: CURRENT POSITION 0 Feb 09 20:48:48 volumio-agm volumio[1025]: info: CoreStateMachine::syncState stateService play Feb 09 20:48:48 volumio-agm volumio[1025]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:48:48 volumio-agm volumio[1025]: info: Received an update from plugin. extracting info from payload Feb 09 20:48:48 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 09 20:48:48 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:48:48 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:48:48 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:48:48 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:48:48 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:48:48 volumio-agm volumio[1025]: info: ------------------------------ 34ms Feb 09 20:48:48 volumio-agm volumio[1025]: info: sendMpdCommand playlistinfo took 27 milliseconds Feb 09 20:48:48 volumio-agm volumio[1025]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:48:48 volumio-agm volumio[1025]: info: ControllerMpd::pushState Feb 09 20:48:48 volumio-agm volumio[1025]: info: CoreCommandRouter::servicePushState Feb 09 20:48:48 volumio-agm volumio[1025]: info: CorePlayQueue::getTrack 0 Feb 09 20:48:48 volumio-agm volumio[1025]: verbose: STATE SERVICE {"status":"play","position":0,"seek":721,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Eddie K Jr., Iosf Jones, Frank Javois - Throwback","artist":"Jazz Radio Spain","album":null,"uri":"https://server5.mediasector.es:8020/jazzradiospain.mp3","trackType":"mp3"} Feb 09 20:48:48 volumio-agm volumio[1025]: verbose: CURRENT POSITION 0 Feb 09 20:48:48 volumio-agm volumio[1025]: info: CoreStateMachine::syncState stateService play Feb 09 20:48:48 volumio-agm volumio[1025]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:48:48 volumio-agm volumio[1025]: info: Received an update from plugin. extracting info from payload Feb 09 20:48:48 volumio-agm volumio[1025]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 09 20:48:48 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:48:48 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:48:48 volumio-agm volumio[1025]: info: CoreStateMachine::pushState Feb 09 20:48:48 volumio-agm volumio[1025]: info: CoreCommandRouter::volumioPushState Feb 09 20:48:48 volumio-agm volumio[1025]: info: ------------------------------ 75ms Feb 09 20:48:48 volumio-agm volumio[1025]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%2016%2F250)%20Jazz%20Radio%20Spain/5e81cbec-89e8-461c-a491-37c376616c94.jpg' Feb 09 20:48:48 volumio-agm volumio[1025]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 20:48:48 volumio-agm volumio[1025]: Error: connect ETIMEDOUT 23.73.2.84:443 Feb 09 20:48:48 volumio-agm volumio[1025]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Feb 09 20:48:48 volumio-agm volumio[1025]: errno: -110, Feb 09 20:48:48 volumio-agm volumio[1025]: code: 'ETIMEDOUT', Feb 09 20:48:48 volumio-agm volumio[1025]: syscall: 'connect', Feb 09 20:48:48 volumio-agm volumio[1025]: address: '23.73.2.84', Feb 09 20:48:48 volumio-agm volumio[1025]: port: 443 Feb 09 20:48:48 volumio-agm volumio[1025]: } Feb 09 20:48:48 volumio-agm volumio[1025]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 20:48:48 volumio-agm sudo[5398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-09 20:47 Feb 09 20:48:48 volumio-agm sudo[5398]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"