-- Logs begin at Thu 2019-02-14 04:11:58 CST, end at Tue 2025-01-21 13:37:54 CST. -- Jan 21 13:36:00 volumio wpa_supplicant[850]: RRM: Ignoring radio measurement request: Not RRM network Jan 21 13:36:00 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Jan 21 13:36:00 volumio volumio[993]: info: Listing playlists Jan 21 13:36:00 volumio volumio[993]: info: Listing playlists Jan 21 13:36:10 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Jan 21 13:36:11 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: pandora , handleBrowseUri Jan 21 13:36:11 volumio volumio[993]: info: [1737488171747] ControllerPandora::handleBrowseUri Jan 21 13:36:11 volumio volumio[993]: info: [1737488171748] ControllerPandora::checkForExpiredStations Jan 21 13:36:11 volumio volumio[993]: info: [1737488171748] ControllerPandora::PandoraHandler::fillStationData Jan 21 13:36:12 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: pandora , handleBrowseUri Jan 21 13:36:12 volumio volumio[993]: info: [1737488172342] ControllerPandora::handleBrowseUri Jan 21 13:36:12 volumio volumio[993]: info: [1737488172342] ControllerPandora::checkForExpiredStations Jan 21 13:36:12 volumio volumio[993]: info: [1737488172342] ControllerPandora::PandoraHandler::fillStationData Jan 21 13:36:12 volumio volumio[993]: info: [1737488172437] ControllerPandora::PandoraHandler::fetchTracks::fetchStationPlaylist Jan 21 13:36:12 volumio volumio[993]: info: [1737488172789] ControllerPandora::PandoraHandler::fetchTracks::fetchStationPlaylist Jan 21 13:36:13 volumio volumio[993]: info: [1737488173472] [Pandora] PandoraHandler::fetchTracks::fetchStationPlaylist: Retrieved Owusu & Hannibal Radio playlist Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: [1737488173472] ControllerPandora::PandoraHandler::fetchTracks::fillNewTracks Jan 21 13:36:13 volumio volumio[993]: info: [1737488173473] [Pandora] PandoraHandler::fetchTracks::fillNewTracks: Fetched 4 track(s) Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::addQueueItems Jan 21 13:36:13 volumio volumio[993]: info: Preload queue cleared Jan 21 13:36:13 volumio volumio[993]: info: Adding Item to queue: /pandora/stationToken=4462923413750867478trackId=2056455101874107906 Jan 21 13:36:13 volumio volumio[993]: info: Exploding uri /pandora/stationToken=4462923413750867478trackId=2056455101874107906 in service pandora Jan 21 13:36:13 volumio volumio[993]: info: [1737488173474] ControllerPandora::explodeUri Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: Adding Item to queue: /pandora/stationToken=4462923413750867478trackId=388063173094243623 Jan 21 13:36:13 volumio volumio[993]: info: Exploding uri /pandora/stationToken=4462923413750867478trackId=388063173094243623 in service pandora Jan 21 13:36:13 volumio volumio[993]: info: [1737488173474] ControllerPandora::explodeUri Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: Adding Item to queue: /pandora/stationToken=4462923413750867478trackId=3608556896136243052 Jan 21 13:36:13 volumio volumio[993]: info: Exploding uri /pandora/stationToken=4462923413750867478trackId=3608556896136243052 in service pandora Jan 21 13:36:13 volumio volumio[993]: info: [1737488173475] ControllerPandora::explodeUri Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: Adding Item to queue: /pandora/stationToken=4462923413750867478trackId=599027446145327859 Jan 21 13:36:13 volumio volumio[993]: info: Exploding uri /pandora/stationToken=4462923413750867478trackId=599027446145327859 in service pandora Jan 21 13:36:13 volumio volumio[993]: info: [1737488173475] ControllerPandora::explodeUri Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: CoreCommandRouter::volumioPushQueue Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::saveQueue Jan 21 13:36:13 volumio volumio[993]: info: CoreStateMachine::updateTrackBlock Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getTrackBlock Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: CoreStateMachine::play index 753 Jan 21 13:36:13 volumio volumio[993]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 13:36:13 volumio volumio[993]: info: CoreStateMachine::stop Jan 21 13:36:13 volumio volumio[993]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 13:36:13 volumio volumio[993]: info: CoreStateMachine::play index undefined Jan 21 13:36:13 volumio volumio[993]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getTrack 753 Jan 21 13:36:13 volumio volumio[993]: info: CoreStateMachine::startPlaybackTimer Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getTrack 753 Jan 21 13:36:13 volumio volumio[993]: info: [1737488173518] ControllerPandora::clearAddPlayTrack Jan 21 13:36:13 volumio volumio[993]: info: ControllerMpd::clear Jan 21 13:36:13 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand clear Jan 21 13:36:13 volumio volumio[993]: info: Jan 21 13:36:13 volumio volumio[993]: ---------------------------- MPD announces system playlist update Jan 21 13:36:13 volumio volumio[993]: info: Ignoring MPD Status Update Jan 21 13:36:13 volumio volumio[993]: info: sendMpdCommand clear took 27 milliseconds Jan 21 13:36:13 volumio volumio[993]: info: [1737488173546] ControllerPandora::checkForExpiredStations Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: [1737488173546] ControllerPandora::removeTrack Jan 21 13:36:13 volumio volumio[993]: info: [1737488173546] [Pandora] removeTrack: Not removing track with uri: null at queue index: -1 Jan 21 13:36:13 volumio volumio[993]: info: [1737488173547] ControllerPandora::appendTracksToMpd Jan 21 13:36:13 volumio volumio[993]: info: [1737488173547] [Pandora] appendTracksToMpd: Added 1 track(s) to mpd Jan 21 13:36:13 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand addid Jan 21 13:36:13 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand play Jan 21 13:36:13 volumio volumio[993]: info: Jan 21 13:36:13 volumio volumio[993]: ---------------------------- MPD announces system playlist update Jan 21 13:36:13 volumio volumio[993]: info: Ignoring MPD Status Update Jan 21 13:36:13 volumio volumio[993]: info: Jan 21 13:36:13 volumio volumio[993]: ---------------------------- MPD announces system playlist update Jan 21 13:36:13 volumio volumio[993]: info: Ignoring MPD Status Update Jan 21 13:36:13 volumio volumio[993]: error: updateQueue error: null Jan 21 13:36:13 volumio volumio[993]: info: Jan 21 13:36:13 volumio volumio[993]: ---------------------------- MPD announces system playlist update Jan 21 13:36:13 volumio volumio[993]: info: Ignoring MPD Status Update Jan 21 13:36:13 volumio volumio[993]: info: ------------------------------ 6ms Jan 21 13:36:13 volumio volumio[993]: info: sendMpdCommand addid took 4 milliseconds Jan 21 13:36:13 volumio volumio[993]: info: sendMpdCommand play took 4 milliseconds Jan 21 13:36:13 volumio volumio[993]: info: ------------------------------ 3ms Jan 21 13:36:13 volumio volumio[993]: info: ------------------------------ 2ms Jan 21 13:36:13 volumio volumio[993]: verbose: MPD COMMAND [object Object] Jan 21 13:36:13 volumio volumio[993]: verbose: MPD COMMAND [object Object] Jan 21 13:36:13 volumio volumio[993]: verbose: MPD COMMAND [object Object] Jan 21 13:36:13 volumio volumio[993]: info: [1737488173552] ControllerPandora::pushState Jan 21 13:36:13 volumio volumio[993]: info: CoreCommandRouter::servicePushState Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getTrack 753 Jan 21 13:36:13 volumio volumio[993]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"Lonnie's Secret","name":"Lonnie's Secret","artist":"Owusu & Hannibal","album":"Living With...","albumart":"http://cont-4.p-cdn.us/images/c0/09/9b/f0/fb8747cdb37609471daf4045/1080W_1080H.jpg","realUri":"http://t3-4.p-cdn.us/access/2056455101874107906.mp3?version=5&lid=6612502&token=SkvcIp1f4zB8dJurAB5q8fsj9ULVHKmo8ShYorXzv21OO6tLuvYMcreke5Bu%2F7ESq0%2Fu1Dz7Zzu%2FWnSN1nsKS3OTyd652xp%2Bhq%2FgEtWSHjm3eXlMa67gVlqnltzKGRa9iQ15WXIr2iqPljY1FFEY84x03BvO5F7g1QV8LYGUFPLpBGA%2Bfo9wBlnBVa8oKVCNIWNyQMhtEgZlPnmhrlJGFNfwn1C%2BKBrc5z%2BXPO1pTj6pfBhhMOTmuK2GfsbyZ1O%2BNPZk1HMpI3lCq79Ie4SUPVywIhdYrH473MuSd8VFXBvywJv1ttkkgzD2zETylKxPuGw8CMUR8TuvIaPH%2BfJ%2B9w%3D%3D","isStreaming":true,"duration":380,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t3-4.p-cdn.us/access/2056455101874107906.mp3?version=5&lid=6612502&token=SkvcIp1f4zB8dJurAB5q8fsj9ULVHKmo8ShYorXzv21OO6tLuvYMcreke5Bu%2F7ESq0%2Fu1Dz7Zzu%2FWnSN1nsKS3OTyd652xp%2Bhq%2FgEtWSHjm3eXlMa67gVlqnltzKGRa9iQ15WXIr2iqPljY1FFEY84x03BvO5F7g1QV8LYGUFPLpBGA%2Bfo9wBlnBVa8oKVCNIWNyQMhtEgZlPnmhrlJGFNfwn1C%2BKBrc5z%2BXPO1pTj6pfBhhMOTmuK2GfsbyZ1O%2BNPZk1HMpI3lCq79Ie4SUPVywIhdYrH473MuSd8VFXBvywJv1ttkkgzD2zETylKxPuGw8CMUR8TuvIaPH%2BfJ%2B9w%3D%3D","seek":0,"status":"play"} Jan 21 13:36:13 volumio volumio[993]: verbose: CURRENT POSITION 753 Jan 21 13:36:13 volumio volumio[993]: info: CoreStateMachine::syncState stateService play Jan 21 13:36:13 volumio volumio[993]: info: CoreStateMachine::syncState currentStatus stop Jan 21 13:36:13 volumio volumio[993]: info: CoreStateMachine::setConsumeUpdateService pandora Jan 21 13:36:13 volumio volumio[993]: info: [1737488173553] ControllerPandora::fetchAndAddTracks Jan 21 13:36:13 volumio volumio[993]: info: [1737488173553] ControllerPandora::getStationTracks Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: [1737488173554] [Pandora] PandoraHandler::getSongMaxDiff: -12 Jan 21 13:36:13 volumio volumio[993]: info: [1737488173554] ControllerPandora::fetchAndAddTracks::getSqInfo Jan 21 13:36:13 volumio volumio[993]: info: [1737488173554] ControllerPandora::getStationTracks Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: [1737488173555] [Pandora] fetchAndAddTracks: diff1: -12 sQPos1: 0 Jan 21 13:36:13 volumio volumio[993]: info: [1737488173555] [Pandora] fetchAndAddTracks: Fetching tracks Jan 21 13:36:13 volumio volumio[993]: info: [1737488173555] ControllerPandora::PandoraHandler::fillStationData Jan 21 13:36:13 volumio volumio[993]: info: Jan 21 13:36:13 volumio volumio[993]: ---------------------------- MPD announces system playlist update Jan 21 13:36:13 volumio volumio[993]: info: Ignoring MPD Status Update Jan 21 13:36:13 volumio volumio[993]: info: Jan 21 13:36:13 volumio volumio[993]: ---------------------------- MPD announces system playlist update Jan 21 13:36:13 volumio volumio[993]: info: Ignoring MPD Status Update Jan 21 13:36:13 volumio volumio[993]: info: Jan 21 13:36:13 volumio volumio[993]: ---------------------------- MPD announces system playlist update Jan 21 13:36:13 volumio volumio[993]: info: Ignoring MPD Status Update Jan 21 13:36:13 volumio volumio[993]: info: ------------------------------ 9ms Jan 21 13:36:13 volumio volumio[993]: info: ------------------------------ 4ms Jan 21 13:36:13 volumio volumio[993]: info: ------------------------------ 3ms Jan 21 13:36:13 volumio volumio[993]: info: Jan 21 13:36:13 volumio volumio[993]: ---------------------------- MPD announces system playlist update Jan 21 13:36:13 volumio volumio[993]: info: Ignoring MPD Status Update Jan 21 13:36:13 volumio volumio[993]: info: Jan 21 13:36:13 volumio volumio[993]: ---------------------------- MPD announces system playlist update Jan 21 13:36:13 volumio volumio[993]: info: Ignoring MPD Status Update Jan 21 13:36:13 volumio volumio[993]: info: ------------------------------ 3ms Jan 21 13:36:13 volumio volumio[993]: info: ------------------------------ 2ms Jan 21 13:36:13 volumio volumio[993]: info: ------------------------------ 1ms Jan 21 13:36:13 volumio volumio[993]: info: [1737488173930] [Pandora] PandoraHandler::fetchTracks::fetchStationPlaylist: Retrieved Owusu & Hannibal Radio playlist Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: [1737488173931] ControllerPandora::PandoraHandler::fetchTracks::fillNewTracks Jan 21 13:36:13 volumio volumio[993]: info: [1737488173931] [Pandora] PandoraHandler::fetchTracks::fillNewTracks: Fetched 4 track(s) Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::addQueueItems Jan 21 13:36:13 volumio volumio[993]: info: Preload queue cleared Jan 21 13:36:13 volumio volumio[993]: info: Adding Item to queue: /pandora/stationToken=4462923413750867478trackId=5852899005235232331 Jan 21 13:36:13 volumio volumio[993]: info: Exploding uri /pandora/stationToken=4462923413750867478trackId=5852899005235232331 in service pandora Jan 21 13:36:13 volumio volumio[993]: info: [1737488173932] ControllerPandora::explodeUri Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: Adding Item to queue: /pandora/stationToken=4462923413750867478trackId=6253314907748615337 Jan 21 13:36:13 volumio volumio[993]: info: Exploding uri /pandora/stationToken=4462923413750867478trackId=6253314907748615337 in service pandora Jan 21 13:36:13 volumio volumio[993]: info: [1737488173933] ControllerPandora::explodeUri Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: Adding Item to queue: /pandora/stationToken=4462923413750867478trackId=5529251590743473828 Jan 21 13:36:13 volumio volumio[993]: info: Exploding uri /pandora/stationToken=4462923413750867478trackId=5529251590743473828 in service pandora Jan 21 13:36:13 volumio volumio[993]: info: [1737488173933] ControllerPandora::explodeUri Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: Adding Item to queue: /pandora/stationToken=4462923413750867478trackId=7910331213368730739 Jan 21 13:36:13 volumio volumio[993]: info: Exploding uri /pandora/stationToken=4462923413750867478trackId=7910331213368730739 in service pandora Jan 21 13:36:13 volumio volumio[993]: info: [1737488173933] ControllerPandora::explodeUri Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: CoreCommandRouter::volumioPushQueue Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::saveQueue Jan 21 13:36:13 volumio volumio[993]: info: CoreStateMachine::updateTrackBlock Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getTrackBlock Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:13 volumio volumio[993]: info: CoreStateMachine::play index 757 Jan 21 13:36:13 volumio volumio[993]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 13:36:13 volumio volumio[993]: info: CoreStateMachine::stop Jan 21 13:36:13 volumio volumio[993]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 13:36:13 volumio volumio[993]: info: CoreStateMachine::stPlaybackTimer Jan 21 13:36:13 volumio volumio[993]: info: CoreStateMachine::updateTrackBlock Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getTrackBlock Jan 21 13:36:13 volumio volumio[993]: info: CoreStateMachine::pushState Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getTrack 757 Jan 21 13:36:13 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 13:36:13 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Jan 21 13:36:13 volumio volumio[993]: info: CoreStateMachine::serviceStop Jan 21 13:36:13 volumio volumio[993]: info: CorePlayQueue::getTrack 757 Jan 21 13:36:13 volumio volumio[993]: info: CoreCommandRouter::serviceStop Jan 21 13:36:13 volumio volumio[993]: info: [1737488173972] ControllerPandora::stop Jan 21 13:36:13 volumio volumio[993]: info: ControllerMpd::stop Jan 21 13:36:13 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand stop Jan 21 13:36:13 volumio volumio[993]: info: Jan 21 13:36:13 volumio volumio[993]: ---------------------------- MPD announces state update: player Jan 21 13:36:13 volumio volumio[993]: info: sendMpdCommand stop took 23 milliseconds Jan 21 13:36:13 volumio volumio[993]: info: ControllerMpd::getState Jan 21 13:36:13 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand status Jan 21 13:36:13 volumio volumio[993]: info: ControllerMpd::clear Jan 21 13:36:13 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand clear Jan 21 13:36:13 volumio volumio[993]: info: Jan 21 13:36:13 volumio volumio[993]: ---------------------------- MPD announces state update: player Jan 21 13:36:13 volumio volumio[993]: info: ControllerMpd::getState Jan 21 13:36:13 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand status Jan 21 13:36:13 volumio volumio[993]: info: Jan 21 13:36:13 volumio volumio[993]: ---------------------------- MPD announces state update: player Jan 21 13:36:13 volumio volumio[993]: info: ControllerMpd::getState Jan 21 13:36:13 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand status Jan 21 13:36:14 volumio volumio[993]: info: Jan 21 13:36:14 volumio volumio[993]: ---------------------------- MPD announces system playlist update Jan 21 13:36:14 volumio volumio[993]: info: Ignoring MPD Status Update Jan 21 13:36:14 volumio volumio[993]: info: sendMpdCommand status took 4 milliseconds Jan 21 13:36:14 volumio volumio[993]: info: sendMpdCommand clear took 3 milliseconds Jan 21 13:36:14 volumio volumio[993]: info: sendMpdCommand status took 2 milliseconds Jan 21 13:36:14 volumio volumio[993]: info: sendMpdCommand status took 2 milliseconds Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::parseState Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 13:36:14 volumio volumio[993]: info: [1737488174002] ControllerPandora::pushState Jan 21 13:36:14 volumio volumio[993]: info: CoreCommandRouter::servicePushState Jan 21 13:36:14 volumio volumio[993]: info: CoreStateMachine::pushState Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getTrack 757 Jan 21 13:36:14 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getTrack 757 Jan 21 13:36:14 volumio volumio[993]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"Lonnie's Secret","name":"Lonnie's Secret","artist":"Owusu & Hannibal","album":"Living With...","albumart":"http://cont-4.p-cdn.us/images/c0/09/9b/f0/fb8747cdb37609471daf4045/1080W_1080H.jpg","realUri":"http://t3-4.p-cdn.us/access/2056455101874107906.mp3?version=5&lid=6612502&token=SkvcIp1f4zB8dJurAB5q8fsj9ULVHKmo8ShYorXzv21OO6tLuvYMcreke5Bu%2F7ESq0%2Fu1Dz7Zzu%2FWnSN1nsKS3OTyd652xp%2Bhq%2FgEtWSHjm3eXlMa67gVlqnltzKGRa9iQ15WXIr2iqPljY1FFEY84x03BvO5F7g1QV8LYGUFPLpBGA%2Bfo9wBlnBVa8oKVCNIWNyQMhtEgZlPnmhrlJGFNfwn1C%2BKBrc5z%2BXPO1pTj6pfBhhMOTmuK2GfsbyZ1O%2BNPZk1HMpI3lCq79Ie4SUPVywIhdYrH473MuSd8VFXBvywJv1ttkkgzD2zETylKxPuGw8CMUR8TuvIaPH%2BfJ%2B9w%3D%3D","isStreaming":true,"duration":380,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t3-4.p-cdn.us/access/2056455101874107906.mp3?version=5&lid=6612502&token=SkvcIp1f4zB8dJurAB5q8fsj9ULVHKmo8ShYorXzv21OO6tLuvYMcreke5Bu%2F7ESq0%2Fu1Dz7Zzu%2FWnSN1nsKS3OTyd652xp%2Bhq%2FgEtWSHjm3eXlMa67gVlqnltzKGRa9iQ15WXIr2iqPljY1FFEY84x03BvO5F7g1QV8LYGUFPLpBGA%2Bfo9wBlnBVa8oKVCNIWNyQMhtEgZlPnmhrlJGFNfwn1C%2BKBrc5z%2BXPO1pTj6pfBhhMOTmuK2GfsbyZ1O%2BNPZk1HMpI3lCq79Ie4SUPVywIhdYrH473MuSd8VFXBvywJv1ttkkgzD2zETylKxPuGw8CMUR8TuvIaPH%2BfJ%2B9w%3D%3D","seek":0,"status":"stop"} Jan 21 13:36:14 volumio volumio[993]: verbose: CURRENT POSITION 757 Jan 21 13:36:14 volumio volumio[993]: info: CoreStateMachine::syncState stateService stop Jan 21 13:36:14 volumio volumio[993]: info: CoreStateMachine::syncState currentStatus stop Jan 21 13:36:14 volumio volumio[993]: info: CoreStateMachine::pushState Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getTrack 757 Jan 21 13:36:14 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Jan 21 13:36:14 volumio volumio[993]: info: No code Jan 21 13:36:14 volumio volumio[993]: info: CoreStateMachine::pushState Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getTrack 757 Jan 21 13:36:14 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Jan 21 13:36:14 volumio volumio[993]: info: CoreStateMachine::setConsumeUpdateService pandora Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::parseState Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::parseState Jan 21 13:36:14 volumio volumio[993]: info: CoreStateMachine::play index undefined Jan 21 13:36:14 volumio volumio[993]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 13:36:14 volumio volumio[993]: info: ControllerMpd::pushState Jan 21 13:36:14 volumio volumio[993]: info: CoreCommandRouter::servicePushState Jan 21 13:36:14 volumio volumio[993]: info: CoreStateMachine::pushState Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getTrack 757 Jan 21 13:36:14 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getTrack 757 Jan 21 13:36:14 volumio volumio[993]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current pandora Received mpd Jan 21 13:36:14 volumio volumio[993]: info: ControllerMpd::pushState Jan 21 13:36:14 volumio volumio[993]: info: CoreCommandRouter::servicePushState Jan 21 13:36:14 volumio volumio[993]: info: CoreStateMachine::pushState Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getTrack 757 Jan 21 13:36:14 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getTrack 757 Jan 21 13:36:14 volumio volumio[993]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current pandora Received mpd Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getTrack 757 Jan 21 13:36:14 volumio volumio[993]: info: CoreStateMachine::startPlaybackTimer Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getTrack 757 Jan 21 13:36:14 volumio volumio[993]: info: [1737488174022] ControllerPandora::clearAddPlayTrack Jan 21 13:36:14 volumio volumio[993]: info: ControllerMpd::clear Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand clear Jan 21 13:36:14 volumio volumio[993]: info: ------------------------------ 24ms Jan 21 13:36:14 volumio volumio[993]: info: ------------------------------ 24ms Jan 21 13:36:14 volumio volumio[993]: info: Jan 21 13:36:14 volumio volumio[993]: ---------------------------- MPD announces system playlist update Jan 21 13:36:14 volumio volumio[993]: info: Ignoring MPD Status Update Jan 21 13:36:14 volumio volumio[993]: info: Jan 21 13:36:14 volumio volumio[993]: ---------------------------- MPD announces system playlist update Jan 21 13:36:14 volumio volumio[993]: info: Ignoring MPD Status Update Jan 21 13:36:14 volumio volumio[993]: error: updateQueue error: null Jan 21 13:36:14 volumio volumio[993]: info: Jan 21 13:36:14 volumio volumio[993]: ---------------------------- MPD announces system playlist update Jan 21 13:36:14 volumio volumio[993]: info: Ignoring MPD Status Update Jan 21 13:36:14 volumio volumio[993]: error: updateQueue error: null Jan 21 13:36:14 volumio volumio[993]: error: updateQueue error: null Jan 21 13:36:14 volumio volumio[993]: info: ------------------------------ 43ms Jan 21 13:36:14 volumio volumio[993]: info: sendMpdCommand playlistinfo took 41 milliseconds Jan 21 13:36:14 volumio volumio[993]: info: sendMpdCommand clear took 21 milliseconds Jan 21 13:36:14 volumio volumio[993]: info: ------------------------------ 18ms Jan 21 13:36:14 volumio volumio[993]: info: ------------------------------ 17ms Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::parseTrackInfo Jan 21 13:36:14 volumio volumio[993]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 21 13:36:14 volumio volumio[993]: info: [1737488174044] ControllerPandora::checkForExpiredStations Jan 21 13:36:14 volumio volumio[993]: info: ------------------------------ 49ms Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:14 volumio volumio[993]: info: [1737488174045] ControllerPandora::removeTrack Jan 21 13:36:14 volumio volumio[993]: info: [1737488174045] [Pandora] removeTrack: Not removing track with uri: null at queue index: -1 Jan 21 13:36:14 volumio volumio[993]: info: [1737488174045] ControllerPandora::appendTracksToMpd Jan 21 13:36:14 volumio volumio[993]: info: [1737488174045] [Pandora] appendTracksToMpd: Added 1 track(s) to mpd Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand addid Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand play Jan 21 13:36:14 volumio volumio[993]: info: Jan 21 13:36:14 volumio volumio[993]: ---------------------------- MPD announces system playlist update Jan 21 13:36:14 volumio volumio[993]: info: Ignoring MPD Status Update Jan 21 13:36:14 volumio volumio[993]: info: Jan 21 13:36:14 volumio volumio[993]: ---------------------------- MPD announces system playlist update Jan 21 13:36:14 volumio volumio[993]: info: Ignoring MPD Status Update Jan 21 13:36:14 volumio volumio[993]: info: [1737488174053] ControllerPandora::PandoraHandler::fetchTracks::fetchStationPlaylist Jan 21 13:36:14 volumio volumio[993]: error: updateQueue error: null Jan 21 13:36:14 volumio volumio[993]: info: Jan 21 13:36:14 volumio volumio[993]: ---------------------------- MPD announces system playlist update Jan 21 13:36:14 volumio volumio[993]: info: Ignoring MPD Status Update Jan 21 13:36:14 volumio volumio[993]: info: ------------------------------ 17ms Jan 21 13:36:14 volumio volumio[993]: info: sendMpdCommand addid took 13 milliseconds Jan 21 13:36:14 volumio volumio[993]: info: sendMpdCommand play took 13 milliseconds Jan 21 13:36:14 volumio volumio[993]: info: ------------------------------ 13ms Jan 21 13:36:14 volumio volumio[993]: info: ------------------------------ 13ms Jan 21 13:36:14 volumio volumio[993]: verbose: MPD COMMAND [object Object] Jan 21 13:36:14 volumio volumio[993]: verbose: MPD COMMAND [object Object] Jan 21 13:36:14 volumio volumio[993]: verbose: MPD COMMAND [object Object] Jan 21 13:36:14 volumio volumio[993]: info: [1737488174060] ControllerPandora::pushState Jan 21 13:36:14 volumio volumio[993]: info: CoreCommandRouter::servicePushState Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getTrack 757 Jan 21 13:36:14 volumio volumio[993]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"Outro","name":"Outro","artist":"Owusu & Hannibal","album":"Living With...","albumart":"http://mediaserver-cont-dc6-1-v4v6.pandora.com/images/c0/09/9b/f0/fb8747cdb37609471daf4045/1080W_1080H.jpg","realUri":"http://t3-3.p-cdn.us/access/5852899005235232331.mp3?version=5&lid=6612502&token=JZVrNpdMeFVgG5QszC37EMDB4u06%2F0VSxT1WziTUksWOqFB1Xt7tXAa9VeRU%2Bdz9rBmq5aFdM0yY2tJH5M1F2U5GdQDg56Js40ezZqh6uG76cfTOxH6Q1wWr7VeWusR92jy1GWP11HUSHc1KzEGM898AGB00dzyr7ML9eOHajbIygS9ssfxKsmIfbmuMI7KCKkPTZWdwAPZJddQr462CSJ9WCGYC%2FyxyxvpaVGiQJoUqdiv0vly0gCoZfANh%2F7KmT7TGCnJzPGwBGSyLXbnlvOwDAc8oKwHeof0P7H77qZWfJvSSY5GZbH6bPnwetQlPNuPIIJ%2BDsvtCoKgq5GldgQ%3D%3D","isStreaming":true,"duration":105,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t3-3.p-cdn.us/access/5852899005235232331.mp3?version=5&lid=6612502&token=JZVrNpdMeFVgG5QszC37EMDB4u06%2F0VSxT1WziTUksWOqFB1Xt7tXAa9VeRU%2Bdz9rBmq5aFdM0yY2tJH5M1F2U5GdQDg56Js40ezZqh6uG76cfTOxH6Q1wWr7VeWusR92jy1GWP11HUSHc1KzEGM898AGB00dzyr7ML9eOHajbIygS9ssfxKsmIfbmuMI7KCKkPTZWdwAPZJddQr462CSJ9WCGYC%2FyxyxvpaVGiQJoUqdiv0vly0gCoZfANh%2F7KmT7TGCnJzPGwBGSyLXbnlvOwDAc8oKwHeof0P7H77qZWfJvSSY5GZbH6bPnwetQlPNuPIIJ%2BDsvtCoKgq5GldgQ%3D%3D","seek":0,"status":"play"} Jan 21 13:36:14 volumio volumio[993]: verbose: CURRENT POSITION 757 Jan 21 13:36:14 volumio volumio[993]: info: CoreStateMachine::syncState stateService play Jan 21 13:36:14 volumio volumio[993]: info: CoreStateMachine::syncState currentStatus stop Jan 21 13:36:14 volumio volumio[993]: info: CoreStateMachine::setConsumeUpdateService pandora Jan 21 13:36:14 volumio volumio[993]: info: [1737488174061] ControllerPandora::fetchAndAddTracks Jan 21 13:36:14 volumio volumio[993]: info: [1737488174061] ControllerPandora::getStationTracks Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:14 volumio volumio[993]: info: [1737488174062] [Pandora] PandoraHandler::getSongMaxDiff: -8 Jan 21 13:36:14 volumio volumio[993]: info: [1737488174062] ControllerPandora::fetchAndAddTracks::getSqInfo Jan 21 13:36:14 volumio volumio[993]: info: [1737488174062] ControllerPandora::getStationTracks Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:14 volumio volumio[993]: info: [1737488174063] [Pandora] fetchAndAddTracks: diff1: -8 sQPos1: 4 Jan 21 13:36:14 volumio volumio[993]: info: [1737488174063] [Pandora] fetchAndAddTracks: Fetching tracks Jan 21 13:36:14 volumio volumio[993]: info: [1737488174063] ControllerPandora::PandoraHandler::fillStationData Jan 21 13:36:14 volumio volumio[993]: info: Jan 21 13:36:14 volumio volumio[993]: ---------------------------- MPD announces system playlist update Jan 21 13:36:14 volumio volumio[993]: info: Ignoring MPD Status Update Jan 21 13:36:14 volumio volumio[993]: info: Jan 21 13:36:14 volumio volumio[993]: ---------------------------- MPD announces system playlist update Jan 21 13:36:14 volumio volumio[993]: info: Ignoring MPD Status Update Jan 21 13:36:14 volumio volumio[993]: info: Jan 21 13:36:14 volumio volumio[993]: ---------------------------- MPD announces system playlist update Jan 21 13:36:14 volumio volumio[993]: info: Ignoring MPD Status Update Jan 21 13:36:14 volumio volumio[993]: info: ------------------------------ 10ms Jan 21 13:36:14 volumio volumio[993]: info: ------------------------------ 5ms Jan 21 13:36:14 volumio volumio[993]: info: ------------------------------ 4ms Jan 21 13:36:14 volumio volumio[993]: info: Jan 21 13:36:14 volumio volumio[993]: ---------------------------- MPD announces system playlist update Jan 21 13:36:14 volumio volumio[993]: info: Ignoring MPD Status Update Jan 21 13:36:14 volumio volumio[993]: info: Jan 21 13:36:14 volumio volumio[993]: ---------------------------- MPD announces system playlist update Jan 21 13:36:14 volumio volumio[993]: info: Ignoring MPD Status Update Jan 21 13:36:14 volumio volumio[993]: info: ------------------------------ 2ms Jan 21 13:36:14 volumio volumio[993]: info: ------------------------------ 2ms Jan 21 13:36:14 volumio volumio[993]: info: ------------------------------ 1ms Jan 21 13:36:14 volumio volumio[993]: info: [1737488174507] ControllerPandora::PandoraHandler::fetchTracks::fetchStationPlaylist Jan 21 13:36:14 volumio volumio[993]: info: [1737488174633] ControllerPandora::pandoraListener Jan 21 13:36:14 volumio volumio[993]: info: ControllerMpd::getState Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand status Jan 21 13:36:14 volumio volumio[993]: info: Jan 21 13:36:14 volumio volumio[993]: ---------------------------- MPD announces state update: player Jan 21 13:36:14 volumio volumio[993]: info: ControllerMpd::getState Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand status Jan 21 13:36:14 volumio volumio[993]: info: Jan 21 13:36:14 volumio volumio[993]: ---------------------------- MPD announces state update: player Jan 21 13:36:14 volumio volumio[993]: info: ControllerMpd::getState Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand status Jan 21 13:36:14 volumio volumio[993]: info: Jan 21 13:36:14 volumio volumio[993]: ---------------------------- MPD announces state update: player Jan 21 13:36:14 volumio volumio[993]: info: ControllerMpd::getState Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand status Jan 21 13:36:14 volumio volumio[993]: info: Jan 21 13:36:14 volumio volumio[993]: ---------------------------- MPD announces state update: player Jan 21 13:36:14 volumio volumio[993]: info: sendMpdCommand status took 79 milliseconds Jan 21 13:36:14 volumio volumio[993]: info: sendMpdCommand status took 80 milliseconds Jan 21 13:36:14 volumio volumio[993]: info: ControllerMpd::getState Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand status Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::parseState Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::parseState Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 13:36:14 volumio volumio[993]: info: sendMpdCommand status took 81 milliseconds Jan 21 13:36:14 volumio volumio[993]: info: sendMpdCommand status took 80 milliseconds Jan 21 13:36:14 volumio volumio[993]: info: sendMpdCommand status took 2 milliseconds Jan 21 13:36:14 volumio volumio[993]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 21 13:36:14 volumio volumio[993]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::parseState Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::parseState Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::parseState Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::parseTrackInfo Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::parseTrackInfo Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getTrack 757 Jan 21 13:36:14 volumio volumio[993]: info: [1737488174718] ControllerPandora::pushState Jan 21 13:36:14 volumio volumio[993]: info: CoreCommandRouter::servicePushState Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getTrack 757 Jan 21 13:36:14 volumio volumio[993]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":105,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Outro","artist":"Owusu & Hannibal","album":"Living With...","uri":"http://t3-3.p-cdn.us/access/5852899005235232331.mp3?version=5&lid=6612502&token=JZVrNpdMeFVgG5QszC37EMDB4u06%2F0VSxT1WziTUksWOqFB1Xt7tXAa9VeRU%2Bdz9rBmq5aFdM0yY2tJH5M1F2U5GdQDg56Js40ezZqh6uG76cfTOxH6Q1wWr7VeWusR92jy1GWP11HUSHc1KzEGM898AGB00dzyr7ML9eOHajbIygS9ssfxKsmIfbmuMI7KCKkPTZWdwAPZJddQr462CSJ9WCGYC%2FyxyxvpaVGiQJoUqdiv0vly0gCoZfANh%2F7KmT7TGCnJzPGwBGSyLXbnlvOwDAc8oKwHeof0P7H77qZWfJvSSY5GZbH6bPnwetQlPNuPIIJ%2BDsvtCoKgq5GldgQ%3D%3D","trackType":"mp3"} Jan 21 13:36:14 volumio volumio[993]: verbose: CURRENT POSITION 757 Jan 21 13:36:14 volumio volumio[993]: info: CoreStateMachine::syncState stateService play Jan 21 13:36:14 volumio volumio[993]: info: CoreStateMachine::syncState currentStatus play Jan 21 13:36:14 volumio volumio[993]: info: Received an update from plugin. extracting info from payload Jan 21 13:36:14 volumio volumio[993]: info: CoreStateMachine::pushState Jan 21 13:36:14 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 13:36:14 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Jan 21 13:36:14 volumio volumio[993]: info: CoreStateMachine::pushState Jan 21 13:36:14 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Jan 21 13:36:14 volumio volumio[993]: info: CoreStateMachine::setConsumeUpdateService pandora Jan 21 13:36:14 volumio volumio[993]: info: ControllerMpd::pushState Jan 21 13:36:14 volumio volumio[993]: info: CoreCommandRouter::servicePushState Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getTrack 757 Jan 21 13:36:14 volumio volumio[993]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jan 21 13:36:14 volumio volumio[993]: info: ------------------------------ 95ms Jan 21 13:36:14 volumio volumio[993]: info: Jan 21 13:36:14 volumio volumio[993]: ---------------------------- MPD announces state update: player Jan 21 13:36:14 volumio volumio[993]: info: ControllerMpd::getState Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand status Jan 21 13:36:14 volumio volumio[993]: info: Jan 21 13:36:14 volumio volumio[993]: ---------------------------- MPD announces state update: player Jan 21 13:36:14 volumio volumio[993]: info: ControllerMpd::getState Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand status Jan 21 13:36:14 volumio volumio[993]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 21 13:36:14 volumio volumio[993]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 21 13:36:14 volumio volumio[993]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 21 13:36:14 volumio volumio[993]: info: sendMpdCommand status took 2 milliseconds Jan 21 13:36:14 volumio volumio[993]: info: sendMpdCommand status took 1 milliseconds Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::parseTrackInfo Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::parseTrackInfo Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::parseTrackInfo Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::parseState Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::parseState Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 13:36:14 volumio volumio[993]: info: ControllerMpd::pushState Jan 21 13:36:14 volumio volumio[993]: info: CoreCommandRouter::servicePushState Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getTrack 757 Jan 21 13:36:14 volumio volumio[993]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jan 21 13:36:14 volumio volumio[993]: info: ControllerMpd::pushState Jan 21 13:36:14 volumio volumio[993]: info: CoreCommandRouter::servicePushState Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getTrack 757 Jan 21 13:36:14 volumio volumio[993]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jan 21 13:36:14 volumio volumio[993]: info: ControllerMpd::pushState Jan 21 13:36:14 volumio volumio[993]: info: CoreCommandRouter::servicePushState Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getTrack 757 Jan 21 13:36:14 volumio volumio[993]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jan 21 13:36:14 volumio volumio[993]: info: ------------------------------ 100ms Jan 21 13:36:14 volumio volumio[993]: info: ------------------------------ 100ms Jan 21 13:36:14 volumio volumio[993]: info: ------------------------------ 22ms Jan 21 13:36:14 volumio volumio[993]: info: CoreCommandRouter::volumioGetQueue Jan 21 13:36:14 volumio volumio[993]: info: CoreStateMachine::getQueue Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:14 volumio volumio[993]: info: sendMpdCommand playlistinfo took 12 milliseconds Jan 21 13:36:14 volumio volumio[993]: info: sendMpdCommand playlistinfo took 13 milliseconds Jan 21 13:36:14 volumio volumio[993]: verbose: ControllerMpd::parseTrackInfo Jan 21 13:36:14 volumio volumio[993]: info: ControllerMpd::pushState Jan 21 13:36:14 volumio volumio[993]: info: CoreCommandRouter::servicePushState Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getTrack 757 Jan 21 13:36:14 volumio volumio[993]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jan 21 13:36:14 volumio volumio[993]: info: ControllerMpd::pushState Jan 21 13:36:14 volumio volumio[993]: info: CoreCommandRouter::servicePushState Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getTrack 757 Jan 21 13:36:14 volumio volumio[993]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Jan 21 13:36:14 volumio volumio[993]: info: ------------------------------ 17ms Jan 21 13:36:14 volumio volumio[993]: info: [1737488174754] [Pandora] PandoraHandler::fetchTracks::fetchStationPlaylist: Retrieved Owusu & Hannibal Radio playlist Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:14 volumio volumio[993]: info: [1737488174754] ControllerPandora::PandoraHandler::fetchTracks::fillNewTracks Jan 21 13:36:14 volumio volumio[993]: info: [1737488174755] [Pandora] PandoraHandler::fetchTracks::fillNewTracks: Fetched 4 track(s) Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::addQueueItems Jan 21 13:36:14 volumio volumio[993]: info: Preload queue cleared Jan 21 13:36:14 volumio volumio[993]: info: Adding Item to queue: /pandora/stationToken=4462923413750867478trackId=7842010608950966601 Jan 21 13:36:14 volumio volumio[993]: info: Exploding uri /pandora/stationToken=4462923413750867478trackId=7842010608950966601 in service pandora Jan 21 13:36:14 volumio volumio[993]: info: [1737488174755] ControllerPandora::explodeUri Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:14 volumio volumio[993]: info: Adding Item to queue: /pandora/stationToken=4462923413750867478trackId=9109143568716549261 Jan 21 13:36:14 volumio volumio[993]: info: Exploding uri /pandora/stationToken=4462923413750867478trackId=9109143568716549261 in service pandora Jan 21 13:36:14 volumio volumio[993]: info: [1737488174756] ControllerPandora::explodeUri Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:14 volumio volumio[993]: info: Adding Item to queue: /pandora/stationToken=4462923413750867478trackId=7655959144482089431 Jan 21 13:36:14 volumio volumio[993]: info: Exploding uri /pandora/stationToken=4462923413750867478trackId=7655959144482089431 in service pandora Jan 21 13:36:14 volumio volumio[993]: info: [1737488174756] ControllerPandora::explodeUri Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:14 volumio volumio[993]: info: Adding Item to queue: /pandora/stationToken=4462923413750867478trackId=3757962505218614309 Jan 21 13:36:14 volumio volumio[993]: info: Exploding uri /pandora/stationToken=4462923413750867478trackId=3757962505218614309 in service pandora Jan 21 13:36:14 volumio volumio[993]: info: [1737488174756] ControllerPandora::explodeUri Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:14 volumio volumio[993]: info: CoreCommandRouter::volumioPushQueue Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::saveQueue Jan 21 13:36:14 volumio volumio[993]: info: CoreStateMachine::updateTrackBlock Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getTrackBlock Jan 21 13:36:14 volumio volumio[993]: info: [1737488174785] ControllerPandora::getStationTracks Jan 21 13:36:14 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:14 volumio volumio[993]: info: [1737488174785] [Pandora] PandoraHandler::getSongMaxDiff: -4 Jan 21 13:36:15 volumio volumio[993]: info: [1737488175187] [Pandora] PandoraHandler::fetchTracks::fetchStationPlaylist: Retrieved Owusu & Hannibal Radio playlist Jan 21 13:36:15 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:15 volumio volumio[993]: info: [1737488175187] ControllerPandora::PandoraHandler::fetchTracks::fillNewTracks Jan 21 13:36:15 volumio volumio[993]: info: [1737488175188] [Pandora] PandoraHandler::fetchTracks::fillNewTracks: Fetched 8 track(s) Jan 21 13:36:15 volumio volumio[993]: info: CorePlayQueue::addQueueItems Jan 21 13:36:15 volumio volumio[993]: info: Preload queue cleared Jan 21 13:36:15 volumio volumio[993]: info: Adding Item to queue: /pandora/stationToken=4462923413750867478trackId=7842010608950966601 Jan 21 13:36:15 volumio volumio[993]: info: Using cached record of: /pandora/stationToken=4462923413750867478trackId=7842010608950966601 Jan 21 13:36:15 volumio volumio[993]: info: Adding Item to queue: /pandora/stationToken=4462923413750867478trackId=9109143568716549261 Jan 21 13:36:15 volumio volumio[993]: info: Using cached record of: /pandora/stationToken=4462923413750867478trackId=9109143568716549261 Jan 21 13:36:15 volumio volumio[993]: info: Adding Item to queue: /pandora/stationToken=4462923413750867478trackId=7655959144482089431 Jan 21 13:36:15 volumio volumio[993]: info: Using cached record of: /pandora/stationToken=4462923413750867478trackId=7655959144482089431 Jan 21 13:36:15 volumio volumio[993]: info: Adding Item to queue: /pandora/stationToken=4462923413750867478trackId=3757962505218614309 Jan 21 13:36:15 volumio volumio[993]: info: Using cached record of: /pandora/stationToken=4462923413750867478trackId=3757962505218614309 Jan 21 13:36:15 volumio volumio[993]: info: Adding Item to queue: /pandora/stationToken=4462923413750867478trackId=5806081576181701247 Jan 21 13:36:15 volumio volumio[993]: info: Exploding uri /pandora/stationToken=4462923413750867478trackId=5806081576181701247 in service pandora Jan 21 13:36:15 volumio volumio[993]: info: [1737488175190] ControllerPandora::explodeUri Jan 21 13:36:15 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:15 volumio volumio[993]: info: Adding Item to queue: /pandora/stationToken=4462923413750867478trackId=637853438039343800 Jan 21 13:36:15 volumio volumio[993]: info: Exploding uri /pandora/stationToken=4462923413750867478trackId=637853438039343800 in service pandora Jan 21 13:36:15 volumio volumio[993]: info: [1737488175191] ControllerPandora::explodeUri Jan 21 13:36:15 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:15 volumio volumio[993]: info: Adding Item to queue: /pandora/stationToken=4462923413750867478trackId=5430959174132842924 Jan 21 13:36:15 volumio volumio[993]: info: Exploding uri /pandora/stationToken=4462923413750867478trackId=5430959174132842924 in service pandora Jan 21 13:36:15 volumio volumio[993]: info: [1737488175191] ControllerPandora::explodeUri Jan 21 13:36:15 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:15 volumio volumio[993]: info: Adding Item to queue: /pandora/stationToken=4462923413750867478trackId=4816604648805455182 Jan 21 13:36:15 volumio volumio[993]: info: Exploding uri /pandora/stationToken=4462923413750867478trackId=4816604648805455182 in service pandora Jan 21 13:36:15 volumio volumio[993]: info: [1737488175191] ControllerPandora::explodeUri Jan 21 13:36:15 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:15 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:15 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:15 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:15 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:15 volumio volumio[993]: info: CoreCommandRouter::volumioPushQueue Jan 21 13:36:15 volumio volumio[993]: info: CorePlayQueue::saveQueue Jan 21 13:36:15 volumio volumio[993]: info: CoreStateMachine::updateTrackBlock Jan 21 13:36:15 volumio volumio[993]: info: CorePlayQueue::getTrackBlock Jan 21 13:36:15 volumio volumio[993]: info: [1737488175220] ControllerPandora::getStationTracks Jan 21 13:36:15 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:15 volumio volumio[993]: info: [1737488175221] [Pandora] PandoraHandler::getSongMaxDiff: 4 Jan 21 13:36:15 volumio volumio[993]: info: [1737488175221] ControllerPandora::fetchAndAddTracks::getSqInfo Jan 21 13:36:15 volumio volumio[993]: info: [1737488175221] ControllerPandora::getStationTracks Jan 21 13:36:15 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:15 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:15 volumio volumio[993]: info: [1737488175222] [Pandora] fetchAndAddTracks: diff2 > 0: 4 sQPos2: 4 Jan 21 13:36:15 volumio volumio[993]: info: [1737488175222] ControllerPandora::removeOldTrackBlock Jan 21 13:36:15 volumio volumio[993]: info: [1737488175223] ControllerPandora::removeStationTracks Jan 21 13:36:15 volumio volumio[993]: info: [1737488175223] ControllerPandora::getStationTracks Jan 21 13:36:15 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:15 volumio volumio[993]: info: [1737488175224] [Pandora] removeStationTracks: Removing 4 tracks from queue Jan 21 13:36:17 volumio wpa_supplicant[850]: RRM: Ignoring radio measurement request: Not RRM network Jan 21 13:36:20 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Jan 21 13:36:20 volumio volumio[993]: info: Listing playlists Jan 21 13:36:20 volumio volumio[993]: info: Listing playlists Jan 21 13:36:25 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:25 volumio volumio[993]: info: [1737488185224] ControllerPandora::removeTrack Jan 21 13:36:25 volumio volumio[993]: info: CoreStateMachine::removeQueueItem Jan 21 13:36:25 volumio volumio[993]: info: CorePlayQueue::removeQueueItem 753 Jan 21 13:36:25 volumio volumio[993]: info: CorePlayQueue::saveQueue Jan 21 13:36:25 volumio volumio[993]: info: CoreCommandRouter::volumioPushQueue Jan 21 13:36:25 volumio volumio[993]: info: [1737488185237] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=4462923413750867478trackId=2056455101874107906 at queue index: 753 Jan 21 13:36:25 volumio volumio[993]: info: ControllerMpd::getState Jan 21 13:36:25 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand status Jan 21 13:36:25 volumio volumio[993]: info: sendMpdCommand status took 58 milliseconds Jan 21 13:36:25 volumio volumio[993]: verbose: ControllerMpd::parseState Jan 21 13:36:25 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 13:36:25 volumio volumio[993]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 21 13:36:25 volumio volumio[993]: verbose: ControllerMpd::parseTrackInfo Jan 21 13:36:25 volumio volumio[993]: info: [1737488185298] ControllerPandora::pushState Jan 21 13:36:25 volumio volumio[993]: info: CoreCommandRouter::servicePushState Jan 21 13:36:25 volumio volumio[993]: info: CorePlayQueue::getTrack 756 Jan 21 13:36:25 volumio volumio[993]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11485,"duration":105,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Outro","artist":"Owusu & Hannibal","album":"Living With...","uri":"http://t3-3.p-cdn.us/access/5852899005235232331.mp3?version=5&lid=6612502&token=JZVrNpdMeFVgG5QszC37EMDB4u06%2F0VSxT1WziTUksWOqFB1Xt7tXAa9VeRU%2Bdz9rBmq5aFdM0yY2tJH5M1F2U5GdQDg56Js40ezZqh6uG76cfTOxH6Q1wWr7VeWusR92jy1GWP11HUSHc1KzEGM898AGB00dzyr7ML9eOHajbIygS9ssfxKsmIfbmuMI7KCKkPTZWdwAPZJddQr462CSJ9WCGYC%2FyxyxvpaVGiQJoUqdiv0vly0gCoZfANh%2F7KmT7TGCnJzPGwBGSyLXbnlvOwDAc8oKwHeof0P7H77qZWfJvSSY5GZbH6bPnwetQlPNuPIIJ%2BDsvtCoKgq5GldgQ%3D%3D","trackType":"mp3"} Jan 21 13:36:25 volumio volumio[993]: verbose: CURRENT POSITION 756 Jan 21 13:36:25 volumio volumio[993]: info: CoreStateMachine::syncState stateService play Jan 21 13:36:25 volumio volumio[993]: info: CoreStateMachine::syncState currentStatus play Jan 21 13:36:25 volumio volumio[993]: info: Received an update from plugin. extracting info from payload Jan 21 13:36:25 volumio volumio[993]: info: CoreStateMachine::pushState Jan 21 13:36:25 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 13:36:25 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Jan 21 13:36:25 volumio volumio[993]: info: CoreStateMachine::pushState Jan 21 13:36:25 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Jan 21 13:36:25 volumio volumio[993]: info: CoreStateMachine::setConsumeUpdateService pandora Jan 21 13:36:30 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Jan 21 13:36:30 volumio nmbd[741]: [2025/01/21 13:36:30.591146, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 21 13:36:30 volumio nmbd[741]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.190 for name WORKGROUP<1d>. Jan 21 13:36:30 volumio nmbd[741]: This response was from IP 192.168.0.159, reporting an IP address of 192.168.0.159. Jan 21 13:36:35 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:35 volumio volumio[993]: info: [1737488195224] ControllerPandora::removeTrack Jan 21 13:36:35 volumio volumio[993]: info: CoreStateMachine::removeQueueItem Jan 21 13:36:35 volumio volumio[993]: info: CorePlayQueue::removeQueueItem 753 Jan 21 13:36:35 volumio volumio[993]: info: CorePlayQueue::saveQueue Jan 21 13:36:35 volumio volumio[993]: info: CoreCommandRouter::volumioPushQueue Jan 21 13:36:35 volumio volumio[993]: info: [1737488195239] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=4462923413750867478trackId=388063173094243623 at queue index: 753 Jan 21 13:36:35 volumio volumio[993]: info: ControllerMpd::getState Jan 21 13:36:35 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand status Jan 21 13:36:35 volumio volumio[993]: info: sendMpdCommand status took 43 milliseconds Jan 21 13:36:35 volumio volumio[993]: verbose: ControllerMpd::parseState Jan 21 13:36:35 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 13:36:35 volumio volumio[993]: info: sendMpdCommand playlistinfo took 13 milliseconds Jan 21 13:36:35 volumio volumio[993]: verbose: ControllerMpd::parseTrackInfo Jan 21 13:36:35 volumio volumio[993]: info: [1737488195297] ControllerPandora::pushState Jan 21 13:36:35 volumio volumio[993]: info: CoreCommandRouter::servicePushState Jan 21 13:36:35 volumio volumio[993]: info: CorePlayQueue::getTrack 755 Jan 21 13:36:35 volumio volumio[993]: verbose: STATE SERVICE {"status":"play","position":0,"seek":21359,"duration":105,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Outro","artist":"Owusu & Hannibal","album":"Living With...","uri":"http://t3-3.p-cdn.us/access/5852899005235232331.mp3?version=5&lid=6612502&token=JZVrNpdMeFVgG5QszC37EMDB4u06%2F0VSxT1WziTUksWOqFB1Xt7tXAa9VeRU%2Bdz9rBmq5aFdM0yY2tJH5M1F2U5GdQDg56Js40ezZqh6uG76cfTOxH6Q1wWr7VeWusR92jy1GWP11HUSHc1KzEGM898AGB00dzyr7ML9eOHajbIygS9ssfxKsmIfbmuMI7KCKkPTZWdwAPZJddQr462CSJ9WCGYC%2FyxyxvpaVGiQJoUqdiv0vly0gCoZfANh%2F7KmT7TGCnJzPGwBGSyLXbnlvOwDAc8oKwHeof0P7H77qZWfJvSSY5GZbH6bPnwetQlPNuPIIJ%2BDsvtCoKgq5GldgQ%3D%3D","trackType":"mp3"} Jan 21 13:36:35 volumio volumio[993]: verbose: CURRENT POSITION 755 Jan 21 13:36:35 volumio volumio[993]: info: CoreStateMachine::syncState stateService play Jan 21 13:36:35 volumio volumio[993]: info: CoreStateMachine::syncState currentStatus play Jan 21 13:36:35 volumio volumio[993]: info: Received an update from plugin. extracting info from payload Jan 21 13:36:35 volumio volumio[993]: info: CoreStateMachine::pushState Jan 21 13:36:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 13:36:35 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Jan 21 13:36:35 volumio volumio[993]: info: CoreStateMachine::pushState Jan 21 13:36:35 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Jan 21 13:36:35 volumio volumio[993]: info: CoreStateMachine::setConsumeUpdateService pandora Jan 21 13:36:35 volumio wpa_supplicant[850]: RRM: Ignoring radio measurement request: Not RRM network Jan 21 13:36:40 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Jan 21 13:36:40 volumio volumio[993]: info: Listing playlists Jan 21 13:36:40 volumio volumio[993]: info: Listing playlists Jan 21 13:36:45 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:45 volumio volumio[993]: info: [1737488205225] ControllerPandora::removeTrack Jan 21 13:36:45 volumio volumio[993]: info: CoreStateMachine::removeQueueItem Jan 21 13:36:45 volumio volumio[993]: info: CorePlayQueue::removeQueueItem 753 Jan 21 13:36:45 volumio volumio[993]: info: CorePlayQueue::saveQueue Jan 21 13:36:45 volumio volumio[993]: info: CoreCommandRouter::volumioPushQueue Jan 21 13:36:45 volumio volumio[993]: info: [1737488205240] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=4462923413750867478trackId=3608556896136243052 at queue index: 753 Jan 21 13:36:45 volumio volumio[993]: info: ControllerMpd::getState Jan 21 13:36:45 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand status Jan 21 13:36:45 volumio volumio[993]: info: sendMpdCommand status took 44 milliseconds Jan 21 13:36:45 volumio volumio[993]: verbose: ControllerMpd::parseState Jan 21 13:36:45 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 13:36:45 volumio volumio[993]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 21 13:36:45 volumio volumio[993]: verbose: ControllerMpd::parseTrackInfo Jan 21 13:36:45 volumio volumio[993]: info: [1737488205298] ControllerPandora::pushState Jan 21 13:36:45 volumio volumio[993]: info: CoreCommandRouter::servicePushState Jan 21 13:36:45 volumio volumio[993]: info: CorePlayQueue::getTrack 754 Jan 21 13:36:45 volumio volumio[993]: verbose: STATE SERVICE {"status":"play","position":0,"seek":31485,"duration":105,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Outro","artist":"Owusu & Hannibal","album":"Living With...","uri":"http://t3-3.p-cdn.us/access/5852899005235232331.mp3?version=5&lid=6612502&token=JZVrNpdMeFVgG5QszC37EMDB4u06%2F0VSxT1WziTUksWOqFB1Xt7tXAa9VeRU%2Bdz9rBmq5aFdM0yY2tJH5M1F2U5GdQDg56Js40ezZqh6uG76cfTOxH6Q1wWr7VeWusR92jy1GWP11HUSHc1KzEGM898AGB00dzyr7ML9eOHajbIygS9ssfxKsmIfbmuMI7KCKkPTZWdwAPZJddQr462CSJ9WCGYC%2FyxyxvpaVGiQJoUqdiv0vly0gCoZfANh%2F7KmT7TGCnJzPGwBGSyLXbnlvOwDAc8oKwHeof0P7H77qZWfJvSSY5GZbH6bPnwetQlPNuPIIJ%2BDsvtCoKgq5GldgQ%3D%3D","trackType":"mp3"} Jan 21 13:36:45 volumio volumio[993]: verbose: CURRENT POSITION 754 Jan 21 13:36:45 volumio volumio[993]: info: CoreStateMachine::syncState stateService play Jan 21 13:36:45 volumio volumio[993]: info: CoreStateMachine::syncState currentStatus play Jan 21 13:36:45 volumio volumio[993]: info: Received an update from plugin. extracting info from payload Jan 21 13:36:45 volumio volumio[993]: info: CoreStateMachine::pushState Jan 21 13:36:45 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 13:36:45 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Jan 21 13:36:45 volumio volumio[993]: info: CoreStateMachine::pushState Jan 21 13:36:45 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Jan 21 13:36:45 volumio volumio[993]: info: CoreStateMachine::setConsumeUpdateService pandora Jan 21 13:36:50 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Jan 21 13:36:53 volumio wpa_supplicant[850]: RRM: Ignoring radio measurement request: Not RRM network Jan 21 13:36:55 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:36:55 volumio volumio[993]: info: [1737488215224] ControllerPandora::removeTrack Jan 21 13:36:55 volumio volumio[993]: info: CoreStateMachine::removeQueueItem Jan 21 13:36:55 volumio volumio[993]: info: CorePlayQueue::removeQueueItem 753 Jan 21 13:36:55 volumio volumio[993]: info: CorePlayQueue::saveQueue Jan 21 13:36:55 volumio volumio[993]: info: CoreCommandRouter::volumioPushQueue Jan 21 13:36:55 volumio volumio[993]: info: [1737488215239] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=4462923413750867478trackId=599027446145327859 at queue index: 753 Jan 21 13:36:55 volumio volumio[993]: info: ControllerMpd::getState Jan 21 13:36:55 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand status Jan 21 13:36:55 volumio volumio[993]: info: sendMpdCommand status took 43 milliseconds Jan 21 13:36:55 volumio volumio[993]: verbose: ControllerMpd::parseState Jan 21 13:36:55 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 13:36:55 volumio volumio[993]: info: sendMpdCommand playlistinfo took 13 milliseconds Jan 21 13:36:55 volumio volumio[993]: verbose: ControllerMpd::parseTrackInfo Jan 21 13:36:55 volumio volumio[993]: info: [1737488215296] ControllerPandora::pushState Jan 21 13:36:55 volumio volumio[993]: info: CoreCommandRouter::servicePushState Jan 21 13:36:55 volumio volumio[993]: info: CorePlayQueue::getTrack 753 Jan 21 13:36:55 volumio volumio[993]: verbose: STATE SERVICE {"status":"play","position":0,"seek":41485,"duration":105,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Outro","artist":"Owusu & Hannibal","album":"Living With...","uri":"http://t3-3.p-cdn.us/access/5852899005235232331.mp3?version=5&lid=6612502&token=JZVrNpdMeFVgG5QszC37EMDB4u06%2F0VSxT1WziTUksWOqFB1Xt7tXAa9VeRU%2Bdz9rBmq5aFdM0yY2tJH5M1F2U5GdQDg56Js40ezZqh6uG76cfTOxH6Q1wWr7VeWusR92jy1GWP11HUSHc1KzEGM898AGB00dzyr7ML9eOHajbIygS9ssfxKsmIfbmuMI7KCKkPTZWdwAPZJddQr462CSJ9WCGYC%2FyxyxvpaVGiQJoUqdiv0vly0gCoZfANh%2F7KmT7TGCnJzPGwBGSyLXbnlvOwDAc8oKwHeof0P7H77qZWfJvSSY5GZbH6bPnwetQlPNuPIIJ%2BDsvtCoKgq5GldgQ%3D%3D","trackType":"mp3"} Jan 21 13:36:55 volumio volumio[993]: verbose: CURRENT POSITION 753 Jan 21 13:36:55 volumio volumio[993]: info: CoreStateMachine::syncState stateService play Jan 21 13:36:55 volumio volumio[993]: info: CoreStateMachine::syncState currentStatus play Jan 21 13:36:55 volumio volumio[993]: info: Received an update from plugin. extracting info from payload Jan 21 13:36:55 volumio volumio[993]: info: CoreStateMachine::pushState Jan 21 13:36:55 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 13:36:55 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Jan 21 13:36:55 volumio volumio[993]: info: CoreStateMachine::pushState Jan 21 13:36:55 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Jan 21 13:36:55 volumio volumio[993]: info: CoreStateMachine::setConsumeUpdateService pandora Jan 21 13:37:00 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Jan 21 13:37:00 volumio volumio[993]: info: Listing playlists Jan 21 13:37:00 volumio volumio[993]: info: Listing playlists Jan 21 13:37:09 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 21 13:37:09 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 21 13:37:10 volumio ntpd[749]: receive: Unexpected origin timestamp 0xeb3a75e6.5c7283a7 does not match aorg 0000000000.00000000 from server@129.250.35.250 xmt 0xeb3a75e6.740c9b26 Jan 21 13:37:10 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Jan 21 13:37:11 volumio wpa_supplicant[850]: RRM: Ignoring radio measurement request: Not RRM network Jan 21 13:37:15 volumio volumio[993]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 13:37:20 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Jan 21 13:37:20 volumio volumio[993]: info: Listing playlists Jan 21 13:37:20 volumio volumio[993]: info: Listing playlists Jan 21 13:37:28 volumio volumio[993]: info: CALLMETHOD: music_service pandora setAccountOptionsConf [object Object] Jan 21 13:37:28 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: pandora , setAccountOptionsConf Jan 21 13:37:28 volumio volumio[993]: info: [1737488248010] ControllerPandora::validateAndSetAccountOptions Jan 21 13:37:28 volumio volumio[993]: info: [1737488248010] ControllerPandora::PandoraHandler::setAccountOptions Jan 21 13:37:28 volumio volumio[993]: info: [1737488248012] ControllerPandora::flushPandora Jan 21 13:37:28 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:37:28 volumio volumio[993]: info: CorePlayQueue::clearAddPlayQueue Jan 21 13:37:28 volumio volumio[993]: info: CorePlayQueue::saveQueue Jan 21 13:37:28 volumio volumio[993]: info: CoreCommandRouter::serviceClearAddPlayTracks Jan 21 13:37:28 volumio volumio[993]: error: WARNING: No clearAddPlayTracks method for service spop Jan 21 13:37:28 volumio volumio[993]: info: CoreCommandRouter::volumioPushQueue Jan 21 13:37:28 volumio volumio[993]: info: [1737488248042] [Pandora] PreventAuthTimeout::init: Timer loaded Jan 21 13:37:28 volumio volumio[993]: info: [1737488248043] [Pandora] PreventAuthTimeout::init: Interval set to 10800000 ms Jan 21 13:37:28 volumio volumio[993]: info: [1737488248043] [Pandora] PreventAuthTimeout::init: Starting delayed timer after next interval Jan 21 13:37:28 volumio volumio[993]: info: [1737488248043] ControllerPandora::PandoraHandler::pandoraLoginAndGetStations Jan 21 13:37:28 volumio volumio[993]: info: [1737488248907] [Pandora] PandoraHandler::pandoraLoginAndGetStations::pandoraLogin: [<=- Refreshed Pandora Login -=>] Jan 21 13:37:28 volumio volumio[993]: info: [1737488248910] [Pandora] validateAndSetAccountOptions: ***DEBUG*** self.loggedIn: true Jan 21 13:37:28 volumio volumio[993]: info: [1737488248911] [Pandora] ExpireOldTracks::init: Timer loaded Jan 21 13:37:28 volumio volumio[993]: info: [1737488248911] [Pandora] ExpireOldTracks::init: Interval set to 300000 ms Jan 21 13:37:28 volumio volumio[993]: info: [1737488248911] [Pandora] ExpireOldTracks::init: Starting delayed timer after next interval Jan 21 13:37:28 volumio volumio[993]: info: [1737488248911] ControllerPandora::PandoraHandler::fillStationData Jan 21 13:37:29 volumio wpa_supplicant[850]: RRM: Ignoring radio measurement request: Not RRM network Jan 21 13:37:30 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Jan 21 13:37:38 volumio volumio[993]: info: CorePlayQueue::getQueue Jan 21 13:37:38 volumio volumio[993]: info: CorePlayQueue::getTrack 753 Jan 21 13:37:38 volumio volumio[993]: info: [1737488258201] ControllerPandora::ExpireOldTracks::reaper Jan 21 13:37:38 volumio volumio[993]: info: [1737488258201] [Pandora] ExpireOldTracks::reaper: No victims found: Expiring zero tracks. Don't worry -- Jason will return. Jan 21 13:37:40 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Jan 21 13:37:40 volumio volumio[993]: info: Listing playlists Jan 21 13:37:40 volumio volumio[993]: info: Listing playlists Jan 21 13:37:47 volumio wpa_supplicant[850]: RRM: Ignoring radio measurement request: Not RRM network Jan 21 13:37:50 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Jan 21 13:37:54 volumio volumio[993]: info: CorePlayQueue::getTrack 753 Jan 21 13:37:54 volumio volumio[993]: info: CorePlayQueue::getTrack 442 Jan 21 13:37:54 volumio volumio[993]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 21 13:37:54 volumio volumio[993]: TypeError: Cannot read property 'service' of undefined Jan 21 13:37:54 volumio volumio[993]: at CoreStateMachine.increasePlaybackTimer (/volumio/app/statemachine.js:454:107) Jan 21 13:37:54 volumio volumio[993]: at listOnTimeout (internal/timers.js:554:17) Jan 21 13:37:54 volumio volumio[993]: at processTimers (internal/timers.js:497:7) Jan 21 13:37:54 volumio volumio[993]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 21 13:37:54 volumio sudo[4828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-21 13:36 Jan 21 13:37:54 volumio sudo[4828]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"