-- Logs begin at Wed 2025-07-02 20:12:23 UTC, end at Thu 2025-07-03 20:38:33 UTC. -- Jul 03 20:37:05 volumio.local volumio[938]: info: CoreCommandRouter::volumioGetState Jul 03 20:37:05 volumio.local volumio[938]: info: Listing playlists Jul 03 20:37:05 volumio.local volumio[938]: info: Listing playlists Jul 03 20:37:15 volumio.local volumio[938]: info: CoreCommandRouter::volumioGetState Jul 03 20:37:25 volumio.local volumio[938]: info: CoreCommandRouter::volumioGetState Jul 03 20:37:25 volumio.local volumio[938]: info: Listing playlists Jul 03 20:37:25 volumio.local volumio[938]: info: Listing playlists Jul 03 20:37:35 volumio.local volumio[938]: info: CoreCommandRouter::volumioGetState Jul 03 20:37:45 volumio.local volumio[938]: info: CoreCommandRouter::volumioGetState Jul 03 20:37:45 volumio.local volumio[938]: info: Listing playlists Jul 03 20:37:45 volumio.local volumio[938]: info: Listing playlists Jul 03 20:37:55 volumio.local volumio[938]: info: CoreCommandRouter::volumioGetState Jul 03 20:38:04 volumio.local nmbd[631]: [2025/07/03 20:38:04.395623, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jul 03 20:38:04 volumio.local nmbd[631]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.12 for name WORKGROUP<1d>. Jul 03 20:38:04 volumio.local nmbd[631]: This response was from IP 192.168.0.10, reporting an IP address of 192.168.0.10. Jul 03 20:38:05 volumio.local volumio[938]: info: CoreCommandRouter::volumioGetState Jul 03 20:38:05 volumio.local volumio[938]: info: Listing playlists Jul 03 20:38:05 volumio.local volumio[938]: info: Listing playlists Jul 03 20:38:15 volumio.local volumio[938]: info: CoreCommandRouter::volumioGetState Jul 03 20:38:25 volumio.local volumio[938]: info: CoreCommandRouter::volumioGetState Jul 03 20:38:25 volumio.local volumio[938]: info: Listing playlists Jul 03 20:38:25 volumio.local volumio[938]: info: Listing playlists Jul 03 20:38:25 volumio.local volumio[938]: info: CorePlayQueue::getTrack 12 Jul 03 20:38:25 volumio.local volumio[938]: info: CorePlayQueue::getTrack 13 Jul 03 20:38:25 volumio.local volumio[938]: info: Prefetching next song Jul 03 20:38:31 volumio.local volumio[938]: info: [1751575111367] ControllerPandora::pandoraListener Jul 03 20:38:31 volumio.local volumio[938]: info: ControllerMpd::getState Jul 03 20:38:31 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand status Jul 03 20:38:31 volumio.local volumio[938]: info: Jul 03 20:38:31 volumio.local volumio[938]: ---------------------------- MPD announces state update: player Jul 03 20:38:31 volumio.local volumio[938]: info: ControllerMpd::getState Jul 03 20:38:31 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand status Jul 03 20:38:31 volumio.local volumio[938]: info: Jul 03 20:38:31 volumio.local volumio[938]: ---------------------------- MPD announces state update: player Jul 03 20:38:31 volumio.local volumio[938]: info: ControllerMpd::getState Jul 03 20:38:31 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand status Jul 03 20:38:31 volumio.local volumio[938]: info: Jul 03 20:38:31 volumio.local volumio[938]: ---------------------------- MPD announces state update: player Jul 03 20:38:31 volumio.local volumio[938]: info: ControllerMpd::getState Jul 03 20:38:31 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand status Jul 03 20:38:31 volumio.local volumio[938]: info: Jul 03 20:38:31 volumio.local volumio[938]: ---------------------------- MPD announces state update: player Jul 03 20:38:31 volumio.local volumio[938]: info: ControllerMpd::getState Jul 03 20:38:31 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand status Jul 03 20:38:31 volumio.local volumio[938]: info: Jul 03 20:38:31 volumio.local volumio[938]: ---------------------------- MPD announces state update: player Jul 03 20:38:31 volumio.local volumio[938]: info: ControllerMpd::getState Jul 03 20:38:31 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand status Jul 03 20:38:31 volumio.local volumio[938]: info: Jul 03 20:38:31 volumio.local volumio[938]: ---------------------------- MPD announces state update: player Jul 03 20:38:31 volumio.local volumio[938]: info: ControllerMpd::getState Jul 03 20:38:31 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand status Jul 03 20:38:31 volumio.local volumio[938]: info: sendMpdCommand status took 7 milliseconds Jul 03 20:38:31 volumio.local volumio[938]: info: sendMpdCommand status took 7 milliseconds Jul 03 20:38:31 volumio.local volumio[938]: info: sendMpdCommand status took 6 milliseconds Jul 03 20:38:31 volumio.local volumio[938]: info: sendMpdCommand status took 5 milliseconds Jul 03 20:38:31 volumio.local volumio[938]: info: sendMpdCommand status took 4 milliseconds Jul 03 20:38:31 volumio.local volumio[938]: info: sendMpdCommand status took 3 milliseconds Jul 03 20:38:31 volumio.local volumio[938]: info: sendMpdCommand status took 2 milliseconds Jul 03 20:38:31 volumio.local volumio[938]: verbose: ControllerMpd::parseState Jul 03 20:38:31 volumio.local volumio[938]: verbose: ControllerMpd::parseState Jul 03 20:38:31 volumio.local volumio[938]: verbose: ControllerMpd::parseState Jul 03 20:38:31 volumio.local volumio[938]: info: CorePlayQueue::getTrack 12 Jul 03 20:38:31 volumio.local volumio[938]: info: [1751575111376] ControllerPandora::pushState Jul 03 20:38:31 volumio.local volumio[938]: info: CoreCommandRouter::servicePushState Jul 03 20:38:31 volumio.local volumio[938]: info: CoreStateMachine::pushState Jul 03 20:38:31 volumio.local volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:38:31 volumio.local volumio[938]: info: CoreCommandRouter::volumioPushState Jul 03 20:38:31 volumio.local volumio[938]: info: CorePlayQueue::getTrack 12 Jul 03 20:38:31 volumio.local volumio[938]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null,"trackType":"mp3"} Jul 03 20:38:31 volumio.local volumio[938]: verbose: CURRENT POSITION 12 Jul 03 20:38:31 volumio.local volumio[938]: info: CoreStateMachine::syncState stateService stop Jul 03 20:38:31 volumio.local volumio[938]: info: CoreStateMachine::syncState currentStatus play Jul 03 20:38:31 volumio.local volumio[938]: info: CoreStateMachine::play index undefined Jul 03 20:38:31 volumio.local volumio[938]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 03 20:38:31 volumio.local volumio[938]: info: CoreStateMachine::pushState Jul 03 20:38:31 volumio.local volumio[938]: info: CorePlayQueue::getTrack 13 Jul 03 20:38:31 volumio.local volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:38:31 volumio.local volumio[938]: info: CoreCommandRouter::volumioPushState Jul 03 20:38:31 volumio.local volumio[938]: info: CoreStateMachine::setConsumeUpdateService pandora Jul 03 20:38:31 volumio.local volumio[938]: info: ControllerMpd::pushState Jul 03 20:38:31 volumio.local volumio[938]: info: CoreCommandRouter::servicePushState Jul 03 20:38:31 volumio.local volumio[938]: info: CoreStateMachine::pushState Jul 03 20:38:31 volumio.local volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:38:31 volumio.local volumio[938]: info: CoreCommandRouter::volumioPushState Jul 03 20:38:31 volumio.local volumio[938]: info: CorePlayQueue::getTrack 13 Jul 03 20:38:31 volumio.local volumio[938]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 03 20:38:31 volumio.local volumio[938]: info: ControllerMpd::pushState Jul 03 20:38:31 volumio.local volumio[938]: info: CoreCommandRouter::servicePushState Jul 03 20:38:31 volumio.local volumio[938]: info: CoreStateMachine::pushState Jul 03 20:38:31 volumio.local volumio[938]: info: CoreCommandRouter::volumioPushState Jul 03 20:38:31 volumio.local volumio[938]: info: CorePlayQueue::getTrack 13 Jul 03 20:38:31 volumio.local volumio[938]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 03 20:38:31 volumio.local volumio[938]: info: ControllerMpd::pushState Jul 03 20:38:31 volumio.local volumio[938]: info: CoreCommandRouter::servicePushState Jul 03 20:38:31 volumio.local volumio[938]: info: CoreStateMachine::pushState Jul 03 20:38:31 volumio.local volumio[938]: info: CoreCommandRouter::volumioPushState Jul 03 20:38:31 volumio.local volumio[938]: info: CorePlayQueue::getTrack 13 Jul 03 20:38:31 volumio.local volumio[938]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 03 20:38:31 volumio.local volumio[938]: info: ControllerMpd::pushState Jul 03 20:38:31 volumio.local volumio[938]: info: CoreCommandRouter::servicePushState Jul 03 20:38:31 volumio.local volumio[938]: info: CoreStateMachine::pushState Jul 03 20:38:31 volumio.local volumio[938]: info: CoreCommandRouter::volumioPushState Jul 03 20:38:31 volumio.local volumio[938]: info: CorePlayQueue::getTrack 13 Jul 03 20:38:31 volumio.local volumio[938]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 03 20:38:31 volumio.local volumio[938]: info: ControllerMpd::pushState Jul 03 20:38:31 volumio.local volumio[938]: info: CoreCommandRouter::servicePushState Jul 03 20:38:31 volumio.local volumio[938]: info: CoreStateMachine::pushState Jul 03 20:38:31 volumio.local volumio[938]: info: CoreCommandRouter::volumioPushState Jul 03 20:38:31 volumio.local volumio[938]: info: CorePlayQueue::getTrack 13 Jul 03 20:38:31 volumio.local volumio[938]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 03 20:38:31 volumio.local volumio[938]: info: ControllerMpd::pushState Jul 03 20:38:31 volumio.local volumio[938]: info: CoreCommandRouter::servicePushState Jul 03 20:38:31 volumio.local volumio[938]: info: CoreStateMachine::pushState Jul 03 20:38:31 volumio.local volumio[938]: info: CoreCommandRouter::volumioPushState Jul 03 20:38:31 volumio.local volumio[938]: info: CorePlayQueue::getTrack 13 Jul 03 20:38:31 volumio.local volumio[938]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 03 20:38:31 volumio.local volumio[938]: info: CorePlayQueue::getTrack 13 Jul 03 20:38:31 volumio.local volumio[938]: info: CoreStateMachine::startPlaybackTimer Jul 03 20:38:31 volumio.local volumio[938]: info: CorePlayQueue::getTrack 13 Jul 03 20:38:31 volumio.local volumio[938]: info: [1751575111402] ControllerPandora::clearAddPlayTrack Jul 03 20:38:31 volumio.local volumio[938]: info: ControllerMpd::clear Jul 03 20:38:31 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand clear Jul 03 20:38:31 volumio.local volumio[938]: info: ------------------------------ 36ms Jul 03 20:38:31 volumio.local volumio[938]: info: ------------------------------ 36ms Jul 03 20:38:31 volumio.local volumio[938]: info: ------------------------------ 35ms Jul 03 20:38:31 volumio.local volumio[938]: info: ------------------------------ 34ms Jul 03 20:38:31 volumio.local volumio[938]: info: ------------------------------ 34ms Jul 03 20:38:31 volumio.local volumio[938]: info: ------------------------------ 33ms Jul 03 20:38:31 volumio.local volumio[938]: info: CoreStateMachine::pushState Jul 03 20:38:31 volumio.local volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:38:31 volumio.local volumio[938]: info: CoreCommandRouter::volumioPushState Jul 03 20:38:31 volumio.local volumio[938]: info: Jul 03 20:38:31 volumio.local volumio[938]: ---------------------------- MPD announces system playlist update Jul 03 20:38:31 volumio.local volumio[938]: info: Ignoring MPD Status Update Jul 03 20:38:31 volumio.local volumio[938]: info: sendMpdCommand clear took 9 milliseconds Jul 03 20:38:31 volumio.local volumio[938]: info: [1751575111412] ControllerPandora::checkForExpiredStations Jul 03 20:38:31 volumio.local volumio[938]: info: [1751575111412] [Pandora] checkForExpiredStations: Stations expired Jul 03 20:38:31 volumio.local volumio[938]: info: [1751575111412] ControllerPandora::PandoraHandler::fillStationData Jul 03 20:38:31 volumio.local volumio[938]: info: CoreCommandRouter::volumioGetQueue Jul 03 20:38:31 volumio.local volumio[938]: info: CoreStateMachine::getQueue Jul 03 20:38:31 volumio.local volumio[938]: info: CorePlayQueue::getQueue Jul 03 20:38:31 volumio.local volumio[938]: info: Jul 03 20:38:31 volumio.local volumio[938]: ---------------------------- MPD announces system playlist update Jul 03 20:38:31 volumio.local volumio[938]: info: Ignoring MPD Status Update Jul 03 20:38:31 volumio.local volumio[938]: info: Jul 03 20:38:31 volumio.local volumio[938]: ---------------------------- MPD announces system playlist update Jul 03 20:38:31 volumio.local volumio[938]: info: Ignoring MPD Status Update Jul 03 20:38:31 volumio.local volumio[938]: info: Jul 03 20:38:31 volumio.local volumio[938]: ---------------------------- MPD announces system playlist update Jul 03 20:38:31 volumio.local volumio[938]: info: Ignoring MPD Status Update Jul 03 20:38:31 volumio.local volumio[938]: info: Jul 03 20:38:31 volumio.local volumio[938]: ---------------------------- MPD announces system playlist update Jul 03 20:38:31 volumio.local volumio[938]: info: Ignoring MPD Status Update Jul 03 20:38:31 volumio.local volumio[938]: info: Jul 03 20:38:31 volumio.local volumio[938]: ---------------------------- MPD announces system playlist update Jul 03 20:38:31 volumio.local volumio[938]: info: Ignoring MPD Status Update Jul 03 20:38:31 volumio.local volumio[938]: error: updateQueue error: null Jul 03 20:38:31 volumio.local volumio[938]: error: updateQueue error: null Jul 03 20:38:31 volumio.local volumio[938]: error: updateQueue error: null Jul 03 20:38:31 volumio.local volumio[938]: error: updateQueue error: null Jul 03 20:38:31 volumio.local volumio[938]: error: updateQueue error: null Jul 03 20:38:31 volumio.local volumio[938]: info: ------------------------------ 28ms Jul 03 20:38:31 volumio.local volumio[938]: info: ------------------------------ 8ms Jul 03 20:38:31 volumio.local volumio[938]: info: ------------------------------ 7ms Jul 03 20:38:31 volumio.local volumio[938]: info: ------------------------------ 6ms Jul 03 20:38:31 volumio.local volumio[938]: info: ------------------------------ 5ms Jul 03 20:38:31 volumio.local volumio[938]: info: ------------------------------ 5ms Jul 03 20:38:31 volumio.local volumio[938]: info: CorePlayQueue::getQueue Jul 03 20:38:31 volumio.local volumio[938]: info: [1751575111661] ControllerPandora::removeTrack Jul 03 20:38:31 volumio.local volumio[938]: info: CoreStateMachine::removeQueueItem Jul 03 20:38:31 volumio.local volumio[938]: info: CorePlayQueue::removeQueueItem 12 Jul 03 20:38:31 volumio.local volumio[938]: info: CorePlayQueue::saveQueue Jul 03 20:38:31 volumio.local volumio[938]: info: CoreCommandRouter::volumioPushQueue Jul 03 20:38:31 volumio.local volumio[938]: info: [1751575111665] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=4515236742503792184trackId=3246147485150912167 at queue index: 12 Jul 03 20:38:31 volumio.local volumio[938]: info: ControllerMpd::getState Jul 03 20:38:31 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand status Jul 03 20:38:31 volumio.local volumio[938]: info: [1751575111671] ControllerPandora::appendTracksToMpd Jul 03 20:38:32 volumio.local volumio[938]: info: [1751575112525] [Pandora] appendTracksToMpd::resolveTrackUri: http://t1-5.p-cdn.us/access/86256330960900526.mp3?version=5&lid=29031992&token=IdaNXBTVOaSMnVA%2BDIew91HzCAnWFTI3gZtZkvErxvG1ZRxMQaooXWIR4r%2FFDR35hh7PC9Cbg1ZQvc%2BH9DRzza84kUg%2FQW0uVATmxT%2BtFkxoD7LcX50CSrJybos5%2ByZVwghqejIoGgyzb%2BN8RPaAASWfD4%2FxNkOyDITmSYCim%2Fy7PSyM%2BnXjze2d%2Bqk52o1hh6ZnY3oeHGcwY00De8X30bV%2BKGETKx5T3RF3SibxBderwt8Mbdri9WXeEl0SjJjQs9%2FgRweI1%2FlwnS3Wb%2BlEnR%2BQSfroBjW%2FfGpl5eE6aH5s6FZmWrmyHnsLDLcG%2Bn82b4GzSlrsbxgpQo4LfyZ8qQ%3D%3D => http://208.85.46.21/access/86256330960900526.mp3?version=5&lid=29031992&token=IdaNXBTVOaSMnVA%2BDIew91HzCAnWFTI3gZtZkvErxvG1ZRxMQaooXWIR4r%2FFDR35hh7PC9Cbg1ZQvc%2BH9DRzza84kUg%2FQW0uVATmxT%2BtFkxoD7LcX50CSrJybos5%2ByZVwghqejIoGgyzb%2BN8RPaAASWfD4%2FxNkOyDITmSYCim%2Fy7PSyM%2BnXjze2d%2Bqk52o1hh6ZnY3oeHGcwY00De8X30bV%2BKGETKx5T3RF3SibxBderwt8Mbdri9WXeEl0SjJjQs9%2FgRweI1%2FlwnS3Wb%2BlEnR%2BQSfroBjW%2FfGpl5eE6aH5s6FZmWrmyHnsLDLcG%2Bn82b4GzSlrsbxgpQo4LfyZ8qQ%3D%3D Jul 03 20:38:32 volumio.local volumio[938]: info: [1751575112525] [Pandora] appendTracksToMpd: Added 1 track(s) to mpd Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand addid Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand play Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces system playlist update Jul 03 20:38:32 volumio.local volumio[938]: info: Ignoring MPD Status Update Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand status took 869 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand addid took 9 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand play took 9 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::parseState Jul 03 20:38:32 volumio.local volumio[938]: info: [1751575112536] ControllerPandora::pushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::servicePushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreStateMachine::pushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::volumioPushState Jul 03 20:38:32 volumio.local volumio[938]: info: CorePlayQueue::getTrack 12 Jul 03 20:38:32 volumio.local volumio[938]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null,"trackType":"mp3"} Jul 03 20:38:32 volumio.local volumio[938]: verbose: CURRENT POSITION 12 Jul 03 20:38:32 volumio.local volumio[938]: info: CoreStateMachine::syncState stateService stop Jul 03 20:38:32 volumio.local volumio[938]: info: CoreStateMachine::syncState currentStatus stop Jul 03 20:38:32 volumio.local volumio[938]: info: CoreStateMachine::pushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::volumioPushState Jul 03 20:38:32 volumio.local volumio[938]: info: No code Jul 03 20:38:32 volumio.local volumio[938]: info: CoreStateMachine::pushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::volumioPushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreStateMachine::setConsumeUpdateService pandora Jul 03 20:38:32 volumio.local volumio[938]: verbose: MPD COMMAND [object Object] Jul 03 20:38:32 volumio.local volumio[938]: info: [1751575112542] ControllerPandora::pushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::servicePushState Jul 03 20:38:32 volumio.local volumio[938]: info: CorePlayQueue::getTrack 12 Jul 03 20:38:32 volumio.local volumio[938]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"Darn That Dream","name":"Darn That Dream","artist":"Bill Evans & Jim Hall","album":"Undercurrent","albumart":"http://mediaserver-cont-usc-mp1-1-v4v6.pandora.com/images/ee/03/5a/ba/d2f94612945bfad2329093d3/1080W_1080H.jpg","realUri":"http://t1-5.p-cdn.us/access/86256330960900526.mp3?version=5&lid=29031992&token=IdaNXBTVOaSMnVA%2BDIew91HzCAnWFTI3gZtZkvErxvG1ZRxMQaooXWIR4r%2FFDR35hh7PC9Cbg1ZQvc%2BH9DRzza84kUg%2FQW0uVATmxT%2BtFkxoD7LcX50CSrJybos5%2ByZVwghqejIoGgyzb%2BN8RPaAASWfD4%2FxNkOyDITmSYCim%2Fy7PSyM%2BnXjze2d%2Bqk52o1hh6ZnY3oeHGcwY00De8X30bV%2BKGETKx5T3RF3SibxBderwt8Mbdri9WXeEl0SjJjQs9%2FgRweI1%2FlwnS3Wb%2BlEnR%2BQSfroBjW%2FfGpl5eE6aH5s6FZmWrmyHnsLDLcG%2Bn82b4GzSlrsbxgpQo4LfyZ8qQ%3D%3D","isStreaming":true,"duration":310,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t1-5.p-cdn.us/access/86256330960900526.mp3?version=5&lid=29031992&token=IdaNXBTVOaSMnVA%2BDIew91HzCAnWFTI3gZtZkvErxvG1ZRxMQaooXWIR4r%2FFDR35hh7PC9Cbg1ZQvc%2BH9DRzza84kUg%2FQW0uVATmxT%2BtFkxoD7LcX50CSrJybos5%2ByZVwghqejIoGgyzb%2BN8RPaAASWfD4%2FxNkOyDITmSYCim%2Fy7PSyM%2BnXjze2d%2Bqk52o1hh6ZnY3oeHGcwY00De8X30bV%2BKGETKx5T3RF3SibxBderwt8Mbdri9WXeEl0SjJjQs9%2FgRweI1%2FlwnS3Wb%2BlEnR%2BQSfroBjW%2FfGpl5eE6aH5s6FZmWrmyHnsLDLcG%2Bn82b4GzSlrsbxgpQo4LfyZ8qQ%3D%3D","seek":0,"status":"play"} Jul 03 20:38:32 volumio.local volumio[938]: verbose: CURRENT POSITION 12 Jul 03 20:38:32 volumio.local volumio[938]: info: CoreStateMachine::syncState stateService play Jul 03 20:38:32 volumio.local volumio[938]: info: CoreStateMachine::syncState currentStatus stop Jul 03 20:38:32 volumio.local volumio[938]: info: CoreStateMachine::setConsumeUpdateService pandora Jul 03 20:38:32 volumio.local volumio[938]: info: [1751575112545] ControllerPandora::fetchAndAddTracks Jul 03 20:38:32 volumio.local volumio[938]: info: [1751575112546] ControllerPandora::getStationTracks Jul 03 20:38:32 volumio.local volumio[938]: info: CorePlayQueue::getQueue Jul 03 20:38:32 volumio.local volumio[938]: info: [1751575112546] [Pandora] PandoraHandler::getSongMaxDiff: -4 Jul 03 20:38:32 volumio.local volumio[938]: info: [1751575112546] ControllerPandora::fetchAndAddTracks::getSqInfo Jul 03 20:38:32 volumio.local volumio[938]: info: [1751575112546] ControllerPandora::getStationTracks Jul 03 20:38:32 volumio.local volumio[938]: info: CorePlayQueue::getQueue Jul 03 20:38:32 volumio.local volumio[938]: info: [1751575112547] [Pandora] fetchAndAddTracks: diff1: -4 sQPos1: 0 Jul 03 20:38:32 volumio.local volumio[938]: info: [1751575112547] [Pandora] fetchAndAddTracks: Fetching tracks Jul 03 20:38:32 volumio.local volumio[938]: info: [1751575112547] ControllerPandora::PandoraHandler::fillStationData Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces system playlist update Jul 03 20:38:32 volumio.local volumio[938]: info: Ignoring MPD Status Update Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces system playlist update Jul 03 20:38:32 volumio.local volumio[938]: info: Ignoring MPD Status Update Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces system playlist update Jul 03 20:38:32 volumio.local volumio[938]: info: Ignoring MPD Status Update Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces system playlist update Jul 03 20:38:32 volumio.local volumio[938]: info: Ignoring MPD Status Update Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces system playlist update Jul 03 20:38:32 volumio.local volumio[938]: info: Ignoring MPD Status Update Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces system playlist update Jul 03 20:38:32 volumio.local volumio[938]: info: Ignoring MPD Status Update Jul 03 20:38:32 volumio.local volumio[938]: info: ------------------------------ 24ms Jul 03 20:38:32 volumio.local volumio[938]: info: ------------------------------ 10ms Jul 03 20:38:32 volumio.local volumio[938]: info: ------------------------------ 9ms Jul 03 20:38:32 volumio.local volumio[938]: info: ------------------------------ 8ms Jul 03 20:38:32 volumio.local volumio[938]: info: ------------------------------ 7ms Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces system playlist update Jul 03 20:38:32 volumio.local volumio[938]: info: Ignoring MPD Status Update Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces system playlist update Jul 03 20:38:32 volumio.local volumio[938]: info: Ignoring MPD Status Update Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces system playlist update Jul 03 20:38:32 volumio.local volumio[938]: info: Ignoring MPD Status Update Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces system playlist update Jul 03 20:38:32 volumio.local volumio[938]: info: Ignoring MPD Status Update Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces system playlist update Jul 03 20:38:32 volumio.local volumio[938]: info: Ignoring MPD Status Update Jul 03 20:38:32 volumio.local volumio[938]: info: ------------------------------ 6ms Jul 03 20:38:32 volumio.local volumio[938]: info: ------------------------------ 4ms Jul 03 20:38:32 volumio.local volumio[938]: info: ------------------------------ 3ms Jul 03 20:38:32 volumio.local volumio[938]: info: [1751575112731] ControllerPandora::pandoraListener Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::getState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand status Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces state update: player Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::getState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand status Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces state update: player Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::getState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand status Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces state update: player Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::getState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand status Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces state update: player Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::getState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand status Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces state update: player Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::getState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand status Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces state update: player Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::getState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand status Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces state update: player Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand status took 23 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::getState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand status Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand status took 23 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand status took 23 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand status took 22 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand status took 21 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand status took 20 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand status took 19 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::parseState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::parseState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::parseState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::parseState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::parseState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::parseState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::parseState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces state update: player Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::getState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand status Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces state update: player Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::getState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand status Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces state update: player Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::getState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand status Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces state update: player Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::getState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand status Jul 03 20:38:32 volumio.local volumio[938]: info: Jul 03 20:38:32 volumio.local volumio[938]: ---------------------------- MPD announces state update: player Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::getState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand status Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand status took 12 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand status took 7 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::parseState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::parseTrackInfo Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::parseTrackInfo Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::parseTrackInfo Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::parseTrackInfo Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::parseTrackInfo Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::parseTrackInfo Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::parseTrackInfo Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::parseState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::parseState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 20:38:32 volumio.local volumio[938]: info: CorePlayQueue::getTrack 12 Jul 03 20:38:32 volumio.local volumio[938]: info: [1751575112771] ControllerPandora::pushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::servicePushState Jul 03 20:38:32 volumio.local volumio[938]: info: CorePlayQueue::getTrack 12 Jul 03 20:38:32 volumio.local volumio[938]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":310,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Darn That Dream","artist":"Bill Evans & Jim Hall","album":"Undercurrent","uri":"http://208.85.46.21/access/86256330960900526.mp3?version=5&lid=29031992&token=IdaNXBTVOaSMnVA%2BDIew91HzCAnWFTI3gZtZkvErxvG1ZRxMQaooXWIR4r%2FFDR35hh7PC9Cbg1ZQvc%2BH9DRzza84kUg%2FQW0uVATmxT%2BtFkxoD7LcX50CSrJybos5%2ByZVwghqejIoGgyzb%2BN8RPaAASWfD4%2FxNkOyDITmSYCim%2Fy7PSyM%2BnXjze2d%2Bqk52o1hh6ZnY3oeHGcwY00De8X30bV%2BKGETKx5T3RF3SibxBderwt8Mbdri9WXeEl0SjJjQs9%2FgRweI1%2FlwnS3Wb%2BlEnR%2BQSfroBjW%2FfGpl5eE6aH5s6FZmWrmyHnsLDLcG%2Bn82b4GzSlrsbxgpQo4LfyZ8qQ%3D%3D","trackType":"mp3"} Jul 03 20:38:32 volumio.local volumio[938]: verbose: CURRENT POSITION 12 Jul 03 20:38:32 volumio.local volumio[938]: info: CoreStateMachine::syncState stateService play Jul 03 20:38:32 volumio.local volumio[938]: info: CoreStateMachine::syncState currentStatus play Jul 03 20:38:32 volumio.local volumio[938]: info: Received an update from plugin. extracting info from payload Jul 03 20:38:32 volumio.local volumio[938]: info: CoreStateMachine::pushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::volumioPushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreStateMachine::pushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::volumioPushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreStateMachine::setConsumeUpdateService pandora Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::pushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::servicePushState Jul 03 20:38:32 volumio.local volumio[938]: info: CorePlayQueue::getTrack 12 Jul 03 20:38:32 volumio.local volumio[938]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::pushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::servicePushState Jul 03 20:38:32 volumio.local volumio[938]: info: CorePlayQueue::getTrack 12 Jul 03 20:38:32 volumio.local volumio[938]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::pushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::servicePushState Jul 03 20:38:32 volumio.local volumio[938]: info: CorePlayQueue::getTrack 12 Jul 03 20:38:32 volumio.local volumio[938]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::pushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::servicePushState Jul 03 20:38:32 volumio.local volumio[938]: info: CorePlayQueue::getTrack 12 Jul 03 20:38:32 volumio.local volumio[938]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::pushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::servicePushState Jul 03 20:38:32 volumio.local volumio[938]: info: CorePlayQueue::getTrack 12 Jul 03 20:38:32 volumio.local volumio[938]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::pushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::servicePushState Jul 03 20:38:32 volumio.local volumio[938]: info: CorePlayQueue::getTrack 12 Jul 03 20:38:32 volumio.local volumio[938]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 03 20:38:32 volumio.local volumio[938]: info: ------------------------------ 57ms Jul 03 20:38:32 volumio.local volumio[938]: info: ------------------------------ 56ms Jul 03 20:38:32 volumio.local volumio[938]: info: ------------------------------ 55ms Jul 03 20:38:32 volumio.local volumio[938]: info: ------------------------------ 54ms Jul 03 20:38:32 volumio.local volumio[938]: info: ------------------------------ 53ms Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand status took 28 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand status took 27 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand status took 25 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand playlistinfo took 23 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::parseState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::parseState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::parseState Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::parseTrackInfo Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::pushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::servicePushState Jul 03 20:38:32 volumio.local volumio[938]: info: CorePlayQueue::getTrack 12 Jul 03 20:38:32 volumio.local volumio[938]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 03 20:38:32 volumio.local volumio[938]: info: ------------------------------ 40ms Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand playlistinfo took 35 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand playlistinfo took 36 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 03 20:38:32 volumio.local volumio[938]: verbose: ControllerMpd::parseTrackInfo Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::pushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::servicePushState Jul 03 20:38:32 volumio.local volumio[938]: info: CorePlayQueue::getTrack 12 Jul 03 20:38:32 volumio.local volumio[938]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::pushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::servicePushState Jul 03 20:38:32 volumio.local volumio[938]: info: CorePlayQueue::getTrack 12 Jul 03 20:38:32 volumio.local volumio[938]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::pushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::servicePushState Jul 03 20:38:32 volumio.local volumio[938]: info: CorePlayQueue::getTrack 12 Jul 03 20:38:32 volumio.local volumio[938]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::pushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::servicePushState Jul 03 20:38:32 volumio.local volumio[938]: info: CorePlayQueue::getTrack 12 Jul 03 20:38:32 volumio.local volumio[938]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 03 20:38:32 volumio.local volumio[938]: info: ControllerMpd::pushState Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::servicePushState Jul 03 20:38:32 volumio.local volumio[938]: info: CorePlayQueue::getTrack 12 Jul 03 20:38:32 volumio.local volumio[938]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 03 20:38:32 volumio.local volumio[938]: info: ------------------------------ 49ms Jul 03 20:38:32 volumio.local volumio[938]: info: ------------------------------ 48ms Jul 03 20:38:32 volumio.local volumio[938]: info: ------------------------------ 46ms Jul 03 20:38:32 volumio.local volumio[938]: info: ------------------------------ 45ms Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::volumioGetQueue Jul 03 20:38:32 volumio.local volumio[938]: info: CoreStateMachine::getQueue Jul 03 20:38:32 volumio.local volumio[938]: info: CorePlayQueue::getQueue Jul 03 20:38:32 volumio.local volumio[938]: info: CoreCommandRouter::volumioGetQueue Jul 03 20:38:32 volumio.local volumio[938]: info: CoreStateMachine::getQueue Jul 03 20:38:32 volumio.local volumio[938]: info: CorePlayQueue::getQueue Jul 03 20:38:32 volumio.local volumio[938]: info: [1751575112957] ControllerPandora::PandoraHandler::fetchTracks::fetchStationPlaylist Jul 03 20:38:33 volumio.local volumio[938]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 20:38:33 volumio.local volumio[938]: SyntaxError: Unexpected token u in JSON at position 0 Jul 03 20:38:33 volumio.local volumio[938]: at JSON.parse () Jul 03 20:38:33 volumio.local volumio[938]: at Request._callback (/data/plugins/music_service/pandora/node_modules/anesidora/anesidora.js:41:31) Jul 03 20:38:33 volumio.local volumio[938]: at Request.self.callback (/data/plugins/music_service/pandora/node_modules/request/request.js:185:22) Jul 03 20:38:33 volumio.local volumio[938]: at Request.emit (events.js:315:20) Jul 03 20:38:33 volumio.local volumio[938]: at Request. (/data/plugins/music_service/pandora/node_modules/request/request.js:1154:10) Jul 03 20:38:33 volumio.local volumio[938]: at Request.emit (events.js:315:20) Jul 03 20:38:33 volumio.local volumio[938]: at IncomingMessage. (/data/plugins/music_service/pandora/node_modules/request/request.js:1076:12) Jul 03 20:38:33 volumio.local volumio[938]: at Object.onceWrapper (events.js:421:28) Jul 03 20:38:33 volumio.local volumio[938]: at IncomingMessage.emit (events.js:327:22) Jul 03 20:38:33 volumio.local volumio[938]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 03 20:38:33 volumio.local volumio[938]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 03 20:38:33 volumio.local volumio[938]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 20:38:33 volumio.local sudo[11581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-03 20:37 Jul 03 20:38:33 volumio.local sudo[11581]: 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="68644ecc3d95554759c26a672c9f9845659ebf49" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="d1eed292e757ec0ddcc4830aa293d402cdfd9c92" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 06 Mar 2023 10:42:18 AM CET" VOLUMIO_VERSION="3.435" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cb521a82390d29f21453532486094e99"