-- Logs begin at Thu 2025-07-03 23:33:47 UTC, end at Fri 2025-07-04 06:41:41 UTC. -- Jul 04 06:40:03 musica volumio[599]: info: CorePlayQueue::getQueue Jul 04 06:40:03 musica volumio[599]: info: [1751611203277] ControllerPandora::removeTrack Jul 04 06:40:03 musica volumio[599]: info: [1751611203277] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=83198947509667521trackId=1957850639998260493 at queue index: 12 Jul 04 06:40:13 musica volumio[599]: info: CorePlayQueue::getQueue Jul 04 06:40:13 musica volumio[599]: info: [1751611213281] ControllerPandora::removeTrack Jul 04 06:40:13 musica volumio[599]: info: [1751611213282] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=83198947509667521trackId=1855755648487286010 at queue index: 13 Jul 04 06:40:23 musica volumio[599]: info: CorePlayQueue::getQueue Jul 04 06:40:23 musica volumio[599]: info: [1751611223283] ControllerPandora::removeTrack Jul 04 06:40:23 musica volumio[599]: info: [1751611223285] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=83198947509667521trackId=4114416618480336004 at queue index: 14 Jul 04 06:40:33 musica volumio[599]: info: CorePlayQueue::getQueue Jul 04 06:40:33 musica volumio[599]: info: [1751611233280] ControllerPandora::removeTrack Jul 04 06:40:33 musica volumio[599]: info: [1751611233281] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=83198947509667521trackId=2336117709895812280 at queue index: 15 Jul 04 06:40:43 musica volumio[599]: info: CorePlayQueue::getQueue Jul 04 06:40:43 musica volumio[599]: info: [1751611243313] ControllerPandora::removeTrack Jul 04 06:40:43 musica volumio[599]: info: [1751611243315] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=83198947509667521trackId=82735238256259011 at queue index: 16 Jul 04 06:40:53 musica volumio[599]: info: CorePlayQueue::getQueue Jul 04 06:40:53 musica volumio[599]: info: [1751611253280] ControllerPandora::removeTrack Jul 04 06:40:53 musica volumio[599]: info: [1751611253281] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=83198947509667521trackId=7173297568333633452 at queue index: 17 Jul 04 06:41:03 musica volumio[599]: info: CorePlayQueue::getQueue Jul 04 06:41:03 musica volumio[599]: info: [1751611263279] ControllerPandora::removeTrack Jul 04 06:41:03 musica volumio[599]: info: [1751611263279] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=83198947509667521trackId=2465496114798762660 at queue index: 18 Jul 04 06:41:13 musica volumio[599]: info: CorePlayQueue::getQueue Jul 04 06:41:13 musica volumio[599]: info: [1751611273317] ControllerPandora::removeTrack Jul 04 06:41:13 musica volumio[599]: info: [1751611273318] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=83198947509667521trackId=1309551331933370429 at queue index: 19 Jul 04 06:41:24 musica volumio[599]: info: [1751611284120] ControllerPandora::pandoraListener Jul 04 06:41:24 musica volumio[599]: info: ControllerMpd::getState Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::sendMpdCommand status Jul 04 06:41:24 musica volumio[599]: info: Jul 04 06:41:24 musica volumio[599]: ---------------------------- MPD announces state update: player Jul 04 06:41:24 musica volumio[599]: info: ControllerMpd::getState Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::sendMpdCommand status Jul 04 06:41:24 musica volumio[599]: info: Jul 04 06:41:24 musica volumio[599]: ---------------------------- MPD announces state update: player Jul 04 06:41:24 musica volumio[599]: info: ControllerMpd::getState Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::sendMpdCommand status Jul 04 06:41:24 musica volumio[599]: info: Jul 04 06:41:24 musica volumio[599]: ---------------------------- MPD announces state update: player Jul 04 06:41:24 musica volumio[599]: info: ControllerMpd::getState Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::sendMpdCommand status Jul 04 06:41:24 musica volumio[599]: info: Jul 04 06:41:24 musica volumio[599]: ---------------------------- MPD announces state update: player Jul 04 06:41:24 musica volumio[599]: info: ControllerMpd::getState Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::sendMpdCommand status Jul 04 06:41:24 musica volumio[599]: info: sendMpdCommand status took 41 milliseconds Jul 04 06:41:24 musica volumio[599]: info: sendMpdCommand status took 32 milliseconds Jul 04 06:41:24 musica volumio[599]: info: sendMpdCommand status took 28 milliseconds Jul 04 06:41:24 musica volumio[599]: info: sendMpdCommand status took 23 milliseconds Jul 04 06:41:24 musica volumio[599]: info: sendMpdCommand status took 20 milliseconds Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::parseState Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::parseState Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::parseState Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::parseState Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::parseState Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::getTrack 1 Jul 04 06:41:24 musica volumio[599]: info: [1751611284179] ControllerPandora::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::servicePushState Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::volumioPushState Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::getTrack 1 Jul 04 06:41:24 musica volumio[599]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null,"trackType":"mp3"} Jul 04 06:41:24 musica volumio[599]: verbose: CURRENT POSITION 1 Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::syncState stateService stop Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::syncState currentStatus play Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::play index undefined Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::pushState Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::getTrack 2 Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::volumioPushState Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::setConsumeUpdateService pandora Jul 04 06:41:24 musica volumio[599]: info: ControllerMpd::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::servicePushState Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::volumioPushState Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::getTrack 2 Jul 04 06:41:24 musica volumio[599]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 04 06:41:24 musica volumio[599]: info: ControllerMpd::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::servicePushState Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::volumioPushState Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::getTrack 2 Jul 04 06:41:24 musica volumio[599]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 04 06:41:24 musica volumio[599]: info: ControllerMpd::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::servicePushState Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::volumioPushState Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::getTrack 2 Jul 04 06:41:24 musica volumio[599]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 04 06:41:24 musica volumio[599]: info: ControllerMpd::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::servicePushState Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::volumioPushState Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::getTrack 2 Jul 04 06:41:24 musica volumio[599]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::getTrack 2 Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::startPlaybackTimer Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::getTrack 2 Jul 04 06:41:24 musica volumio[599]: info: [1751611284243] ControllerPandora::clearAddPlayTrack Jul 04 06:41:24 musica volumio[599]: info: ControllerMpd::clear Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::sendMpdCommand clear Jul 04 06:41:24 musica volumio[599]: info: ------------------------------ 117ms Jul 04 06:41:24 musica volumio[599]: info: ------------------------------ 107ms Jul 04 06:41:24 musica volumio[599]: info: ------------------------------ 104ms Jul 04 06:41:24 musica volumio[599]: info: ------------------------------ 100ms Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::volumioPushState Jul 04 06:41:24 musica volumio[599]: info: Jul 04 06:41:24 musica volumio[599]: ---------------------------- MPD announces system playlist update Jul 04 06:41:24 musica volumio[599]: info: Ignoring MPD Status Update Jul 04 06:41:24 musica volumio[599]: info: sendMpdCommand clear took 95 milliseconds Jul 04 06:41:24 musica volumio[599]: info: [1751611284343] ControllerPandora::checkForExpiredStations Jul 04 06:41:24 musica volumio[599]: info: [1751611284343] [Pandora] checkForExpiredStations: Stations expired Jul 04 06:41:24 musica volumio[599]: info: [1751611284344] ControllerPandora::PandoraHandler::fillStationData Jul 04 06:41:24 musica volumio[599]: info: Jul 04 06:41:24 musica volumio[599]: ---------------------------- MPD announces system playlist update Jul 04 06:41:24 musica volumio[599]: info: Ignoring MPD Status Update Jul 04 06:41:24 musica volumio[599]: info: Jul 04 06:41:24 musica volumio[599]: ---------------------------- MPD announces system playlist update Jul 04 06:41:24 musica volumio[599]: info: Ignoring MPD Status Update Jul 04 06:41:24 musica volumio[599]: info: Jul 04 06:41:24 musica volumio[599]: ---------------------------- MPD announces system playlist update Jul 04 06:41:24 musica volumio[599]: info: Ignoring MPD Status Update Jul 04 06:41:24 musica volumio[599]: error: updateQueue error: null Jul 04 06:41:24 musica volumio[599]: error: updateQueue error: null Jul 04 06:41:24 musica volumio[599]: error: updateQueue error: null Jul 04 06:41:24 musica volumio[599]: error: updateQueue error: null Jul 04 06:41:24 musica volumio[599]: info: ------------------------------ 92ms Jul 04 06:41:24 musica volumio[599]: info: ------------------------------ 58ms Jul 04 06:41:24 musica volumio[599]: info: ------------------------------ 55ms Jul 04 06:41:24 musica volumio[599]: info: ------------------------------ 52ms Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::getQueue Jul 04 06:41:24 musica volumio[599]: info: [1751611284562] ControllerPandora::removeTrack Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::removeQueueItem Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::removeQueueItem 1 Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::saveQueue Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::volumioPushQueue Jul 04 06:41:24 musica volumio[599]: info: [1751611284577] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=83198947509667521trackId=246907599182378782 at queue index: 1 Jul 04 06:41:24 musica volumio[599]: info: ControllerMpd::getState Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::sendMpdCommand status Jul 04 06:41:24 musica volumio[599]: info: [1751611284586] ControllerPandora::appendTracksToMpd Jul 04 06:41:24 musica volumio[599]: info: [1751611284588] [Pandora] appendTracksToMpd: Added 1 track(s) to mpd Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::sendMpdCommand addid Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::sendMpdCommand play Jul 04 06:41:24 musica volumio[599]: info: Jul 04 06:41:24 musica volumio[599]: ---------------------------- MPD announces system playlist update Jul 04 06:41:24 musica volumio[599]: info: Ignoring MPD Status Update Jul 04 06:41:24 musica volumio[599]: info: sendMpdCommand status took 41 milliseconds Jul 04 06:41:24 musica volumio[599]: info: sendMpdCommand addid took 29 milliseconds Jul 04 06:41:24 musica volumio[599]: info: sendMpdCommand play took 28 milliseconds Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::parseState Jul 04 06:41:24 musica volumio[599]: info: [1751611284626] ControllerPandora::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::servicePushState Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::volumioPushState Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::getTrack 1 Jul 04 06:41:24 musica volumio[599]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null,"trackType":"mp3"} Jul 04 06:41:24 musica volumio[599]: verbose: CURRENT POSITION 1 Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::syncState stateService stop Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::syncState currentStatus stop Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::volumioPushState Jul 04 06:41:24 musica volumio[599]: info: No code Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::volumioPushState Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::setConsumeUpdateService pandora Jul 04 06:41:24 musica volumio[599]: verbose: MPD COMMAND [object Object] Jul 04 06:41:24 musica volumio[599]: verbose: MPD COMMAND [object Object] Jul 04 06:41:24 musica volumio[599]: verbose: MPD COMMAND [object Object] Jul 04 06:41:24 musica volumio[599]: info: [1751611284643] ControllerPandora::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::servicePushState Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::getTrack 1 Jul 04 06:41:24 musica volumio[599]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"Deja Que Salga la Luna (Remastered)","name":"Deja Que Salga la Luna (Remastered)","artist":"José Alfredo Jiménez","album":"Fiesta Latina (Remastered)","albumart":"http://mediaserver-cont-usc-mp1-2-v4v6.pandora.com/images/56/8b/b4/6d/cdad40008fa0f8e07fbaab42/1080W_1080H.jpg","realUri":"http://audio-usc-mp1-t1-2-v4v6.pandora.com/access/275594584904164597.mp3?version=5&lid=634959990&token=%2BptTB2Zfgad584b1ID1EX0Or5ZnhcGzxYq2V5i%2FXWXrluZJ6D8z4QjjuZ%2BvBxHi3Dsx1d0tgOxCIQCZMDJl0wNU4n1JIa2NrCYRTdHgIPDsZWkjRpWe1lt0aRXr6fh3MRKYF%2Fn3u81q45l1f0eca1WS%2FSf0eXhVEGN5x%2FbvMcvsNUafHDgXy%2Fp8Lv9GfvdXcmUy%2FrCErmWsp65yS7KHXr24fRAyjMTVSHNj43Zq4BlAHEk2J3gNxeCOQfnqAr0CpSNCl4KJ%2FYVUmmw2KiiMlMTcvxT4p5%2FCqxrhuExAR8ohMy4faETNWLPMnTpxqPpHO%2FFZy7k8u5eI%3D","isStreaming":true,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://audio-usc-mp1-t1-2-v4v6.pandora.com/access/275594584904164597.mp3?version=5&lid=634959990&token=%2BptTB2Zfgad584b1ID1EX0Or5ZnhcGzxYq2V5i%2FXWXrluZJ6D8z4QjjuZ%2BvBxHi3Dsx1d0tgOxCIQCZMDJl0wNU4n1JIa2NrCYRTdHgIPDsZWkjRpWe1lt0aRXr6fh3MRKYF%2Fn3u81q45l1f0eca1WS%2FSf0eXhVEGN5x%2FbvMcvsNUafHDgXy%2Fp8Lv9GfvdXcmUy%2FrCErmWsp65yS7KHXr24fRAyjMTVSHNj43Zq4BlAHEk2J3gNxeCOQfnqAr0CpSNCl4KJ%2FYVUmmw2KiiMlMTcvxT4p5%2FCqxrhuExAR8ohMy4faETNWLPMnTpxqPpHO%2FFZy7k8u5eI%3D","seek":0,"status":"play"} Jul 04 06:41:24 musica volumio[599]: verbose: CURRENT POSITION 1 Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::syncState stateService play Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::syncState currentStatus stop Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::volumioPushState Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::setConsumeUpdateService pandora Jul 04 06:41:24 musica volumio[599]: info: [1751611284657] ControllerPandora::fetchAndAddTracks Jul 04 06:41:24 musica volumio[599]: info: [1751611284658] ControllerPandora::getStationTracks Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::getQueue Jul 04 06:41:24 musica volumio[599]: info: [1751611284660] [Pandora] PandoraHandler::getSongMaxDiff: 2 Jul 04 06:41:24 musica volumio[599]: info: [1751611284662] ControllerPandora::fetchAndAddTracks::getSqInfo Jul 04 06:41:24 musica volumio[599]: info: [1751611284662] ControllerPandora::getStationTracks Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::getQueue Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::getQueue Jul 04 06:41:24 musica volumio[599]: info: [1751611284666] [Pandora] fetchAndAddTracks: diff1: 2 sQPos1: 0 Jul 04 06:41:24 musica volumio[599]: info: [1751611284666] [Pandora] fetchAndAddTracks: Not fetching tracks: qPos == 0 && diff1 >= 0 Jul 04 06:41:24 musica volumio[599]: info: Jul 04 06:41:24 musica volumio[599]: ---------------------------- MPD announces system playlist update Jul 04 06:41:24 musica volumio[599]: info: Ignoring MPD Status Update Jul 04 06:41:24 musica volumio[599]: info: Jul 04 06:41:24 musica volumio[599]: ---------------------------- MPD announces system playlist update Jul 04 06:41:24 musica volumio[599]: info: Ignoring MPD Status Update Jul 04 06:41:24 musica volumio[599]: info: Jul 04 06:41:24 musica volumio[599]: ---------------------------- MPD announces system playlist update Jul 04 06:41:24 musica volumio[599]: info: Ignoring MPD Status Update Jul 04 06:41:24 musica volumio[599]: info: Jul 04 06:41:24 musica volumio[599]: ---------------------------- MPD announces system playlist update Jul 04 06:41:24 musica volumio[599]: info: Ignoring MPD Status Update Jul 04 06:41:24 musica volumio[599]: info: [1751611284763] ControllerPandora::pandoraListener Jul 04 06:41:24 musica volumio[599]: info: ControllerMpd::getState Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::sendMpdCommand status Jul 04 06:41:24 musica volumio[599]: info: Jul 04 06:41:24 musica volumio[599]: ---------------------------- MPD announces state update: player Jul 04 06:41:24 musica volumio[599]: info: ------------------------------ 149ms Jul 04 06:41:24 musica volumio[599]: info: ------------------------------ 99ms Jul 04 06:41:24 musica volumio[599]: info: ------------------------------ 97ms Jul 04 06:41:24 musica volumio[599]: info: ------------------------------ 95ms Jul 04 06:41:24 musica volumio[599]: info: ControllerMpd::getState Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::sendMpdCommand status Jul 04 06:41:24 musica volumio[599]: info: Jul 04 06:41:24 musica volumio[599]: ---------------------------- MPD announces system playlist update Jul 04 06:41:24 musica volumio[599]: info: Ignoring MPD Status Update Jul 04 06:41:24 musica volumio[599]: info: Jul 04 06:41:24 musica volumio[599]: ---------------------------- MPD announces system playlist update Jul 04 06:41:24 musica volumio[599]: info: Ignoring MPD Status Update Jul 04 06:41:24 musica volumio[599]: info: Jul 04 06:41:24 musica volumio[599]: ---------------------------- MPD announces system playlist update Jul 04 06:41:24 musica volumio[599]: info: Ignoring MPD Status Update Jul 04 06:41:24 musica volumio[599]: info: Jul 04 06:41:24 musica volumio[599]: ---------------------------- MPD announces state update: player Jul 04 06:41:24 musica volumio[599]: info: ControllerMpd::getState Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::sendMpdCommand status Jul 04 06:41:24 musica volumio[599]: info: sendMpdCommand status took 74 milliseconds Jul 04 06:41:24 musica volumio[599]: info: ------------------------------ 79ms Jul 04 06:41:24 musica volumio[599]: info: sendMpdCommand status took 71 milliseconds Jul 04 06:41:24 musica volumio[599]: info: ------------------------------ 69ms Jul 04 06:41:24 musica volumio[599]: info: ------------------------------ 69ms Jul 04 06:41:24 musica volumio[599]: info: ------------------------------ 65ms Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::parseState Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::parseState Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 06:41:24 musica volumio[599]: info: Jul 04 06:41:24 musica volumio[599]: ---------------------------- MPD announces state update: player Jul 04 06:41:24 musica volumio[599]: info: ControllerMpd::getState Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::sendMpdCommand status Jul 04 06:41:24 musica volumio[599]: info: Jul 04 06:41:24 musica volumio[599]: ---------------------------- MPD announces state update: player Jul 04 06:41:24 musica volumio[599]: info: ControllerMpd::getState Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::sendMpdCommand status Jul 04 06:41:24 musica volumio[599]: info: Jul 04 06:41:24 musica volumio[599]: ---------------------------- MPD announces state update: player Jul 04 06:41:24 musica volumio[599]: info: ControllerMpd::getState Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::sendMpdCommand status Jul 04 06:41:24 musica volumio[599]: info: sendMpdCommand status took 53 milliseconds Jul 04 06:41:24 musica volumio[599]: info: sendMpdCommand playlistinfo took 47 milliseconds Jul 04 06:41:24 musica volumio[599]: info: sendMpdCommand playlistinfo took 47 milliseconds Jul 04 06:41:24 musica volumio[599]: info: sendMpdCommand status took 42 milliseconds Jul 04 06:41:24 musica volumio[599]: info: sendMpdCommand status took 39 milliseconds Jul 04 06:41:24 musica volumio[599]: info: sendMpdCommand status took 37 milliseconds Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::parseState Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::parseTrackInfo Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::parseTrackInfo Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::parseState Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::parseState Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::parseState Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::getTrack 1 Jul 04 06:41:24 musica volumio[599]: info: [1751611284909] ControllerPandora::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::servicePushState Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::getTrack 1 Jul 04 06:41:24 musica volumio[599]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Deja Que Salga la Luna (Remastered)","artist":"José Alfredo Jiménez","album":"Fiesta Latina (Remastered)","uri":"http://audio-usc-mp1-t1-2-v4v6.pandora.com/access/275594584904164597.mp3?version=5&lid=634959990&token=%2BptTB2Zfgad584b1ID1EX0Or5ZnhcGzxYq2V5i%2FXWXrluZJ6D8z4QjjuZ%2BvBxHi3Dsx1d0tgOxCIQCZMDJl0wNU4n1JIa2NrCYRTdHgIPDsZWkjRpWe1lt0aRXr6fh3MRKYF%2Fn3u81q45l1f0eca1WS%2FSf0eXhVEGN5x%2FbvMcvsNUafHDgXy%2Fp8Lv9GfvdXcmUy%2FrCErmWsp65yS7KHXr24fRAyjMTVSHNj43Zq4BlAHEk2J3gNxeCOQfnqAr0CpSNCl4KJ%2FYVUmmw2KiiMlMTcvxT4p5%2FCqxrhuExAR8ohMy4faETNWLPMnTpxqPpHO%2FFZy7k8u5eI%3D","trackType":"mp3"} Jul 04 06:41:24 musica volumio[599]: verbose: CURRENT POSITION 1 Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::syncState stateService play Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::syncState currentStatus play Jul 04 06:41:24 musica volumio[599]: info: Received an update from plugin. extracting info from payload Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::volumioPushState Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::volumioPushState Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::setConsumeUpdateService pandora Jul 04 06:41:24 musica volumio[599]: info: ControllerMpd::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::servicePushState Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::getTrack 1 Jul 04 06:41:24 musica volumio[599]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 04 06:41:24 musica volumio[599]: info: ------------------------------ 171ms Jul 04 06:41:24 musica volumio[599]: info: sendMpdCommand playlistinfo took 42 milliseconds Jul 04 06:41:24 musica volumio[599]: info: sendMpdCommand playlistinfo took 37 milliseconds Jul 04 06:41:24 musica volumio[599]: info: sendMpdCommand playlistinfo took 36 milliseconds Jul 04 06:41:24 musica volumio[599]: info: sendMpdCommand playlistinfo took 36 milliseconds Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::parseTrackInfo Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::parseTrackInfo Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::parseTrackInfo Jul 04 06:41:24 musica volumio[599]: verbose: ControllerMpd::parseTrackInfo Jul 04 06:41:24 musica volumio[599]: info: ControllerMpd::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::servicePushState Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::getTrack 1 Jul 04 06:41:24 musica volumio[599]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 04 06:41:24 musica volumio[599]: info: ControllerMpd::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::servicePushState Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::getTrack 1 Jul 04 06:41:24 musica volumio[599]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 04 06:41:24 musica volumio[599]: info: ControllerMpd::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::servicePushState Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::getTrack 1 Jul 04 06:41:24 musica volumio[599]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 04 06:41:24 musica volumio[599]: info: ControllerMpd::pushState Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::servicePushState Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::getTrack 1 Jul 04 06:41:24 musica volumio[599]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jul 04 06:41:24 musica volumio[599]: info: ------------------------------ 122ms Jul 04 06:41:24 musica volumio[599]: info: ------------------------------ 107ms Jul 04 06:41:24 musica volumio[599]: info: ------------------------------ 104ms Jul 04 06:41:24 musica volumio[599]: info: ------------------------------ 101ms Jul 04 06:41:24 musica volumio[599]: info: CoreCommandRouter::volumioGetQueue Jul 04 06:41:24 musica volumio[599]: info: CoreStateMachine::getQueue Jul 04 06:41:24 musica volumio[599]: info: CorePlayQueue::getQueue Jul 04 06:41:40 musica volumio[599]: info: [1751611300071] ControllerPandora::PreventAuthTimeout::fn Jul 04 06:41:40 musica volumio[599]: info: [1751611300074] ControllerPandora::PandoraHandler::pandoraLoginAndGetStations Jul 04 06:41:40 musica volumio[599]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 06:41:40 musica volumio[599]: SyntaxError: Unexpected token u in JSON at position 0 Jul 04 06:41:40 musica volumio[599]: at JSON.parse () Jul 04 06:41:40 musica volumio[599]: at Request._callback (/data/plugins/music_service/pandora/node_modules/anesidora/anesidora.js:41:31) Jul 04 06:41:40 musica volumio[599]: at Request.self.callback (/data/plugins/music_service/pandora/node_modules/request/request.js:185:22) Jul 04 06:41:40 musica volumio[599]: at Request.emit (events.js:315:20) Jul 04 06:41:40 musica volumio[599]: at Request. (/data/plugins/music_service/pandora/node_modules/request/request.js:1154:10) Jul 04 06:41:40 musica volumio[599]: at Request.emit (events.js:315:20) Jul 04 06:41:40 musica volumio[599]: at IncomingMessage. (/data/plugins/music_service/pandora/node_modules/request/request.js:1076:12) Jul 04 06:41:40 musica volumio[599]: at Object.onceWrapper (events.js:421:28) Jul 04 06:41:40 musica volumio[599]: at IncomingMessage.emit (events.js:327:22) Jul 04 06:41:40 musica volumio[599]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 04 06:41:40 musica volumio[599]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 04 06:41:40 musica volumio[599]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 06:41:41 musica sudo[5750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-04 06:40 Jul 04 06:41:41 musica sudo[5750]: 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="3cfa1502993c2310fc263d6ebda1707071fe4cc2" VOLUMIO_FE_VERSION="8efe4f08f83e5de574651b8ed2333629c140d365" VOLUMIO_FE3_VERSION="8f1d07fcde806ea34f52cff908ec76359e14970e" VOLUMIO_BE_VERSION="e249dfa388452e6b8443121dc9b4cabca459c531" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 09 Jun 2022 05:55:51 PM CEST" VOLUMIO_VERSION="3.301" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6a8974e587cd4bab189d231f79b65462"