-- Logs begin at Tue 2025-05-20 10:45:53 UTC, end at Tue 2025-05-20 21:05:00 UTC. -- May 20 21:04:29 volumio wpa_supplicant[995]: wlan0: WPA: Group rekeying completed with 08:33:ed:8c:c6:7f [GTK=CCMP] May 20 21:04:43 volumio volumio[1122]: info: CorePlayQueue::getTrack 1 May 20 21:04:43 volumio volumio[1122]: info: CorePlayQueue::getTrack 2 May 20 21:04:43 volumio volumio[1122]: info: Prefetching next song May 20 21:04:44 volumio volumio[1122]: info: [1747775084021] ControllerPandora::pandoraListener May 20 21:04:44 volumio volumio[1122]: info: ControllerMpd::getState May 20 21:04:44 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 20 21:04:44 volumio volumio[1122]: info: May 20 21:04:44 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 20 21:04:44 volumio volumio[1122]: info: ControllerMpd::getState May 20 21:04:44 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 20 21:04:44 volumio volumio[1122]: info: sendMpdCommand status took 2 milliseconds May 20 21:04:44 volumio volumio[1122]: info: sendMpdCommand status took 1 milliseconds May 20 21:04:44 volumio volumio[1122]: verbose: ControllerMpd::parseState May 20 21:04:44 volumio volumio[1122]: verbose: ControllerMpd::parseState May 20 21:04:44 volumio volumio[1122]: info: CorePlayQueue::getTrack 1 May 20 21:04:44 volumio volumio[1122]: info: [1747775084026] ControllerPandora::pushState May 20 21:04:44 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 20 21:04:44 volumio volumio[1122]: info: CoreStateMachine::pushState May 20 21:04:44 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 21:04:44 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 20 21:04:44 volumio volumio[1122]: info: CorePlayQueue::getTrack 1 May 20 21:04:44 volumio volumio[1122]: 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"} May 20 21:04:44 volumio volumio[1122]: verbose: CURRENT POSITION 1 May 20 21:04:44 volumio volumio[1122]: info: CoreStateMachine::syncState stateService stop May 20 21:04:44 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 20 21:04:44 volumio volumio[1122]: info: CoreStateMachine::play index undefined May 20 21:04:44 volumio volumio[1122]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 21:04:44 volumio volumio[1122]: info: CoreStateMachine::pushState May 20 21:04:44 volumio volumio[1122]: info: CorePlayQueue::getTrack 2 May 20 21:04:44 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 21:04:44 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 20 21:04:44 volumio volumio[1122]: info: CoreStateMachine::setConsumeUpdateService pandora May 20 21:04:44 volumio volumio[1122]: info: ControllerMpd::pushState May 20 21:04:44 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 20 21:04:44 volumio volumio[1122]: info: CoreStateMachine::pushState May 20 21:04:44 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 21:04:44 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 20 21:04:44 volumio volumio[1122]: info: CorePlayQueue::getTrack 2 May 20 21:04:44 volumio volumio[1122]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd May 20 21:04:44 volumio volumio[1122]: info: CorePlayQueue::getTrack 2 May 20 21:04:44 volumio volumio[1122]: info: CoreStateMachine::startPlaybackTimer May 20 21:04:44 volumio volumio[1122]: info: CorePlayQueue::getTrack 2 May 20 21:04:44 volumio volumio[1122]: info: [1747775084039] ControllerPandora::clearAddPlayTrack May 20 21:04:44 volumio volumio[1122]: info: ControllerMpd::clear May 20 21:04:44 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand clear May 20 21:04:44 volumio volumio[1122]: info: ------------------------------ 19ms May 20 21:04:44 volumio volumio[1122]: info: CoreStateMachine::pushState May 20 21:04:44 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 21:04:44 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 20 21:04:44 volumio volumio[1122]: info: May 20 21:04:44 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 20 21:04:44 volumio volumio[1122]: info: Ignoring MPD Status Update May 20 21:04:44 volumio volumio[1122]: info: sendMpdCommand clear took 10 milliseconds May 20 21:04:44 volumio volumio[1122]: info: [1747775084049] ControllerPandora::checkForExpiredStations May 20 21:04:44 volumio volumio[1122]: info: [1747775084050] [Pandora] checkForExpiredStations: Stations expired May 20 21:04:44 volumio volumio[1122]: info: [1747775084050] ControllerPandora::PandoraHandler::fillStationData May 20 21:04:44 volumio volumio[1122]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 May 20 21:04:44 volumio volumio[1122]: SPOTIFY: SPOTIFY VOLUME 78 May 20 21:04:44 volumio volumio[1122]: SPOTIFY: VOLUMIO VOLUME 77 May 20 21:04:44 volumio volumio[1122]: SPOTIFY: DELTA VOLUME ENOUGH: false May 20 21:04:44 volumio volumio[1122]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 May 20 21:04:44 volumio volumio[1122]: SPOTIFY: SPOTIFY VOLUME 78 May 20 21:04:44 volumio volumio[1122]: SPOTIFY: VOLUMIO VOLUME 77 May 20 21:04:44 volumio volumio[1122]: SPOTIFY: DELTA VOLUME ENOUGH: false May 20 21:04:44 volumio volumio[1122]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 May 20 21:04:44 volumio volumio[1122]: SPOTIFY: SPOTIFY VOLUME 78 May 20 21:04:44 volumio volumio[1122]: SPOTIFY: VOLUMIO VOLUME 77 May 20 21:04:44 volumio volumio[1122]: SPOTIFY: DELTA VOLUME ENOUGH: false May 20 21:04:44 volumio volumio[1122]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 May 20 21:04:44 volumio volumio[1122]: SPOTIFY: SPOTIFY VOLUME 78 May 20 21:04:44 volumio volumio[1122]: SPOTIFY: VOLUMIO VOLUME 77 May 20 21:04:44 volumio volumio[1122]: SPOTIFY: DELTA VOLUME ENOUGH: false May 20 21:04:44 volumio volumio[1122]: info: CoreCommandRouter::volumioGetQueue May 20 21:04:44 volumio volumio[1122]: info: CoreStateMachine::getQueue May 20 21:04:44 volumio volumio[1122]: info: CorePlayQueue::getQueue May 20 21:04:44 volumio volumio[1122]: error: updateQueue error: null May 20 21:04:44 volumio volumio[1122]: info: ------------------------------ 23ms May 20 21:04:44 volumio volumio[1122]: info: CorePlayQueue::getQueue May 20 21:04:44 volumio volumio[1122]: info: [1747775084952] ControllerPandora::removeTrack May 20 21:04:44 volumio volumio[1122]: info: CoreStateMachine::removeQueueItem May 20 21:04:44 volumio volumio[1122]: info: CorePlayQueue::removeQueueItem 1 May 20 21:04:44 volumio volumio[1122]: info: CorePlayQueue::saveQueue May 20 21:04:44 volumio volumio[1122]: info: CoreCommandRouter::volumioPushQueue May 20 21:04:44 volumio volumio[1122]: info: [1747775084954] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=179372130079613113trackId=1674869457061006586 at queue index: 1 May 20 21:04:44 volumio volumio[1122]: info: ControllerMpd::getState May 20 21:04:44 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 20 21:04:44 volumio volumio[1122]: info: [1747775084959] ControllerPandora::appendTracksToMpd May 20 21:04:45 volumio volumio[1122]: info: [1747775085597] [Pandora] appendTracksToMpd::resolveTrackUri: http://t1-5.p-cdn.us/access/1078183930163851027.mp3?version=5&lid=329494207&token=b077Gn5RJaVsZgI13U3kc87VoMoULiG3jKkg93URG4kGuGyPx%2FGtK6tR1o9d3%2FzM3qih%2F3FJwBHT0t%2FH9rjKKMGUaiTV3MSwrqbdTKMhOgyy8Gmf6xsE7vXsoAUIjDAKDEFJy1O6g0RfsFCRUD5at5lG75zH25SDIoOWzVa4Uv%2FOH%2BxieFEG1USjvqLffEKJhNT44CyWEu2Qnoh8%2B%2Fd1yW5PQOguttkm%2BVI7eJzABZ1t%2BrRvCI1cBv%2BDO71v6mhLrItDRYiQGM43pzsnBmENW26NhVLeS9t4XyQYcTT6rZSGERsBLsqiyQCEgSwXmehnuz0EqPFGK2Hgh9TYTZHbXQ%3D%3D => http://208.85.42.22/access/1078183930163851027.mp3?version=5&lid=329494207&token=b077Gn5RJaVsZgI13U3kc87VoMoULiG3jKkg93URG4kGuGyPx%2FGtK6tR1o9d3%2FzM3qih%2F3FJwBHT0t%2FH9rjKKMGUaiTV3MSwrqbdTKMhOgyy8Gmf6xsE7vXsoAUIjDAKDEFJy1O6g0RfsFCRUD5at5lG75zH25SDIoOWzVa4Uv%2FOH%2BxieFEG1USjvqLffEKJhNT44CyWEu2Qnoh8%2B%2Fd1yW5PQOguttkm%2BVI7eJzABZ1t%2BrRvCI1cBv%2BDO71v6mhLrItDRYiQGM43pzsnBmENW26NhVLeS9t4XyQYcTT6rZSGERsBLsqiyQCEgSwXmehnuz0EqPFGK2Hgh9TYTZHbXQ%3D%3D May 20 21:04:45 volumio volumio[1122]: info: [1747775085597] [Pandora] appendTracksToMpd: Added 1 track(s) to mpd May 20 21:04:45 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand addid May 20 21:04:45 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand play May 20 21:04:45 volumio volumio[1122]: info: May 20 21:04:45 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 20 21:04:45 volumio volumio[1122]: info: Ignoring MPD Status Update May 20 21:04:45 volumio volumio[1122]: info: sendMpdCommand status took 682 milliseconds May 20 21:04:45 volumio volumio[1122]: info: sendMpdCommand addid took 40 milliseconds May 20 21:04:45 volumio volumio[1122]: info: sendMpdCommand play took 40 milliseconds May 20 21:04:45 volumio volumio[1122]: verbose: ControllerMpd::parseState May 20 21:04:45 volumio volumio[1122]: info: [1747775085638] ControllerPandora::pushState May 20 21:04:45 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 20 21:04:45 volumio volumio[1122]: info: CoreStateMachine::pushState May 20 21:04:45 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 21:04:45 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 20 21:04:45 volumio volumio[1122]: info: CorePlayQueue::getTrack 1 May 20 21:04:45 volumio volumio[1122]: 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"} May 20 21:04:45 volumio volumio[1122]: verbose: CURRENT POSITION 1 May 20 21:04:45 volumio volumio[1122]: info: CoreStateMachine::syncState stateService stop May 20 21:04:45 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus stop May 20 21:04:45 volumio volumio[1122]: info: CoreStateMachine::pushState May 20 21:04:45 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 20 21:04:45 volumio volumio[1122]: info: No code May 20 21:04:45 volumio volumio[1122]: info: CoreStateMachine::pushState May 20 21:04:45 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 20 21:04:45 volumio volumio[1122]: info: CoreStateMachine::setConsumeUpdateService pandora May 20 21:04:45 volumio volumio[1122]: verbose: MPD COMMAND [object Object] May 20 21:04:45 volumio volumio[1122]: info: [1747775085642] ControllerPandora::pushState May 20 21:04:45 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 20 21:04:45 volumio volumio[1122]: info: CorePlayQueue::getTrack 1 May 20 21:04:45 volumio volumio[1122]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"I Will Wait","name":"I Will Wait","artist":"Mumford & Sons","album":"Babel (Deluxe Version)","albumart":"http://mediaserver-cont-dc6-2-v4v6.pandora.com/images/4c/52/7f/52/22734f578d9389dfecc35cee/1080W_1080H.jpg","realUri":"http://t1-5.p-cdn.us/access/1078183930163851027.mp3?version=5&lid=329494207&token=b077Gn5RJaVsZgI13U3kc87VoMoULiG3jKkg93URG4kGuGyPx%2FGtK6tR1o9d3%2FzM3qih%2F3FJwBHT0t%2FH9rjKKMGUaiTV3MSwrqbdTKMhOgyy8Gmf6xsE7vXsoAUIjDAKDEFJy1O6g0RfsFCRUD5at5lG75zH25SDIoOWzVa4Uv%2FOH%2BxieFEG1USjvqLffEKJhNT44CyWEu2Qnoh8%2B%2Fd1yW5PQOguttkm%2BVI7eJzABZ1t%2BrRvCI1cBv%2BDO71v6mhLrItDRYiQGM43pzsnBmENW26NhVLeS9t4XyQYcTT6rZSGERsBLsqiyQCEgSwXmehnuz0EqPFGK2Hgh9TYTZHbXQ%3D%3D","isStreaming":true,"duration":277,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t1-5.p-cdn.us/access/1078183930163851027.mp3?version=5&lid=329494207&token=b077Gn5RJaVsZgI13U3kc87VoMoULiG3jKkg93URG4kGuGyPx%2FGtK6tR1o9d3%2FzM3qih%2F3FJwBHT0t%2FH9rjKKMGUaiTV3MSwrqbdTKMhOgyy8Gmf6xsE7vXsoAUIjDAKDEFJy1O6g0RfsFCRUD5at5lG75zH25SDIoOWzVa4Uv%2FOH%2BxieFEG1USjvqLffEKJhNT44CyWEu2Qnoh8%2B%2Fd1yW5PQOguttkm%2BVI7eJzABZ1t%2BrRvCI1cBv%2BDO71v6mhLrItDRYiQGM43pzsnBmENW26NhVLeS9t4XyQYcTT6rZSGERsBLsqiyQCEgSwXmehnuz0EqPFGK2Hgh9TYTZHbXQ%3D%3D","seek":0,"status":"play"} May 20 21:04:45 volumio volumio[1122]: verbose: CURRENT POSITION 1 May 20 21:04:45 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 20 21:04:45 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus stop May 20 21:04:45 volumio volumio[1122]: info: CoreStateMachine::setConsumeUpdateService pandora May 20 21:04:45 volumio volumio[1122]: info: [1747775085646] ControllerPandora::fetchAndAddTracks May 20 21:04:45 volumio volumio[1122]: info: [1747775085646] ControllerPandora::getStationTracks May 20 21:04:45 volumio volumio[1122]: info: CorePlayQueue::getQueue May 20 21:04:45 volumio volumio[1122]: info: [1747775085647] [Pandora] PandoraHandler::getSongMaxDiff: -1 May 20 21:04:45 volumio volumio[1122]: info: [1747775085647] ControllerPandora::fetchAndAddTracks::getSqInfo May 20 21:04:45 volumio volumio[1122]: info: [1747775085647] ControllerPandora::getStationTracks May 20 21:04:45 volumio volumio[1122]: info: CorePlayQueue::getQueue May 20 21:04:45 volumio volumio[1122]: info: CorePlayQueue::getQueue May 20 21:04:45 volumio volumio[1122]: info: [1747775085647] [Pandora] fetchAndAddTracks: diff1: -1 sQPos1: 0 May 20 21:04:45 volumio volumio[1122]: info: [1747775085647] [Pandora] fetchAndAddTracks: Fetching tracks May 20 21:04:45 volumio volumio[1122]: info: [1747775085647] ControllerPandora::PandoraHandler::fillStationData May 20 21:04:45 volumio volumio[1122]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 May 20 21:04:45 volumio volumio[1122]: SPOTIFY: SPOTIFY VOLUME 78 May 20 21:04:45 volumio volumio[1122]: SPOTIFY: VOLUMIO VOLUME 77 May 20 21:04:45 volumio volumio[1122]: SPOTIFY: DELTA VOLUME ENOUGH: false May 20 21:04:45 volumio volumio[1122]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 May 20 21:04:45 volumio volumio[1122]: SPOTIFY: SPOTIFY VOLUME 78 May 20 21:04:45 volumio volumio[1122]: SPOTIFY: VOLUMIO VOLUME 77 May 20 21:04:45 volumio volumio[1122]: SPOTIFY: DELTA VOLUME ENOUGH: false May 20 21:04:45 volumio volumio[1122]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 May 20 21:04:45 volumio volumio[1122]: SPOTIFY: SPOTIFY VOLUME 78 May 20 21:04:45 volumio volumio[1122]: SPOTIFY: VOLUMIO VOLUME 77 May 20 21:04:45 volumio volumio[1122]: SPOTIFY: DELTA VOLUME ENOUGH: false May 20 21:04:45 volumio volumio[1122]: info: May 20 21:04:45 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 20 21:04:45 volumio volumio[1122]: info: Ignoring MPD Status Update May 20 21:04:45 volumio volumio[1122]: info: ------------------------------ 17ms May 20 21:04:45 volumio volumio[1122]: info: ------------------------------ 1ms May 20 21:04:45 volumio volumio[1122]: info: [1747775085801] ControllerPandora::pandoraListener May 20 21:04:45 volumio volumio[1122]: info: ControllerMpd::getState May 20 21:04:45 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 20 21:04:45 volumio volumio[1122]: info: May 20 21:04:45 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 20 21:04:45 volumio volumio[1122]: info: ControllerMpd::getState May 20 21:04:45 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 20 21:04:46 volumio volumio[1122]: info: May 20 21:04:46 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 20 21:04:46 volumio volumio[1122]: info: sendMpdCommand status took 219 milliseconds May 20 21:04:46 volumio volumio[1122]: info: sendMpdCommand status took 218 milliseconds May 20 21:04:46 volumio volumio[1122]: info: ControllerMpd::getState May 20 21:04:46 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 20 21:04:46 volumio volumio[1122]: verbose: ControllerMpd::parseState May 20 21:04:46 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 21:04:46 volumio volumio[1122]: verbose: ControllerMpd::parseState May 20 21:04:46 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 21:04:46 volumio volumio[1122]: info: sendMpdCommand status took 3 milliseconds May 20 21:04:46 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 3 milliseconds May 20 21:04:46 volumio volumio[1122]: verbose: ControllerMpd::parseState May 20 21:04:46 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 21:04:46 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 20 21:04:46 volumio volumio[1122]: info: CorePlayQueue::getTrack 1 May 20 21:04:46 volumio volumio[1122]: info: [1747775086029] ControllerPandora::pushState May 20 21:04:46 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 20 21:04:46 volumio volumio[1122]: info: CorePlayQueue::getTrack 1 May 20 21:04:46 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":277,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"I Will Wait","artist":"Mumford & Sons","album":"Babel (Deluxe Version)","uri":"http://208.85.42.22/access/1078183930163851027.mp3?version=5&lid=329494207&token=b077Gn5RJaVsZgI13U3kc87VoMoULiG3jKkg93URG4kGuGyPx%2FGtK6tR1o9d3%2FzM3qih%2F3FJwBHT0t%2FH9rjKKMGUaiTV3MSwrqbdTKMhOgyy8Gmf6xsE7vXsoAUIjDAKDEFJy1O6g0RfsFCRUD5at5lG75zH25SDIoOWzVa4Uv%2FOH%2BxieFEG1USjvqLffEKJhNT44CyWEu2Qnoh8%2B%2Fd1yW5PQOguttkm%2BVI7eJzABZ1t%2BrRvCI1cBv%2BDO71v6mhLrItDRYiQGM43pzsnBmENW26NhVLeS9t4XyQYcTT6rZSGERsBLsqiyQCEgSwXmehnuz0EqPFGK2Hgh9TYTZHbXQ%3D%3D","trackType":"mp3"} May 20 21:04:46 volumio volumio[1122]: verbose: CURRENT POSITION 1 May 20 21:04:46 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 20 21:04:46 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 20 21:04:46 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 20 21:04:46 volumio volumio[1122]: info: CoreStateMachine::pushState May 20 21:04:46 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 21:04:46 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 20 21:04:46 volumio volumio[1122]: info: CoreStateMachine::pushState May 20 21:04:46 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 20 21:04:46 volumio volumio[1122]: info: CoreStateMachine::setConsumeUpdateService pandora May 20 21:04:46 volumio volumio[1122]: info: ControllerMpd::pushState May 20 21:04:46 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 20 21:04:46 volumio volumio[1122]: info: CorePlayQueue::getTrack 1 May 20 21:04:46 volumio volumio[1122]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd May 20 21:04:46 volumio volumio[1122]: info: ------------------------------ 234ms May 20 21:04:46 volumio volumio[1122]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 May 20 21:04:46 volumio volumio[1122]: SPOTIFY: SPOTIFY VOLUME 78 May 20 21:04:46 volumio volumio[1122]: SPOTIFY: VOLUMIO VOLUME 77 May 20 21:04:46 volumio volumio[1122]: SPOTIFY: DELTA VOLUME ENOUGH: false May 20 21:04:46 volumio volumio[1122]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 May 20 21:04:46 volumio volumio[1122]: SPOTIFY: SPOTIFY VOLUME 78 May 20 21:04:46 volumio volumio[1122]: SPOTIFY: VOLUMIO VOLUME 77 May 20 21:04:46 volumio volumio[1122]: SPOTIFY: DELTA VOLUME ENOUGH: false May 20 21:04:46 volumio volumio[1122]: info: CoreCommandRouter::volumioGetQueue May 20 21:04:46 volumio volumio[1122]: info: CoreStateMachine::getQueue May 20 21:04:46 volumio volumio[1122]: info: CorePlayQueue::getQueue May 20 21:04:46 volumio volumio[1122]: info: CoreCommandRouter::volumioGetQueue May 20 21:04:46 volumio volumio[1122]: info: CoreStateMachine::getQueue May 20 21:04:46 volumio volumio[1122]: info: CorePlayQueue::getQueue May 20 21:04:46 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 25 milliseconds May 20 21:04:46 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 20 21:04:46 volumio volumio[1122]: info: ControllerMpd::pushState May 20 21:04:46 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 20 21:04:46 volumio volumio[1122]: info: CorePlayQueue::getTrack 1 May 20 21:04:46 volumio volumio[1122]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd May 20 21:04:46 volumio volumio[1122]: info: ------------------------------ 34ms May 20 21:05:00 volumio volumio[1122]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 21:05:00 volumio volumio[1122]: SyntaxError: Unexpected end of JSON input May 20 21:05:00 volumio volumio[1122]: at JSON.parse () May 20 21:05:00 volumio volumio[1122]: at Request._callback (/data/plugins/music_service/pandora/node_modules/anesidora/anesidora.js:41:31) May 20 21:05:00 volumio volumio[1122]: at Request.self.callback (/data/plugins/music_service/pandora/node_modules/request/request.js:185:22) May 20 21:05:00 volumio volumio[1122]: at Request.emit (events.js:400:28) May 20 21:05:00 volumio volumio[1122]: at Request. (/data/plugins/music_service/pandora/node_modules/request/request.js:1154:10) May 20 21:05:00 volumio volumio[1122]: at Request.emit (events.js:400:28) May 20 21:05:00 volumio volumio[1122]: at IncomingMessage. (/data/plugins/music_service/pandora/node_modules/request/request.js:1076:12) May 20 21:05:00 volumio volumio[1122]: at Object.onceWrapper (events.js:519:28) May 20 21:05:00 volumio volumio[1122]: at IncomingMessage.emit (events.js:412:35) May 20 21:05:00 volumio volumio[1122]: at endReadableNT (internal/streams/readable.js:1333:12) May 20 21:05:00 volumio volumio[1122]: at processTicksAndRejections (internal/process/task_queues.js:82:21) May 20 21:05:00 volumio volumio[1122]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 21:05:00 volumio sudo[27925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-20 21:04 May 20 21:05:00 volumio sudo[27925]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:53:51 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="53825f995260683aee752aed6b29f24e"