-- Logs begin at Fri 2025-03-14 19:13:06 CDT, end at Sat 2025-03-15 05:10:29 CDT. -- Mar 15 05:09:18 duncan-volumio volumiologrotate[574]: ls: cannot access '/var/log/samba/log.wb-DUNCAN': No such file or directory Mar 15 05:09:18 duncan-volumio volumiologrotate[574]: ls: cannot access 'VOLUMIO': No such file or directory Mar 15 05:09:53 duncan-volumio volumio[1064]: info: CorePlayQueue::getQueue Mar 15 05:09:53 duncan-volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 15 05:09:53 duncan-volumio volumio[1064]: info: [1742033393345] ControllerPandora::ExpireOldTracks::reaper Mar 15 05:09:53 duncan-volumio volumio[1064]: info: [1742033393345] [Pandora] ExpireOldTracks::reaper: No victims found: Expiring zero tracks. Don't worry -- Jason will return. Mar 15 05:10:18 duncan-volumio volumiologrotate[574]: ls: cannot access '/var/log/samba/log.wb-DUNCAN': No such file or directory Mar 15 05:10:18 duncan-volumio volumiologrotate[574]: ls: cannot access 'VOLUMIO': No such file or directory Mar 15 05:10:22 duncan-volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 15 05:10:22 duncan-volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 15 05:10:22 duncan-volumio volumio[1064]: info: Prefetching next song Mar 15 05:10:27 duncan-volumio volumio[1064]: info: [1742033427121] ControllerPandora::pandoraListener Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ControllerMpd::getState Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Mar 15 05:10:27 duncan-volumio volumio[1064]: ---------------------------- MPD announces state update: player Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ControllerMpd::getState Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Mar 15 05:10:27 duncan-volumio volumio[1064]: ---------------------------- MPD announces state update: player Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ControllerMpd::getState Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Mar 15 05:10:27 duncan-volumio volumio[1064]: ---------------------------- MPD announces state update: player Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ControllerMpd::getState Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 15 05:10:27 duncan-volumio volumio[1064]: info: sendMpdCommand status took 5 milliseconds Mar 15 05:10:27 duncan-volumio volumio[1064]: info: sendMpdCommand status took 4 milliseconds Mar 15 05:10:27 duncan-volumio volumio[1064]: info: sendMpdCommand status took 3 milliseconds Mar 15 05:10:27 duncan-volumio volumio[1064]: info: sendMpdCommand status took 1 milliseconds Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 15 05:10:27 duncan-volumio volumio[1064]: info: [1742033427128] ControllerPandora::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 15 05:10:27 duncan-volumio volumio[1064]: 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"} Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: CURRENT POSITION 0 Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::syncState stateService stop Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::play index undefined Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::setConsumeUpdateService pandora Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ControllerMpd::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ControllerMpd::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ControllerMpd::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::startPlaybackTimer Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 15 05:10:27 duncan-volumio volumio[1064]: info: [1742033427145] ControllerPandora::clearAddPlayTrack Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ControllerMpd::clear Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand clear Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ------------------------------ 24ms Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ------------------------------ 22ms Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ------------------------------ 21ms Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Mar 15 05:10:27 duncan-volumio volumio[1064]: ---------------------------- MPD announces system playlist update Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Ignoring MPD Status Update Mar 15 05:10:27 duncan-volumio volumio[1064]: info: sendMpdCommand clear took 6 milliseconds Mar 15 05:10:27 duncan-volumio volumio[1064]: info: [1742033427151] ControllerPandora::checkForExpiredStations Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::getQueue Mar 15 05:10:27 duncan-volumio volumio[1064]: info: [1742033427151] ControllerPandora::removeTrack Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::removeQueueItem Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::removeQueueItem 0 Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::saveQueue Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::volumioPushQueue Mar 15 05:10:27 duncan-volumio volumio[1064]: info: [1742033427154] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=477458006808985354trackId=3948013107952656275 at queue index: 0 Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ControllerMpd::getState Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 15 05:10:27 duncan-volumio volumio[1064]: info: [1742033427157] ControllerPandora::appendTracksToMpd Mar 15 05:10:27 duncan-volumio volumio[1064]: info: [1742033427157] [Pandora] appendTracksToMpd: Added 1 track(s) to mpd Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand addid Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand play Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Mar 15 05:10:27 duncan-volumio volumio[1064]: ---------------------------- MPD announces system playlist update Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Ignoring MPD Status Update Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Mar 15 05:10:27 duncan-volumio volumio[1064]: ---------------------------- MPD announces system playlist update Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Ignoring MPD Status Update Mar 15 05:10:27 duncan-volumio volumio[1064]: error: updateQueue error: null Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Mar 15 05:10:27 duncan-volumio volumio[1064]: ---------------------------- MPD announces system playlist update Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Ignoring MPD Status Update Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ------------------------------ 20ms Mar 15 05:10:27 duncan-volumio volumio[1064]: info: sendMpdCommand status took 16 milliseconds Mar 15 05:10:27 duncan-volumio volumio[1064]: info: sendMpdCommand addid took 13 milliseconds Mar 15 05:10:27 duncan-volumio volumio[1064]: info: sendMpdCommand play took 12 milliseconds Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ------------------------------ 3ms Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ------------------------------ 2ms Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: [1742033427171] ControllerPandora::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 15 05:10:27 duncan-volumio volumio[1064]: 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"} Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: CURRENT POSITION 0 Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::syncState stateService stop Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus stop Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: No code Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::setConsumeUpdateService pandora Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: MPD COMMAND [object Object] Mar 15 05:10:27 duncan-volumio volumio[1064]: info: [1742033427176] ControllerPandora::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"Just like Heaven","name":"Just like Heaven","artist":"The Cure","album":"Kiss Me, Kiss Me, Kiss Me","albumart":"http://cont-1.p-cdn.us/images/a6/d3/ab/1e/599e44f3ad0fbb7820d647e5/1080W_1080H.jpg","realUri":"http://t1-1.p-cdn.us/access/8254741931074171299.mp3?version=5&lid=184811274&token=z9sLCmoHi8z8Lnv9OiJcyOVon2CwXj3LzgXULXZf3CyJbf7gCYxNpiibKCL3X3OtPDZ60ICFgRpXnTZQShj7DrGIUl4vYqazNt3w2f1NuOH2N78C8Za0Ofw5ag4oSnx%2Bk5qkETAEr%2FdAQOT9uCMZpZoxxwMxdpmSdUjpsQJNy%2B1dttEq1E240ORejO0iVYGRTJVr%2F%2FGmocWl1boPvDGMm3Bnmi6OwVG9jl%2BqhO380lLRDOggw66krzgViXHHjO41CDLEeCj%2FF33ZdH2dm9H%2FzaSgV2Rh6vFREXZK0U5ac6ng7Co%2FpeuUe%2FdaCb2vAqHr9MpUuCHBMRzUnx51GgZXqA%3D%3D","isStreaming":true,"duration":212,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t1-1.p-cdn.us/access/8254741931074171299.mp3?version=5&lid=184811274&token=z9sLCmoHi8z8Lnv9OiJcyOVon2CwXj3LzgXULXZf3CyJbf7gCYxNpiibKCL3X3OtPDZ60ICFgRpXnTZQShj7DrGIUl4vYqazNt3w2f1NuOH2N78C8Za0Ofw5ag4oSnx%2Bk5qkETAEr%2FdAQOT9uCMZpZoxxwMxdpmSdUjpsQJNy%2B1dttEq1E240ORejO0iVYGRTJVr%2F%2FGmocWl1boPvDGMm3Bnmi6OwVG9jl%2BqhO380lLRDOggw66krzgViXHHjO41CDLEeCj%2FF33ZdH2dm9H%2FzaSgV2Rh6vFREXZK0U5ac6ng7Co%2FpeuUe%2FdaCb2vAqHr9MpUuCHBMRzUnx51GgZXqA%3D%3D","seek":0,"status":"play"} Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: CURRENT POSITION 0 Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus stop Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::setConsumeUpdateService pandora Mar 15 05:10:27 duncan-volumio volumio[1064]: info: [1742033427179] ControllerPandora::fetchAndAddTracks Mar 15 05:10:27 duncan-volumio volumio[1064]: info: [1742033427180] ControllerPandora::getStationTracks Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::getQueue Mar 15 05:10:27 duncan-volumio volumio[1064]: info: [1742033427180] [Pandora] PandoraHandler::getSongMaxDiff: -1 Mar 15 05:10:27 duncan-volumio volumio[1064]: info: [1742033427180] ControllerPandora::fetchAndAddTracks::getSqInfo Mar 15 05:10:27 duncan-volumio volumio[1064]: info: [1742033427180] ControllerPandora::getStationTracks Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::getQueue Mar 15 05:10:27 duncan-volumio volumio[1064]: info: [1742033427181] [Pandora] fetchAndAddTracks: diff1: -1 sQPos1: 0 Mar 15 05:10:27 duncan-volumio volumio[1064]: info: [1742033427181] [Pandora] fetchAndAddTracks: Fetching tracks Mar 15 05:10:27 duncan-volumio volumio[1064]: info: [1742033427181] ControllerPandora::PandoraHandler::fillStationData Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Mar 15 05:10:27 duncan-volumio volumio[1064]: ---------------------------- MPD announces system playlist update Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Ignoring MPD Status Update Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Mar 15 05:10:27 duncan-volumio volumio[1064]: ---------------------------- MPD announces system playlist update Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Ignoring MPD Status Update Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Mar 15 05:10:27 duncan-volumio volumio[1064]: ---------------------------- MPD announces system playlist update Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Ignoring MPD Status Update Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ------------------------------ 18ms Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ------------------------------ 5ms Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ------------------------------ 4ms Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Mar 15 05:10:27 duncan-volumio volumio[1064]: ---------------------------- MPD announces system playlist update Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Ignoring MPD Status Update Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Mar 15 05:10:27 duncan-volumio volumio[1064]: ---------------------------- MPD announces system playlist update Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Ignoring MPD Status Update Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ------------------------------ 6ms Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ------------------------------ 4ms Mar 15 05:10:27 duncan-volumio volumio[1064]: info: [1742033427316] ControllerPandora::pandoraListener Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ControllerMpd::getState Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Mar 15 05:10:27 duncan-volumio volumio[1064]: ---------------------------- MPD announces state update: player Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ControllerMpd::getState Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Mar 15 05:10:27 duncan-volumio volumio[1064]: ---------------------------- MPD announces state update: player Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ControllerMpd::getState Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Mar 15 05:10:27 duncan-volumio volumio[1064]: ---------------------------- MPD announces state update: player Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ControllerMpd::getState Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Mar 15 05:10:27 duncan-volumio volumio[1064]: ---------------------------- MPD announces state update: player Mar 15 05:10:27 duncan-volumio volumio[1064]: info: sendMpdCommand status took 4 milliseconds Mar 15 05:10:27 duncan-volumio volumio[1064]: info: sendMpdCommand status took 3 milliseconds Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ControllerMpd::getState Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 15 05:10:27 duncan-volumio volumio[1064]: info: sendMpdCommand status took 3 milliseconds Mar 15 05:10:27 duncan-volumio volumio[1064]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 15 05:10:27 duncan-volumio volumio[1064]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 15 05:10:27 duncan-volumio volumio[1064]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 15 05:10:27 duncan-volumio volumio[1064]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 15 05:10:27 duncan-volumio volumio[1064]: info: [1742033427327] ControllerPandora::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":212,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Just like Heaven","artist":"The Cure","album":"Kiss Me, Kiss Me, Kiss Me","uri":"http://t1-1.p-cdn.us/access/8254741931074171299.mp3?version=5&lid=184811274&token=z9sLCmoHi8z8Lnv9OiJcyOVon2CwXj3LzgXULXZf3CyJbf7gCYxNpiibKCL3X3OtPDZ60ICFgRpXnTZQShj7DrGIUl4vYqazNt3w2f1NuOH2N78C8Za0Ofw5ag4oSnx%2Bk5qkETAEr%2FdAQOT9uCMZpZoxxwMxdpmSdUjpsQJNy%2B1dttEq1E240ORejO0iVYGRTJVr%2F%2FGmocWl1boPvDGMm3Bnmi6OwVG9jl%2BqhO380lLRDOggw66krzgViXHHjO41CDLEeCj%2FF33ZdH2dm9H%2FzaSgV2Rh6vFREXZK0U5ac6ng7Co%2FpeuUe%2FdaCb2vAqHr9MpUuCHBMRzUnx51GgZXqA%3D%3D","trackType":"mp3"} Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: CURRENT POSITION 0 Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::setConsumeUpdateService pandora Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ControllerMpd::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ControllerMpd::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ControllerMpd::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ------------------------------ 17ms Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ------------------------------ 16ms Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ------------------------------ 15ms Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Mar 15 05:10:27 duncan-volumio volumio[1064]: ---------------------------- MPD announces state update: player Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ControllerMpd::getState Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 15 05:10:27 duncan-volumio volumio[1064]: info: Mar 15 05:10:27 duncan-volumio volumio[1064]: ---------------------------- MPD announces state update: player Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ControllerMpd::getState Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 15 05:10:27 duncan-volumio volumio[1064]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 15 05:10:27 duncan-volumio volumio[1064]: info: sendMpdCommand status took 2 milliseconds Mar 15 05:10:27 duncan-volumio volumio[1064]: info: sendMpdCommand status took 2 milliseconds Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ControllerMpd::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ------------------------------ 18ms Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::volumioGetQueue Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreStateMachine::getQueue Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::getQueue Mar 15 05:10:27 duncan-volumio volumio[1064]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 15 05:10:27 duncan-volumio volumio[1064]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Mar 15 05:10:27 duncan-volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ControllerMpd::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ControllerMpd::pushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 15 05:10:27 duncan-volumio volumio[1064]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ------------------------------ 22ms Mar 15 05:10:27 duncan-volumio volumio[1064]: info: ------------------------------ 21ms Mar 15 05:10:27 duncan-volumio volumio[1064]: info: [1742033427442] ControllerPandora::PandoraHandler::fetchTracks::fetchStationPlaylist Mar 15 05:10:28 duncan-volumio volumio[1064]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 15 05:10:28 duncan-volumio volumio[1064]: SyntaxError: Unexpected token u in JSON at position 0 Mar 15 05:10:28 duncan-volumio volumio[1064]: at JSON.parse () Mar 15 05:10:28 duncan-volumio volumio[1064]: at Request._callback (/data/plugins/music_service/pandora/node_modules/anesidora/anesidora.js:41:31) Mar 15 05:10:28 duncan-volumio volumio[1064]: at Request.self.callback (/data/plugins/music_service/pandora/node_modules/request/request.js:185:22) Mar 15 05:10:28 duncan-volumio volumio[1064]: at Request.emit (events.js:315:20) Mar 15 05:10:28 duncan-volumio volumio[1064]: at Request. (/data/plugins/music_service/pandora/node_modules/request/request.js:1154:10) Mar 15 05:10:28 duncan-volumio volumio[1064]: at Request.emit (events.js:315:20) Mar 15 05:10:28 duncan-volumio volumio[1064]: at IncomingMessage. (/data/plugins/music_service/pandora/node_modules/request/request.js:1076:12) Mar 15 05:10:28 duncan-volumio volumio[1064]: at Object.onceWrapper (events.js:421:28) Mar 15 05:10:28 duncan-volumio volumio[1064]: at IncomingMessage.emit (events.js:327:22) Mar 15 05:10:28 duncan-volumio volumio[1064]: at endReadableNT (internal/streams/readable.js:1327:12) Mar 15 05:10:28 duncan-volumio volumio[1064]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Mar 15 05:10:28 duncan-volumio volumio[1064]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 15 05:10:29 duncan-volumio sudo[1015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-15 05:09 Mar 15 05:10:29 duncan-volumio sudo[1015]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"