-- Logs begin at Mon 2026-06-01 14:50:17 UTC, end at Mon 2026-06-01 23:29:20 UTC. -- Jun 01 23:28:01 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:01 volumio volumio[17389]: info: [1780356481500] ControllerPandora::removeTrack Jun 01 23:28:01 volumio volumio[17389]: info: [1780356481500] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=4502368651449014492trackId=8065454328758002159 at queue index: 6 Jun 01 23:28:11 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:11 volumio volumio[17389]: info: [1780356491500] ControllerPandora::removeTrack Jun 01 23:28:11 volumio volumio[17389]: info: [1780356491500] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=4502368651449014492trackId=4974388867939118187 at queue index: 7 Jun 01 23:28:21 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:21 volumio volumio[17389]: info: [1780356501500] ControllerPandora::removeTrack Jun 01 23:28:21 volumio volumio[17389]: info: [1780356501500] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=4502368651449014492trackId=8386266618714009497 at queue index: 8 Jun 01 23:28:31 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:31 volumio volumio[17389]: info: [1780356511500] ControllerPandora::removeTrack Jun 01 23:28:31 volumio volumio[17389]: info: [1780356511500] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=4502368651449014492trackId=6293204190004407287 at queue index: 9 Jun 01 23:28:41 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:41 volumio volumio[17389]: info: [1780356521500] ControllerPandora::removeTrack Jun 01 23:28:41 volumio volumio[17389]: info: [1780356521500] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=4502368651449014492trackId=98338582255081922 at queue index: 10 Jun 01 23:28:51 volumio volumio[17389]: verbose: New Socket.io Connection to 192.168.4.38 from 192.168.4.41 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Total Clients: 4 Jun 01 23:28:51 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 01 23:28:51 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 01 23:28:51 volumio volumio[17389]: info: CoreCommandRouter::volumioGetVisibleSources Jun 01 23:28:51 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 23:28:51 volumio volumio[17389]: info: CoreCommandRouter::volumioGetState Jun 01 23:28:51 volumio volumio[17389]: info: CoreCommandRouter::volumioGetState Jun 01 23:28:51 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 01 23:28:51 volumio volumio[17389]: info: Received Get System Info Jun 01 23:28:51 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 23:28:51 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 23:28:51 volumio volumio[17389]: info: Discovery: Getting this device information Jun 01 23:28:51 volumio volumio[17389]: info: CoreCommandRouter::volumioGetState Jun 01 23:28:51 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 23:28:51 volumio volumio[17389]: info: CoreCommandRouter::volumioGetState Jun 01 23:28:51 volumio volumio[17389]: info: Listing playlists Jun 01 23:28:51 volumio volumio[17389]: info: CoreCommandRouter::volumioGetQueue Jun 01 23:28:51 volumio volumio[17389]: info: CoreStateMachine::getQueue Jun 01 23:28:51 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:51 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 01 23:28:51 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:51 volumio volumio[17389]: info: [1780356531500] ControllerPandora::removeTrack Jun 01 23:28:51 volumio volumio[17389]: info: [1780356531500] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=4502368651449014492trackId=3250778692161411560 at queue index: 11 Jun 01 23:28:56 volumio volumio[17389]: info: CoreCommandRouter::volumioPlay Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::play index 18 Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::stop Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::stPlaybackTimer Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::updateTrackBlock Jun 01 23:28:56 volumio volumio[17389]: info: CorePlayQueue::getTrackBlock Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:28:56 volumio volumio[17389]: info: CorePlayQueue::getTrack 0 Jun 01 23:28:56 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:28:56 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::serviceStop Jun 01 23:28:56 volumio volumio[17389]: info: CorePlayQueue::getTrack 0 Jun 01 23:28:56 volumio volumio[17389]: info: CoreCommandRouter::serviceStop Jun 01 23:28:56 volumio volumio[17389]: info: [1780356536701] ControllerPandora::stop Jun 01 23:28:56 volumio volumio[17389]: info: ControllerMpd::stop Jun 01 23:28:56 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 23:28:56 volumio volumio[17389]: info: Jun 01 23:28:56 volumio volumio[17389]: ---------------------------- MPD announces state update: player Jun 01 23:28:56 volumio volumio[17389]: info: sendMpdCommand stop took 19 milliseconds Jun 01 23:28:56 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:28:56 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:28:56 volumio volumio[17389]: info: ControllerMpd::clear Jun 01 23:28:56 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 23:28:56 volumio volumio[17389]: info: Jun 01 23:28:56 volumio volumio[17389]: ---------------------------- MPD announces system playlist update Jun 01 23:28:56 volumio volumio[17389]: info: Ignoring MPD Status Update Jun 01 23:28:56 volumio volumio[17389]: info: sendMpdCommand status took 2 milliseconds Jun 01 23:28:56 volumio volumio[17389]: info: sendMpdCommand clear took 2 milliseconds Jun 01 23:28:56 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:28:56 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 23:28:56 volumio volumio[17389]: info: [1780356536723] ControllerPandora::pushState Jun 01 23:28:56 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:28:56 volumio volumio[17389]: info: CorePlayQueue::getTrack 0 Jun 01 23:28:56 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:28:56 volumio volumio[17389]: info: CorePlayQueue::getTrack 0 Jun 01 23:28:56 volumio volumio[17389]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"Skyfall","name":"Skyfall","artist":"Adele","album":"Skyfall","albumart":"http://mediaserver-cont-dc6-1-v4v6.pandora.com/images/2d/37/dc/7b/3d0f48a9a5f8122d643eac57/1080W_1080H.jpg","realUri":"http://t1-3.p-cdn.us/access/5547969720176834184.mp3?version=5&lid=360257756&token=eRkuKBVO4gh4eH7mCY3hy2skWXrNsKboQ%2FpD6HSGHbXA7zv3UDeco5kRHjhERQU3SbrEpPvNv55%2BFxxq6P82JXxIBHPuuLpTis1RXMwbjORgF6TCmOP20J%2BWZp4fK9gbFDn9C6H2Mz9X3j9FHqsRyFBNckyF%2BrPZqcafNrZ26YLiD2YNvM%2BDngbAZi6S9LzkdHserp5I3AOtZuXv%2BC49o5%2B%2BcF40jP0B%2Bx0tmkQ6x96y721s5xlqjfXlb3IGZirtGLKTKe1v92TamnFIerp3V0CaoPSI0mhI%2FfEV1GLSVA7UX7lLI9nRvlDUPIBpuK34zpxperk5jmUF5%2BuJbgZ0PbtCsoPGQ%2BK6","isStreaming":true,"duration":286,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t1-3.p-cdn.us/access/5547969720176834184.mp3?version=5&lid=360257756&token=eRkuKBVO4gh4eH7mCY3hy2skWXrNsKboQ%2FpD6HSGHbXA7zv3UDeco5kRHjhERQU3SbrEpPvNv55%2BFxxq6P82JXxIBHPuuLpTis1RXMwbjORgF6TCmOP20J%2BWZp4fK9gbFDn9C6H2Mz9X3j9FHqsRyFBNckyF%2BrPZqcafNrZ26YLiD2YNvM%2BDngbAZi6S9LzkdHserp5I3AOtZuXv%2BC49o5%2B%2BcF40jP0B%2Bx0tmkQ6x96y721s5xlqjfXlb3IGZirtGLKTKe1v92TamnFIerp3V0CaoPSI0mhI%2FfEV1GLSVA7UX7lLI9nRvlDUPIBpuK34zpxperk5jmUF5%2BuJbgZ0PbtCsoPGQ%2BK6","seek":0,"status":"stop"} Jun 01 23:28:56 volumio volumio[17389]: verbose: CURRENT POSITION 0 Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::syncState stateService stop Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::syncState currentStatus stop Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:28:56 volumio volumio[17389]: info: CorePlayQueue::getTrack 0 Jun 01 23:28:56 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:28:56 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:28:56 volumio volumio[17389]: info: No code Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:28:56 volumio volumio[17389]: info: CorePlayQueue::getTrack 0 Jun 01 23:28:56 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService pandora Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::play index undefined Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 23:28:56 volumio volumio[17389]: info: CorePlayQueue::getTrack 18 Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::startPlaybackTimer Jun 01 23:28:56 volumio volumio[17389]: info: CorePlayQueue::getTrack 18 Jun 01 23:28:56 volumio volumio[17389]: info: [1780356536737] ControllerPandora::clearAddPlayTrack Jun 01 23:28:56 volumio volumio[17389]: info: ControllerMpd::clear Jun 01 23:28:56 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 23:28:56 volumio volumio[17389]: error: updateQueue error: null Jun 01 23:28:56 volumio volumio[17389]: info: Jun 01 23:28:56 volumio volumio[17389]: ---------------------------- MPD announces system playlist update Jun 01 23:28:56 volumio volumio[17389]: info: Ignoring MPD Status Update Jun 01 23:28:56 volumio volumio[17389]: info: ------------------------------ 18ms Jun 01 23:28:56 volumio volumio[17389]: info: sendMpdCommand playlistinfo took 17 milliseconds Jun 01 23:28:56 volumio volumio[17389]: info: sendMpdCommand clear took 2 milliseconds Jun 01 23:28:56 volumio volumio[17389]: verbose: ControllerMpd::parseTrackInfo Jun 01 23:28:56 volumio volumio[17389]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 01 23:28:56 volumio volumio[17389]: info: [1780356536741] ControllerPandora::checkForExpiredStations Jun 01 23:28:56 volumio volumio[17389]: info: ------------------------------ 22ms Jun 01 23:28:56 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:56 volumio volumio[17389]: info: [1780356536741] ControllerPandora::removeTrack Jun 01 23:28:56 volumio volumio[17389]: info: [1780356536741] [Pandora] removeTrack: Not removing track with uri: null at queue index: -1 Jun 01 23:28:56 volumio volumio[17389]: info: [1780356536741] ControllerPandora::appendTracksToMpd Jun 01 23:28:56 volumio volumio[17389]: info: [1780356536741] [Pandora] appendTracksToMpd: Added 1 track(s) to mpd Jun 01 23:28:56 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand addid Jun 01 23:28:56 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand play Jun 01 23:28:56 volumio volumio[17389]: error: updateQueue error: null Jun 01 23:28:56 volumio volumio[17389]: info: Jun 01 23:28:56 volumio volumio[17389]: ---------------------------- MPD announces system playlist update Jun 01 23:28:56 volumio volumio[17389]: info: Ignoring MPD Status Update Jun 01 23:28:56 volumio volumio[17389]: info: ------------------------------ 7ms Jun 01 23:28:56 volumio volumio[17389]: info: sendMpdCommand addid took 5 milliseconds Jun 01 23:28:56 volumio volumio[17389]: info: sendMpdCommand play took 5 milliseconds Jun 01 23:28:56 volumio volumio[17389]: verbose: MPD COMMAND [object Object] Jun 01 23:28:56 volumio volumio[17389]: verbose: MPD COMMAND [object Object] Jun 01 23:28:56 volumio volumio[17389]: info: [1780356536748] ControllerPandora::pushState Jun 01 23:28:56 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:28:56 volumio volumio[17389]: info: CorePlayQueue::getTrack 18 Jun 01 23:28:56 volumio volumio[17389]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"I Think We're Alone Now (80s Flashback Dance Fitness)","name":"I Think We're Alone Now (80s Flashback Dance Fitness)","artist":"Tiffany","album":"Body By Jake: 80s Flashback Dance Fitness (BPM 100-164)","albumart":"http://cont-4.p-cdn.us/images/a0/06/89/81/0fd94d0d954385a38d92b70c/1080W_1080H.jpg","realUri":"http://audio-dc6-t1-1-v4v6.pandora.com/access/8678293103330173767.mp3?version=5&lid=360257756&token=6sDG9SHprfNWMo3addOEFrdlzkT%2B5tG2tVzuGkYJxAOYEwzLRlMYMi3n0OOJaqsST8wg4ABEfWHHr17TSOA5h8IwvEFWkSwMYzsF2NNCOUGN5NsjDFL9ZVjY3bf9udq44M8y%2FpWrIMed7uCne8VHS1dZFtdl%2B2bxAqjNoTuHTTz5spoDXdNxpEOABit%2FxCW0du%2BDwUDGljqoh1r%2FruACUBMXhFyJpbJ0A1nJB9siC3Swd3s3NdzBN8ub5MoctFKShHtE0G2fUm0QRmnIxvECNd0OHPW%2BlJK0ckYXRLAZkfXNgFAVYZAkIj58dRhGgO6IV5e1%2BAS0YYdmBNEv9iKl%2FfID7VTyyPZT","isStreaming":true,"duration":193,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://audio-dc6-t1-1-v4v6.pandora.com/access/8678293103330173767.mp3?version=5&lid=360257756&token=6sDG9SHprfNWMo3addOEFrdlzkT%2B5tG2tVzuGkYJxAOYEwzLRlMYMi3n0OOJaqsST8wg4ABEfWHHr17TSOA5h8IwvEFWkSwMYzsF2NNCOUGN5NsjDFL9ZVjY3bf9udq44M8y%2FpWrIMed7uCne8VHS1dZFtdl%2B2bxAqjNoTuHTTz5spoDXdNxpEOABit%2FxCW0du%2BDwUDGljqoh1r%2FruACUBMXhFyJpbJ0A1nJB9siC3Swd3s3NdzBN8ub5MoctFKShHtE0G2fUm0QRmnIxvECNd0OHPW%2BlJK0ckYXRLAZkfXNgFAVYZAkIj58dRhGgO6IV5e1%2BAS0YYdmBNEv9iKl%2FfID7VTyyPZT","seek":0,"status":"play"} Jun 01 23:28:56 volumio volumio[17389]: verbose: CURRENT POSITION 18 Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::syncState stateService play Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::syncState currentStatus stop Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService pandora Jun 01 23:28:56 volumio volumio[17389]: info: [1780356536749] ControllerPandora::fetchAndAddTracks Jun 01 23:28:56 volumio volumio[17389]: info: [1780356536749] ControllerPandora::getStationTracks Jun 01 23:28:56 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:56 volumio volumio[17389]: info: [1780356536749] [Pandora] PandoraHandler::getSongMaxDiff: 3 Jun 01 23:28:56 volumio volumio[17389]: info: [1780356536749] ControllerPandora::fetchAndAddTracks::getSqInfo Jun 01 23:28:56 volumio volumio[17389]: info: [1780356536749] ControllerPandora::getStationTracks Jun 01 23:28:56 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:56 volumio volumio[17389]: info: [1780356536750] [Pandora] fetchAndAddTracks: diff1: 3 sQPos1: 18 Jun 01 23:28:56 volumio volumio[17389]: info: [1780356536750] [Pandora] fetchAndAddTracks: Fetching tracks Jun 01 23:28:56 volumio volumio[17389]: info: [1780356536750] ControllerPandora::PandoraHandler::fillStationData Jun 01 23:28:56 volumio volumio[17389]: info: Jun 01 23:28:56 volumio volumio[17389]: ---------------------------- MPD announces system playlist update Jun 01 23:28:56 volumio volumio[17389]: info: Ignoring MPD Status Update Jun 01 23:28:56 volumio volumio[17389]: info: ------------------------------ 10ms Jun 01 23:28:56 volumio volumio[17389]: info: ------------------------------ 2ms Jun 01 23:28:56 volumio volumio[17389]: info: [1780356536791] ControllerPandora::pandoraListener Jun 01 23:28:56 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:28:56 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:28:56 volumio volumio[17389]: info: Jun 01 23:28:56 volumio volumio[17389]: ---------------------------- MPD announces state update: player Jun 01 23:28:56 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:28:56 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:28:56 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jun 01 23:28:56 volumio volumio[17389]: info: Jun 01 23:28:56 volumio volumio[17389]: ---------------------------- MPD announces state update: player Jun 01 23:28:56 volumio volumio[17389]: info: sendMpdCommand status took 17 milliseconds Jun 01 23:28:56 volumio volumio[17389]: info: sendMpdCommand status took 17 milliseconds Jun 01 23:28:56 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:28:56 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:28:56 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:28:56 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 23:28:56 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:28:56 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 23:28:56 volumio volumio[17389]: info: sendMpdCommand status took 2 milliseconds Jun 01 23:28:56 volumio volumio[17389]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 01 23:28:56 volumio volumio[17389]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 01 23:28:56 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:28:56 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 23:28:56 volumio volumio[17389]: verbose: ControllerMpd::parseTrackInfo Jun 01 23:28:56 volumio volumio[17389]: info: CorePlayQueue::getTrack 18 Jun 01 23:28:56 volumio volumio[17389]: info: [1780356536813] ControllerPandora::pushState Jun 01 23:28:56 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:28:56 volumio volumio[17389]: info: CorePlayQueue::getTrack 18 Jun 01 23:28:56 volumio volumio[17389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":193,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Think We're Alone Now (80s Flashback Dance Fitness)","artist":"Tiffany","album":"Body By Jake: 80s Flashback Dance Fitness (BPM 100-164)","uri":"http://audio-dc6-t1-1-v4v6.pandora.com/access/8678293103330173767.mp3?version=5&lid=360257756&token=6sDG9SHprfNWMo3addOEFrdlzkT%2B5tG2tVzuGkYJxAOYEwzLRlMYMi3n0OOJaqsST8wg4ABEfWHHr17TSOA5h8IwvEFWkSwMYzsF2NNCOUGN5NsjDFL9ZVjY3bf9udq44M8y%2FpWrIMed7uCne8VHS1dZFtdl%2B2bxAqjNoTuHTTz5spoDXdNxpEOABit%2FxCW0du%2BDwUDGljqoh1r%2FruACUBMXhFyJpbJ0A1nJB9siC3Swd3s3NdzBN8ub5MoctFKShHtE0G2fUm0QRmnIxvECNd0OHPW%2BlJK0ckYXRLAZkfXNgFAVYZAkIj58dRhGgO6IV5e1%2BAS0YYdmBNEv9iKl%2FfID7VTyyPZT","trackType":"mp3"} Jun 01 23:28:56 volumio volumio[17389]: verbose: CURRENT POSITION 18 Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::syncState stateService play Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::syncState currentStatus play Jun 01 23:28:56 volumio volumio[17389]: info: Received an update from plugin. extracting info from payload Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:28:56 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:28:56 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:28:56 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService pandora Jun 01 23:28:56 volumio volumio[17389]: info: ControllerMpd::pushState Jun 01 23:28:56 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:28:56 volumio volumio[17389]: info: CorePlayQueue::getTrack 18 Jun 01 23:28:56 volumio volumio[17389]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jun 01 23:28:56 volumio volumio[17389]: info: ------------------------------ 34ms Jun 01 23:28:56 volumio volumio[17389]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 01 23:28:56 volumio volumio[17389]: verbose: ControllerMpd::parseTrackInfo Jun 01 23:28:56 volumio volumio[17389]: info: ControllerMpd::pushState Jun 01 23:28:56 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:28:56 volumio volumio[17389]: info: CorePlayQueue::getTrack 18 Jun 01 23:28:56 volumio volumio[17389]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jun 01 23:28:56 volumio volumio[17389]: info: ------------------------------ 19ms Jun 01 23:28:56 volumio volumio[17389]: info: CoreCommandRouter::volumioGetQueue Jun 01 23:28:56 volumio volumio[17389]: info: CoreStateMachine::getQueue Jun 01 23:28:56 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:57 volumio volumio[17389]: info: [1780356537159] ControllerPandora::PandoraHandler::fetchTracks::fetchStationPlaylist Jun 01 23:28:57 volumio volumio[17389]: info: [1780356537651] [Pandora] PandoraHandler::fetchTracks::fetchStationPlaylist: Retrieved Lady Gaga Radio playlist Jun 01 23:28:57 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:57 volumio volumio[17389]: info: [1780356537654] ControllerPandora::PandoraHandler::fetchTracks::fillNewTracks Jun 01 23:28:57 volumio volumio[17389]: info: [1780356537654] [Pandora] PandoraHandler::fetchTracks::fillNewTracks: Fetched 4 track(s) Jun 01 23:28:57 volumio volumio[17389]: info: CorePlayQueue::addQueueItems Jun 01 23:28:57 volumio volumio[17389]: info: Preload queue cleared Jun 01 23:28:57 volumio volumio[17389]: info: Adding Item to queue: /pandora/stationToken=4502368651449014492trackId=7145190848554675333 Jun 01 23:28:57 volumio volumio[17389]: info: Exploding uri /pandora/stationToken=4502368651449014492trackId=7145190848554675333 in service pandora Jun 01 23:28:57 volumio volumio[17389]: info: [1780356537655] ControllerPandora::explodeUri Jun 01 23:28:57 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:57 volumio volumio[17389]: info: Adding Item to queue: /pandora/stationToken=4502368651449014492trackId=4587785692438097600 Jun 01 23:28:57 volumio volumio[17389]: info: Exploding uri /pandora/stationToken=4502368651449014492trackId=4587785692438097600 in service pandora Jun 01 23:28:57 volumio volumio[17389]: info: [1780356537656] ControllerPandora::explodeUri Jun 01 23:28:57 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:57 volumio volumio[17389]: info: Adding Item to queue: /pandora/stationToken=4502368651449014492trackId=6620698570153691092 Jun 01 23:28:57 volumio volumio[17389]: info: Exploding uri /pandora/stationToken=4502368651449014492trackId=6620698570153691092 in service pandora Jun 01 23:28:57 volumio volumio[17389]: info: [1780356537656] ControllerPandora::explodeUri Jun 01 23:28:57 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:57 volumio volumio[17389]: info: Adding Item to queue: /pandora/stationToken=4502368651449014492trackId=357805107800475057 Jun 01 23:28:57 volumio volumio[17389]: info: Exploding uri /pandora/stationToken=4502368651449014492trackId=357805107800475057 in service pandora Jun 01 23:28:57 volumio volumio[17389]: info: [1780356537656] ControllerPandora::explodeUri Jun 01 23:28:57 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:57 volumio volumio[17389]: info: CoreCommandRouter::volumioPushQueue Jun 01 23:28:57 volumio volumio[17389]: info: CorePlayQueue::saveQueue Jun 01 23:28:57 volumio volumio[17389]: info: CoreStateMachine::updateTrackBlock Jun 01 23:28:57 volumio volumio[17389]: info: CorePlayQueue::getTrackBlock Jun 01 23:28:57 volumio volumio[17389]: info: [1780356537674] ControllerPandora::getStationTracks Jun 01 23:28:57 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:57 volumio volumio[17389]: info: [1780356537674] [Pandora] PandoraHandler::getSongMaxDiff: 7 Jun 01 23:28:57 volumio volumio[17389]: info: [1780356537674] ControllerPandora::fetchAndAddTracks::getSqInfo Jun 01 23:28:57 volumio volumio[17389]: info: [1780356537674] ControllerPandora::getStationTracks Jun 01 23:28:57 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:57 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:57 volumio volumio[17389]: info: [1780356537675] [Pandora] fetchAndAddTracks: diff2 > 0: 7 sQPos2: 18 Jun 01 23:28:57 volumio volumio[17389]: info: [1780356537675] ControllerPandora::removeOldTrackBlock Jun 01 23:28:57 volumio volumio[17389]: info: [1780356537675] ControllerPandora::removeStationTracks Jun 01 23:28:57 volumio volumio[17389]: info: [1780356537675] ControllerPandora::getStationTracks Jun 01 23:28:57 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:57 volumio volumio[17389]: info: [1780356537676] [Pandora] removeStationTracks: Removing 7 tracks from queue Jun 01 23:28:58 volumio volumio[17389]: info: CoreCommandRouter::volumioPlay Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::play index 22 Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::stop Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::stPlaybackTimer Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::updateTrackBlock Jun 01 23:28:58 volumio volumio[17389]: info: CorePlayQueue::getTrackBlock Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:28:58 volumio volumio[17389]: info: CorePlayQueue::getTrack 18 Jun 01 23:28:58 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:28:58 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::serviceStop Jun 01 23:28:58 volumio volumio[17389]: info: CorePlayQueue::getTrack 18 Jun 01 23:28:58 volumio volumio[17389]: info: CoreCommandRouter::serviceStop Jun 01 23:28:58 volumio volumio[17389]: info: [1780356538485] ControllerPandora::stop Jun 01 23:28:58 volumio volumio[17389]: info: ControllerMpd::stop Jun 01 23:28:58 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 23:28:58 volumio volumio[17389]: info: sendMpdCommand stop took 82 milliseconds Jun 01 23:28:58 volumio volumio[17389]: info: ControllerMpd::clear Jun 01 23:28:58 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 23:28:58 volumio volumio[17389]: info: Jun 01 23:28:58 volumio volumio[17389]: ---------------------------- MPD announces state update: player Jun 01 23:28:58 volumio volumio[17389]: info: Jun 01 23:28:58 volumio volumio[17389]: ---------------------------- MPD announces system playlist update Jun 01 23:28:58 volumio volumio[17389]: info: Ignoring MPD Status Update Jun 01 23:28:58 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:28:58 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:28:58 volumio volumio[17389]: info: sendMpdCommand clear took 1 milliseconds Jun 01 23:28:58 volumio volumio[17389]: info: [1780356538569] ControllerPandora::pushState Jun 01 23:28:58 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:28:58 volumio volumio[17389]: info: CorePlayQueue::getTrack 18 Jun 01 23:28:58 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:28:58 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:28:58 volumio volumio[17389]: info: CorePlayQueue::getTrack 18 Jun 01 23:28:58 volumio volumio[17389]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"I Think We're Alone Now (80s Flashback Dance Fitness)","name":"I Think We're Alone Now (80s Flashback Dance Fitness)","artist":"Tiffany","album":"Body By Jake: 80s Flashback Dance Fitness (BPM 100-164)","albumart":"http://cont-4.p-cdn.us/images/a0/06/89/81/0fd94d0d954385a38d92b70c/1080W_1080H.jpg","realUri":"http://audio-dc6-t1-1-v4v6.pandora.com/access/8678293103330173767.mp3?version=5&lid=360257756&token=6sDG9SHprfNWMo3addOEFrdlzkT%2B5tG2tVzuGkYJxAOYEwzLRlMYMi3n0OOJaqsST8wg4ABEfWHHr17TSOA5h8IwvEFWkSwMYzsF2NNCOUGN5NsjDFL9ZVjY3bf9udq44M8y%2FpWrIMed7uCne8VHS1dZFtdl%2B2bxAqjNoTuHTTz5spoDXdNxpEOABit%2FxCW0du%2BDwUDGljqoh1r%2FruACUBMXhFyJpbJ0A1nJB9siC3Swd3s3NdzBN8ub5MoctFKShHtE0G2fUm0QRmnIxvECNd0OHPW%2BlJK0ckYXRLAZkfXNgFAVYZAkIj58dRhGgO6IV5e1%2BAS0YYdmBNEv9iKl%2FfID7VTyyPZT","isStreaming":true,"duration":193,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://audio-dc6-t1-1-v4v6.pandora.com/access/8678293103330173767.mp3?version=5&lid=360257756&token=6sDG9SHprfNWMo3addOEFrdlzkT%2B5tG2tVzuGkYJxAOYEwzLRlMYMi3n0OOJaqsST8wg4ABEfWHHr17TSOA5h8IwvEFWkSwMYzsF2NNCOUGN5NsjDFL9ZVjY3bf9udq44M8y%2FpWrIMed7uCne8VHS1dZFtdl%2B2bxAqjNoTuHTTz5spoDXdNxpEOABit%2FxCW0du%2BDwUDGljqoh1r%2FruACUBMXhFyJpbJ0A1nJB9siC3Swd3s3NdzBN8ub5MoctFKShHtE0G2fUm0QRmnIxvECNd0OHPW%2BlJK0ckYXRLAZkfXNgFAVYZAkIj58dRhGgO6IV5e1%2BAS0YYdmBNEv9iKl%2FfID7VTyyPZT","seek":0,"status":"stop"} Jun 01 23:28:58 volumio volumio[17389]: verbose: CURRENT POSITION 18 Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::syncState stateService stop Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::syncState currentStatus stop Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:28:58 volumio volumio[17389]: info: CorePlayQueue::getTrack 18 Jun 01 23:28:58 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:28:58 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:28:58 volumio volumio[17389]: info: No code Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:28:58 volumio volumio[17389]: info: CorePlayQueue::getTrack 18 Jun 01 23:28:58 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService pandora Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::play index undefined Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 23:28:58 volumio volumio[17389]: info: CorePlayQueue::getTrack 22 Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::startPlaybackTimer Jun 01 23:28:58 volumio volumio[17389]: info: CorePlayQueue::getTrack 22 Jun 01 23:28:58 volumio volumio[17389]: info: [1780356538580] ControllerPandora::clearAddPlayTrack Jun 01 23:28:58 volumio volumio[17389]: info: ControllerMpd::clear Jun 01 23:28:58 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 23:28:58 volumio volumio[17389]: error: updateQueue error: null Jun 01 23:28:58 volumio volumio[17389]: info: Jun 01 23:28:58 volumio volumio[17389]: ---------------------------- MPD announces system playlist update Jun 01 23:28:58 volumio volumio[17389]: info: Ignoring MPD Status Update Jun 01 23:28:58 volumio volumio[17389]: info: sendMpdCommand status took 13 milliseconds Jun 01 23:28:58 volumio volumio[17389]: info: ------------------------------ 15ms Jun 01 23:28:58 volumio volumio[17389]: info: sendMpdCommand clear took 2 milliseconds Jun 01 23:28:58 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:28:58 volumio volumio[17389]: info: ControllerMpd::pushState Jun 01 23:28:58 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:28:58 volumio volumio[17389]: info: CorePlayQueue::getTrack 22 Jun 01 23:28:58 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:28:58 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:28:58 volumio volumio[17389]: info: CorePlayQueue::getTrack 22 Jun 01 23:28:58 volumio volumio[17389]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current pandora Received mpd Jun 01 23:28:58 volumio volumio[17389]: info: [1780356538586] ControllerPandora::checkForExpiredStations Jun 01 23:28:58 volumio volumio[17389]: info: ------------------------------ 20ms Jun 01 23:28:58 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:58 volumio volumio[17389]: info: [1780356538588] ControllerPandora::removeTrack Jun 01 23:28:58 volumio volumio[17389]: info: [1780356538588] [Pandora] removeTrack: Not removing track with uri: null at queue index: -1 Jun 01 23:28:58 volumio volumio[17389]: info: [1780356538588] ControllerPandora::appendTracksToMpd Jun 01 23:28:58 volumio volumio[17389]: info: [1780356538589] [Pandora] appendTracksToMpd: Added 1 track(s) to mpd Jun 01 23:28:58 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand addid Jun 01 23:28:58 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand play Jun 01 23:28:58 volumio volumio[17389]: error: updateQueue error: null Jun 01 23:28:58 volumio volumio[17389]: info: Jun 01 23:28:58 volumio volumio[17389]: ---------------------------- MPD announces system playlist update Jun 01 23:28:58 volumio volumio[17389]: info: Ignoring MPD Status Update Jun 01 23:28:58 volumio volumio[17389]: info: ------------------------------ 12ms Jun 01 23:28:58 volumio volumio[17389]: info: sendMpdCommand addid took 5 milliseconds Jun 01 23:28:58 volumio volumio[17389]: info: sendMpdCommand play took 6 milliseconds Jun 01 23:28:58 volumio volumio[17389]: verbose: MPD COMMAND [object Object] Jun 01 23:28:58 volumio volumio[17389]: info: [1780356538595] ControllerPandora::pushState Jun 01 23:28:58 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:28:58 volumio volumio[17389]: info: CorePlayQueue::getTrack 22 Jun 01 23:28:58 volumio volumio[17389]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"Midnight Sky","name":"Midnight Sky","artist":"Miley Cyrus","album":"Plastic Hearts","albumart":"http://mediaserver-cont-usc-mp1-2-v4v6.pandora.com/images/49/36/f8/d4/e0ea4576a185d45a19cea2ea/1080W_1080H.jpg","realUri":"http://t1-5.p-cdn.us/access/357805107800475057.mp3?version=5&lid=360257756&token=vCtbgb%2BAwQ31xJQUhvQIPcMJs1P6lZLLCpd%2F14fhqpA23mrdcVRibkRvsAx5VdUdVeCXClS%2B7RFC9KKIePG14q7WbNLcocJLkRf0QpR90M0F0cGe1ckv4NDDSS9TrOKB9RBC%2Bn%2FgRBxmFaTvZY8y4eBmfwZBZQKSDHdLz2D9kfede46ePvtiSvcRRJc9RJZ3xRrwNXYEdEgZxX6CYVVmSSleSBVmxxL4tsvaYGZFe2RPY%2BT1QieYYTKEuQJNd9UjGInQicTZN9c5zUpHDUnT88jcI1PmmHJD%2FjZIlE%2Bn%2FzMN1s8yYYu7v7zcIbdhr93JEgRgWsDnSDA%2BjehzeUShH3cuDFn27rHw","isStreaming":true,"duration":223,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t1-5.p-cdn.us/access/357805107800475057.mp3?version=5&lid=360257756&token=vCtbgb%2BAwQ31xJQUhvQIPcMJs1P6lZLLCpd%2F14fhqpA23mrdcVRibkRvsAx5VdUdVeCXClS%2B7RFC9KKIePG14q7WbNLcocJLkRf0QpR90M0F0cGe1ckv4NDDSS9TrOKB9RBC%2Bn%2FgRBxmFaTvZY8y4eBmfwZBZQKSDHdLz2D9kfede46ePvtiSvcRRJc9RJZ3xRrwNXYEdEgZxX6CYVVmSSleSBVmxxL4tsvaYGZFe2RPY%2BT1QieYYTKEuQJNd9UjGInQicTZN9c5zUpHDUnT88jcI1PmmHJD%2FjZIlE%2Bn%2FzMN1s8yYYu7v7zcIbdhr93JEgRgWsDnSDA%2BjehzeUShH3cuDFn27rHw","seek":0,"status":"play"} Jun 01 23:28:58 volumio volumio[17389]: verbose: CURRENT POSITION 22 Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::syncState stateService play Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::syncState currentStatus stop Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService pandora Jun 01 23:28:58 volumio volumio[17389]: info: [1780356538596] ControllerPandora::fetchAndAddTracks Jun 01 23:28:58 volumio volumio[17389]: info: [1780356538596] ControllerPandora::getStationTracks Jun 01 23:28:58 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:58 volumio volumio[17389]: info: [1780356538597] [Pandora] PandoraHandler::getSongMaxDiff: 7 Jun 01 23:28:58 volumio volumio[17389]: info: [1780356538597] ControllerPandora::fetchAndAddTracks::getSqInfo Jun 01 23:28:58 volumio volumio[17389]: info: [1780356538597] ControllerPandora::getStationTracks Jun 01 23:28:58 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:58 volumio volumio[17389]: info: [1780356538597] [Pandora] fetchAndAddTracks: diff1: 7 sQPos1: 22 Jun 01 23:28:58 volumio volumio[17389]: info: [1780356538597] [Pandora] fetchAndAddTracks: Fetching tracks Jun 01 23:28:58 volumio volumio[17389]: info: [1780356538601] ControllerPandora::PandoraHandler::fillStationData Jun 01 23:28:58 volumio volumio[17389]: info: Jun 01 23:28:58 volumio volumio[17389]: ---------------------------- MPD announces system playlist update Jun 01 23:28:58 volumio volumio[17389]: info: Ignoring MPD Status Update Jun 01 23:28:58 volumio volumio[17389]: info: ------------------------------ 11ms Jun 01 23:28:58 volumio volumio[17389]: info: ------------------------------ 2ms Jun 01 23:28:58 volumio volumio[17389]: info: [1780356538808] ControllerPandora::pandoraListener Jun 01 23:28:58 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:28:58 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:28:58 volumio volumio[17389]: info: Jun 01 23:28:58 volumio volumio[17389]: ---------------------------- MPD announces state update: player Jun 01 23:28:58 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:28:58 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:28:58 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jun 01 23:28:58 volumio volumio[17389]: info: sendMpdCommand status took 17 milliseconds Jun 01 23:28:58 volumio volumio[17389]: info: sendMpdCommand status took 14 milliseconds Jun 01 23:28:58 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:28:58 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 23:28:58 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:28:58 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 23:28:58 volumio volumio[17389]: info: Jun 01 23:28:58 volumio volumio[17389]: ---------------------------- MPD announces state update: player Jun 01 23:28:58 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:28:58 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:28:58 volumio volumio[17389]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 01 23:28:58 volumio volumio[17389]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 01 23:28:58 volumio volumio[17389]: verbose: ControllerMpd::parseTrackInfo Jun 01 23:28:58 volumio volumio[17389]: info: CorePlayQueue::getTrack 22 Jun 01 23:28:58 volumio volumio[17389]: info: [1780356538828] ControllerPandora::pushState Jun 01 23:28:58 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:28:58 volumio volumio[17389]: info: CorePlayQueue::getTrack 22 Jun 01 23:28:58 volumio volumio[17389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Midnight Sky","artist":"Miley Cyrus","album":"Plastic Hearts","uri":"http://t1-5.p-cdn.us/access/357805107800475057.mp3?version=5&lid=360257756&token=vCtbgb%2BAwQ31xJQUhvQIPcMJs1P6lZLLCpd%2F14fhqpA23mrdcVRibkRvsAx5VdUdVeCXClS%2B7RFC9KKIePG14q7WbNLcocJLkRf0QpR90M0F0cGe1ckv4NDDSS9TrOKB9RBC%2Bn%2FgRBxmFaTvZY8y4eBmfwZBZQKSDHdLz2D9kfede46ePvtiSvcRRJc9RJZ3xRrwNXYEdEgZxX6CYVVmSSleSBVmxxL4tsvaYGZFe2RPY%2BT1QieYYTKEuQJNd9UjGInQicTZN9c5zUpHDUnT88jcI1PmmHJD%2FjZIlE%2Bn%2FzMN1s8yYYu7v7zcIbdhr93JEgRgWsDnSDA%2BjehzeUShH3cuDFn27rHw","trackType":"mp3"} Jun 01 23:28:58 volumio volumio[17389]: verbose: CURRENT POSITION 22 Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::syncState stateService play Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::syncState currentStatus play Jun 01 23:28:58 volumio volumio[17389]: info: Received an update from plugin. extracting info from payload Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:28:58 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:28:58 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:28:58 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService pandora Jun 01 23:28:58 volumio volumio[17389]: info: ControllerMpd::pushState Jun 01 23:28:58 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:28:58 volumio volumio[17389]: info: CorePlayQueue::getTrack 22 Jun 01 23:28:58 volumio volumio[17389]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jun 01 23:28:58 volumio volumio[17389]: info: ------------------------------ 28ms Jun 01 23:28:58 volumio volumio[17389]: info: sendMpdCommand status took 10 milliseconds Jun 01 23:28:58 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:28:58 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 23:28:58 volumio volumio[17389]: info: CoreCommandRouter::volumioGetQueue Jun 01 23:28:58 volumio volumio[17389]: info: CoreStateMachine::getQueue Jun 01 23:28:58 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:58 volumio volumio[17389]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 01 23:28:58 volumio volumio[17389]: verbose: ControllerMpd::parseTrackInfo Jun 01 23:28:58 volumio volumio[17389]: info: ControllerMpd::pushState Jun 01 23:28:58 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:28:58 volumio volumio[17389]: info: CorePlayQueue::getTrack 22 Jun 01 23:28:58 volumio volumio[17389]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jun 01 23:28:58 volumio volumio[17389]: info: ------------------------------ 27ms Jun 01 23:28:58 volumio volumio[17389]: info: [1780356538957] ControllerPandora::PandoraHandler::fetchTracks::fetchStationPlaylist Jun 01 23:28:59 volumio volumio[17389]: info: [1780356539533] [Pandora] PandoraHandler::fetchTracks::fetchStationPlaylist: Retrieved Lady Gaga Radio playlist Jun 01 23:28:59 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:59 volumio volumio[17389]: info: [1780356539533] ControllerPandora::PandoraHandler::fetchTracks::fillNewTracks Jun 01 23:28:59 volumio volumio[17389]: info: [1780356539533] [Pandora] PandoraHandler::fetchTracks::fillNewTracks: Fetched 4 track(s) Jun 01 23:28:59 volumio volumio[17389]: info: CorePlayQueue::addQueueItems Jun 01 23:28:59 volumio volumio[17389]: info: Preload queue cleared Jun 01 23:28:59 volumio volumio[17389]: info: Adding Item to queue: /pandora/stationToken=4502368651449014492trackId=163974699304837560 Jun 01 23:28:59 volumio volumio[17389]: info: Exploding uri /pandora/stationToken=4502368651449014492trackId=163974699304837560 in service pandora Jun 01 23:28:59 volumio volumio[17389]: info: [1780356539534] ControllerPandora::explodeUri Jun 01 23:28:59 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:59 volumio volumio[17389]: info: Adding Item to queue: /pandora/stationToken=4502368651449014492trackId=4417512755212160896 Jun 01 23:28:59 volumio volumio[17389]: info: Exploding uri /pandora/stationToken=4502368651449014492trackId=4417512755212160896 in service pandora Jun 01 23:28:59 volumio volumio[17389]: info: [1780356539534] ControllerPandora::explodeUri Jun 01 23:28:59 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:59 volumio volumio[17389]: info: Adding Item to queue: /pandora/stationToken=4502368651449014492trackId=1356243021957868869 Jun 01 23:28:59 volumio volumio[17389]: info: Exploding uri /pandora/stationToken=4502368651449014492trackId=1356243021957868869 in service pandora Jun 01 23:28:59 volumio volumio[17389]: info: [1780356539535] ControllerPandora::explodeUri Jun 01 23:28:59 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:59 volumio volumio[17389]: info: Adding Item to queue: /pandora/stationToken=4502368651449014492trackId=5146403210401332287 Jun 01 23:28:59 volumio volumio[17389]: info: Exploding uri /pandora/stationToken=4502368651449014492trackId=5146403210401332287 in service pandora Jun 01 23:28:59 volumio volumio[17389]: info: [1780356539535] ControllerPandora::explodeUri Jun 01 23:28:59 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:59 volumio volumio[17389]: info: CoreCommandRouter::volumioPushQueue Jun 01 23:28:59 volumio volumio[17389]: info: CorePlayQueue::saveQueue Jun 01 23:28:59 volumio volumio[17389]: info: CoreStateMachine::updateTrackBlock Jun 01 23:28:59 volumio volumio[17389]: info: CorePlayQueue::getTrackBlock Jun 01 23:28:59 volumio volumio[17389]: info: [1780356539548] ControllerPandora::getStationTracks Jun 01 23:28:59 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:59 volumio volumio[17389]: info: [1780356539548] [Pandora] PandoraHandler::getSongMaxDiff: 11 Jun 01 23:28:59 volumio volumio[17389]: info: [1780356539548] ControllerPandora::fetchAndAddTracks::getSqInfo Jun 01 23:28:59 volumio volumio[17389]: info: [1780356539548] ControllerPandora::getStationTracks Jun 01 23:28:59 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:59 volumio volumio[17389]: info: [1780356539549] [Pandora] fetchAndAddTracks: diff2 > 0: 11 sQPos2: 22 Jun 01 23:28:59 volumio volumio[17389]: info: [1780356539549] ControllerPandora::removeOldTrackBlock Jun 01 23:28:59 volumio volumio[17389]: info: [1780356539549] ControllerPandora::removeStationTracks Jun 01 23:28:59 volumio volumio[17389]: info: [1780356539549] ControllerPandora::getStationTracks Jun 01 23:28:59 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:28:59 volumio volumio[17389]: info: [1780356539550] [Pandora] removeStationTracks: Removing 11 tracks from queue Jun 01 23:29:00 volumio volumio[17389]: info: CoreCommandRouter::volumioPlay Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::play index 26 Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::stop Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::stPlaybackTimer Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::updateTrackBlock Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::getTrackBlock Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::getTrack 22 Jun 01 23:29:00 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:29:00 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::serviceStop Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::getTrack 22 Jun 01 23:29:00 volumio volumio[17389]: info: CoreCommandRouter::serviceStop Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540124] ControllerPandora::stop Jun 01 23:29:00 volumio volumio[17389]: info: ControllerMpd::stop Jun 01 23:29:00 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 23:29:00 volumio volumio[17389]: info: Jun 01 23:29:00 volumio volumio[17389]: ---------------------------- MPD announces state update: player Jun 01 23:29:00 volumio volumio[17389]: info: sendMpdCommand stop took 91 milliseconds Jun 01 23:29:00 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:29:00 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:29:00 volumio volumio[17389]: info: ControllerMpd::clear Jun 01 23:29:00 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 23:29:00 volumio volumio[17389]: info: Jun 01 23:29:00 volumio volumio[17389]: ---------------------------- MPD announces system playlist update Jun 01 23:29:00 volumio volumio[17389]: info: Ignoring MPD Status Update Jun 01 23:29:00 volumio volumio[17389]: info: sendMpdCommand status took 1 milliseconds Jun 01 23:29:00 volumio volumio[17389]: info: sendMpdCommand clear took 2 milliseconds Jun 01 23:29:00 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:29:00 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540218] ControllerPandora::pushState Jun 01 23:29:00 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::getTrack 22 Jun 01 23:29:00 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:29:00 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::getTrack 22 Jun 01 23:29:00 volumio volumio[17389]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"Midnight Sky","name":"Midnight Sky","artist":"Miley Cyrus","album":"Plastic Hearts","albumart":"http://mediaserver-cont-usc-mp1-2-v4v6.pandora.com/images/49/36/f8/d4/e0ea4576a185d45a19cea2ea/1080W_1080H.jpg","realUri":"http://t1-5.p-cdn.us/access/357805107800475057.mp3?version=5&lid=360257756&token=vCtbgb%2BAwQ31xJQUhvQIPcMJs1P6lZLLCpd%2F14fhqpA23mrdcVRibkRvsAx5VdUdVeCXClS%2B7RFC9KKIePG14q7WbNLcocJLkRf0QpR90M0F0cGe1ckv4NDDSS9TrOKB9RBC%2Bn%2FgRBxmFaTvZY8y4eBmfwZBZQKSDHdLz2D9kfede46ePvtiSvcRRJc9RJZ3xRrwNXYEdEgZxX6CYVVmSSleSBVmxxL4tsvaYGZFe2RPY%2BT1QieYYTKEuQJNd9UjGInQicTZN9c5zUpHDUnT88jcI1PmmHJD%2FjZIlE%2Bn%2FzMN1s8yYYu7v7zcIbdhr93JEgRgWsDnSDA%2BjehzeUShH3cuDFn27rHw","isStreaming":true,"duration":223,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t1-5.p-cdn.us/access/357805107800475057.mp3?version=5&lid=360257756&token=vCtbgb%2BAwQ31xJQUhvQIPcMJs1P6lZLLCpd%2F14fhqpA23mrdcVRibkRvsAx5VdUdVeCXClS%2B7RFC9KKIePG14q7WbNLcocJLkRf0QpR90M0F0cGe1ckv4NDDSS9TrOKB9RBC%2Bn%2FgRBxmFaTvZY8y4eBmfwZBZQKSDHdLz2D9kfede46ePvtiSvcRRJc9RJZ3xRrwNXYEdEgZxX6CYVVmSSleSBVmxxL4tsvaYGZFe2RPY%2BT1QieYYTKEuQJNd9UjGInQicTZN9c5zUpHDUnT88jcI1PmmHJD%2FjZIlE%2Bn%2FzMN1s8yYYu7v7zcIbdhr93JEgRgWsDnSDA%2BjehzeUShH3cuDFn27rHw","seek":0,"status":"stop"} Jun 01 23:29:00 volumio volumio[17389]: verbose: CURRENT POSITION 22 Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::syncState stateService stop Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::syncState currentStatus stop Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::getTrack 22 Jun 01 23:29:00 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:29:00 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:00 volumio volumio[17389]: info: No code Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::getTrack 22 Jun 01 23:29:00 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService pandora Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::play index undefined Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::getTrack 26 Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::startPlaybackTimer Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::getTrack 26 Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540232] ControllerPandora::clearAddPlayTrack Jun 01 23:29:00 volumio volumio[17389]: info: ControllerMpd::clear Jun 01 23:29:00 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 23:29:00 volumio volumio[17389]: error: updateQueue error: null Jun 01 23:29:00 volumio volumio[17389]: info: Jun 01 23:29:00 volumio volumio[17389]: ---------------------------- MPD announces system playlist update Jun 01 23:29:00 volumio volumio[17389]: info: Ignoring MPD Status Update Jun 01 23:29:00 volumio volumio[17389]: info: ------------------------------ 16ms Jun 01 23:29:00 volumio volumio[17389]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 01 23:29:00 volumio volumio[17389]: info: sendMpdCommand clear took 1 milliseconds Jun 01 23:29:00 volumio volumio[17389]: verbose: ControllerMpd::parseTrackInfo Jun 01 23:29:00 volumio volumio[17389]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540235] ControllerPandora::checkForExpiredStations Jun 01 23:29:00 volumio volumio[17389]: info: ------------------------------ 20ms Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540235] ControllerPandora::removeTrack Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540235] [Pandora] removeTrack: Not removing track with uri: null at queue index: -1 Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540235] ControllerPandora::appendTracksToMpd Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540235] [Pandora] appendTracksToMpd: Added 1 track(s) to mpd Jun 01 23:29:00 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand addid Jun 01 23:29:00 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand play Jun 01 23:29:00 volumio volumio[17389]: error: updateQueue error: null Jun 01 23:29:00 volumio volumio[17389]: info: Jun 01 23:29:00 volumio volumio[17389]: ---------------------------- MPD announces system playlist update Jun 01 23:29:00 volumio volumio[17389]: info: Ignoring MPD Status Update Jun 01 23:29:00 volumio volumio[17389]: info: ------------------------------ 7ms Jun 01 23:29:00 volumio volumio[17389]: info: sendMpdCommand addid took 4 milliseconds Jun 01 23:29:00 volumio volumio[17389]: info: sendMpdCommand play took 4 milliseconds Jun 01 23:29:00 volumio volumio[17389]: verbose: MPD COMMAND [object Object] Jun 01 23:29:00 volumio volumio[17389]: verbose: MPD COMMAND [object Object] Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540241] ControllerPandora::pushState Jun 01 23:29:00 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::getTrack 26 Jun 01 23:29:00 volumio volumio[17389]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"Sunflower (Spider-Man: Into the Spider-Verse)","name":"Sunflower (Spider-Man: Into the Spider-Verse)","artist":"Post Malone & Swae Lee","album":"The Diamond Collection (Deluxe)","albumart":"http://mediaserver-cont-usc-mp1-1-v4v6.pandora.com/images/da/73/7a/48/560f42daa5d5ef1d51c2c032/1080W_1080H.jpg","realUri":"http://audio-sv5-t1-1-v4v6.pandora.com/access/5146403210401332287.mp3?version=5&lid=360257756&token=Z6P6AYREWXPoMI3YZ3BVHLSPBJE8zNg64p3NoiVO0zi87PGPFX6PZQYRpvC%2BG5RygBU3qWtjh2GH11UqS4uNiJ3IZPsKxyydaMaBDLEjzaeuVgr1pqmihImsLrm%2F8fjmw3uQn1tvprhgXuzuWrfj3lf3eEnt1qWUn46p2VbzL4GxKiAvZk3zihccN2ynf2An9zqebz66KlEJPE%2F4FY9jix2Z2erMsfVgFIOY5h%2BnD5tUInEzoUbctsKH5NfGWhqx%2FMVk%2BdGhS6CjVlPUVrpW8c9HHkCQjwvlYN4YUpN3WV6uBl%2BiHQu2IOlM9VOLiP9iotdKqe4gjyWSsIorfi3V52Vt94I1hq4M","isStreaming":true,"duration":158,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://audio-sv5-t1-1-v4v6.pandora.com/access/5146403210401332287.mp3?version=5&lid=360257756&token=Z6P6AYREWXPoMI3YZ3BVHLSPBJE8zNg64p3NoiVO0zi87PGPFX6PZQYRpvC%2BG5RygBU3qWtjh2GH11UqS4uNiJ3IZPsKxyydaMaBDLEjzaeuVgr1pqmihImsLrm%2F8fjmw3uQn1tvprhgXuzuWrfj3lf3eEnt1qWUn46p2VbzL4GxKiAvZk3zihccN2ynf2An9zqebz66KlEJPE%2F4FY9jix2Z2erMsfVgFIOY5h%2BnD5tUInEzoUbctsKH5NfGWhqx%2FMVk%2BdGhS6CjVlPUVrpW8c9HHkCQjwvlYN4YUpN3WV6uBl%2BiHQu2IOlM9VOLiP9iotdKqe4gjyWSsIorfi3V52Vt94I1hq4M","seek":0,"status":"play"} Jun 01 23:29:00 volumio volumio[17389]: verbose: CURRENT POSITION 26 Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::syncState stateService play Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::syncState currentStatus stop Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService pandora Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540242] ControllerPandora::fetchAndAddTracks Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540242] ControllerPandora::getStationTracks Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540242] [Pandora] PandoraHandler::getSongMaxDiff: 11 Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540242] ControllerPandora::fetchAndAddTracks::getSqInfo Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540243] ControllerPandora::getStationTracks Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540243] [Pandora] fetchAndAddTracks: diff1: 11 sQPos1: 26 Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540243] [Pandora] fetchAndAddTracks: Fetching tracks Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540243] ControllerPandora::PandoraHandler::fillStationData Jun 01 23:29:00 volumio volumio[17389]: info: Jun 01 23:29:00 volumio volumio[17389]: ---------------------------- MPD announces system playlist update Jun 01 23:29:00 volumio volumio[17389]: info: Ignoring MPD Status Update Jun 01 23:29:00 volumio volumio[17389]: info: ------------------------------ 7ms Jun 01 23:29:00 volumio volumio[17389]: info: ------------------------------ 1ms Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540490] ControllerPandora::pandoraListener Jun 01 23:29:00 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:29:00 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:29:00 volumio volumio[17389]: info: Jun 01 23:29:00 volumio volumio[17389]: ---------------------------- MPD announces state update: player Jun 01 23:29:00 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:29:00 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:29:00 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jun 01 23:29:00 volumio volumio[17389]: info: Jun 01 23:29:00 volumio volumio[17389]: ---------------------------- MPD announces state update: player Jun 01 23:29:00 volumio volumio[17389]: info: sendMpdCommand status took 18 milliseconds Jun 01 23:29:00 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:29:00 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:29:00 volumio volumio[17389]: info: sendMpdCommand status took 17 milliseconds Jun 01 23:29:00 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:29:00 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 23:29:00 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:29:00 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 23:29:00 volumio volumio[17389]: info: sendMpdCommand status took 3 milliseconds Jun 01 23:29:00 volumio volumio[17389]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 01 23:29:00 volumio volumio[17389]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 01 23:29:00 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:29:00 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 23:29:00 volumio volumio[17389]: verbose: ControllerMpd::parseTrackInfo Jun 01 23:29:00 volumio volumio[17389]: verbose: ControllerMpd::parseTrackInfo Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::getTrack 26 Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540514] ControllerPandora::pushState Jun 01 23:29:00 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::getTrack 26 Jun 01 23:29:00 volumio volumio[17389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":158,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sunflower (Spider-Man: Into the Spider-Verse)","artist":"Post Malone & Swae Lee","album":"The Diamond Collection (Deluxe)","uri":"http://audio-sv5-t1-1-v4v6.pandora.com/access/5146403210401332287.mp3?version=5&lid=360257756&token=Z6P6AYREWXPoMI3YZ3BVHLSPBJE8zNg64p3NoiVO0zi87PGPFX6PZQYRpvC%2BG5RygBU3qWtjh2GH11UqS4uNiJ3IZPsKxyydaMaBDLEjzaeuVgr1pqmihImsLrm%2F8fjmw3uQn1tvprhgXuzuWrfj3lf3eEnt1qWUn46p2VbzL4GxKiAvZk3zihccN2ynf2An9zqebz66KlEJPE%2F4FY9jix2Z2erMsfVgFIOY5h%2BnD5tUInEzoUbctsKH5NfGWhqx%2FMVk%2BdGhS6CjVlPUVrpW8c9HHkCQjwvlYN4YUpN3WV6uBl%2BiHQu2IOlM9VOLiP9iotdKqe4gjyWSsIorfi3V52Vt94I1hq4M","trackType":"mp3"} Jun 01 23:29:00 volumio volumio[17389]: verbose: CURRENT POSITION 26 Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::syncState stateService play Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::syncState currentStatus play Jun 01 23:29:00 volumio volumio[17389]: info: Received an update from plugin. extracting info from payload Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:00 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:29:00 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:00 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService pandora Jun 01 23:29:00 volumio volumio[17389]: info: ControllerMpd::pushState Jun 01 23:29:00 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::getTrack 26 Jun 01 23:29:00 volumio volumio[17389]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jun 01 23:29:00 volumio volumio[17389]: info: ------------------------------ 30ms Jun 01 23:29:00 volumio volumio[17389]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 01 23:29:00 volumio volumio[17389]: verbose: ControllerMpd::parseTrackInfo Jun 01 23:29:00 volumio volumio[17389]: info: ControllerMpd::pushState Jun 01 23:29:00 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::getTrack 26 Jun 01 23:29:00 volumio volumio[17389]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jun 01 23:29:00 volumio volumio[17389]: info: ------------------------------ 15ms Jun 01 23:29:00 volumio volumio[17389]: info: CoreCommandRouter::volumioGetQueue Jun 01 23:29:00 volumio volumio[17389]: info: CoreStateMachine::getQueue Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540572] ControllerPandora::PandoraHandler::fetchTracks::fetchStationPlaylist Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540972] [Pandora] PandoraHandler::fetchTracks::fetchStationPlaylist: Retrieved Lady Gaga Radio playlist Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540974] ControllerPandora::PandoraHandler::fetchTracks::fillNewTracks Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540975] [Pandora] PandoraHandler::fetchTracks::fillNewTracks: Fetched 4 track(s) Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::addQueueItems Jun 01 23:29:00 volumio volumio[17389]: info: Preload queue cleared Jun 01 23:29:00 volumio volumio[17389]: info: Adding Item to queue: /pandora/stationToken=4502368651449014492trackId=2548170601519613374 Jun 01 23:29:00 volumio volumio[17389]: info: Exploding uri /pandora/stationToken=4502368651449014492trackId=2548170601519613374 in service pandora Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540977] ControllerPandora::explodeUri Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:00 volumio volumio[17389]: info: Adding Item to queue: /pandora/stationToken=4502368651449014492trackId=342382503937135681 Jun 01 23:29:00 volumio volumio[17389]: info: Exploding uri /pandora/stationToken=4502368651449014492trackId=342382503937135681 in service pandora Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540977] ControllerPandora::explodeUri Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:00 volumio volumio[17389]: info: Adding Item to queue: /pandora/stationToken=4502368651449014492trackId=3727840775148957404 Jun 01 23:29:00 volumio volumio[17389]: info: Exploding uri /pandora/stationToken=4502368651449014492trackId=3727840775148957404 in service pandora Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540978] ControllerPandora::explodeUri Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:00 volumio volumio[17389]: info: Adding Item to queue: /pandora/stationToken=4502368651449014492trackId=7421883049801108865 Jun 01 23:29:00 volumio volumio[17389]: info: Exploding uri /pandora/stationToken=4502368651449014492trackId=7421883049801108865 in service pandora Jun 01 23:29:00 volumio volumio[17389]: info: [1780356540978] ControllerPandora::explodeUri Jun 01 23:29:00 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:00 volumio volumio[17389]: info: CoreCommandRouter::volumioPushQueue Jun 01 23:29:01 volumio volumio[17389]: info: CorePlayQueue::saveQueue Jun 01 23:29:01 volumio volumio[17389]: info: CoreStateMachine::updateTrackBlock Jun 01 23:29:01 volumio volumio[17389]: info: CorePlayQueue::getTrackBlock Jun 01 23:29:01 volumio volumio[17389]: info: [1780356541007] ControllerPandora::getStationTracks Jun 01 23:29:01 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:01 volumio volumio[17389]: info: [1780356541008] [Pandora] PandoraHandler::getSongMaxDiff: 15 Jun 01 23:29:01 volumio volumio[17389]: info: [1780356541009] ControllerPandora::fetchAndAddTracks::getSqInfo Jun 01 23:29:01 volumio volumio[17389]: info: [1780356541010] ControllerPandora::getStationTracks Jun 01 23:29:01 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:01 volumio volumio[17389]: info: [1780356541011] [Pandora] fetchAndAddTracks: diff2 > 0: 15 sQPos2: 26 Jun 01 23:29:01 volumio volumio[17389]: info: [1780356541011] ControllerPandora::removeOldTrackBlock Jun 01 23:29:01 volumio volumio[17389]: info: [1780356541011] ControllerPandora::removeStationTracks Jun 01 23:29:01 volumio volumio[17389]: info: [1780356541011] ControllerPandora::getStationTracks Jun 01 23:29:01 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:01 volumio volumio[17389]: info: [1780356541014] [Pandora] removeStationTracks: Removing 15 tracks from queue Jun 01 23:29:01 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:01 volumio volumio[17389]: info: [1780356541500] ControllerPandora::removeTrack Jun 01 23:29:01 volumio volumio[17389]: info: CoreStateMachine::removeQueueItem Jun 01 23:29:01 volumio volumio[17389]: info: CorePlayQueue::removeQueueItem 12 Jun 01 23:29:01 volumio volumio[17389]: info: CorePlayQueue::saveQueue Jun 01 23:29:01 volumio volumio[17389]: info: CoreCommandRouter::volumioPushQueue Jun 01 23:29:01 volumio volumio[17389]: info: [1780356541504] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=4502368651449014492trackId=2508307970744400328 at queue index: 12 Jun 01 23:29:01 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:29:01 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:29:01 volumio volumio[17389]: info: sendMpdCommand status took 7 milliseconds Jun 01 23:29:01 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:29:01 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 23:29:01 volumio volumio[17389]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 01 23:29:01 volumio volumio[17389]: verbose: ControllerMpd::parseTrackInfo Jun 01 23:29:01 volumio volumio[17389]: info: [1780356541513] ControllerPandora::pushState Jun 01 23:29:01 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:29:01 volumio volumio[17389]: info: CorePlayQueue::getTrack 25 Jun 01 23:29:01 volumio volumio[17389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1542,"duration":158,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Sunflower (Spider-Man: Into the Spider-Verse)","artist":"Post Malone & Swae Lee","album":"The Diamond Collection (Deluxe)","uri":"http://audio-sv5-t1-1-v4v6.pandora.com/access/5146403210401332287.mp3?version=5&lid=360257756&token=Z6P6AYREWXPoMI3YZ3BVHLSPBJE8zNg64p3NoiVO0zi87PGPFX6PZQYRpvC%2BG5RygBU3qWtjh2GH11UqS4uNiJ3IZPsKxyydaMaBDLEjzaeuVgr1pqmihImsLrm%2F8fjmw3uQn1tvprhgXuzuWrfj3lf3eEnt1qWUn46p2VbzL4GxKiAvZk3zihccN2ynf2An9zqebz66KlEJPE%2F4FY9jix2Z2erMsfVgFIOY5h%2BnD5tUInEzoUbctsKH5NfGWhqx%2FMVk%2BdGhS6CjVlPUVrpW8c9HHkCQjwvlYN4YUpN3WV6uBl%2BiHQu2IOlM9VOLiP9iotdKqe4gjyWSsIorfi3V52Vt94I1hq4M","trackType":"mp3"} Jun 01 23:29:01 volumio volumio[17389]: verbose: CURRENT POSITION 25 Jun 01 23:29:01 volumio volumio[17389]: info: CoreStateMachine::syncState stateService play Jun 01 23:29:01 volumio volumio[17389]: info: CoreStateMachine::syncState currentStatus play Jun 01 23:29:01 volumio volumio[17389]: info: Received an update from plugin. extracting info from payload Jun 01 23:29:01 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:01 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:29:01 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:01 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:01 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:01 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService pandora Jun 01 23:29:02 volumio volumio[17389]: info: CoreCommandRouter::volumioPlay Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::play index 29 Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::stop Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::stPlaybackTimer Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::updateTrackBlock Jun 01 23:29:02 volumio volumio[17389]: info: CorePlayQueue::getTrackBlock Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:02 volumio volumio[17389]: info: CorePlayQueue::getTrack 25 Jun 01 23:29:02 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:29:02 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::serviceStop Jun 01 23:29:02 volumio volumio[17389]: info: CorePlayQueue::getTrack 25 Jun 01 23:29:02 volumio volumio[17389]: info: CoreCommandRouter::serviceStop Jun 01 23:29:02 volumio volumio[17389]: info: [1780356542276] ControllerPandora::stop Jun 01 23:29:02 volumio volumio[17389]: info: ControllerMpd::stop Jun 01 23:29:02 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 23:29:02 volumio volumio[17389]: info: Jun 01 23:29:02 volumio volumio[17389]: ---------------------------- MPD announces state update: player Jun 01 23:29:02 volumio volumio[17389]: info: sendMpdCommand stop took 51 milliseconds Jun 01 23:29:02 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:29:02 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:29:02 volumio volumio[17389]: info: ControllerMpd::clear Jun 01 23:29:02 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 23:29:02 volumio volumio[17389]: info: Jun 01 23:29:02 volumio volumio[17389]: ---------------------------- MPD announces system playlist update Jun 01 23:29:02 volumio volumio[17389]: info: Ignoring MPD Status Update Jun 01 23:29:02 volumio volumio[17389]: info: sendMpdCommand status took 2 milliseconds Jun 01 23:29:02 volumio volumio[17389]: info: sendMpdCommand clear took 1 milliseconds Jun 01 23:29:02 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:29:02 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 23:29:02 volumio volumio[17389]: info: [1780356542329] ControllerPandora::pushState Jun 01 23:29:02 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:02 volumio volumio[17389]: info: CorePlayQueue::getTrack 25 Jun 01 23:29:02 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:02 volumio volumio[17389]: info: CorePlayQueue::getTrack 25 Jun 01 23:29:02 volumio volumio[17389]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"Sunflower (Spider-Man: Into the Spider-Verse)","name":"Sunflower (Spider-Man: Into the Spider-Verse)","artist":"Post Malone & Swae Lee","album":"The Diamond Collection (Deluxe)","albumart":"http://mediaserver-cont-usc-mp1-1-v4v6.pandora.com/images/da/73/7a/48/560f42daa5d5ef1d51c2c032/1080W_1080H.jpg","realUri":"http://audio-sv5-t1-1-v4v6.pandora.com/access/5146403210401332287.mp3?version=5&lid=360257756&token=Z6P6AYREWXPoMI3YZ3BVHLSPBJE8zNg64p3NoiVO0zi87PGPFX6PZQYRpvC%2BG5RygBU3qWtjh2GH11UqS4uNiJ3IZPsKxyydaMaBDLEjzaeuVgr1pqmihImsLrm%2F8fjmw3uQn1tvprhgXuzuWrfj3lf3eEnt1qWUn46p2VbzL4GxKiAvZk3zihccN2ynf2An9zqebz66KlEJPE%2F4FY9jix2Z2erMsfVgFIOY5h%2BnD5tUInEzoUbctsKH5NfGWhqx%2FMVk%2BdGhS6CjVlPUVrpW8c9HHkCQjwvlYN4YUpN3WV6uBl%2BiHQu2IOlM9VOLiP9iotdKqe4gjyWSsIorfi3V52Vt94I1hq4M","isStreaming":true,"duration":158,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://audio-sv5-t1-1-v4v6.pandora.com/access/5146403210401332287.mp3?version=5&lid=360257756&token=Z6P6AYREWXPoMI3YZ3BVHLSPBJE8zNg64p3NoiVO0zi87PGPFX6PZQYRpvC%2BG5RygBU3qWtjh2GH11UqS4uNiJ3IZPsKxyydaMaBDLEjzaeuVgr1pqmihImsLrm%2F8fjmw3uQn1tvprhgXuzuWrfj3lf3eEnt1qWUn46p2VbzL4GxKiAvZk3zihccN2ynf2An9zqebz66KlEJPE%2F4FY9jix2Z2erMsfVgFIOY5h%2BnD5tUInEzoUbctsKH5NfGWhqx%2FMVk%2BdGhS6CjVlPUVrpW8c9HHkCQjwvlYN4YUpN3WV6uBl%2BiHQu2IOlM9VOLiP9iotdKqe4gjyWSsIorfi3V52Vt94I1hq4M","seek":0,"status":"stop"} Jun 01 23:29:02 volumio volumio[17389]: verbose: CURRENT POSITION 25 Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::syncState stateService stop Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::syncState currentStatus stop Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:02 volumio volumio[17389]: info: CorePlayQueue::getTrack 25 Jun 01 23:29:02 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:29:02 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:02 volumio volumio[17389]: info: No code Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:02 volumio volumio[17389]: info: CorePlayQueue::getTrack 25 Jun 01 23:29:02 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService pandora Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::play index undefined Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 23:29:02 volumio volumio[17389]: info: CorePlayQueue::getTrack 29 Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::startPlaybackTimer Jun 01 23:29:02 volumio volumio[17389]: info: CorePlayQueue::getTrack 29 Jun 01 23:29:02 volumio volumio[17389]: info: [1780356542339] ControllerPandora::clearAddPlayTrack Jun 01 23:29:02 volumio volumio[17389]: info: ControllerMpd::clear Jun 01 23:29:02 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 23:29:02 volumio volumio[17389]: error: updateQueue error: null Jun 01 23:29:02 volumio volumio[17389]: info: Jun 01 23:29:02 volumio volumio[17389]: ---------------------------- MPD announces system playlist update Jun 01 23:29:02 volumio volumio[17389]: info: Ignoring MPD Status Update Jun 01 23:29:02 volumio volumio[17389]: info: ------------------------------ 15ms Jun 01 23:29:02 volumio volumio[17389]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 01 23:29:02 volumio volumio[17389]: info: sendMpdCommand clear took 2 milliseconds Jun 01 23:29:02 volumio volumio[17389]: verbose: ControllerMpd::parseTrackInfo Jun 01 23:29:02 volumio volumio[17389]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 01 23:29:02 volumio volumio[17389]: info: [1780356542344] ControllerPandora::checkForExpiredStations Jun 01 23:29:02 volumio volumio[17389]: info: ------------------------------ 18ms Jun 01 23:29:02 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:02 volumio volumio[17389]: info: [1780356542345] ControllerPandora::removeTrack Jun 01 23:29:02 volumio volumio[17389]: info: [1780356542345] [Pandora] removeTrack: Not removing track with uri: null at queue index: -1 Jun 01 23:29:02 volumio volumio[17389]: info: [1780356542345] ControllerPandora::appendTracksToMpd Jun 01 23:29:02 volumio volumio[17389]: info: [1780356542345] [Pandora] appendTracksToMpd: Added 1 track(s) to mpd Jun 01 23:29:02 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand addid Jun 01 23:29:02 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand play Jun 01 23:29:02 volumio volumio[17389]: error: updateQueue error: null Jun 01 23:29:02 volumio volumio[17389]: info: Jun 01 23:29:02 volumio volumio[17389]: ---------------------------- MPD announces system playlist update Jun 01 23:29:02 volumio volumio[17389]: info: Ignoring MPD Status Update Jun 01 23:29:02 volumio volumio[17389]: info: ------------------------------ 7ms Jun 01 23:29:02 volumio volumio[17389]: info: sendMpdCommand addid took 5 milliseconds Jun 01 23:29:02 volumio volumio[17389]: info: sendMpdCommand play took 4 milliseconds Jun 01 23:29:02 volumio volumio[17389]: verbose: MPD COMMAND [object Object] Jun 01 23:29:02 volumio volumio[17389]: verbose: MPD COMMAND [object Object] Jun 01 23:29:02 volumio volumio[17389]: info: [1780356542351] ControllerPandora::pushState Jun 01 23:29:02 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:29:02 volumio volumio[17389]: info: CorePlayQueue::getTrack 29 Jun 01 23:29:02 volumio volumio[17389]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"greedy","name":"greedy","artist":"Tate McRae","album":"THINK LATER","albumart":"http://mediaserver-cont-sv5-2-v4v6.pandora.com/images/5a/fc/e8/72/e7914f94b3533f5237db5597/1080W_1080H.jpg","realUri":"http://t1-2.p-cdn.us/access/7421883049801108865.mp3?version=5&lid=360257756&token=B9H7KNjTxa1c9zUT1J6lBPqgrqiUsHr8gsAri%2BGZKr1hMAbVCl59UdZoDoXzKAi4%2Ft%2BBho7eWImVa6rHqf6rqazeBFkZF%2BE9DBoZ4%2FO8TF%2BDSkK%2FmGkKOfO9mR6kiEffLHYsEeIFvlP987X%2FUtUMv3w88zLq01ZX3RaEKYUjEh0O2XCim6Dg8efZUDGD7%2B2F7ieI9%2FHk1QbXdgIPrdQ87x3HvqoK9qF79W36U1NJasbolnumyRM47rRVSyneRAZhds3Va%2BQo1sEB%2FbCZQL41FpBP0vtVNlqW46hlglH2dt02AxwyJvoUGU5wFgCKmnCH0J1R0NikQlLe6ewdkL%2Booo8x17%2BcILXH","isStreaming":true,"duration":130,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t1-2.p-cdn.us/access/7421883049801108865.mp3?version=5&lid=360257756&token=B9H7KNjTxa1c9zUT1J6lBPqgrqiUsHr8gsAri%2BGZKr1hMAbVCl59UdZoDoXzKAi4%2Ft%2BBho7eWImVa6rHqf6rqazeBFkZF%2BE9DBoZ4%2FO8TF%2BDSkK%2FmGkKOfO9mR6kiEffLHYsEeIFvlP987X%2FUtUMv3w88zLq01ZX3RaEKYUjEh0O2XCim6Dg8efZUDGD7%2B2F7ieI9%2FHk1QbXdgIPrdQ87x3HvqoK9qF79W36U1NJasbolnumyRM47rRVSyneRAZhds3Va%2BQo1sEB%2FbCZQL41FpBP0vtVNlqW46hlglH2dt02AxwyJvoUGU5wFgCKmnCH0J1R0NikQlLe6ewdkL%2Booo8x17%2BcILXH","seek":0,"status":"play"} Jun 01 23:29:02 volumio volumio[17389]: verbose: CURRENT POSITION 29 Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::syncState stateService play Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::syncState currentStatus stop Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService pandora Jun 01 23:29:02 volumio volumio[17389]: info: [1780356542352] ControllerPandora::fetchAndAddTracks Jun 01 23:29:02 volumio volumio[17389]: info: [1780356542352] ControllerPandora::getStationTracks Jun 01 23:29:02 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:02 volumio volumio[17389]: info: [1780356542352] [Pandora] PandoraHandler::getSongMaxDiff: 14 Jun 01 23:29:02 volumio volumio[17389]: info: [1780356542352] ControllerPandora::fetchAndAddTracks::getSqInfo Jun 01 23:29:02 volumio volumio[17389]: info: [1780356542352] ControllerPandora::getStationTracks Jun 01 23:29:02 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:02 volumio volumio[17389]: info: [1780356542352] [Pandora] fetchAndAddTracks: diff1: 14 sQPos1: 29 Jun 01 23:29:02 volumio volumio[17389]: info: [1780356542353] [Pandora] fetchAndAddTracks: Fetching tracks Jun 01 23:29:02 volumio volumio[17389]: info: [1780356542353] ControllerPandora::PandoraHandler::fillStationData Jun 01 23:29:02 volumio volumio[17389]: info: Jun 01 23:29:02 volumio volumio[17389]: ---------------------------- MPD announces system playlist update Jun 01 23:29:02 volumio volumio[17389]: info: Ignoring MPD Status Update Jun 01 23:29:02 volumio volumio[17389]: info: ------------------------------ 7ms Jun 01 23:29:02 volumio volumio[17389]: info: ------------------------------ 1ms Jun 01 23:29:02 volumio volumio[17389]: info: [1780356542460] ControllerPandora::pandoraListener Jun 01 23:29:02 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:29:02 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:29:02 volumio volumio[17389]: info: Jun 01 23:29:02 volumio volumio[17389]: ---------------------------- MPD announces state update: player Jun 01 23:29:02 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:29:02 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:29:02 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jun 01 23:29:02 volumio volumio[17389]: info: sendMpdCommand status took 15 milliseconds Jun 01 23:29:02 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:29:02 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 23:29:02 volumio volumio[17389]: info: Jun 01 23:29:02 volumio volumio[17389]: ---------------------------- MPD announces state update: player Jun 01 23:29:02 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:29:02 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:29:02 volumio volumio[17389]: info: sendMpdCommand status took 46 milliseconds Jun 01 23:29:02 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:29:02 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 23:29:02 volumio volumio[17389]: info: sendMpdCommand playlistinfo took 61 milliseconds Jun 01 23:29:02 volumio volumio[17389]: verbose: ControllerMpd::parseTrackInfo Jun 01 23:29:02 volumio volumio[17389]: info: CorePlayQueue::getTrack 29 Jun 01 23:29:02 volumio volumio[17389]: info: [1780356542541] ControllerPandora::pushState Jun 01 23:29:02 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:29:02 volumio volumio[17389]: info: CorePlayQueue::getTrack 29 Jun 01 23:29:02 volumio volumio[17389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":130,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"greedy","artist":"Tate McRae","album":"THINK LATER","uri":"http://t1-2.p-cdn.us/access/7421883049801108865.mp3?version=5&lid=360257756&token=B9H7KNjTxa1c9zUT1J6lBPqgrqiUsHr8gsAri%2BGZKr1hMAbVCl59UdZoDoXzKAi4%2Ft%2BBho7eWImVa6rHqf6rqazeBFkZF%2BE9DBoZ4%2FO8TF%2BDSkK%2FmGkKOfO9mR6kiEffLHYsEeIFvlP987X%2FUtUMv3w88zLq01ZX3RaEKYUjEh0O2XCim6Dg8efZUDGD7%2B2F7ieI9%2FHk1QbXdgIPrdQ87x3HvqoK9qF79W36U1NJasbolnumyRM47rRVSyneRAZhds3Va%2BQo1sEB%2FbCZQL41FpBP0vtVNlqW46hlglH2dt02AxwyJvoUGU5wFgCKmnCH0J1R0NikQlLe6ewdkL%2Booo8x17%2BcILXH","trackType":"mp3"} Jun 01 23:29:02 volumio volumio[17389]: verbose: CURRENT POSITION 29 Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::syncState stateService play Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::syncState currentStatus play Jun 01 23:29:02 volumio volumio[17389]: info: Received an update from plugin. extracting info from payload Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:02 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:29:02 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:02 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService pandora Jun 01 23:29:02 volumio volumio[17389]: info: sendMpdCommand status took 48 milliseconds Jun 01 23:29:02 volumio volumio[17389]: info: sendMpdCommand playlistinfo took 47 milliseconds Jun 01 23:29:02 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:29:02 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 23:29:02 volumio volumio[17389]: verbose: ControllerMpd::parseTrackInfo Jun 01 23:29:02 volumio volumio[17389]: info: ControllerMpd::pushState Jun 01 23:29:02 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:29:02 volumio volumio[17389]: info: CorePlayQueue::getTrack 29 Jun 01 23:29:02 volumio volumio[17389]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jun 01 23:29:02 volumio volumio[17389]: info: ------------------------------ 102ms Jun 01 23:29:02 volumio volumio[17389]: info: CoreCommandRouter::volumioGetQueue Jun 01 23:29:02 volumio volumio[17389]: info: CoreStateMachine::getQueue Jun 01 23:29:02 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:02 volumio volumio[17389]: info: sendMpdCommand playlistinfo took 44 milliseconds Jun 01 23:29:02 volumio volumio[17389]: verbose: ControllerMpd::parseTrackInfo Jun 01 23:29:02 volumio volumio[17389]: info: ControllerMpd::pushState Jun 01 23:29:02 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:29:02 volumio volumio[17389]: info: CorePlayQueue::getTrack 29 Jun 01 23:29:02 volumio volumio[17389]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jun 01 23:29:02 volumio volumio[17389]: info: ------------------------------ 97ms Jun 01 23:29:02 volumio volumio[17389]: info: [1780356542674] ControllerPandora::PandoraHandler::fetchTracks::fetchStationPlaylist Jun 01 23:29:03 volumio volumio[17389]: info: [1780356543300] [Pandora] PandoraHandler::fetchTracks::fetchStationPlaylist: Retrieved Lady Gaga Radio playlist Jun 01 23:29:03 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:03 volumio volumio[17389]: info: [1780356543301] ControllerPandora::PandoraHandler::fetchTracks::fillNewTracks Jun 01 23:29:03 volumio volumio[17389]: info: [1780356543301] [Pandora] PandoraHandler::fetchTracks::fillNewTracks: Fetched 4 track(s) Jun 01 23:29:03 volumio volumio[17389]: info: CorePlayQueue::addQueueItems Jun 01 23:29:03 volumio volumio[17389]: info: Preload queue cleared Jun 01 23:29:03 volumio volumio[17389]: info: Adding Item to queue: /pandora/stationToken=4502368651449014492trackId=1190583743602678975 Jun 01 23:29:03 volumio volumio[17389]: info: Exploding uri /pandora/stationToken=4502368651449014492trackId=1190583743602678975 in service pandora Jun 01 23:29:03 volumio volumio[17389]: info: [1780356543302] ControllerPandora::explodeUri Jun 01 23:29:03 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:03 volumio volumio[17389]: info: Adding Item to queue: /pandora/stationToken=4502368651449014492trackId=950156540319191263 Jun 01 23:29:03 volumio volumio[17389]: info: Exploding uri /pandora/stationToken=4502368651449014492trackId=950156540319191263 in service pandora Jun 01 23:29:03 volumio volumio[17389]: info: [1780356543302] ControllerPandora::explodeUri Jun 01 23:29:03 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:03 volumio volumio[17389]: info: Adding Item to queue: /pandora/stationToken=4502368651449014492trackId=122667708296225607 Jun 01 23:29:03 volumio volumio[17389]: info: Exploding uri /pandora/stationToken=4502368651449014492trackId=122667708296225607 in service pandora Jun 01 23:29:03 volumio volumio[17389]: info: [1780356543302] ControllerPandora::explodeUri Jun 01 23:29:03 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:03 volumio volumio[17389]: info: Adding Item to queue: /pandora/stationToken=4502368651449014492trackId=3319191810356540552 Jun 01 23:29:03 volumio volumio[17389]: info: Exploding uri /pandora/stationToken=4502368651449014492trackId=3319191810356540552 in service pandora Jun 01 23:29:03 volumio volumio[17389]: info: [1780356543303] ControllerPandora::explodeUri Jun 01 23:29:03 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:03 volumio volumio[17389]: info: CoreCommandRouter::volumioPushQueue Jun 01 23:29:03 volumio volumio[17389]: info: CorePlayQueue::saveQueue Jun 01 23:29:03 volumio volumio[17389]: info: CoreStateMachine::updateTrackBlock Jun 01 23:29:03 volumio volumio[17389]: info: CorePlayQueue::getTrackBlock Jun 01 23:29:03 volumio volumio[17389]: info: [1780356543315] ControllerPandora::getStationTracks Jun 01 23:29:03 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:03 volumio volumio[17389]: info: [1780356543316] [Pandora] PandoraHandler::getSongMaxDiff: 18 Jun 01 23:29:03 volumio volumio[17389]: info: [1780356543316] ControllerPandora::fetchAndAddTracks::getSqInfo Jun 01 23:29:03 volumio volumio[17389]: info: [1780356543316] ControllerPandora::getStationTracks Jun 01 23:29:03 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:03 volumio volumio[17389]: info: [1780356543316] [Pandora] fetchAndAddTracks: diff2 > 0: 18 sQPos2: 29 Jun 01 23:29:03 volumio volumio[17389]: info: [1780356543317] ControllerPandora::removeOldTrackBlock Jun 01 23:29:03 volumio volumio[17389]: info: [1780356543317] ControllerPandora::removeStationTracks Jun 01 23:29:03 volumio volumio[17389]: info: [1780356543317] ControllerPandora::getStationTracks Jun 01 23:29:03 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:03 volumio volumio[17389]: info: [1780356543318] [Pandora] removeStationTracks: Removing 18 tracks from queue Jun 01 23:29:04 volumio volumio[17389]: info: CoreCommandRouter::volumioPlay Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::play index 33 Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::stop Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::stPlaybackTimer Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::updateTrackBlock Jun 01 23:29:04 volumio volumio[17389]: info: CorePlayQueue::getTrackBlock Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:04 volumio volumio[17389]: info: CorePlayQueue::getTrack 29 Jun 01 23:29:04 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:29:04 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::serviceStop Jun 01 23:29:04 volumio volumio[17389]: info: CorePlayQueue::getTrack 29 Jun 01 23:29:04 volumio volumio[17389]: info: CoreCommandRouter::serviceStop Jun 01 23:29:04 volumio volumio[17389]: info: [1780356544219] ControllerPandora::stop Jun 01 23:29:04 volumio volumio[17389]: info: ControllerMpd::stop Jun 01 23:29:04 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 23:29:04 volumio volumio[17389]: info: Jun 01 23:29:04 volumio volumio[17389]: ---------------------------- MPD announces state update: player Jun 01 23:29:04 volumio volumio[17389]: info: sendMpdCommand stop took 90 milliseconds Jun 01 23:29:04 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:29:04 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:29:04 volumio volumio[17389]: info: ControllerMpd::clear Jun 01 23:29:04 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 23:29:04 volumio volumio[17389]: info: Jun 01 23:29:04 volumio volumio[17389]: ---------------------------- MPD announces system playlist update Jun 01 23:29:04 volumio volumio[17389]: info: Ignoring MPD Status Update Jun 01 23:29:04 volumio volumio[17389]: info: sendMpdCommand status took 2 milliseconds Jun 01 23:29:04 volumio volumio[17389]: info: sendMpdCommand clear took 2 milliseconds Jun 01 23:29:04 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:29:04 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 23:29:04 volumio volumio[17389]: info: [1780356544320] ControllerPandora::pushState Jun 01 23:29:04 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:04 volumio volumio[17389]: info: CorePlayQueue::getTrack 29 Jun 01 23:29:04 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:29:04 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:04 volumio volumio[17389]: info: CorePlayQueue::getTrack 29 Jun 01 23:29:04 volumio volumio[17389]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"greedy","name":"greedy","artist":"Tate McRae","album":"THINK LATER","albumart":"http://mediaserver-cont-sv5-2-v4v6.pandora.com/images/5a/fc/e8/72/e7914f94b3533f5237db5597/1080W_1080H.jpg","realUri":"http://t1-2.p-cdn.us/access/7421883049801108865.mp3?version=5&lid=360257756&token=B9H7KNjTxa1c9zUT1J6lBPqgrqiUsHr8gsAri%2BGZKr1hMAbVCl59UdZoDoXzKAi4%2Ft%2BBho7eWImVa6rHqf6rqazeBFkZF%2BE9DBoZ4%2FO8TF%2BDSkK%2FmGkKOfO9mR6kiEffLHYsEeIFvlP987X%2FUtUMv3w88zLq01ZX3RaEKYUjEh0O2XCim6Dg8efZUDGD7%2B2F7ieI9%2FHk1QbXdgIPrdQ87x3HvqoK9qF79W36U1NJasbolnumyRM47rRVSyneRAZhds3Va%2BQo1sEB%2FbCZQL41FpBP0vtVNlqW46hlglH2dt02AxwyJvoUGU5wFgCKmnCH0J1R0NikQlLe6ewdkL%2Booo8x17%2BcILXH","isStreaming":true,"duration":130,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t1-2.p-cdn.us/access/7421883049801108865.mp3?version=5&lid=360257756&token=B9H7KNjTxa1c9zUT1J6lBPqgrqiUsHr8gsAri%2BGZKr1hMAbVCl59UdZoDoXzKAi4%2Ft%2BBho7eWImVa6rHqf6rqazeBFkZF%2BE9DBoZ4%2FO8TF%2BDSkK%2FmGkKOfO9mR6kiEffLHYsEeIFvlP987X%2FUtUMv3w88zLq01ZX3RaEKYUjEh0O2XCim6Dg8efZUDGD7%2B2F7ieI9%2FHk1QbXdgIPrdQ87x3HvqoK9qF79W36U1NJasbolnumyRM47rRVSyneRAZhds3Va%2BQo1sEB%2FbCZQL41FpBP0vtVNlqW46hlglH2dt02AxwyJvoUGU5wFgCKmnCH0J1R0NikQlLe6ewdkL%2Booo8x17%2BcILXH","seek":0,"status":"stop"} Jun 01 23:29:04 volumio volumio[17389]: verbose: CURRENT POSITION 29 Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::syncState stateService stop Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::syncState currentStatus stop Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:04 volumio volumio[17389]: info: CorePlayQueue::getTrack 29 Jun 01 23:29:04 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:29:04 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:04 volumio volumio[17389]: info: No code Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:04 volumio volumio[17389]: info: CorePlayQueue::getTrack 29 Jun 01 23:29:04 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService pandora Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::play index undefined Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 23:29:04 volumio volumio[17389]: info: CorePlayQueue::getTrack 33 Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::startPlaybackTimer Jun 01 23:29:04 volumio volumio[17389]: info: CorePlayQueue::getTrack 33 Jun 01 23:29:04 volumio volumio[17389]: info: [1780356544331] ControllerPandora::clearAddPlayTrack Jun 01 23:29:04 volumio volumio[17389]: info: ControllerMpd::clear Jun 01 23:29:04 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 23:29:04 volumio volumio[17389]: error: updateQueue error: null Jun 01 23:29:04 volumio volumio[17389]: info: Jun 01 23:29:04 volumio volumio[17389]: ---------------------------- MPD announces system playlist update Jun 01 23:29:04 volumio volumio[17389]: info: Ignoring MPD Status Update Jun 01 23:29:04 volumio volumio[17389]: info: ------------------------------ 14ms Jun 01 23:29:04 volumio volumio[17389]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 01 23:29:04 volumio volumio[17389]: info: sendMpdCommand clear took 1 milliseconds Jun 01 23:29:04 volumio volumio[17389]: verbose: ControllerMpd::parseTrackInfo Jun 01 23:29:04 volumio volumio[17389]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 01 23:29:04 volumio volumio[17389]: info: [1780356544335] ControllerPandora::checkForExpiredStations Jun 01 23:29:04 volumio volumio[17389]: info: ------------------------------ 25ms Jun 01 23:29:04 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:04 volumio volumio[17389]: info: [1780356544335] ControllerPandora::removeTrack Jun 01 23:29:04 volumio volumio[17389]: info: [1780356544335] [Pandora] removeTrack: Not removing track with uri: null at queue index: -1 Jun 01 23:29:04 volumio volumio[17389]: info: [1780356544335] ControllerPandora::appendTracksToMpd Jun 01 23:29:04 volumio volumio[17389]: info: [1780356544335] [Pandora] appendTracksToMpd: Added 1 track(s) to mpd Jun 01 23:29:04 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand addid Jun 01 23:29:04 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand play Jun 01 23:29:04 volumio volumio[17389]: error: updateQueue error: null Jun 01 23:29:04 volumio volumio[17389]: info: Jun 01 23:29:04 volumio volumio[17389]: ---------------------------- MPD announces system playlist update Jun 01 23:29:04 volumio volumio[17389]: info: Ignoring MPD Status Update Jun 01 23:29:04 volumio volumio[17389]: info: ------------------------------ 8ms Jun 01 23:29:04 volumio volumio[17389]: info: sendMpdCommand addid took 6 milliseconds Jun 01 23:29:04 volumio volumio[17389]: info: sendMpdCommand play took 7 milliseconds Jun 01 23:29:04 volumio volumio[17389]: verbose: MPD COMMAND [object Object] Jun 01 23:29:04 volumio volumio[17389]: verbose: MPD COMMAND [object Object] Jun 01 23:29:04 volumio volumio[17389]: verbose: MPD COMMAND [object Object] Jun 01 23:29:04 volumio volumio[17389]: info: [1780356544345] ControllerPandora::pushState Jun 01 23:29:04 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:29:04 volumio volumio[17389]: info: CorePlayQueue::getTrack 33 Jun 01 23:29:04 volumio volumio[17389]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"TiK ToK","name":"TiK ToK","artist":"Kesha","album":"Animal (Expanded Edition)","albumart":"http://mediaserver-cont-usc-mp1-2-v4v6.pandora.com/images/52/72/65/69/1a424dc497d2d023e432a9e7/1080W_1080H.jpg","realUri":"http://t1-3.p-cdn.us/access/3319191810356540552.mp3?version=5&lid=360257756&token=Z5PSmsPidbOo0Rs61iLttFNLITR7NtzVVXDu5DmcKPI9164UZa5o4%2FQrqSLOYY1RMC2q5SNRSzkDt1oKT5VsA9RRrH7IzDAlKvfSXFwBYEdWL8LA4aO1%2B%2FY1d%2F63YDgjVv782pAlWOj6S3wqhJ%2FsofPDK884qbWMsIL%2BxP%2BElyassEa94fmAzxtiCrgXQMQPas%2B3Kq1zNj0b%2FdwHRGWlmFtZWPrPeLgIb4EmXcFvwBlY7s2oRHpdOxsVHZMB0uKpL1oPicQmK%2Bn%2Fye14kthcCBU%2FhbQEG5MW7ogyiL3jBmRmpmqCoOg%2BYgyshl5JeUIJ%2BtVBPwXHQpc2p%2F5QpccHgRvtJ5jaPFND","isStreaming":true,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t1-3.p-cdn.us/access/3319191810356540552.mp3?version=5&lid=360257756&token=Z5PSmsPidbOo0Rs61iLttFNLITR7NtzVVXDu5DmcKPI9164UZa5o4%2FQrqSLOYY1RMC2q5SNRSzkDt1oKT5VsA9RRrH7IzDAlKvfSXFwBYEdWL8LA4aO1%2B%2FY1d%2F63YDgjVv782pAlWOj6S3wqhJ%2FsofPDK884qbWMsIL%2BxP%2BElyassEa94fmAzxtiCrgXQMQPas%2B3Kq1zNj0b%2FdwHRGWlmFtZWPrPeLgIb4EmXcFvwBlY7s2oRHpdOxsVHZMB0uKpL1oPicQmK%2Bn%2Fye14kthcCBU%2FhbQEG5MW7ogyiL3jBmRmpmqCoOg%2BYgyshl5JeUIJ%2BtVBPwXHQpc2p%2F5QpccHgRvtJ5jaPFND","seek":0,"status":"play"} Jun 01 23:29:04 volumio volumio[17389]: verbose: CURRENT POSITION 33 Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::syncState stateService play Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::syncState currentStatus stop Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService pandora Jun 01 23:29:04 volumio volumio[17389]: info: [1780356544349] ControllerPandora::fetchAndAddTracks Jun 01 23:29:04 volumio volumio[17389]: info: [1780356544350] ControllerPandora::getStationTracks Jun 01 23:29:04 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:04 volumio volumio[17389]: info: [1780356544351] [Pandora] PandoraHandler::getSongMaxDiff: 18 Jun 01 23:29:04 volumio volumio[17389]: info: [1780356544351] ControllerPandora::fetchAndAddTracks::getSqInfo Jun 01 23:29:04 volumio volumio[17389]: info: [1780356544352] ControllerPandora::getStationTracks Jun 01 23:29:04 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:04 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:04 volumio volumio[17389]: info: [1780356544353] [Pandora] fetchAndAddTracks: diff1: 18 sQPos1: 33 Jun 01 23:29:04 volumio volumio[17389]: info: [1780356544354] [Pandora] fetchAndAddTracks: Fetching tracks Jun 01 23:29:04 volumio volumio[17389]: info: [1780356544354] ControllerPandora::PandoraHandler::fillStationData Jun 01 23:29:04 volumio volumio[17389]: info: Jun 01 23:29:04 volumio volumio[17389]: ---------------------------- MPD announces system playlist update Jun 01 23:29:04 volumio volumio[17389]: info: Ignoring MPD Status Update Jun 01 23:29:04 volumio volumio[17389]: info: ------------------------------ 20ms Jun 01 23:29:04 volumio volumio[17389]: info: ------------------------------ 3ms Jun 01 23:29:04 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jun 01 23:29:04 volumio volumio[17389]: info: [1780356544559] ControllerPandora::pandoraListener Jun 01 23:29:04 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:29:04 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:29:04 volumio volumio[17389]: info: Jun 01 23:29:04 volumio volumio[17389]: ---------------------------- MPD announces state update: player Jun 01 23:29:04 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:29:04 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:29:04 volumio volumio[17389]: info: Jun 01 23:29:04 volumio volumio[17389]: ---------------------------- MPD announces state update: player Jun 01 23:29:04 volumio volumio[17389]: info: sendMpdCommand status took 12 milliseconds Jun 01 23:29:04 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:29:04 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:29:04 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:29:04 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 23:29:04 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:29:04 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 23:29:04 volumio volumio[17389]: info: sendMpdCommand status took 1 milliseconds Jun 01 23:29:04 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:29:04 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 23:29:04 volumio volumio[17389]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 01 23:29:04 volumio volumio[17389]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 01 23:29:04 volumio volumio[17389]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 01 23:29:04 volumio volumio[17389]: verbose: ControllerMpd::parseTrackInfo Jun 01 23:29:04 volumio volumio[17389]: verbose: ControllerMpd::parseTrackInfo Jun 01 23:29:04 volumio volumio[17389]: info: CorePlayQueue::getTrack 33 Jun 01 23:29:04 volumio volumio[17389]: info: [1780356544577] ControllerPandora::pushState Jun 01 23:29:04 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:29:04 volumio volumio[17389]: info: CorePlayQueue::getTrack 33 Jun 01 23:29:04 volumio volumio[17389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"TiK ToK","artist":"Kesha","album":"Animal (Expanded Edition)","uri":"http://t1-3.p-cdn.us/access/3319191810356540552.mp3?version=5&lid=360257756&token=Z5PSmsPidbOo0Rs61iLttFNLITR7NtzVVXDu5DmcKPI9164UZa5o4%2FQrqSLOYY1RMC2q5SNRSzkDt1oKT5VsA9RRrH7IzDAlKvfSXFwBYEdWL8LA4aO1%2B%2FY1d%2F63YDgjVv782pAlWOj6S3wqhJ%2FsofPDK884qbWMsIL%2BxP%2BElyassEa94fmAzxtiCrgXQMQPas%2B3Kq1zNj0b%2FdwHRGWlmFtZWPrPeLgIb4EmXcFvwBlY7s2oRHpdOxsVHZMB0uKpL1oPicQmK%2Bn%2Fye14kthcCBU%2FhbQEG5MW7ogyiL3jBmRmpmqCoOg%2BYgyshl5JeUIJ%2BtVBPwXHQpc2p%2F5QpccHgRvtJ5jaPFND","trackType":"mp3"} Jun 01 23:29:04 volumio volumio[17389]: verbose: CURRENT POSITION 33 Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::syncState stateService play Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::syncState currentStatus play Jun 01 23:29:04 volumio volumio[17389]: info: Received an update from plugin. extracting info from payload Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:04 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:29:04 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:04 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService pandora Jun 01 23:29:04 volumio volumio[17389]: info: ControllerMpd::pushState Jun 01 23:29:04 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:29:04 volumio volumio[17389]: info: CorePlayQueue::getTrack 33 Jun 01 23:29:04 volumio volumio[17389]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jun 01 23:29:04 volumio volumio[17389]: info: ControllerMpd::pushState Jun 01 23:29:04 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:29:04 volumio volumio[17389]: info: CorePlayQueue::getTrack 33 Jun 01 23:29:04 volumio volumio[17389]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jun 01 23:29:04 volumio volumio[17389]: info: ------------------------------ 24ms Jun 01 23:29:04 volumio volumio[17389]: info: ------------------------------ 12ms Jun 01 23:29:04 volumio volumio[17389]: info: CoreCommandRouter::volumioGetQueue Jun 01 23:29:04 volumio volumio[17389]: info: CoreStateMachine::getQueue Jun 01 23:29:04 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:04 volumio volumio[17389]: info: [1780356544708] ControllerPandora::PandoraHandler::fetchTracks::fetchStationPlaylist Jun 01 23:29:05 volumio volumio[17389]: info: [1780356545410] [Pandora] PandoraHandler::fetchTracks::fetchStationPlaylist: Retrieved Lady Gaga Radio playlist Jun 01 23:29:05 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:05 volumio volumio[17389]: info: [1780356545410] ControllerPandora::PandoraHandler::fetchTracks::fillNewTracks Jun 01 23:29:05 volumio volumio[17389]: info: [1780356545411] [Pandora] PandoraHandler::fetchTracks::fillNewTracks: Fetched 4 track(s) Jun 01 23:29:05 volumio volumio[17389]: info: CorePlayQueue::addQueueItems Jun 01 23:29:05 volumio volumio[17389]: info: Preload queue cleared Jun 01 23:29:05 volumio volumio[17389]: info: Adding Item to queue: /pandora/stationToken=4502368651449014492trackId=3542073658960752748 Jun 01 23:29:05 volumio volumio[17389]: info: Exploding uri /pandora/stationToken=4502368651449014492trackId=3542073658960752748 in service pandora Jun 01 23:29:05 volumio volumio[17389]: info: [1780356545411] ControllerPandora::explodeUri Jun 01 23:29:05 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:05 volumio volumio[17389]: info: Adding Item to queue: /pandora/stationToken=4502368651449014492trackId=5709884647187026541 Jun 01 23:29:05 volumio volumio[17389]: info: Exploding uri /pandora/stationToken=4502368651449014492trackId=5709884647187026541 in service pandora Jun 01 23:29:05 volumio volumio[17389]: info: [1780356545412] ControllerPandora::explodeUri Jun 01 23:29:05 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:05 volumio volumio[17389]: info: Adding Item to queue: /pandora/stationToken=4502368651449014492trackId=3168251537919743065 Jun 01 23:29:05 volumio volumio[17389]: info: Exploding uri /pandora/stationToken=4502368651449014492trackId=3168251537919743065 in service pandora Jun 01 23:29:05 volumio volumio[17389]: info: [1780356545412] ControllerPandora::explodeUri Jun 01 23:29:05 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:05 volumio volumio[17389]: info: Adding Item to queue: /pandora/stationToken=4502368651449014492trackId=5846119136686592685 Jun 01 23:29:05 volumio volumio[17389]: info: Exploding uri /pandora/stationToken=4502368651449014492trackId=5846119136686592685 in service pandora Jun 01 23:29:05 volumio volumio[17389]: info: [1780356545412] ControllerPandora::explodeUri Jun 01 23:29:05 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:05 volumio volumio[17389]: info: CoreCommandRouter::volumioPushQueue Jun 01 23:29:05 volumio volumio[17389]: info: CorePlayQueue::saveQueue Jun 01 23:29:05 volumio volumio[17389]: info: CoreStateMachine::updateTrackBlock Jun 01 23:29:05 volumio volumio[17389]: info: CorePlayQueue::getTrackBlock Jun 01 23:29:05 volumio volumio[17389]: info: [1780356545428] ControllerPandora::getStationTracks Jun 01 23:29:05 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:05 volumio volumio[17389]: info: [1780356545428] [Pandora] PandoraHandler::getSongMaxDiff: 22 Jun 01 23:29:05 volumio volumio[17389]: info: [1780356545429] ControllerPandora::fetchAndAddTracks::getSqInfo Jun 01 23:29:05 volumio volumio[17389]: info: [1780356545429] ControllerPandora::getStationTracks Jun 01 23:29:05 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:05 volumio volumio[17389]: info: [1780356545429] [Pandora] fetchAndAddTracks: diff2 > 0: 22 sQPos2: 33 Jun 01 23:29:05 volumio volumio[17389]: info: [1780356545429] ControllerPandora::removeOldTrackBlock Jun 01 23:29:05 volumio volumio[17389]: info: [1780356545429] ControllerPandora::removeStationTracks Jun 01 23:29:05 volumio volumio[17389]: info: [1780356545429] ControllerPandora::getStationTracks Jun 01 23:29:05 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:05 volumio volumio[17389]: info: [1780356545431] [Pandora] removeStationTracks: Removing 22 tracks from queue Jun 01 23:29:07 volumio volumio[17389]: info: CoreCommandRouter::volumioGetState Jun 01 23:29:07 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:07 volumio volumio[17389]: info: [1780356547676] ControllerPandora::removeTrack Jun 01 23:29:07 volumio volumio[17389]: info: CoreStateMachine::removeQueueItem Jun 01 23:29:07 volumio volumio[17389]: info: CorePlayQueue::removeQueueItem 0 Jun 01 23:29:07 volumio volumio[17389]: info: CorePlayQueue::saveQueue Jun 01 23:29:07 volumio volumio[17389]: info: CoreCommandRouter::volumioPushQueue Jun 01 23:29:07 volumio volumio[17389]: info: [1780356547680] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=4502368651449014492trackId=5547969720176834184 at queue index: 0 Jun 01 23:29:07 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:29:07 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:29:07 volumio volumio[17389]: info: sendMpdCommand status took 15 milliseconds Jun 01 23:29:07 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:29:07 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 23:29:07 volumio volumio[17389]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 01 23:29:07 volumio volumio[17389]: verbose: ControllerMpd::parseTrackInfo Jun 01 23:29:07 volumio volumio[17389]: info: [1780356547697] ControllerPandora::pushState Jun 01 23:29:07 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:29:07 volumio volumio[17389]: info: CorePlayQueue::getTrack 32 Jun 01 23:29:07 volumio volumio[17389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3680,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TiK ToK","artist":"Kesha","album":"Animal (Expanded Edition)","uri":"http://t1-3.p-cdn.us/access/3319191810356540552.mp3?version=5&lid=360257756&token=Z5PSmsPidbOo0Rs61iLttFNLITR7NtzVVXDu5DmcKPI9164UZa5o4%2FQrqSLOYY1RMC2q5SNRSzkDt1oKT5VsA9RRrH7IzDAlKvfSXFwBYEdWL8LA4aO1%2B%2FY1d%2F63YDgjVv782pAlWOj6S3wqhJ%2FsofPDK884qbWMsIL%2BxP%2BElyassEa94fmAzxtiCrgXQMQPas%2B3Kq1zNj0b%2FdwHRGWlmFtZWPrPeLgIb4EmXcFvwBlY7s2oRHpdOxsVHZMB0uKpL1oPicQmK%2Bn%2Fye14kthcCBU%2FhbQEG5MW7ogyiL3jBmRmpmqCoOg%2BYgyshl5JeUIJ%2BtVBPwXHQpc2p%2F5QpccHgRvtJ5jaPFND","trackType":"mp3"} Jun 01 23:29:07 volumio volumio[17389]: verbose: CURRENT POSITION 32 Jun 01 23:29:07 volumio volumio[17389]: info: CoreStateMachine::syncState stateService play Jun 01 23:29:07 volumio volumio[17389]: info: CoreStateMachine::syncState currentStatus play Jun 01 23:29:07 volumio volumio[17389]: info: Received an update from plugin. extracting info from payload Jun 01 23:29:07 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:07 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:29:07 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:07 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:07 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:07 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService pandora Jun 01 23:29:09 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:09 volumio volumio[17389]: info: [1780356549550] ControllerPandora::removeTrack Jun 01 23:29:09 volumio volumio[17389]: info: [1780356549550] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=4502368651449014492trackId=5547969720176834184 at queue index: -1 Jun 01 23:29:11 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:11 volumio volumio[17389]: info: [1780356551013] ControllerPandora::removeTrack Jun 01 23:29:11 volumio volumio[17389]: info: [1780356551013] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=4502368651449014492trackId=5547969720176834184 at queue index: -1 Jun 01 23:29:11 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:11 volumio volumio[17389]: info: [1780356551500] ControllerPandora::removeTrack Jun 01 23:29:11 volumio volumio[17389]: info: CoreStateMachine::removeQueueItem Jun 01 23:29:11 volumio volumio[17389]: info: CorePlayQueue::removeQueueItem 11 Jun 01 23:29:11 volumio volumio[17389]: info: CorePlayQueue::saveQueue Jun 01 23:29:11 volumio volumio[17389]: info: CoreCommandRouter::volumioPushQueue Jun 01 23:29:11 volumio volumio[17389]: info: [1780356551510] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=4502368651449014492trackId=988044906796796034 at queue index: 11 Jun 01 23:29:11 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:29:11 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:29:11 volumio volumio[17389]: info: sendMpdCommand status took 10 milliseconds Jun 01 23:29:11 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:29:11 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 23:29:11 volumio volumio[17389]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 01 23:29:11 volumio volumio[17389]: verbose: ControllerMpd::parseTrackInfo Jun 01 23:29:11 volumio volumio[17389]: info: [1780356551524] ControllerPandora::pushState Jun 01 23:29:11 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:29:11 volumio volumio[17389]: info: CorePlayQueue::getTrack 31 Jun 01 23:29:11 volumio volumio[17389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7520,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TiK ToK","artist":"Kesha","album":"Animal (Expanded Edition)","uri":"http://t1-3.p-cdn.us/access/3319191810356540552.mp3?version=5&lid=360257756&token=Z5PSmsPidbOo0Rs61iLttFNLITR7NtzVVXDu5DmcKPI9164UZa5o4%2FQrqSLOYY1RMC2q5SNRSzkDt1oKT5VsA9RRrH7IzDAlKvfSXFwBYEdWL8LA4aO1%2B%2FY1d%2F63YDgjVv782pAlWOj6S3wqhJ%2FsofPDK884qbWMsIL%2BxP%2BElyassEa94fmAzxtiCrgXQMQPas%2B3Kq1zNj0b%2FdwHRGWlmFtZWPrPeLgIb4EmXcFvwBlY7s2oRHpdOxsVHZMB0uKpL1oPicQmK%2Bn%2Fye14kthcCBU%2FhbQEG5MW7ogyiL3jBmRmpmqCoOg%2BYgyshl5JeUIJ%2BtVBPwXHQpc2p%2F5QpccHgRvtJ5jaPFND","trackType":"mp3"} Jun 01 23:29:11 volumio volumio[17389]: verbose: CURRENT POSITION 31 Jun 01 23:29:11 volumio volumio[17389]: info: CoreStateMachine::syncState stateService play Jun 01 23:29:11 volumio volumio[17389]: info: CoreStateMachine::syncState currentStatus play Jun 01 23:29:11 volumio volumio[17389]: info: Received an update from plugin. extracting info from payload Jun 01 23:29:11 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:11 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:29:11 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:11 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:11 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:11 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService pandora Jun 01 23:29:13 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:13 volumio volumio[17389]: info: [1780356553317] ControllerPandora::removeTrack Jun 01 23:29:13 volumio volumio[17389]: info: [1780356553318] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=4502368651449014492trackId=5547969720176834184 at queue index: -1 Jun 01 23:29:13 volumio volumio[17389]: info: CoreCommandRouter::volumioRemoveQueueItem Jun 01 23:29:13 volumio volumio[17389]: info: CoreStateMachine::removeQueueItem Jun 01 23:29:13 volumio volumio[17389]: info: CorePlayQueue::removeQueueItem 33 Jun 01 23:29:13 volumio volumio[17389]: info: CorePlayQueue::saveQueue Jun 01 23:29:13 volumio volumio[17389]: info: CoreCommandRouter::volumioPushQueue Jun 01 23:29:14 volumio volumio[17389]: info: CoreCommandRouter::volumioRemoveQueueItem Jun 01 23:29:14 volumio volumio[17389]: info: CoreStateMachine::removeQueueItem Jun 01 23:29:14 volumio volumio[17389]: info: CorePlayQueue::removeQueueItem 34 Jun 01 23:29:14 volumio volumio[17389]: info: CorePlayQueue::saveQueue Jun 01 23:29:14 volumio volumio[17389]: info: CoreCommandRouter::volumioPushQueue Jun 01 23:29:15 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:15 volumio volumio[17389]: info: [1780356555429] ControllerPandora::removeTrack Jun 01 23:29:15 volumio volumio[17389]: info: [1780356555429] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=4502368651449014492trackId=5547969720176834184 at queue index: -1 Jun 01 23:29:17 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:17 volumio volumio[17389]: info: [1780356557675] ControllerPandora::removeTrack Jun 01 23:29:17 volumio volumio[17389]: info: CoreStateMachine::removeQueueItem Jun 01 23:29:17 volumio volumio[17389]: info: CorePlayQueue::removeQueueItem 0 Jun 01 23:29:17 volumio volumio[17389]: info: CorePlayQueue::saveQueue Jun 01 23:29:17 volumio volumio[17389]: info: CoreCommandRouter::volumioPushQueue Jun 01 23:29:17 volumio volumio[17389]: info: [1780356557681] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=4502368651449014492trackId=7701065148744478121 at queue index: 0 Jun 01 23:29:17 volumio volumio[17389]: info: ControllerMpd::getState Jun 01 23:29:17 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand status Jun 01 23:29:17 volumio volumio[17389]: info: sendMpdCommand status took 8 milliseconds Jun 01 23:29:17 volumio volumio[17389]: verbose: ControllerMpd::parseState Jun 01 23:29:17 volumio volumio[17389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 23:29:17 volumio volumio[17389]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 01 23:29:17 volumio volumio[17389]: verbose: ControllerMpd::parseTrackInfo Jun 01 23:29:17 volumio volumio[17389]: info: [1780356557692] ControllerPandora::pushState Jun 01 23:29:17 volumio volumio[17389]: info: CoreCommandRouter::servicePushState Jun 01 23:29:17 volumio volumio[17389]: info: CorePlayQueue::getTrack 30 Jun 01 23:29:17 volumio volumio[17389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13668,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TiK ToK","artist":"Kesha","album":"Animal (Expanded Edition)","uri":"http://t1-3.p-cdn.us/access/3319191810356540552.mp3?version=5&lid=360257756&token=Z5PSmsPidbOo0Rs61iLttFNLITR7NtzVVXDu5DmcKPI9164UZa5o4%2FQrqSLOYY1RMC2q5SNRSzkDt1oKT5VsA9RRrH7IzDAlKvfSXFwBYEdWL8LA4aO1%2B%2FY1d%2F63YDgjVv782pAlWOj6S3wqhJ%2FsofPDK884qbWMsIL%2BxP%2BElyassEa94fmAzxtiCrgXQMQPas%2B3Kq1zNj0b%2FdwHRGWlmFtZWPrPeLgIb4EmXcFvwBlY7s2oRHpdOxsVHZMB0uKpL1oPicQmK%2Bn%2Fye14kthcCBU%2FhbQEG5MW7ogyiL3jBmRmpmqCoOg%2BYgyshl5JeUIJ%2BtVBPwXHQpc2p%2F5QpccHgRvtJ5jaPFND","trackType":"mp3"} Jun 01 23:29:17 volumio volumio[17389]: verbose: CURRENT POSITION 30 Jun 01 23:29:17 volumio volumio[17389]: info: CoreStateMachine::syncState stateService play Jun 01 23:29:17 volumio volumio[17389]: info: CoreStateMachine::syncState currentStatus play Jun 01 23:29:17 volumio volumio[17389]: info: Received an update from plugin. extracting info from payload Jun 01 23:29:17 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:17 volumio volumio[17389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 23:29:17 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:17 volumio volumio[17389]: info: CoreStateMachine::pushState Jun 01 23:29:17 volumio volumio[17389]: info: CoreCommandRouter::volumioPushState Jun 01 23:29:17 volumio volumio[17389]: info: CoreStateMachine::setConsumeUpdateService pandora Jun 01 23:29:17 volumio volumio[17389]: info: CoreCommandRouter::volumioMoveQueue Jun 01 23:29:17 volumio volumio[17389]: info: CoreStateMachine::moveQueueItem 33 32 Jun 01 23:29:17 volumio volumio[17389]: info: CorePlayQueue::moveQueueItem 33 --> 32 Jun 01 23:29:17 volumio volumio[17389]: info: CoreCommandRouter::volumioPushQueue Jun 01 23:29:19 volumio volumio[17389]: info: CorePlayQueue::getQueue Jun 01 23:29:19 volumio volumio[17389]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 23:29:19 volumio volumio[17389]: TypeError: Cannot read property 'uri' of undefined Jun 01 23:29:19 volumio volumio[17389]: at /data/plugins/music_service/pandora/index.js:699:51 Jun 01 23:29:19 volumio volumio[17389]: at Array.findIndex () Jun 01 23:29:19 volumio volumio[17389]: at ControllerPandora.getQueueIndex (/data/plugins/music_service/pandora/index.js:699:28) Jun 01 23:29:19 volumio volumio[17389]: at ControllerPandora.removeTrack (/data/plugins/music_service/pandora/index.js:736:24) Jun 01 23:29:19 volumio volumio[17389]: at Timeout._onTimeout (/data/plugins/music_service/pandora/helpers.js:62:13) Jun 01 23:29:19 volumio volumio[17389]: at listOnTimeout (internal/timers.js:554:17) Jun 01 23:29:19 volumio volumio[17389]: at processTimers (internal/timers.js:497:7) Jun 01 23:29:19 volumio volumio[17389]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 23:29:20 volumio sudo[18884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-06-01 23:28 Jun 01 23:29:20 volumio sudo[18884]: 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="c87c6e2b1ae33dc5801986cbcbe970898b92e7b4" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="b949d6001d7e656b4668f31c119d2247cdf45158" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 30 Mar 2023 05:45:03 PM CEST" VOLUMIO_VERSION="3.449" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6428d8e2c06e98483f5c5d77e8f01989"