-- Logs begin at Sat 2025-05-17 12:08:21 UTC, end at Sat 2025-05-17 12:56:55 UTC. -- May 17 12:55:09 volumio volumio[732]: info: CorePlayQueue::getTrack 0 May 17 12:55:09 volumio volumio[732]: info: CorePlayQueue::getTrack 1 May 17 12:55:09 volumio volumio[732]: info: Prefetching next song May 17 12:55:14 volumio volumio[732]: info: [1747486514575] ControllerPandora::pandoraListener May 17 12:55:14 volumio volumio[732]: info: ControllerMpd::getState May 17 12:55:14 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:55:14 volumio volumio[732]: info: May 17 12:55:14 volumio volumio[732]: ---------------------------- MPD announces state update: player May 17 12:55:14 volumio volumio[732]: info: ControllerMpd::getState May 17 12:55:14 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:55:14 volumio volumio[732]: info: sendMpdCommand status took 4 milliseconds May 17 12:55:14 volumio volumio[732]: info: sendMpdCommand status took 3 milliseconds May 17 12:55:14 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:55:14 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:55:14 volumio volumio[732]: info: CorePlayQueue::getTrack 0 May 17 12:55:14 volumio volumio[732]: info: [1747486514580] ControllerPandora::pushState May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:55:14 volumio volumio[732]: info: CorePlayQueue::getTrack 0 May 17 12:55:14 volumio volumio[732]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null,"trackType":"mp3"} May 17 12:55:14 volumio volumio[732]: verbose: CURRENT POSITION 0 May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::syncState stateService stop May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus play May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::play index undefined May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:55:14 volumio volumio[732]: info: CorePlayQueue::getTrack 1 May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:55:14 volumio volumio[732]: info: ControllerMpd::pushState May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:55:14 volumio volumio[732]: info: CorePlayQueue::getTrack 1 May 17 12:55:14 volumio volumio[732]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd May 17 12:55:14 volumio volumio[732]: info: CorePlayQueue::getTrack 1 May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::startPlaybackTimer May 17 12:55:14 volumio volumio[732]: info: CorePlayQueue::getTrack 1 May 17 12:55:14 volumio volumio[732]: info: [1747486514591] ControllerPandora::clearAddPlayTrack May 17 12:55:14 volumio volumio[732]: info: ControllerMpd::clear May 17 12:55:14 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand clear May 17 12:55:14 volumio volumio[732]: info: ------------------------------ 17ms May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:55:14 volumio volumio[732]: info: May 17 12:55:14 volumio volumio[732]: ---------------------------- MPD announces system playlist update May 17 12:55:14 volumio volumio[732]: info: Ignoring MPD Status Update May 17 12:55:14 volumio volumio[732]: info: sendMpdCommand clear took 6 milliseconds May 17 12:55:14 volumio volumio[732]: info: [1747486514598] ControllerPandora::checkForExpiredStations May 17 12:55:14 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:55:14 volumio volumio[732]: info: [1747486514598] ControllerPandora::removeTrack May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::removeQueueItem May 17 12:55:14 volumio volumio[732]: info: CorePlayQueue::removeQueueItem 0 May 17 12:55:14 volumio volumio[732]: info: CorePlayQueue::saveQueue May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::volumioPushQueue May 17 12:55:14 volumio volumio[732]: info: [1747486514600] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=4498449034229913820trackId=1789509916668937645 at queue index: 0 May 17 12:55:14 volumio volumio[732]: info: ControllerMpd::getState May 17 12:55:14 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:55:14 volumio volumio[732]: info: [1747486514603] ControllerPandora::appendTracksToMpd May 17 12:55:14 volumio volumio[732]: info: [1747486514603] [Pandora] appendTracksToMpd: Added 1 track(s) to mpd May 17 12:55:14 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand addid May 17 12:55:14 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand play May 17 12:55:14 volumio volumio[732]: error: updateQueue error: null May 17 12:55:14 volumio volumio[732]: info: May 17 12:55:14 volumio volumio[732]: ---------------------------- MPD announces system playlist update May 17 12:55:14 volumio volumio[732]: info: Ignoring MPD Status Update May 17 12:55:14 volumio volumio[732]: info: ------------------------------ 13ms May 17 12:55:14 volumio volumio[732]: info: sendMpdCommand status took 8 milliseconds May 17 12:55:14 volumio volumio[732]: info: sendMpdCommand addid took 5 milliseconds May 17 12:55:14 volumio volumio[732]: info: sendMpdCommand play took 6 milliseconds May 17 12:55:14 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:55:14 volumio volumio[732]: info: [1747486514611] ControllerPandora::pushState May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:55:14 volumio volumio[732]: info: CorePlayQueue::getTrack 0 May 17 12:55:14 volumio volumio[732]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null,"trackType":"mp3"} May 17 12:55:14 volumio volumio[732]: verbose: CURRENT POSITION 0 May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::syncState stateService stop May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus stop May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:55:14 volumio volumio[732]: info: No code May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:55:14 volumio volumio[732]: verbose: MPD COMMAND [object Object] May 17 12:55:14 volumio volumio[732]: info: [1747486514616] ControllerPandora::pushState May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:55:14 volumio volumio[732]: info: CorePlayQueue::getTrack 0 May 17 12:55:14 volumio volumio[732]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"Stay With Me","name":"Stay With Me","artist":"Sam Smith","album":"In The Lonely Hour (Drowning Shadows Edition)","albumart":"http://cont-1.p-cdn.us/images/d1/a0/91/2e/4d9044518521da3a73bdb66f/1080W_1080H.jpg","realUri":"http://audio-dc6-t1-1-v4v6.pandora.com/access/6422679386369652951.mp3?version=5&lid=360257756&token=TKs0jLH8kPiHmq1PRE9eoRECEB3NxYZ7JwMf5fnw0%2FZjKDiu4Q1Mzm2fOqjbsTkPalsbwySmDa3jqIm9tJg5yM%2FC7jc9tLHc4lbe6aEalcyAopKuQ0G9vGmX1FVj5r9Z5Ba2D%2BLMl7FTKXbI0YcI7U115spTdKQir9ACKTDc%2BjxB7bEWp5oKwvkwIJg6PC%2BdlMC72HjM7hJyJYzsbLzbU5Vag1U0YbHUKcH35YhX19DmsLJxSw7uZQ039bQhmuT6nk2BJmNzcFMBGUovRtG8p%2Ba3vijhIO7JwXhr75YdXo%2FPusWIejS7kilmODdawe0rpc8H0wDpJhojA7d%2FzVK16w%3D%3D","isStreaming":true,"duration":173,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://audio-dc6-t1-1-v4v6.pandora.com/access/6422679386369652951.mp3?version=5&lid=360257756&token=TKs0jLH8kPiHmq1PRE9eoRECEB3NxYZ7JwMf5fnw0%2FZjKDiu4Q1Mzm2fOqjbsTkPalsbwySmDa3jqIm9tJg5yM%2FC7jc9tLHc4lbe6aEalcyAopKuQ0G9vGmX1FVj5r9Z5Ba2D%2BLMl7FTKXbI0YcI7U115spTdKQir9ACKTDc%2BjxB7bEWp5oKwvkwIJg6PC%2BdlMC72HjM7hJyJYzsbLzbU5Vag1U0YbHUKcH35YhX19DmsLJxSw7uZQ039bQhmuT6nk2BJmNzcFMBGUovRtG8p%2Ba3vijhIO7JwXhr75YdXo%2FPusWIejS7kilmODdawe0rpc8H0wDpJhojA7d%2FzVK16w%3D%3D","seek":0,"status":"play"} May 17 12:55:14 volumio volumio[732]: verbose: CURRENT POSITION 0 May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::syncState stateService play May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus stop May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:55:14 volumio volumio[732]: info: [1747486514620] ControllerPandora::fetchAndAddTracks May 17 12:55:14 volumio volumio[732]: info: [1747486514620] ControllerPandora::getStationTracks May 17 12:55:14 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:55:14 volumio volumio[732]: info: [1747486514621] [Pandora] PandoraHandler::getSongMaxDiff: -1 May 17 12:55:14 volumio volumio[732]: info: [1747486514621] ControllerPandora::fetchAndAddTracks::getSqInfo May 17 12:55:14 volumio volumio[732]: info: [1747486514621] ControllerPandora::getStationTracks May 17 12:55:14 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:55:14 volumio volumio[732]: info: [1747486514630] [Pandora] fetchAndAddTracks: diff1: -1 sQPos1: 0 May 17 12:55:14 volumio volumio[732]: info: [1747486514631] [Pandora] fetchAndAddTracks: Fetching tracks May 17 12:55:14 volumio volumio[732]: info: [1747486514631] ControllerPandora::PandoraHandler::fillStationData May 17 12:55:14 volumio volumio[732]: info: May 17 12:55:14 volumio volumio[732]: ---------------------------- MPD announces system playlist update May 17 12:55:14 volumio volumio[732]: info: Ignoring MPD Status Update May 17 12:55:14 volumio volumio[732]: info: ------------------------------ 34ms May 17 12:55:14 volumio volumio[732]: info: ------------------------------ 3ms May 17 12:55:14 volumio volumio[732]: info: [1747486514689] ControllerPandora::pandoraListener May 17 12:55:14 volumio volumio[732]: info: ControllerMpd::getState May 17 12:55:14 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:55:14 volumio volumio[732]: info: May 17 12:55:14 volumio volumio[732]: ---------------------------- MPD announces state update: player May 17 12:55:14 volumio volumio[732]: info: ControllerMpd::getState May 17 12:55:14 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:55:14 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 17 12:55:14 volumio volumio[732]: info: May 17 12:55:14 volumio volumio[732]: ---------------------------- MPD announces state update: player May 17 12:55:14 volumio volumio[732]: info: sendMpdCommand status took 17 milliseconds May 17 12:55:14 volumio volumio[732]: info: ControllerMpd::getState May 17 12:55:14 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:55:14 volumio volumio[732]: info: sendMpdCommand status took 18 milliseconds May 17 12:55:14 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:55:14 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:55:14 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:55:14 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:55:14 volumio volumio[732]: info: sendMpdCommand status took 5 milliseconds May 17 12:55:14 volumio volumio[732]: info: sendMpdCommand playlistinfo took 3 milliseconds May 17 12:55:14 volumio volumio[732]: info: sendMpdCommand playlistinfo took 3 milliseconds May 17 12:55:14 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:55:14 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:55:14 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:55:14 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:55:14 volumio volumio[732]: info: CorePlayQueue::getTrack 0 May 17 12:55:14 volumio volumio[732]: info: [1747486514720] ControllerPandora::pushState May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:55:14 volumio volumio[732]: info: CorePlayQueue::getTrack 0 May 17 12:55:14 volumio volumio[732]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":173,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Stay With Me","artist":"Sam Smith","album":"In The Lonely Hour (Drowning Shadows Edition)","uri":"http://audio-dc6-t1-1-v4v6.pandora.com/access/6422679386369652951.mp3?version=5&lid=360257756&token=TKs0jLH8kPiHmq1PRE9eoRECEB3NxYZ7JwMf5fnw0%2FZjKDiu4Q1Mzm2fOqjbsTkPalsbwySmDa3jqIm9tJg5yM%2FC7jc9tLHc4lbe6aEalcyAopKuQ0G9vGmX1FVj5r9Z5Ba2D%2BLMl7FTKXbI0YcI7U115spTdKQir9ACKTDc%2BjxB7bEWp5oKwvkwIJg6PC%2BdlMC72HjM7hJyJYzsbLzbU5Vag1U0YbHUKcH35YhX19DmsLJxSw7uZQ039bQhmuT6nk2BJmNzcFMBGUovRtG8p%2Ba3vijhIO7JwXhr75YdXo%2FPusWIejS7kilmODdawe0rpc8H0wDpJhojA7d%2FzVK16w%3D%3D","trackType":"mp3"} May 17 12:55:14 volumio volumio[732]: verbose: CURRENT POSITION 0 May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::syncState stateService play May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus play May 17 12:55:14 volumio volumio[732]: info: Received an update from plugin. extracting info from payload May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:55:14 volumio volumio[732]: info: ControllerMpd::pushState May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:55:14 volumio volumio[732]: info: CorePlayQueue::getTrack 0 May 17 12:55:14 volumio volumio[732]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd May 17 12:55:14 volumio volumio[732]: info: ------------------------------ 37ms May 17 12:55:14 volumio volumio[732]: info: sendMpdCommand playlistinfo took 10 milliseconds May 17 12:55:14 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:55:14 volumio volumio[732]: info: ControllerMpd::pushState May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:55:14 volumio volumio[732]: info: CorePlayQueue::getTrack 0 May 17 12:55:14 volumio volumio[732]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd May 17 12:55:14 volumio volumio[732]: info: ------------------------------ 24ms May 17 12:55:14 volumio volumio[732]: info: CoreCommandRouter::volumioGetQueue May 17 12:55:14 volumio volumio[732]: info: CoreStateMachine::getQueue May 17 12:55:14 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:55:14 volumio volumio[732]: info: [1747486514861] ControllerPandora::PandoraHandler::fetchTracks::fetchStationPlaylist May 17 12:55:15 volumio volumio[732]: info: [1747486515494] [Pandora] PandoraHandler::fetchTracks::fetchStationPlaylist: Retrieved Today's Hits Radio playlist May 17 12:55:15 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:55:15 volumio volumio[732]: info: [1747486515494] ControllerPandora::PandoraHandler::fetchTracks::fillNewTracks May 17 12:55:15 volumio volumio[732]: info: [1747486515495] [Pandora] PandoraHandler::fetchTracks::fillNewTracks: Fetched 4 track(s) May 17 12:55:15 volumio volumio[732]: info: CorePlayQueue::addQueueItems May 17 12:55:15 volumio volumio[732]: info: Preload queue cleared May 17 12:55:15 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=7660779752648894819 May 17 12:55:15 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=7660779752648894819 in service pandora May 17 12:55:15 volumio volumio[732]: info: [1747486515495] ControllerPandora::explodeUri May 17 12:55:15 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:55:15 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=2965495703883065134 May 17 12:55:15 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=2965495703883065134 in service pandora May 17 12:55:15 volumio volumio[732]: info: [1747486515496] ControllerPandora::explodeUri May 17 12:55:15 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:55:15 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=1868208829693674667 May 17 12:55:15 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=1868208829693674667 in service pandora May 17 12:55:15 volumio volumio[732]: info: [1747486515496] ControllerPandora::explodeUri May 17 12:55:15 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:55:15 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=307661930620090685 May 17 12:55:15 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=307661930620090685 in service pandora May 17 12:55:15 volumio volumio[732]: info: [1747486515496] ControllerPandora::explodeUri May 17 12:55:15 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:55:15 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:55:15 volumio volumio[732]: info: CoreCommandRouter::volumioPushQueue May 17 12:55:15 volumio volumio[732]: info: CorePlayQueue::saveQueue May 17 12:55:15 volumio volumio[732]: info: CoreStateMachine::updateTrackBlock May 17 12:55:15 volumio volumio[732]: info: CorePlayQueue::getTrackBlock May 17 12:55:15 volumio volumio[732]: info: [1747486515504] ControllerPandora::getStationTracks May 17 12:55:15 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:55:15 volumio volumio[732]: info: [1747486515504] [Pandora] PandoraHandler::getSongMaxDiff: 3 May 17 12:55:15 volumio volumio[732]: info: [1747486515504] ControllerPandora::fetchAndAddTracks::getSqInfo May 17 12:55:15 volumio volumio[732]: info: [1747486515504] ControllerPandora::getStationTracks May 17 12:55:15 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:55:15 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:55:15 volumio volumio[732]: info: [1747486515504] [Pandora] fetchAndAddTracks: diff2 > 0: 3 sQPos2: 0 May 17 12:55:15 volumio volumio[732]: info: [1747486515505] ControllerPandora::removeOldTrackBlock May 17 12:55:15 volumio volumio[732]: info: [1747486515505] ControllerPandora::removeStationTracks May 17 12:55:15 volumio volumio[732]: info: [1747486515505] ControllerPandora::getStationTracks May 17 12:55:15 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:55:15 volumio volumio[732]: info: [1747486515505] [Pandora] removeStationTracks: Removing 11 tracks from queue May 17 12:55:20 volumio volumio[732]: error: error May 17 12:55:25 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:55:25 volumio volumio[732]: info: [1747486525509] ControllerPandora::removeTrack May 17 12:55:25 volumio volumio[732]: info: [1747486525509] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=4498449034229913820trackId=6422679386369652951 at queue index: 0 May 17 12:55:35 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:55:35 volumio volumio[732]: info: [1747486535506] ControllerPandora::removeTrack May 17 12:55:35 volumio volumio[732]: info: [1747486535506] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=4498449034229913820trackId=193726920087947402 at queue index: 1 May 17 12:55:45 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:55:45 volumio volumio[732]: info: [1747486545506] ControllerPandora::removeTrack May 17 12:55:45 volumio volumio[732]: info: [1747486545506] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=4498449034229913820trackId=1787112181257610977 at queue index: 2 May 17 12:55:55 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:55:55 volumio volumio[732]: info: [1747486555506] ControllerPandora::removeTrack May 17 12:55:55 volumio volumio[732]: info: [1747486555506] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=4498449034229913820trackId=5911033918560108367 at queue index: 3 May 17 12:56:05 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:05 volumio volumio[732]: info: [1747486565506] ControllerPandora::removeTrack May 17 12:56:05 volumio volumio[732]: info: [1747486565506] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=4498449034229913820trackId=2647941763438586053 at queue index: 4 May 17 12:56:10 volumio volumio[732]: error: error May 17 12:56:15 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:15 volumio volumio[732]: info: [1747486575506] ControllerPandora::removeTrack May 17 12:56:15 volumio volumio[732]: info: [1747486575506] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=4498449034229913820trackId=2586380582415094618 at queue index: 5 May 17 12:56:18 volumio volumio[732]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.6 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Total Clients: 4 May 17 12:56:18 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 17 12:56:18 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 17 12:56:18 volumio volumio[732]: info: CoreCommandRouter::volumioGetVisibleSources May 17 12:56:18 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 17 12:56:18 volumio volumio[732]: info: CoreCommandRouter::volumioGetState May 17 12:56:18 volumio volumio[732]: info: CoreCommandRouter::volumioGetState May 17 12:56:18 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 17 12:56:18 volumio volumio[732]: info: Received Get System Info May 17 12:56:18 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 17 12:56:18 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 17 12:56:18 volumio volumio[732]: info: Discovery: Getting this device information May 17 12:56:18 volumio volumio[732]: info: CoreCommandRouter::volumioGetState May 17 12:56:18 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 17 12:56:18 volumio volumio[732]: info: CoreCommandRouter::volumioGetState May 17 12:56:18 volumio volumio[732]: info: Listing playlists May 17 12:56:18 volumio volumio[732]: info: CoreCommandRouter::volumioGetQueue May 17 12:56:18 volumio volumio[732]: info: CoreStateMachine::getQueue May 17 12:56:18 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:18 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 17 12:56:23 volumio volumio[732]: info: CoreCommandRouter::volumioPlay May 17 12:56:23 volumio volumio[732]: info: CoreStateMachine::play index 10 May 17 12:56:23 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 12:56:23 volumio volumio[732]: info: CoreStateMachine::stop May 17 12:56:23 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 12:56:23 volumio volumio[732]: info: CoreStateMachine::stPlaybackTimer May 17 12:56:23 volumio volumio[732]: info: CoreStateMachine::updateTrackBlock May 17 12:56:23 volumio volumio[732]: info: CorePlayQueue::getTrackBlock May 17 12:56:23 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:23 volumio volumio[732]: info: CorePlayQueue::getTrack 0 May 17 12:56:23 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:23 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:23 volumio volumio[732]: info: CoreStateMachine::serviceStop May 17 12:56:23 volumio volumio[732]: info: CorePlayQueue::getTrack 0 May 17 12:56:23 volumio volumio[732]: info: CoreCommandRouter::serviceStop May 17 12:56:23 volumio volumio[732]: info: [1747486583929] ControllerPandora::stop May 17 12:56:23 volumio volumio[732]: info: ControllerMpd::stop May 17 12:56:23 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand stop May 17 12:56:23 volumio volumio[732]: info: May 17 12:56:23 volumio volumio[732]: ---------------------------- MPD announces state update: player May 17 12:56:23 volumio volumio[732]: info: sendMpdCommand stop took 31 milliseconds May 17 12:56:23 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:23 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:23 volumio volumio[732]: info: [1747486583963] ControllerPandora::pushState May 17 12:56:23 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:23 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:23 volumio volumio[732]: info: CorePlayQueue::getTrack 0 May 17 12:56:23 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:23 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:23 volumio volumio[732]: info: CorePlayQueue::getTrack 0 May 17 12:56:23 volumio volumio[732]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"Stay With Me","name":"Stay With Me","artist":"Sam Smith","album":"In The Lonely Hour (Drowning Shadows Edition)","albumart":"http://cont-1.p-cdn.us/images/d1/a0/91/2e/4d9044518521da3a73bdb66f/1080W_1080H.jpg","realUri":"http://audio-dc6-t1-1-v4v6.pandora.com/access/6422679386369652951.mp3?version=5&lid=360257756&token=TKs0jLH8kPiHmq1PRE9eoRECEB3NxYZ7JwMf5fnw0%2FZjKDiu4Q1Mzm2fOqjbsTkPalsbwySmDa3jqIm9tJg5yM%2FC7jc9tLHc4lbe6aEalcyAopKuQ0G9vGmX1FVj5r9Z5Ba2D%2BLMl7FTKXbI0YcI7U115spTdKQir9ACKTDc%2BjxB7bEWp5oKwvkwIJg6PC%2BdlMC72HjM7hJyJYzsbLzbU5Vag1U0YbHUKcH35YhX19DmsLJxSw7uZQ039bQhmuT6nk2BJmNzcFMBGUovRtG8p%2Ba3vijhIO7JwXhr75YdXo%2FPusWIejS7kilmODdawe0rpc8H0wDpJhojA7d%2FzVK16w%3D%3D","isStreaming":true,"duration":173,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://audio-dc6-t1-1-v4v6.pandora.com/access/6422679386369652951.mp3?version=5&lid=360257756&token=TKs0jLH8kPiHmq1PRE9eoRECEB3NxYZ7JwMf5fnw0%2FZjKDiu4Q1Mzm2fOqjbsTkPalsbwySmDa3jqIm9tJg5yM%2FC7jc9tLHc4lbe6aEalcyAopKuQ0G9vGmX1FVj5r9Z5Ba2D%2BLMl7FTKXbI0YcI7U115spTdKQir9ACKTDc%2BjxB7bEWp5oKwvkwIJg6PC%2BdlMC72HjM7hJyJYzsbLzbU5Vag1U0YbHUKcH35YhX19DmsLJxSw7uZQ039bQhmuT6nk2BJmNzcFMBGUovRtG8p%2Ba3vijhIO7JwXhr75YdXo%2FPusWIejS7kilmODdawe0rpc8H0wDpJhojA7d%2FzVK16w%3D%3D","seek":0,"status":"stop"} May 17 12:56:23 volumio volumio[732]: verbose: CURRENT POSITION 0 May 17 12:56:23 volumio volumio[732]: info: CoreStateMachine::syncState stateService stop May 17 12:56:23 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus stop May 17 12:56:23 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:23 volumio volumio[732]: info: CorePlayQueue::getTrack 0 May 17 12:56:23 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:23 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:23 volumio volumio[732]: info: No code May 17 12:56:23 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:23 volumio volumio[732]: info: CorePlayQueue::getTrack 0 May 17 12:56:23 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:23 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:56:23 volumio volumio[732]: info: CoreStateMachine::play index undefined May 17 12:56:23 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 12:56:23 volumio volumio[732]: info: CorePlayQueue::getTrack 10 May 17 12:56:23 volumio volumio[732]: info: CoreStateMachine::startPlaybackTimer May 17 12:56:23 volumio volumio[732]: info: CorePlayQueue::getTrack 10 May 17 12:56:23 volumio volumio[732]: info: [1747486583981] ControllerPandora::clearAddPlayTrack May 17 12:56:23 volumio volumio[732]: info: ControllerMpd::clear May 17 12:56:23 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand clear May 17 12:56:23 volumio volumio[732]: info: May 17 12:56:23 volumio volumio[732]: ---------------------------- MPD announces system playlist update May 17 12:56:23 volumio volumio[732]: info: Ignoring MPD Status Update May 17 12:56:23 volumio volumio[732]: info: sendMpdCommand status took 22 milliseconds May 17 12:56:23 volumio volumio[732]: info: sendMpdCommand clear took 3 milliseconds May 17 12:56:23 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:23 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:23 volumio volumio[732]: info: [1747486583987] ControllerPandora::checkForExpiredStations May 17 12:56:23 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:23 volumio volumio[732]: info: [1747486583989] ControllerPandora::removeTrack May 17 12:56:23 volumio volumio[732]: info: [1747486583989] [Pandora] removeTrack: Not removing track with uri: null at queue index: -1 May 17 12:56:23 volumio volumio[732]: info: [1747486583990] ControllerPandora::appendTracksToMpd May 17 12:56:23 volumio volumio[732]: info: [1747486583990] [Pandora] appendTracksToMpd: Added 1 track(s) to mpd May 17 12:56:23 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand addid May 17 12:56:23 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand play May 17 12:56:24 volumio volumio[732]: error: updateQueue error: null May 17 12:56:24 volumio volumio[732]: info: May 17 12:56:24 volumio volumio[732]: ---------------------------- MPD announces system playlist update May 17 12:56:24 volumio volumio[732]: info: Ignoring MPD Status Update May 17 12:56:24 volumio volumio[732]: info: ------------------------------ 19ms May 17 12:56:24 volumio volumio[732]: info: sendMpdCommand playlistinfo took 15 milliseconds May 17 12:56:24 volumio volumio[732]: info: sendMpdCommand addid took 12 milliseconds May 17 12:56:24 volumio volumio[732]: info: sendMpdCommand play took 11 milliseconds May 17 12:56:24 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:56:24 volumio volumio[732]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 17 12:56:24 volumio volumio[732]: verbose: MPD COMMAND [object Object] May 17 12:56:24 volumio volumio[732]: verbose: MPD COMMAND [object Object] May 17 12:56:24 volumio volumio[732]: verbose: MPD COMMAND [object Object] May 17 12:56:24 volumio volumio[732]: info: [1747486584009] ControllerPandora::pushState May 17 12:56:24 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::getTrack 10 May 17 12:56:24 volumio volumio[732]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"Here","name":"Here","artist":"Alessia Cara","album":"Know-It-All (Deluxe)","albumart":"http://cont-5.p-cdn.us/images/b1/c6/0b/87/e2a94543bcd2298d07d290e5/1080W_1080H.jpg","realUri":"http://t1-2.p-cdn.us/access/307661930620090685.mp3?version=5&lid=360257756&token=4nNHcozGpyb593A4iFlN3PtiiNovTXvoNB8MvZqtAuJYpiE9zAIRKEbbPF5JxZlbLL730Z6Qces3lM1zRM5WEZxoGwHw2y5AcdnEdAAkaVwUwPkgd4FbMQqzGFLZgO7TuNwRiGVT9zWGVTZ%2BiB8YuqTYODLc0ze%2BzPIQSaR9hOdeVB7hSulQVnP6bTjQwRmV8qDY092OtakWIk0zA0xPyWt1%2BGu%2BdxmxZvZ1WhRBrIA7ee6azJ0aLD6bgBBImTsW8AzfBqMCJ9yHAL6DgAgnjeCd0lURBEZHIqpsJB%2FYTcGZjebqZC3Ni8Jn9r3wZOgHAupaVdppV0ESs0LVI35LxQ%3D%3D","isStreaming":true,"duration":199,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t1-2.p-cdn.us/access/307661930620090685.mp3?version=5&lid=360257756&token=4nNHcozGpyb593A4iFlN3PtiiNovTXvoNB8MvZqtAuJYpiE9zAIRKEbbPF5JxZlbLL730Z6Qces3lM1zRM5WEZxoGwHw2y5AcdnEdAAkaVwUwPkgd4FbMQqzGFLZgO7TuNwRiGVT9zWGVTZ%2BiB8YuqTYODLc0ze%2BzPIQSaR9hOdeVB7hSulQVnP6bTjQwRmV8qDY092OtakWIk0zA0xPyWt1%2BGu%2BdxmxZvZ1WhRBrIA7ee6azJ0aLD6bgBBImTsW8AzfBqMCJ9yHAL6DgAgnjeCd0lURBEZHIqpsJB%2FYTcGZjebqZC3Ni8Jn9r3wZOgHAupaVdppV0ESs0LVI35LxQ%3D%3D","seek":0,"status":"play"} May 17 12:56:24 volumio volumio[732]: verbose: CURRENT POSITION 10 May 17 12:56:24 volumio volumio[732]: info: CoreStateMachine::syncState stateService play May 17 12:56:24 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus stop May 17 12:56:24 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:56:24 volumio volumio[732]: info: ------------------------------ 52ms May 17 12:56:24 volumio volumio[732]: info: [1747486584012] ControllerPandora::fetchAndAddTracks May 17 12:56:24 volumio volumio[732]: info: [1747486584012] ControllerPandora::getStationTracks May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:24 volumio volumio[732]: info: [1747486584013] [Pandora] PandoraHandler::getSongMaxDiff: 3 May 17 12:56:24 volumio volumio[732]: info: [1747486584014] ControllerPandora::fetchAndAddTracks::getSqInfo May 17 12:56:24 volumio volumio[732]: info: [1747486584014] ControllerPandora::getStationTracks May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:24 volumio volumio[732]: info: [1747486584015] [Pandora] fetchAndAddTracks: diff1: 3 sQPos1: 10 May 17 12:56:24 volumio volumio[732]: info: [1747486584016] [Pandora] fetchAndAddTracks: Fetching tracks May 17 12:56:24 volumio volumio[732]: info: [1747486584016] ControllerPandora::PandoraHandler::fillStationData May 17 12:56:24 volumio volumio[732]: info: May 17 12:56:24 volumio volumio[732]: ---------------------------- MPD announces system playlist update May 17 12:56:24 volumio volumio[732]: info: Ignoring MPD Status Update May 17 12:56:24 volumio volumio[732]: info: ------------------------------ 25ms May 17 12:56:24 volumio volumio[732]: info: ------------------------------ 3ms May 17 12:56:24 volumio volumio[732]: info: [1747486584200] ControllerPandora::PandoraHandler::fetchTracks::fetchStationPlaylist May 17 12:56:24 volumio volumio[732]: info: [1747486584208] ControllerPandora::pandoraListener May 17 12:56:24 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:24 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:24 volumio volumio[732]: info: May 17 12:56:24 volumio volumio[732]: ---------------------------- MPD announces state update: player May 17 12:56:24 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:24 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:24 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 17 12:56:24 volumio volumio[732]: info: May 17 12:56:24 volumio volumio[732]: ---------------------------- MPD announces state update: player May 17 12:56:24 volumio volumio[732]: info: sendMpdCommand status took 18 milliseconds May 17 12:56:24 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:24 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:24 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:24 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:24 volumio volumio[732]: info: sendMpdCommand status took 19 milliseconds May 17 12:56:24 volumio volumio[732]: info: sendMpdCommand status took 2 milliseconds May 17 12:56:24 volumio volumio[732]: info: sendMpdCommand playlistinfo took 1 milliseconds May 17 12:56:24 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:24 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:24 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:24 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:24 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::getTrack 10 May 17 12:56:24 volumio volumio[732]: info: [1747486584230] ControllerPandora::pushState May 17 12:56:24 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::getTrack 10 May 17 12:56:24 volumio volumio[732]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":199,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Here","artist":"Alessia Cara","album":"Know-It-All (Deluxe)","uri":"http://t1-2.p-cdn.us/access/307661930620090685.mp3?version=5&lid=360257756&token=4nNHcozGpyb593A4iFlN3PtiiNovTXvoNB8MvZqtAuJYpiE9zAIRKEbbPF5JxZlbLL730Z6Qces3lM1zRM5WEZxoGwHw2y5AcdnEdAAkaVwUwPkgd4FbMQqzGFLZgO7TuNwRiGVT9zWGVTZ%2BiB8YuqTYODLc0ze%2BzPIQSaR9hOdeVB7hSulQVnP6bTjQwRmV8qDY092OtakWIk0zA0xPyWt1%2BGu%2BdxmxZvZ1WhRBrIA7ee6azJ0aLD6bgBBImTsW8AzfBqMCJ9yHAL6DgAgnjeCd0lURBEZHIqpsJB%2FYTcGZjebqZC3Ni8Jn9r3wZOgHAupaVdppV0ESs0LVI35LxQ%3D%3D","trackType":"mp3"} May 17 12:56:24 volumio volumio[732]: verbose: CURRENT POSITION 10 May 17 12:56:24 volumio volumio[732]: info: CoreStateMachine::syncState stateService play May 17 12:56:24 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus play May 17 12:56:24 volumio volumio[732]: info: Received an update from plugin. extracting info from payload May 17 12:56:24 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:24 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:24 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:24 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:24 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:24 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:56:24 volumio volumio[732]: info: sendMpdCommand playlistinfo took 9 milliseconds May 17 12:56:24 volumio volumio[732]: info: sendMpdCommand playlistinfo took 9 milliseconds May 17 12:56:24 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:56:24 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:56:24 volumio volumio[732]: info: ControllerMpd::pushState May 17 12:56:24 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::getTrack 10 May 17 12:56:24 volumio volumio[732]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd May 17 12:56:24 volumio volumio[732]: info: ControllerMpd::pushState May 17 12:56:24 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::getTrack 10 May 17 12:56:24 volumio volumio[732]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd May 17 12:56:24 volumio volumio[732]: info: ------------------------------ 32ms May 17 12:56:24 volumio volumio[732]: info: ------------------------------ 19ms May 17 12:56:24 volumio volumio[732]: info: CoreCommandRouter::volumioGetQueue May 17 12:56:24 volumio volumio[732]: info: CoreStateMachine::getQueue May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:24 volumio volumio[732]: info: [1747486584529] [Pandora] PandoraHandler::fetchTracks::fetchStationPlaylist: Retrieved Today's Hits Radio playlist May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:24 volumio volumio[732]: info: [1747486584530] ControllerPandora::PandoraHandler::fetchTracks::fillNewTracks May 17 12:56:24 volumio volumio[732]: info: [1747486584531] [Pandora] PandoraHandler::fetchTracks::fillNewTracks: Fetched 4 track(s) May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::addQueueItems May 17 12:56:24 volumio volumio[732]: info: Preload queue cleared May 17 12:56:24 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=2859944741448406985 May 17 12:56:24 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=2859944741448406985 in service pandora May 17 12:56:24 volumio volumio[732]: info: [1747486584534] ControllerPandora::explodeUri May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:24 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=1784380989348473482 May 17 12:56:24 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=1784380989348473482 in service pandora May 17 12:56:24 volumio volumio[732]: info: [1747486584535] ControllerPandora::explodeUri May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:24 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=7820066280397637179 May 17 12:56:24 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=7820066280397637179 in service pandora May 17 12:56:24 volumio volumio[732]: info: [1747486584537] ControllerPandora::explodeUri May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:24 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=778459084161429859 May 17 12:56:24 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=778459084161429859 in service pandora May 17 12:56:24 volumio volumio[732]: info: [1747486584538] ControllerPandora::explodeUri May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:24 volumio volumio[732]: info: CoreCommandRouter::volumioPushQueue May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::saveQueue May 17 12:56:24 volumio volumio[732]: info: CoreStateMachine::updateTrackBlock May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::getTrackBlock May 17 12:56:24 volumio volumio[732]: info: [1747486584554] ControllerPandora::getStationTracks May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:24 volumio volumio[732]: info: [1747486584554] [Pandora] PandoraHandler::getSongMaxDiff: 7 May 17 12:56:24 volumio volumio[732]: info: [1747486584554] ControllerPandora::fetchAndAddTracks::getSqInfo May 17 12:56:24 volumio volumio[732]: info: [1747486584554] ControllerPandora::getStationTracks May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:24 volumio volumio[732]: info: [1747486584555] [Pandora] fetchAndAddTracks: diff2 > 0: 7 sQPos2: 10 May 17 12:56:24 volumio volumio[732]: info: [1747486584555] ControllerPandora::removeOldTrackBlock May 17 12:56:24 volumio volumio[732]: info: [1747486584555] ControllerPandora::removeStationTracks May 17 12:56:24 volumio volumio[732]: info: [1747486584555] ControllerPandora::getStationTracks May 17 12:56:24 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:24 volumio volumio[732]: info: [1747486584556] [Pandora] removeStationTracks: Removing 7 tracks from queue May 17 12:56:25 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:25 volumio volumio[732]: info: [1747486585508] ControllerPandora::removeTrack May 17 12:56:25 volumio volumio[732]: info: CoreStateMachine::removeQueueItem May 17 12:56:25 volumio volumio[732]: info: CorePlayQueue::removeQueueItem 6 May 17 12:56:25 volumio volumio[732]: info: CorePlayQueue::saveQueue May 17 12:56:25 volumio volumio[732]: info: CoreCommandRouter::volumioPushQueue May 17 12:56:25 volumio volumio[732]: info: [1747486585513] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=4498449034229913820trackId=3790119154033127499 at queue index: 6 May 17 12:56:25 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:25 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:25 volumio volumio[732]: info: sendMpdCommand status took 7 milliseconds May 17 12:56:25 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:25 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:25 volumio volumio[732]: info: sendMpdCommand playlistinfo took 2 milliseconds May 17 12:56:25 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:56:25 volumio volumio[732]: info: [1747486585524] ControllerPandora::pushState May 17 12:56:25 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:25 volumio volumio[732]: info: CorePlayQueue::getTrack 9 May 17 12:56:25 volumio volumio[732]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1805,"duration":199,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Here","artist":"Alessia Cara","album":"Know-It-All (Deluxe)","uri":"http://t1-2.p-cdn.us/access/307661930620090685.mp3?version=5&lid=360257756&token=4nNHcozGpyb593A4iFlN3PtiiNovTXvoNB8MvZqtAuJYpiE9zAIRKEbbPF5JxZlbLL730Z6Qces3lM1zRM5WEZxoGwHw2y5AcdnEdAAkaVwUwPkgd4FbMQqzGFLZgO7TuNwRiGVT9zWGVTZ%2BiB8YuqTYODLc0ze%2BzPIQSaR9hOdeVB7hSulQVnP6bTjQwRmV8qDY092OtakWIk0zA0xPyWt1%2BGu%2BdxmxZvZ1WhRBrIA7ee6azJ0aLD6bgBBImTsW8AzfBqMCJ9yHAL6DgAgnjeCd0lURBEZHIqpsJB%2FYTcGZjebqZC3Ni8Jn9r3wZOgHAupaVdppV0ESs0LVI35LxQ%3D%3D","trackType":"mp3"} May 17 12:56:25 volumio volumio[732]: verbose: CURRENT POSITION 9 May 17 12:56:25 volumio volumio[732]: info: CoreStateMachine::syncState stateService play May 17 12:56:25 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus play May 17 12:56:25 volumio volumio[732]: info: Received an update from plugin. extracting info from payload May 17 12:56:25 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:25 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:25 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:25 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:25 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:25 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:56:28 volumio volumio[732]: info: CoreCommandRouter::volumioPlay May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::play index 13 May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::stop May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::stPlaybackTimer May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::updateTrackBlock May 17 12:56:28 volumio volumio[732]: info: CorePlayQueue::getTrackBlock May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:28 volumio volumio[732]: info: CorePlayQueue::getTrack 9 May 17 12:56:28 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:28 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::serviceStop May 17 12:56:28 volumio volumio[732]: info: CorePlayQueue::getTrack 9 May 17 12:56:28 volumio volumio[732]: info: CoreCommandRouter::serviceStop May 17 12:56:28 volumio volumio[732]: info: [1747486588274] ControllerPandora::stop May 17 12:56:28 volumio volumio[732]: info: ControllerMpd::stop May 17 12:56:28 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand stop May 17 12:56:28 volumio volumio[732]: info: sendMpdCommand stop took 79 milliseconds May 17 12:56:28 volumio volumio[732]: info: [1747486588353] ControllerPandora::pushState May 17 12:56:28 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:28 volumio volumio[732]: info: CorePlayQueue::getTrack 9 May 17 12:56:28 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:28 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:28 volumio volumio[732]: info: CorePlayQueue::getTrack 9 May 17 12:56:28 volumio volumio[732]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"Here","name":"Here","artist":"Alessia Cara","album":"Know-It-All (Deluxe)","albumart":"http://cont-5.p-cdn.us/images/b1/c6/0b/87/e2a94543bcd2298d07d290e5/1080W_1080H.jpg","realUri":"http://t1-2.p-cdn.us/access/307661930620090685.mp3?version=5&lid=360257756&token=4nNHcozGpyb593A4iFlN3PtiiNovTXvoNB8MvZqtAuJYpiE9zAIRKEbbPF5JxZlbLL730Z6Qces3lM1zRM5WEZxoGwHw2y5AcdnEdAAkaVwUwPkgd4FbMQqzGFLZgO7TuNwRiGVT9zWGVTZ%2BiB8YuqTYODLc0ze%2BzPIQSaR9hOdeVB7hSulQVnP6bTjQwRmV8qDY092OtakWIk0zA0xPyWt1%2BGu%2BdxmxZvZ1WhRBrIA7ee6azJ0aLD6bgBBImTsW8AzfBqMCJ9yHAL6DgAgnjeCd0lURBEZHIqpsJB%2FYTcGZjebqZC3Ni8Jn9r3wZOgHAupaVdppV0ESs0LVI35LxQ%3D%3D","isStreaming":true,"duration":199,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t1-2.p-cdn.us/access/307661930620090685.mp3?version=5&lid=360257756&token=4nNHcozGpyb593A4iFlN3PtiiNovTXvoNB8MvZqtAuJYpiE9zAIRKEbbPF5JxZlbLL730Z6Qces3lM1zRM5WEZxoGwHw2y5AcdnEdAAkaVwUwPkgd4FbMQqzGFLZgO7TuNwRiGVT9zWGVTZ%2BiB8YuqTYODLc0ze%2BzPIQSaR9hOdeVB7hSulQVnP6bTjQwRmV8qDY092OtakWIk0zA0xPyWt1%2BGu%2BdxmxZvZ1WhRBrIA7ee6azJ0aLD6bgBBImTsW8AzfBqMCJ9yHAL6DgAgnjeCd0lURBEZHIqpsJB%2FYTcGZjebqZC3Ni8Jn9r3wZOgHAupaVdppV0ESs0LVI35LxQ%3D%3D","seek":0,"status":"stop"} May 17 12:56:28 volumio volumio[732]: verbose: CURRENT POSITION 9 May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::syncState stateService stop May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus stop May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:28 volumio volumio[732]: info: CorePlayQueue::getTrack 9 May 17 12:56:28 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:28 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:28 volumio volumio[732]: info: No code May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:28 volumio volumio[732]: info: CorePlayQueue::getTrack 9 May 17 12:56:28 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::play index undefined May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 12:56:28 volumio volumio[732]: info: CorePlayQueue::getTrack 13 May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::startPlaybackTimer May 17 12:56:28 volumio volumio[732]: info: CorePlayQueue::getTrack 13 May 17 12:56:28 volumio volumio[732]: info: [1747486588363] ControllerPandora::clearAddPlayTrack May 17 12:56:28 volumio volumio[732]: info: ControllerMpd::clear May 17 12:56:28 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand clear May 17 12:56:28 volumio volumio[732]: info: May 17 12:56:28 volumio volumio[732]: ---------------------------- MPD announces state update: player May 17 12:56:28 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:28 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:28 volumio volumio[732]: info: May 17 12:56:28 volumio volumio[732]: ---------------------------- MPD announces system playlist update May 17 12:56:28 volumio volumio[732]: info: Ignoring MPD Status Update May 17 12:56:28 volumio volumio[732]: info: sendMpdCommand clear took 8 milliseconds May 17 12:56:28 volumio volumio[732]: info: sendMpdCommand status took 7 milliseconds May 17 12:56:28 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:28 volumio volumio[732]: info: [1747486588371] ControllerPandora::checkForExpiredStations May 17 12:56:28 volumio volumio[732]: info: ControllerMpd::pushState May 17 12:56:28 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:28 volumio volumio[732]: info: CorePlayQueue::getTrack 13 May 17 12:56:28 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:28 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:28 volumio volumio[732]: info: CorePlayQueue::getTrack 13 May 17 12:56:28 volumio volumio[732]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current pandora Received mpd May 17 12:56:28 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:28 volumio volumio[732]: info: [1747486588374] ControllerPandora::removeTrack May 17 12:56:28 volumio volumio[732]: info: [1747486588374] [Pandora] removeTrack: Not removing track with uri: null at queue index: -1 May 17 12:56:28 volumio volumio[732]: info: ------------------------------ 13ms May 17 12:56:28 volumio volumio[732]: info: [1747486588376] ControllerPandora::appendTracksToMpd May 17 12:56:28 volumio volumio[732]: info: [1747486588377] [Pandora] appendTracksToMpd: Added 1 track(s) to mpd May 17 12:56:28 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand addid May 17 12:56:28 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand play May 17 12:56:28 volumio volumio[732]: error: updateQueue error: null May 17 12:56:28 volumio volumio[732]: info: May 17 12:56:28 volumio volumio[732]: ---------------------------- MPD announces system playlist update May 17 12:56:28 volumio volumio[732]: info: Ignoring MPD Status Update May 17 12:56:28 volumio volumio[732]: info: ------------------------------ 9ms May 17 12:56:28 volumio volumio[732]: info: sendMpdCommand addid took 2 milliseconds May 17 12:56:28 volumio volumio[732]: info: sendMpdCommand play took 3 milliseconds May 17 12:56:28 volumio volumio[732]: verbose: MPD COMMAND [object Object] May 17 12:56:28 volumio volumio[732]: verbose: MPD COMMAND [object Object] May 17 12:56:28 volumio volumio[732]: info: [1747486588381] ControllerPandora::pushState May 17 12:56:28 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:28 volumio volumio[732]: info: CorePlayQueue::getTrack 13 May 17 12:56:28 volumio volumio[732]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"Outta My Head","name":"Outta My Head","artist":"Khalid","album":"Free Spirit","albumart":"http://mediaserver-cont-sv5-1-v4v6.pandora.com/images/2b/a7/08/73/a9f04dc4a53e0192f52b1d23/1080W_1080H.jpg","realUri":"http://t1-3.p-cdn.us/access/778459084161429859.mp3?version=5&lid=360257756&token=Qp94ty7Lj4y253XgtXjrfBXbc%2FmBxOi8%2Bvc5gWxsN1SfoRCIPpqL0CA1SEX1f9BoSNiUMHnNNi78JnRfYtBhWuRawC%2FWeY%2FrDqN%2BQLdDjiPCCEfWABsqCObCBAGlLJn2mEryfINA7Wg%2FDZOzRAIrqCQqtd9dxzFjHox6Wb5h%2Focc3T5c925ccevMsyCgwotWwR9VUxGnFGQrobkS2m0OGlgW9pGzfCwikPkASRIDrixcYVRr%2BuO%2FgbaQqzuC%2B2YNmSPA3YctjJGVxJdXGLSjn9nkWs22Hn%2FNp%2BoaikMvgg3hEehYkpdsH1Dz7jZXuN9Fj8B%2FwcySGUOXI%2Fl9UwUdjA%3D%3D","isStreaming":true,"duration":177,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t1-3.p-cdn.us/access/778459084161429859.mp3?version=5&lid=360257756&token=Qp94ty7Lj4y253XgtXjrfBXbc%2FmBxOi8%2Bvc5gWxsN1SfoRCIPpqL0CA1SEX1f9BoSNiUMHnNNi78JnRfYtBhWuRawC%2FWeY%2FrDqN%2BQLdDjiPCCEfWABsqCObCBAGlLJn2mEryfINA7Wg%2FDZOzRAIrqCQqtd9dxzFjHox6Wb5h%2Focc3T5c925ccevMsyCgwotWwR9VUxGnFGQrobkS2m0OGlgW9pGzfCwikPkASRIDrixcYVRr%2BuO%2FgbaQqzuC%2B2YNmSPA3YctjJGVxJdXGLSjn9nkWs22Hn%2FNp%2BoaikMvgg3hEehYkpdsH1Dz7jZXuN9Fj8B%2FwcySGUOXI%2Fl9UwUdjA%3D%3D","seek":0,"status":"play"} May 17 12:56:28 volumio volumio[732]: verbose: CURRENT POSITION 13 May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::syncState stateService play May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus stop May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:56:28 volumio volumio[732]: info: [1747486588381] ControllerPandora::fetchAndAddTracks May 17 12:56:28 volumio volumio[732]: info: [1747486588382] ControllerPandora::getStationTracks May 17 12:56:28 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:28 volumio volumio[732]: info: [1747486588382] [Pandora] PandoraHandler::getSongMaxDiff: 6 May 17 12:56:28 volumio volumio[732]: info: [1747486588382] ControllerPandora::fetchAndAddTracks::getSqInfo May 17 12:56:28 volumio volumio[732]: info: [1747486588382] ControllerPandora::getStationTracks May 17 12:56:28 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:28 volumio volumio[732]: info: [1747486588382] [Pandora] fetchAndAddTracks: diff1: 6 sQPos1: 13 May 17 12:56:28 volumio volumio[732]: info: [1747486588382] [Pandora] fetchAndAddTracks: Fetching tracks May 17 12:56:28 volumio volumio[732]: info: [1747486588383] ControllerPandora::PandoraHandler::fillStationData May 17 12:56:28 volumio volumio[732]: info: May 17 12:56:28 volumio volumio[732]: ---------------------------- MPD announces system playlist update May 17 12:56:28 volumio volumio[732]: info: Ignoring MPD Status Update May 17 12:56:28 volumio volumio[732]: info: ------------------------------ 9ms May 17 12:56:28 volumio volumio[732]: info: ------------------------------ 1ms May 17 12:56:28 volumio volumio[732]: info: [1747486588578] ControllerPandora::PandoraHandler::fetchTracks::fetchStationPlaylist May 17 12:56:28 volumio volumio[732]: info: [1747486588684] ControllerPandora::pandoraListener May 17 12:56:28 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:28 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:28 volumio volumio[732]: info: May 17 12:56:28 volumio volumio[732]: ---------------------------- MPD announces state update: player May 17 12:56:28 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 17 12:56:28 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:28 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:28 volumio volumio[732]: info: May 17 12:56:28 volumio volumio[732]: ---------------------------- MPD announces state update: player May 17 12:56:28 volumio volumio[732]: info: sendMpdCommand status took 18 milliseconds May 17 12:56:28 volumio volumio[732]: info: sendMpdCommand status took 14 milliseconds May 17 12:56:28 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:28 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:28 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:28 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:28 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:28 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:28 volumio volumio[732]: info: sendMpdCommand status took 2 milliseconds May 17 12:56:28 volumio volumio[732]: info: sendMpdCommand playlistinfo took 1 milliseconds May 17 12:56:28 volumio volumio[732]: info: sendMpdCommand playlistinfo took 1 milliseconds May 17 12:56:28 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:28 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:28 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:56:28 volumio volumio[732]: info: CorePlayQueue::getTrack 13 May 17 12:56:28 volumio volumio[732]: info: [1747486588705] ControllerPandora::pushState May 17 12:56:28 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:28 volumio volumio[732]: info: CorePlayQueue::getTrack 13 May 17 12:56:28 volumio volumio[732]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":177,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Outta My Head","artist":"Khalid","album":"Free Spirit","uri":"http://t1-3.p-cdn.us/access/778459084161429859.mp3?version=5&lid=360257756&token=Qp94ty7Lj4y253XgtXjrfBXbc%2FmBxOi8%2Bvc5gWxsN1SfoRCIPpqL0CA1SEX1f9BoSNiUMHnNNi78JnRfYtBhWuRawC%2FWeY%2FrDqN%2BQLdDjiPCCEfWABsqCObCBAGlLJn2mEryfINA7Wg%2FDZOzRAIrqCQqtd9dxzFjHox6Wb5h%2Focc3T5c925ccevMsyCgwotWwR9VUxGnFGQrobkS2m0OGlgW9pGzfCwikPkASRIDrixcYVRr%2BuO%2FgbaQqzuC%2B2YNmSPA3YctjJGVxJdXGLSjn9nkWs22Hn%2FNp%2BoaikMvgg3hEehYkpdsH1Dz7jZXuN9Fj8B%2FwcySGUOXI%2Fl9UwUdjA%3D%3D","trackType":"mp3"} May 17 12:56:28 volumio volumio[732]: verbose: CURRENT POSITION 13 May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::syncState stateService play May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus play May 17 12:56:28 volumio volumio[732]: info: Received an update from plugin. extracting info from payload May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:28 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:28 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:28 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:56:28 volumio volumio[732]: info: ControllerMpd::pushState May 17 12:56:28 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:28 volumio volumio[732]: info: CorePlayQueue::getTrack 13 May 17 12:56:28 volumio volumio[732]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd May 17 12:56:28 volumio volumio[732]: info: ------------------------------ 27ms May 17 12:56:28 volumio volumio[732]: info: sendMpdCommand playlistinfo took 10 milliseconds May 17 12:56:28 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:56:28 volumio volumio[732]: info: ControllerMpd::pushState May 17 12:56:28 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:28 volumio volumio[732]: info: CorePlayQueue::getTrack 13 May 17 12:56:28 volumio volumio[732]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd May 17 12:56:28 volumio volumio[732]: info: ------------------------------ 14ms May 17 12:56:28 volumio volumio[732]: info: CoreCommandRouter::volumioGetQueue May 17 12:56:28 volumio volumio[732]: info: CoreStateMachine::getQueue May 17 12:56:28 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:29 volumio volumio[732]: info: [1747486589080] [Pandora] PandoraHandler::fetchTracks::fetchStationPlaylist: Retrieved Today's Hits Radio playlist May 17 12:56:29 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:29 volumio volumio[732]: info: [1747486589081] ControllerPandora::PandoraHandler::fetchTracks::fillNewTracks May 17 12:56:29 volumio volumio[732]: info: [1747486589081] [Pandora] PandoraHandler::fetchTracks::fillNewTracks: Fetched 4 track(s) May 17 12:56:29 volumio volumio[732]: info: CorePlayQueue::addQueueItems May 17 12:56:29 volumio volumio[732]: info: Preload queue cleared May 17 12:56:29 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=4398909784791215551 May 17 12:56:29 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=4398909784791215551 in service pandora May 17 12:56:29 volumio volumio[732]: info: [1747486589082] ControllerPandora::explodeUri May 17 12:56:29 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:29 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=1273857794034164643 May 17 12:56:29 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=1273857794034164643 in service pandora May 17 12:56:29 volumio volumio[732]: info: [1747486589082] ControllerPandora::explodeUri May 17 12:56:29 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:29 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=6160257656200431288 May 17 12:56:29 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=6160257656200431288 in service pandora May 17 12:56:29 volumio volumio[732]: info: [1747486589082] ControllerPandora::explodeUri May 17 12:56:29 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:29 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=3251130516864062151 May 17 12:56:29 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=3251130516864062151 in service pandora May 17 12:56:29 volumio volumio[732]: info: [1747486589083] ControllerPandora::explodeUri May 17 12:56:29 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:29 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:29 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:29 volumio volumio[732]: info: CoreCommandRouter::volumioPushQueue May 17 12:56:29 volumio volumio[732]: info: CorePlayQueue::saveQueue May 17 12:56:29 volumio volumio[732]: info: CoreStateMachine::updateTrackBlock May 17 12:56:29 volumio volumio[732]: info: CorePlayQueue::getTrackBlock May 17 12:56:29 volumio volumio[732]: info: [1747486589100] ControllerPandora::getStationTracks May 17 12:56:29 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:29 volumio volumio[732]: info: [1747486589100] [Pandora] PandoraHandler::getSongMaxDiff: 10 May 17 12:56:29 volumio volumio[732]: info: [1747486589100] ControllerPandora::fetchAndAddTracks::getSqInfo May 17 12:56:29 volumio volumio[732]: info: [1747486589100] ControllerPandora::getStationTracks May 17 12:56:29 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:29 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:29 volumio volumio[732]: info: [1747486589100] [Pandora] fetchAndAddTracks: diff2 > 0: 10 sQPos2: 13 May 17 12:56:29 volumio volumio[732]: info: [1747486589101] ControllerPandora::removeOldTrackBlock May 17 12:56:29 volumio volumio[732]: info: [1747486589101] ControllerPandora::removeStationTracks May 17 12:56:29 volumio volumio[732]: info: [1747486589101] ControllerPandora::getStationTracks May 17 12:56:29 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:29 volumio volumio[732]: info: [1747486589102] [Pandora] removeStationTracks: Removing 10 tracks from queue May 17 12:56:30 volumio volumio[732]: info: CoreCommandRouter::volumioPlay May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::play index 17 May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::stop May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::stPlaybackTimer May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::updateTrackBlock May 17 12:56:30 volumio volumio[732]: info: CorePlayQueue::getTrackBlock May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:30 volumio volumio[732]: info: CorePlayQueue::getTrack 13 May 17 12:56:30 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:30 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::serviceStop May 17 12:56:30 volumio volumio[732]: info: CorePlayQueue::getTrack 13 May 17 12:56:30 volumio volumio[732]: info: CoreCommandRouter::serviceStop May 17 12:56:30 volumio volumio[732]: info: [1747486590743] ControllerPandora::stop May 17 12:56:30 volumio volumio[732]: info: ControllerMpd::stop May 17 12:56:30 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand stop May 17 12:56:30 volumio volumio[732]: info: May 17 12:56:30 volumio volumio[732]: ---------------------------- MPD announces state update: player May 17 12:56:30 volumio volumio[732]: info: sendMpdCommand stop took 41 milliseconds May 17 12:56:30 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:30 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:30 volumio volumio[732]: info: [1747486590785] ControllerPandora::pushState May 17 12:56:30 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:30 volumio volumio[732]: info: CorePlayQueue::getTrack 13 May 17 12:56:30 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:30 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:30 volumio volumio[732]: info: CorePlayQueue::getTrack 13 May 17 12:56:30 volumio volumio[732]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"Outta My Head","name":"Outta My Head","artist":"Khalid","album":"Free Spirit","albumart":"http://mediaserver-cont-sv5-1-v4v6.pandora.com/images/2b/a7/08/73/a9f04dc4a53e0192f52b1d23/1080W_1080H.jpg","realUri":"http://t1-3.p-cdn.us/access/778459084161429859.mp3?version=5&lid=360257756&token=Qp94ty7Lj4y253XgtXjrfBXbc%2FmBxOi8%2Bvc5gWxsN1SfoRCIPpqL0CA1SEX1f9BoSNiUMHnNNi78JnRfYtBhWuRawC%2FWeY%2FrDqN%2BQLdDjiPCCEfWABsqCObCBAGlLJn2mEryfINA7Wg%2FDZOzRAIrqCQqtd9dxzFjHox6Wb5h%2Focc3T5c925ccevMsyCgwotWwR9VUxGnFGQrobkS2m0OGlgW9pGzfCwikPkASRIDrixcYVRr%2BuO%2FgbaQqzuC%2B2YNmSPA3YctjJGVxJdXGLSjn9nkWs22Hn%2FNp%2BoaikMvgg3hEehYkpdsH1Dz7jZXuN9Fj8B%2FwcySGUOXI%2Fl9UwUdjA%3D%3D","isStreaming":true,"duration":177,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t1-3.p-cdn.us/access/778459084161429859.mp3?version=5&lid=360257756&token=Qp94ty7Lj4y253XgtXjrfBXbc%2FmBxOi8%2Bvc5gWxsN1SfoRCIPpqL0CA1SEX1f9BoSNiUMHnNNi78JnRfYtBhWuRawC%2FWeY%2FrDqN%2BQLdDjiPCCEfWABsqCObCBAGlLJn2mEryfINA7Wg%2FDZOzRAIrqCQqtd9dxzFjHox6Wb5h%2Focc3T5c925ccevMsyCgwotWwR9VUxGnFGQrobkS2m0OGlgW9pGzfCwikPkASRIDrixcYVRr%2BuO%2FgbaQqzuC%2B2YNmSPA3YctjJGVxJdXGLSjn9nkWs22Hn%2FNp%2BoaikMvgg3hEehYkpdsH1Dz7jZXuN9Fj8B%2FwcySGUOXI%2Fl9UwUdjA%3D%3D","seek":0,"status":"stop"} May 17 12:56:30 volumio volumio[732]: verbose: CURRENT POSITION 13 May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::syncState stateService stop May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus stop May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:30 volumio volumio[732]: info: CorePlayQueue::getTrack 13 May 17 12:56:30 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:30 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:30 volumio volumio[732]: info: No code May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:30 volumio volumio[732]: info: CorePlayQueue::getTrack 13 May 17 12:56:30 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::play index undefined May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 12:56:30 volumio volumio[732]: info: CorePlayQueue::getTrack 17 May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::startPlaybackTimer May 17 12:56:30 volumio volumio[732]: info: CorePlayQueue::getTrack 17 May 17 12:56:30 volumio volumio[732]: info: [1747486590795] ControllerPandora::clearAddPlayTrack May 17 12:56:30 volumio volumio[732]: info: ControllerMpd::clear May 17 12:56:30 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand clear May 17 12:56:30 volumio volumio[732]: info: May 17 12:56:30 volumio volumio[732]: ---------------------------- MPD announces system playlist update May 17 12:56:30 volumio volumio[732]: info: Ignoring MPD Status Update May 17 12:56:30 volumio volumio[732]: info: sendMpdCommand status took 13 milliseconds May 17 12:56:30 volumio volumio[732]: info: sendMpdCommand clear took 2 milliseconds May 17 12:56:30 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:30 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:30 volumio volumio[732]: info: [1747486590800] ControllerPandora::checkForExpiredStations May 17 12:56:30 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:30 volumio volumio[732]: info: [1747486590800] ControllerPandora::removeTrack May 17 12:56:30 volumio volumio[732]: info: [1747486590800] [Pandora] removeTrack: Not removing track with uri: null at queue index: -1 May 17 12:56:30 volumio volumio[732]: info: [1747486590800] ControllerPandora::appendTracksToMpd May 17 12:56:30 volumio volumio[732]: info: [1747486590800] [Pandora] appendTracksToMpd: Added 1 track(s) to mpd May 17 12:56:30 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand addid May 17 12:56:30 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand play May 17 12:56:30 volumio volumio[732]: error: updateQueue error: null May 17 12:56:30 volumio volumio[732]: info: May 17 12:56:30 volumio volumio[732]: ---------------------------- MPD announces system playlist update May 17 12:56:30 volumio volumio[732]: info: Ignoring MPD Status Update May 17 12:56:30 volumio volumio[732]: info: ------------------------------ 9ms May 17 12:56:30 volumio volumio[732]: info: sendMpdCommand playlistinfo took 7 milliseconds May 17 12:56:30 volumio volumio[732]: info: sendMpdCommand addid took 5 milliseconds May 17 12:56:30 volumio volumio[732]: info: sendMpdCommand play took 5 milliseconds May 17 12:56:30 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:56:30 volumio volumio[732]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 17 12:56:30 volumio volumio[732]: verbose: MPD COMMAND [object Object] May 17 12:56:30 volumio volumio[732]: info: [1747486590809] ControllerPandora::pushState May 17 12:56:30 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:30 volumio volumio[732]: info: CorePlayQueue::getTrack 17 May 17 12:56:30 volumio volumio[732]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"When I'm Gone","name":"When I'm Gone","artist":"Alesso & Katy Perry","album":"When I'm Gone","albumart":"http://cont-4.p-cdn.us/images/0d/63/f3/03/851d457eb2317d1d82255b7f/1080W_1080H.jpg","realUri":"http://t1-5.p-cdn.us/access/3251130516864062151.mp3?version=5&lid=360257756&token=Vi0E8JJHZQzKcHXaY991aaCpLmw368ebuWM3vmx%2FXiSigMd80Fi09dG4QfPH7p8tLfASC6xN0ZM0x6BOXIqrFNaK%2Frrcbio%2B3B37IKkAK9I%2BbEHAPrYAO1IS4s%2FXJzbTpUa0QzHq16lriUZkNXoTaVSkQn3pcafiqeuhXBCTWDpS9bbgYb4KhGPASL2vrLlGqRE04%2Bx4NwxnqTxMWeJ4jp4tvBoXkb7yoMA1RT77nrbbw44VNktcZsEIPLiHSjUEjq76xOw2mjB%2FFYZiBo8YlJ%2B5IaLJ9rRunnRXbNcR0P5cnWczNcRTH0wwIt1e7U051jaezCMSWGdRzwNFG2K0fQ%3D%3D","isStreaming":true,"duration":161,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t1-5.p-cdn.us/access/3251130516864062151.mp3?version=5&lid=360257756&token=Vi0E8JJHZQzKcHXaY991aaCpLmw368ebuWM3vmx%2FXiSigMd80Fi09dG4QfPH7p8tLfASC6xN0ZM0x6BOXIqrFNaK%2Frrcbio%2B3B37IKkAK9I%2BbEHAPrYAO1IS4s%2FXJzbTpUa0QzHq16lriUZkNXoTaVSkQn3pcafiqeuhXBCTWDpS9bbgYb4KhGPASL2vrLlGqRE04%2Bx4NwxnqTxMWeJ4jp4tvBoXkb7yoMA1RT77nrbbw44VNktcZsEIPLiHSjUEjq76xOw2mjB%2FFYZiBo8YlJ%2B5IaLJ9rRunnRXbNcR0P5cnWczNcRTH0wwIt1e7U051jaezCMSWGdRzwNFG2K0fQ%3D%3D","seek":0,"status":"play"} May 17 12:56:30 volumio volumio[732]: verbose: CURRENT POSITION 17 May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::syncState stateService play May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus stop May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:56:30 volumio volumio[732]: info: ------------------------------ 26ms May 17 12:56:30 volumio volumio[732]: info: [1747486590810] ControllerPandora::fetchAndAddTracks May 17 12:56:30 volumio volumio[732]: info: [1747486590810] ControllerPandora::getStationTracks May 17 12:56:30 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:30 volumio volumio[732]: info: [1747486590810] [Pandora] PandoraHandler::getSongMaxDiff: 10 May 17 12:56:30 volumio volumio[732]: info: [1747486590810] ControllerPandora::fetchAndAddTracks::getSqInfo May 17 12:56:30 volumio volumio[732]: info: [1747486590810] ControllerPandora::getStationTracks May 17 12:56:30 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:30 volumio volumio[732]: info: [1747486590811] [Pandora] fetchAndAddTracks: diff1: 10 sQPos1: 17 May 17 12:56:30 volumio volumio[732]: info: [1747486590811] [Pandora] fetchAndAddTracks: Fetching tracks May 17 12:56:30 volumio volumio[732]: info: [1747486590811] ControllerPandora::PandoraHandler::fillStationData May 17 12:56:30 volumio volumio[732]: info: May 17 12:56:30 volumio volumio[732]: ---------------------------- MPD announces system playlist update May 17 12:56:30 volumio volumio[732]: info: Ignoring MPD Status Update May 17 12:56:30 volumio volumio[732]: info: ------------------------------ 14ms May 17 12:56:30 volumio volumio[732]: info: ------------------------------ 4ms May 17 12:56:30 volumio volumio[732]: info: [1747486590938] ControllerPandora::pandoraListener May 17 12:56:30 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:30 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:30 volumio volumio[732]: info: May 17 12:56:30 volumio volumio[732]: ---------------------------- MPD announces state update: player May 17 12:56:30 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:30 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:30 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 17 12:56:30 volumio volumio[732]: info: sendMpdCommand status took 15 milliseconds May 17 12:56:30 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:30 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:30 volumio volumio[732]: info: May 17 12:56:30 volumio volumio[732]: ---------------------------- MPD announces state update: player May 17 12:56:30 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:30 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:30 volumio volumio[732]: info: sendMpdCommand status took 13 milliseconds May 17 12:56:30 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:30 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:30 volumio volumio[732]: info: sendMpdCommand playlistinfo took 2 milliseconds May 17 12:56:30 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:56:30 volumio volumio[732]: info: CorePlayQueue::getTrack 17 May 17 12:56:30 volumio volumio[732]: info: [1747486590959] ControllerPandora::pushState May 17 12:56:30 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:30 volumio volumio[732]: info: CorePlayQueue::getTrack 17 May 17 12:56:30 volumio volumio[732]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":161,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"When I'm Gone","artist":"Alesso & Katy Perry","album":"When I'm Gone","uri":"http://t1-5.p-cdn.us/access/3251130516864062151.mp3?version=5&lid=360257756&token=Vi0E8JJHZQzKcHXaY991aaCpLmw368ebuWM3vmx%2FXiSigMd80Fi09dG4QfPH7p8tLfASC6xN0ZM0x6BOXIqrFNaK%2Frrcbio%2B3B37IKkAK9I%2BbEHAPrYAO1IS4s%2FXJzbTpUa0QzHq16lriUZkNXoTaVSkQn3pcafiqeuhXBCTWDpS9bbgYb4KhGPASL2vrLlGqRE04%2Bx4NwxnqTxMWeJ4jp4tvBoXkb7yoMA1RT77nrbbw44VNktcZsEIPLiHSjUEjq76xOw2mjB%2FFYZiBo8YlJ%2B5IaLJ9rRunnRXbNcR0P5cnWczNcRTH0wwIt1e7U051jaezCMSWGdRzwNFG2K0fQ%3D%3D","trackType":"mp3"} May 17 12:56:30 volumio volumio[732]: verbose: CURRENT POSITION 17 May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::syncState stateService play May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus play May 17 12:56:30 volumio volumio[732]: info: Received an update from plugin. extracting info from payload May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:30 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:30 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:30 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:56:30 volumio volumio[732]: info: sendMpdCommand status took 14 milliseconds May 17 12:56:30 volumio volumio[732]: info: sendMpdCommand playlistinfo took 14 milliseconds May 17 12:56:30 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:30 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:30 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:56:30 volumio volumio[732]: info: ControllerMpd::pushState May 17 12:56:30 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:30 volumio volumio[732]: info: CorePlayQueue::getTrack 17 May 17 12:56:30 volumio volumio[732]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd May 17 12:56:30 volumio volumio[732]: info: ------------------------------ 33ms May 17 12:56:30 volumio volumio[732]: info: CoreCommandRouter::volumioGetQueue May 17 12:56:30 volumio volumio[732]: info: CoreStateMachine::getQueue May 17 12:56:30 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:30 volumio volumio[732]: info: sendMpdCommand playlistinfo took 14 milliseconds May 17 12:56:30 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:56:30 volumio volumio[732]: info: ControllerMpd::pushState May 17 12:56:30 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:30 volumio volumio[732]: info: CorePlayQueue::getTrack 17 May 17 12:56:30 volumio volumio[732]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd May 17 12:56:30 volumio volumio[732]: info: ------------------------------ 35ms May 17 12:56:31 volumio volumio[732]: info: [1747486591003] ControllerPandora::PandoraHandler::fetchTracks::fetchStationPlaylist May 17 12:56:31 volumio volumio[732]: info: [1747486591288] [Pandora] PandoraHandler::fetchTracks::fetchStationPlaylist: Retrieved Today's Hits Radio playlist May 17 12:56:31 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:31 volumio volumio[732]: info: [1747486591289] ControllerPandora::PandoraHandler::fetchTracks::fillNewTracks May 17 12:56:31 volumio volumio[732]: info: [1747486591289] [Pandora] PandoraHandler::fetchTracks::fillNewTracks: Fetched 4 track(s) May 17 12:56:31 volumio volumio[732]: info: CorePlayQueue::addQueueItems May 17 12:56:31 volumio volumio[732]: info: Preload queue cleared May 17 12:56:31 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=8240019120726037608 May 17 12:56:31 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=8240019120726037608 in service pandora May 17 12:56:31 volumio volumio[732]: info: [1747486591290] ControllerPandora::explodeUri May 17 12:56:31 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:31 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=6973261230828601962 May 17 12:56:31 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=6973261230828601962 in service pandora May 17 12:56:31 volumio volumio[732]: info: [1747486591290] ControllerPandora::explodeUri May 17 12:56:31 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:31 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=1915603369019948154 May 17 12:56:31 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=1915603369019948154 in service pandora May 17 12:56:31 volumio volumio[732]: info: [1747486591290] ControllerPandora::explodeUri May 17 12:56:31 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:31 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=2861532338484001408 May 17 12:56:31 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=2861532338484001408 in service pandora May 17 12:56:31 volumio volumio[732]: info: [1747486591291] ControllerPandora::explodeUri May 17 12:56:31 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:31 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:31 volumio volumio[732]: info: CoreCommandRouter::volumioPushQueue May 17 12:56:31 volumio volumio[732]: info: CorePlayQueue::saveQueue May 17 12:56:31 volumio volumio[732]: info: CoreStateMachine::updateTrackBlock May 17 12:56:31 volumio volumio[732]: info: CorePlayQueue::getTrackBlock May 17 12:56:31 volumio volumio[732]: info: [1747486591305] ControllerPandora::getStationTracks May 17 12:56:31 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:31 volumio volumio[732]: info: [1747486591305] [Pandora] PandoraHandler::getSongMaxDiff: 14 May 17 12:56:31 volumio volumio[732]: info: [1747486591305] ControllerPandora::fetchAndAddTracks::getSqInfo May 17 12:56:31 volumio volumio[732]: info: [1747486591306] ControllerPandora::getStationTracks May 17 12:56:31 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:31 volumio volumio[732]: info: [1747486591306] [Pandora] fetchAndAddTracks: diff2 > 0: 14 sQPos2: 17 May 17 12:56:31 volumio volumio[732]: info: [1747486591306] ControllerPandora::removeOldTrackBlock May 17 12:56:31 volumio volumio[732]: info: [1747486591306] ControllerPandora::removeStationTracks May 17 12:56:31 volumio volumio[732]: info: [1747486591306] ControllerPandora::getStationTracks May 17 12:56:31 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:31 volumio volumio[732]: info: [1747486591307] [Pandora] removeStationTracks: Removing 14 tracks from queue May 17 12:56:32 volumio volumio[732]: info: CoreCommandRouter::volumioPlay May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::play index 21 May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::stop May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::stPlaybackTimer May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::updateTrackBlock May 17 12:56:32 volumio volumio[732]: info: CorePlayQueue::getTrackBlock May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:32 volumio volumio[732]: info: CorePlayQueue::getTrack 17 May 17 12:56:32 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:32 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::serviceStop May 17 12:56:32 volumio volumio[732]: info: CorePlayQueue::getTrack 17 May 17 12:56:32 volumio volumio[732]: info: CoreCommandRouter::serviceStop May 17 12:56:32 volumio volumio[732]: info: [1747486592517] ControllerPandora::stop May 17 12:56:32 volumio volumio[732]: info: ControllerMpd::stop May 17 12:56:32 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand stop May 17 12:56:32 volumio volumio[732]: info: May 17 12:56:32 volumio volumio[732]: ---------------------------- MPD announces state update: player May 17 12:56:32 volumio volumio[732]: info: sendMpdCommand stop took 23 milliseconds May 17 12:56:32 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:32 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:32 volumio volumio[732]: info: [1747486592541] ControllerPandora::pushState May 17 12:56:32 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:32 volumio volumio[732]: info: CorePlayQueue::getTrack 17 May 17 12:56:32 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:32 volumio volumio[732]: info: CorePlayQueue::getTrack 17 May 17 12:56:32 volumio volumio[732]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"When I'm Gone","name":"When I'm Gone","artist":"Alesso & Katy Perry","album":"When I'm Gone","albumart":"http://cont-4.p-cdn.us/images/0d/63/f3/03/851d457eb2317d1d82255b7f/1080W_1080H.jpg","realUri":"http://t1-5.p-cdn.us/access/3251130516864062151.mp3?version=5&lid=360257756&token=Vi0E8JJHZQzKcHXaY991aaCpLmw368ebuWM3vmx%2FXiSigMd80Fi09dG4QfPH7p8tLfASC6xN0ZM0x6BOXIqrFNaK%2Frrcbio%2B3B37IKkAK9I%2BbEHAPrYAO1IS4s%2FXJzbTpUa0QzHq16lriUZkNXoTaVSkQn3pcafiqeuhXBCTWDpS9bbgYb4KhGPASL2vrLlGqRE04%2Bx4NwxnqTxMWeJ4jp4tvBoXkb7yoMA1RT77nrbbw44VNktcZsEIPLiHSjUEjq76xOw2mjB%2FFYZiBo8YlJ%2B5IaLJ9rRunnRXbNcR0P5cnWczNcRTH0wwIt1e7U051jaezCMSWGdRzwNFG2K0fQ%3D%3D","isStreaming":true,"duration":161,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t1-5.p-cdn.us/access/3251130516864062151.mp3?version=5&lid=360257756&token=Vi0E8JJHZQzKcHXaY991aaCpLmw368ebuWM3vmx%2FXiSigMd80Fi09dG4QfPH7p8tLfASC6xN0ZM0x6BOXIqrFNaK%2Frrcbio%2B3B37IKkAK9I%2BbEHAPrYAO1IS4s%2FXJzbTpUa0QzHq16lriUZkNXoTaVSkQn3pcafiqeuhXBCTWDpS9bbgYb4KhGPASL2vrLlGqRE04%2Bx4NwxnqTxMWeJ4jp4tvBoXkb7yoMA1RT77nrbbw44VNktcZsEIPLiHSjUEjq76xOw2mjB%2FFYZiBo8YlJ%2B5IaLJ9rRunnRXbNcR0P5cnWczNcRTH0wwIt1e7U051jaezCMSWGdRzwNFG2K0fQ%3D%3D","seek":0,"status":"stop"} May 17 12:56:32 volumio volumio[732]: verbose: CURRENT POSITION 17 May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::syncState stateService stop May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus stop May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:32 volumio volumio[732]: info: CorePlayQueue::getTrack 17 May 17 12:56:32 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:32 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:32 volumio volumio[732]: info: No code May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:32 volumio volumio[732]: info: CorePlayQueue::getTrack 17 May 17 12:56:32 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::play index undefined May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 12:56:32 volumio volumio[732]: info: CorePlayQueue::getTrack 21 May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::startPlaybackTimer May 17 12:56:32 volumio volumio[732]: info: CorePlayQueue::getTrack 21 May 17 12:56:32 volumio volumio[732]: info: [1747486592552] ControllerPandora::clearAddPlayTrack May 17 12:56:32 volumio volumio[732]: info: ControllerMpd::clear May 17 12:56:32 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand clear May 17 12:56:32 volumio volumio[732]: info: May 17 12:56:32 volumio volumio[732]: ---------------------------- MPD announces system playlist update May 17 12:56:32 volumio volumio[732]: info: Ignoring MPD Status Update May 17 12:56:32 volumio volumio[732]: info: sendMpdCommand status took 12 milliseconds May 17 12:56:32 volumio volumio[732]: info: sendMpdCommand clear took 2 milliseconds May 17 12:56:32 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:32 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:32 volumio volumio[732]: info: [1747486592554] ControllerPandora::checkForExpiredStations May 17 12:56:32 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:32 volumio volumio[732]: info: [1747486592555] ControllerPandora::removeTrack May 17 12:56:32 volumio volumio[732]: info: [1747486592555] [Pandora] removeTrack: Not removing track with uri: null at queue index: -1 May 17 12:56:32 volumio volumio[732]: info: [1747486592555] ControllerPandora::appendTracksToMpd May 17 12:56:32 volumio volumio[732]: info: [1747486592555] [Pandora] appendTracksToMpd: Added 1 track(s) to mpd May 17 12:56:32 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand addid May 17 12:56:32 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand play May 17 12:56:32 volumio volumio[732]: error: updateQueue error: null May 17 12:56:32 volumio volumio[732]: info: May 17 12:56:32 volumio volumio[732]: ---------------------------- MPD announces system playlist update May 17 12:56:32 volumio volumio[732]: info: Ignoring MPD Status Update May 17 12:56:32 volumio volumio[732]: info: ------------------------------ 6ms May 17 12:56:32 volumio volumio[732]: info: sendMpdCommand playlistinfo took 5 milliseconds May 17 12:56:32 volumio volumio[732]: info: sendMpdCommand addid took 7 milliseconds May 17 12:56:32 volumio volumio[732]: info: sendMpdCommand play took 8 milliseconds May 17 12:56:32 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:56:32 volumio volumio[732]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 17 12:56:32 volumio volumio[732]: verbose: MPD COMMAND [object Object] May 17 12:56:32 volumio volumio[732]: info: [1747486592564] ControllerPandora::pushState May 17 12:56:32 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:32 volumio volumio[732]: info: CorePlayQueue::getTrack 21 May 17 12:56:32 volumio volumio[732]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"End of Beginning","name":"End of Beginning","artist":"Djo","album":"DECIDE","albumart":"http://cont-1.p-cdn.us/images/72/79/2c/ee/e3994382927069c560c76b7d/1080W_1080H.jpg","realUri":"http://t1-2.p-cdn.us/access/2861532338484001408.mp3?version=5&lid=360257756&token=YPI98lQO5DOkA1E%2B1WjaWi4R%2BKNF87avJqQhHozJ75c1I531gTn24HMDZ%2B2AYyjoF5uf%2Fgb23B%2BGwBw10SKM6uLU9yc7EusBtyy11itt%2B98NInAGjavGl0y80iuyQCqL%2FfrfP77z4D3SSS6EGmYl4o0N3rWRg1K%2FV1veyjorSz8rbRmOJMa0TV4GxM%2F87v%2FVryWRBkDnXcHdEM54rKhKSCx4eMvU8fHGgOuggjW4FT7Dq%2BhMSmDbxdLqYA647DCupK6sIisairkhvpgv5pMj9CDTRj%2F83z%2FW5JaoCeuRsqQNxY4EYgUrCAhZerkk4lN8NyDbDaFD5EajbEF5LTxxfw%3D%3D","isStreaming":true,"duration":159,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t1-2.p-cdn.us/access/2861532338484001408.mp3?version=5&lid=360257756&token=YPI98lQO5DOkA1E%2B1WjaWi4R%2BKNF87avJqQhHozJ75c1I531gTn24HMDZ%2B2AYyjoF5uf%2Fgb23B%2BGwBw10SKM6uLU9yc7EusBtyy11itt%2B98NInAGjavGl0y80iuyQCqL%2FfrfP77z4D3SSS6EGmYl4o0N3rWRg1K%2FV1veyjorSz8rbRmOJMa0TV4GxM%2F87v%2FVryWRBkDnXcHdEM54rKhKSCx4eMvU8fHGgOuggjW4FT7Dq%2BhMSmDbxdLqYA647DCupK6sIisairkhvpgv5pMj9CDTRj%2F83z%2FW5JaoCeuRsqQNxY4EYgUrCAhZerkk4lN8NyDbDaFD5EajbEF5LTxxfw%3D%3D","seek":0,"status":"play"} May 17 12:56:32 volumio volumio[732]: verbose: CURRENT POSITION 21 May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::syncState stateService play May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus stop May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:56:32 volumio volumio[732]: info: ------------------------------ 25ms May 17 12:56:32 volumio volumio[732]: info: [1747486592565] ControllerPandora::fetchAndAddTracks May 17 12:56:32 volumio volumio[732]: info: [1747486592565] ControllerPandora::getStationTracks May 17 12:56:32 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:32 volumio volumio[732]: info: [1747486592566] [Pandora] PandoraHandler::getSongMaxDiff: 14 May 17 12:56:32 volumio volumio[732]: info: [1747486592566] ControllerPandora::fetchAndAddTracks::getSqInfo May 17 12:56:32 volumio volumio[732]: info: [1747486592566] ControllerPandora::getStationTracks May 17 12:56:32 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:32 volumio volumio[732]: info: [1747486592566] [Pandora] fetchAndAddTracks: diff1: 14 sQPos1: 21 May 17 12:56:32 volumio volumio[732]: info: [1747486592566] [Pandora] fetchAndAddTracks: Fetching tracks May 17 12:56:32 volumio volumio[732]: info: [1747486592566] ControllerPandora::PandoraHandler::fillStationData May 17 12:56:32 volumio volumio[732]: info: May 17 12:56:32 volumio volumio[732]: ---------------------------- MPD announces system playlist update May 17 12:56:32 volumio volumio[732]: info: Ignoring MPD Status Update May 17 12:56:32 volumio volumio[732]: info: ------------------------------ 11ms May 17 12:56:32 volumio volumio[732]: info: ------------------------------ 1ms May 17 12:56:32 volumio volumio[732]: info: [1747486592753] ControllerPandora::PandoraHandler::fetchTracks::fetchStationPlaylist May 17 12:56:32 volumio volumio[732]: info: [1747486592761] ControllerPandora::pandoraListener May 17 12:56:32 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:32 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:32 volumio volumio[732]: info: May 17 12:56:32 volumio volumio[732]: ---------------------------- MPD announces state update: player May 17 12:56:32 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:32 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:32 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 17 12:56:32 volumio volumio[732]: info: May 17 12:56:32 volumio volumio[732]: ---------------------------- MPD announces state update: player May 17 12:56:32 volumio volumio[732]: info: sendMpdCommand status took 18 milliseconds May 17 12:56:32 volumio volumio[732]: info: sendMpdCommand status took 18 milliseconds May 17 12:56:32 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:32 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:32 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:32 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:32 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:32 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:32 volumio volumio[732]: info: sendMpdCommand status took 3 milliseconds May 17 12:56:32 volumio volumio[732]: info: sendMpdCommand playlistinfo took 3 milliseconds May 17 12:56:32 volumio volumio[732]: info: sendMpdCommand playlistinfo took 3 milliseconds May 17 12:56:32 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:32 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:32 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:56:32 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:56:32 volumio volumio[732]: info: CorePlayQueue::getTrack 21 May 17 12:56:32 volumio volumio[732]: info: [1747486592785] ControllerPandora::pushState May 17 12:56:32 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:32 volumio volumio[732]: info: CorePlayQueue::getTrack 21 May 17 12:56:32 volumio volumio[732]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":159,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"End of Beginning","artist":"Djo","album":"DECIDE","uri":"http://t1-2.p-cdn.us/access/2861532338484001408.mp3?version=5&lid=360257756&token=YPI98lQO5DOkA1E%2B1WjaWi4R%2BKNF87avJqQhHozJ75c1I531gTn24HMDZ%2B2AYyjoF5uf%2Fgb23B%2BGwBw10SKM6uLU9yc7EusBtyy11itt%2B98NInAGjavGl0y80iuyQCqL%2FfrfP77z4D3SSS6EGmYl4o0N3rWRg1K%2FV1veyjorSz8rbRmOJMa0TV4GxM%2F87v%2FVryWRBkDnXcHdEM54rKhKSCx4eMvU8fHGgOuggjW4FT7Dq%2BhMSmDbxdLqYA647DCupK6sIisairkhvpgv5pMj9CDTRj%2F83z%2FW5JaoCeuRsqQNxY4EYgUrCAhZerkk4lN8NyDbDaFD5EajbEF5LTxxfw%3D%3D","trackType":"mp3"} May 17 12:56:32 volumio volumio[732]: verbose: CURRENT POSITION 21 May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::syncState stateService play May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus play May 17 12:56:32 volumio volumio[732]: info: Received an update from plugin. extracting info from payload May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:32 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:32 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:32 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:56:32 volumio volumio[732]: info: ControllerMpd::pushState May 17 12:56:32 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:32 volumio volumio[732]: info: CorePlayQueue::getTrack 21 May 17 12:56:32 volumio volumio[732]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd May 17 12:56:32 volumio volumio[732]: info: ------------------------------ 32ms May 17 12:56:32 volumio volumio[732]: info: sendMpdCommand playlistinfo took 9 milliseconds May 17 12:56:32 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:56:32 volumio volumio[732]: info: ControllerMpd::pushState May 17 12:56:32 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:32 volumio volumio[732]: info: CorePlayQueue::getTrack 21 May 17 12:56:32 volumio volumio[732]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd May 17 12:56:32 volumio volumio[732]: info: ------------------------------ 15ms May 17 12:56:32 volumio volumio[732]: info: CoreCommandRouter::volumioGetQueue May 17 12:56:32 volumio volumio[732]: info: CoreStateMachine::getQueue May 17 12:56:32 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:33 volumio volumio[732]: info: [1747486593440] [Pandora] PandoraHandler::fetchTracks::fetchStationPlaylist: Retrieved Today's Hits Radio playlist May 17 12:56:33 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:33 volumio volumio[732]: info: [1747486593441] ControllerPandora::PandoraHandler::fetchTracks::fillNewTracks May 17 12:56:33 volumio volumio[732]: info: [1747486593442] [Pandora] PandoraHandler::fetchTracks::fillNewTracks: Fetched 4 track(s) May 17 12:56:33 volumio volumio[732]: info: CorePlayQueue::addQueueItems May 17 12:56:33 volumio volumio[732]: info: Preload queue cleared May 17 12:56:33 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=8322719386191529176 May 17 12:56:33 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=8322719386191529176 in service pandora May 17 12:56:33 volumio volumio[732]: info: [1747486593444] ControllerPandora::explodeUri May 17 12:56:33 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:33 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=1919726072663806683 May 17 12:56:33 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=1919726072663806683 in service pandora May 17 12:56:33 volumio volumio[732]: info: [1747486593445] ControllerPandora::explodeUri May 17 12:56:33 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:33 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=4106695659474757610 May 17 12:56:33 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=4106695659474757610 in service pandora May 17 12:56:33 volumio volumio[732]: info: [1747486593447] ControllerPandora::explodeUri May 17 12:56:33 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:33 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=1282001327590728910 May 17 12:56:33 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=1282001327590728910 in service pandora May 17 12:56:33 volumio volumio[732]: info: [1747486593448] ControllerPandora::explodeUri May 17 12:56:33 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:33 volumio volumio[732]: info: CoreCommandRouter::volumioPushQueue May 17 12:56:33 volumio volumio[732]: info: CorePlayQueue::saveQueue May 17 12:56:33 volumio volumio[732]: info: CoreStateMachine::updateTrackBlock May 17 12:56:33 volumio volumio[732]: info: CorePlayQueue::getTrackBlock May 17 12:56:33 volumio volumio[732]: info: [1747486593464] ControllerPandora::getStationTracks May 17 12:56:33 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:33 volumio volumio[732]: info: [1747486593465] [Pandora] PandoraHandler::getSongMaxDiff: 18 May 17 12:56:33 volumio volumio[732]: info: [1747486593466] ControllerPandora::fetchAndAddTracks::getSqInfo May 17 12:56:33 volumio volumio[732]: info: [1747486593467] ControllerPandora::getStationTracks May 17 12:56:33 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:33 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:33 volumio volumio[732]: info: [1747486593469] [Pandora] fetchAndAddTracks: diff2 > 0: 18 sQPos2: 21 May 17 12:56:33 volumio volumio[732]: info: [1747486593469] ControllerPandora::removeOldTrackBlock May 17 12:56:33 volumio volumio[732]: info: [1747486593470] ControllerPandora::removeStationTracks May 17 12:56:33 volumio volumio[732]: info: [1747486593470] ControllerPandora::getStationTracks May 17 12:56:33 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:33 volumio volumio[732]: info: [1747486593472] [Pandora] removeStationTracks: Removing 18 tracks from queue May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::volumioPlay May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::play index 19 May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::stop May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::stPlaybackTimer May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::updateTrackBlock May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getTrackBlock May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getTrack 21 May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::serviceStop May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getTrack 21 May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::serviceStop May 17 12:56:34 volumio volumio[732]: info: [1747486594252] ControllerPandora::stop May 17 12:56:34 volumio volumio[732]: info: ControllerMpd::stop May 17 12:56:34 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand stop May 17 12:56:34 volumio volumio[732]: info: sendMpdCommand stop took 16 milliseconds May 17 12:56:34 volumio volumio[732]: info: [1747486594269] ControllerPandora::pushState May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getTrack 21 May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getTrack 21 May 17 12:56:34 volumio volumio[732]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"End of Beginning","name":"End of Beginning","artist":"Djo","album":"DECIDE","albumart":"http://cont-1.p-cdn.us/images/72/79/2c/ee/e3994382927069c560c76b7d/1080W_1080H.jpg","realUri":"http://t1-2.p-cdn.us/access/2861532338484001408.mp3?version=5&lid=360257756&token=YPI98lQO5DOkA1E%2B1WjaWi4R%2BKNF87avJqQhHozJ75c1I531gTn24HMDZ%2B2AYyjoF5uf%2Fgb23B%2BGwBw10SKM6uLU9yc7EusBtyy11itt%2B98NInAGjavGl0y80iuyQCqL%2FfrfP77z4D3SSS6EGmYl4o0N3rWRg1K%2FV1veyjorSz8rbRmOJMa0TV4GxM%2F87v%2FVryWRBkDnXcHdEM54rKhKSCx4eMvU8fHGgOuggjW4FT7Dq%2BhMSmDbxdLqYA647DCupK6sIisairkhvpgv5pMj9CDTRj%2F83z%2FW5JaoCeuRsqQNxY4EYgUrCAhZerkk4lN8NyDbDaFD5EajbEF5LTxxfw%3D%3D","isStreaming":true,"duration":159,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t1-2.p-cdn.us/access/2861532338484001408.mp3?version=5&lid=360257756&token=YPI98lQO5DOkA1E%2B1WjaWi4R%2BKNF87avJqQhHozJ75c1I531gTn24HMDZ%2B2AYyjoF5uf%2Fgb23B%2BGwBw10SKM6uLU9yc7EusBtyy11itt%2B98NInAGjavGl0y80iuyQCqL%2FfrfP77z4D3SSS6EGmYl4o0N3rWRg1K%2FV1veyjorSz8rbRmOJMa0TV4GxM%2F87v%2FVryWRBkDnXcHdEM54rKhKSCx4eMvU8fHGgOuggjW4FT7Dq%2BhMSmDbxdLqYA647DCupK6sIisairkhvpgv5pMj9CDTRj%2F83z%2FW5JaoCeuRsqQNxY4EYgUrCAhZerkk4lN8NyDbDaFD5EajbEF5LTxxfw%3D%3D","seek":0,"status":"stop"} May 17 12:56:34 volumio volumio[732]: verbose: CURRENT POSITION 21 May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::syncState stateService stop May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus stop May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getTrack 21 May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:34 volumio volumio[732]: info: No code May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getTrack 21 May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::play index undefined May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getTrack 19 May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::startPlaybackTimer May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getTrack 19 May 17 12:56:34 volumio volumio[732]: info: [1747486594279] ControllerPandora::clearAddPlayTrack May 17 12:56:34 volumio volumio[732]: info: ControllerMpd::clear May 17 12:56:34 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand clear May 17 12:56:34 volumio volumio[732]: info: May 17 12:56:34 volumio volumio[732]: ---------------------------- MPD announces state update: player May 17 12:56:34 volumio volumio[732]: info: May 17 12:56:34 volumio volumio[732]: ---------------------------- MPD announces system playlist update May 17 12:56:34 volumio volumio[732]: info: Ignoring MPD Status Update May 17 12:56:34 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:34 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:34 volumio volumio[732]: info: sendMpdCommand clear took 2 milliseconds May 17 12:56:34 volumio volumio[732]: info: [1747486594281] ControllerPandora::checkForExpiredStations May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:34 volumio volumio[732]: info: [1747486594281] ControllerPandora::removeTrack May 17 12:56:34 volumio volumio[732]: info: [1747486594282] [Pandora] removeTrack: Not removing track with uri: null at queue index: -1 May 17 12:56:34 volumio volumio[732]: info: [1747486594282] ControllerPandora::appendTracksToMpd May 17 12:56:34 volumio volumio[732]: info: [1747486594282] [Pandora] appendTracksToMpd: Added 1 track(s) to mpd May 17 12:56:34 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand addid May 17 12:56:34 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand play May 17 12:56:34 volumio volumio[732]: error: updateQueue error: null May 17 12:56:34 volumio volumio[732]: info: May 17 12:56:34 volumio volumio[732]: ---------------------------- MPD announces system playlist update May 17 12:56:34 volumio volumio[732]: info: Ignoring MPD Status Update May 17 12:56:34 volumio volumio[732]: info: sendMpdCommand status took 6 milliseconds May 17 12:56:34 volumio volumio[732]: info: ------------------------------ 7ms May 17 12:56:34 volumio volumio[732]: info: sendMpdCommand addid took 5 milliseconds May 17 12:56:34 volumio volumio[732]: info: sendMpdCommand play took 5 milliseconds May 17 12:56:34 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:34 volumio volumio[732]: info: ControllerMpd::pushState May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getTrack 19 May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getTrack 19 May 17 12:56:34 volumio volumio[732]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current pandora Received mpd May 17 12:56:34 volumio volumio[732]: verbose: MPD COMMAND [object Object] May 17 12:56:34 volumio volumio[732]: info: [1747486594291] ControllerPandora::pushState May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getTrack 19 May 17 12:56:34 volumio volumio[732]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"Va Va Voom","name":"Va Va Voom","artist":"Nicki Minaj","album":"Pink Friday ... Roman Reloaded (Deluxe Edition)","albumart":"http://mediaserver-cont-sv5-2-v4v6.pandora.com/images/75/65/0e/7d/6a6b46adafc0054a1600df68/1080W_1080H.jpg","realUri":"http://t1-4.p-cdn.us/access/6973261230828601962.mp3?version=5&lid=360257756&token=YPI98lQO5DOPKbpWh7dd9AIea1jBVzzXF6XGvZjJrmedbCTB6n5%2B3Y2GBFfocCb7cnRL00DPTBURP4dWu21vkw5KTo%2FMWU3IdwK9hslwkkp7ppVYZkjXjh6c1bel9yk1TpSwDxN6pgQurj1INsIFR8bihgctmfIpJTPDtpEGTcRnfBhibp9IByI0CArF7zCTgVGqI%2FKd16H0YgLkmWj%2FL9cy47X0eBbLtwq0q9JpL5155PZxqOb6GpU9dqECeyAzTdYBiVgPeXF9uWzbFYJI9Le9uqTwEsjWQ%2FqAjULJ0QsvNJu8q1oZlD%2FlR4BpxAmJKlFJwfgr3gUw0spm8gwOBg%3D%3D","isStreaming":true,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t1-4.p-cdn.us/access/6973261230828601962.mp3?version=5&lid=360257756&token=YPI98lQO5DOPKbpWh7dd9AIea1jBVzzXF6XGvZjJrmedbCTB6n5%2B3Y2GBFfocCb7cnRL00DPTBURP4dWu21vkw5KTo%2FMWU3IdwK9hslwkkp7ppVYZkjXjh6c1bel9yk1TpSwDxN6pgQurj1INsIFR8bihgctmfIpJTPDtpEGTcRnfBhibp9IByI0CArF7zCTgVGqI%2FKd16H0YgLkmWj%2FL9cy47X0eBbLtwq0q9JpL5155PZxqOb6GpU9dqECeyAzTdYBiVgPeXF9uWzbFYJI9Le9uqTwEsjWQ%2FqAjULJ0QsvNJu8q1oZlD%2FlR4BpxAmJKlFJwfgr3gUw0spm8gwOBg%3D%3D","seek":0,"status":"play"} May 17 12:56:34 volumio volumio[732]: verbose: CURRENT POSITION 19 May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::syncState stateService play May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus stop May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:56:34 volumio volumio[732]: info: ------------------------------ 14ms May 17 12:56:34 volumio volumio[732]: info: [1747486594294] ControllerPandora::fetchAndAddTracks May 17 12:56:34 volumio volumio[732]: info: [1747486594294] ControllerPandora::getStationTracks May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:34 volumio volumio[732]: info: [1747486594295] [Pandora] PandoraHandler::getSongMaxDiff: 18 May 17 12:56:34 volumio volumio[732]: info: [1747486594295] ControllerPandora::fetchAndAddTracks::getSqInfo May 17 12:56:34 volumio volumio[732]: info: [1747486594295] ControllerPandora::getStationTracks May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:34 volumio volumio[732]: info: [1747486594295] [Pandora] fetchAndAddTracks: diff1: 18 sQPos1: 19 May 17 12:56:34 volumio volumio[732]: info: [1747486594295] [Pandora] fetchAndAddTracks: Fetching tracks May 17 12:56:34 volumio volumio[732]: info: [1747486594295] ControllerPandora::PandoraHandler::fillStationData May 17 12:56:34 volumio volumio[732]: info: May 17 12:56:34 volumio volumio[732]: ---------------------------- MPD announces system playlist update May 17 12:56:34 volumio volumio[732]: info: Ignoring MPD Status Update May 17 12:56:34 volumio volumio[732]: info: ------------------------------ 13ms May 17 12:56:34 volumio volumio[732]: info: ------------------------------ 5ms May 17 12:56:34 volumio volumio[732]: info: [1747486594467] ControllerPandora::pandoraListener May 17 12:56:34 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 17 12:56:34 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:34 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:34 volumio volumio[732]: info: May 17 12:56:34 volumio volumio[732]: ---------------------------- MPD announces state update: player May 17 12:56:34 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:34 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:34 volumio volumio[732]: info: [1747486594482] ControllerPandora::PandoraHandler::fetchTracks::fetchStationPlaylist May 17 12:56:34 volumio volumio[732]: info: May 17 12:56:34 volumio volumio[732]: ---------------------------- MPD announces state update: player May 17 12:56:34 volumio volumio[732]: info: sendMpdCommand status took 22 milliseconds May 17 12:56:34 volumio volumio[732]: info: sendMpdCommand status took 21 milliseconds May 17 12:56:34 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:34 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:34 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:34 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:34 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:34 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:34 volumio volumio[732]: info: sendMpdCommand status took 4 milliseconds May 17 12:56:34 volumio volumio[732]: info: sendMpdCommand playlistinfo took 5 milliseconds May 17 12:56:34 volumio volumio[732]: info: sendMpdCommand playlistinfo took 6 milliseconds May 17 12:56:34 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:34 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:34 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:56:34 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getTrack 19 May 17 12:56:34 volumio volumio[732]: info: [1747486594501] ControllerPandora::pushState May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getTrack 19 May 17 12:56:34 volumio volumio[732]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Va Va Voom","artist":"Nicki Minaj","album":"Pink Friday ... Roman Reloaded (Deluxe Edition)","uri":"http://t1-4.p-cdn.us/access/6973261230828601962.mp3?version=5&lid=360257756&token=YPI98lQO5DOPKbpWh7dd9AIea1jBVzzXF6XGvZjJrmedbCTB6n5%2B3Y2GBFfocCb7cnRL00DPTBURP4dWu21vkw5KTo%2FMWU3IdwK9hslwkkp7ppVYZkjXjh6c1bel9yk1TpSwDxN6pgQurj1INsIFR8bihgctmfIpJTPDtpEGTcRnfBhibp9IByI0CArF7zCTgVGqI%2FKd16H0YgLkmWj%2FL9cy47X0eBbLtwq0q9JpL5155PZxqOb6GpU9dqECeyAzTdYBiVgPeXF9uWzbFYJI9Le9uqTwEsjWQ%2FqAjULJ0QsvNJu8q1oZlD%2FlR4BpxAmJKlFJwfgr3gUw0spm8gwOBg%3D%3D","trackType":"mp3"} May 17 12:56:34 volumio volumio[732]: verbose: CURRENT POSITION 19 May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::syncState stateService play May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus play May 17 12:56:34 volumio volumio[732]: info: Received an update from plugin. extracting info from payload May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:56:34 volumio volumio[732]: info: ControllerMpd::pushState May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getTrack 19 May 17 12:56:34 volumio volumio[732]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd May 17 12:56:34 volumio volumio[732]: info: ------------------------------ 49ms May 17 12:56:34 volumio volumio[732]: info: sendMpdCommand playlistinfo took 20 milliseconds May 17 12:56:34 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:56:34 volumio volumio[732]: info: ControllerMpd::pushState May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getTrack 19 May 17 12:56:34 volumio volumio[732]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd May 17 12:56:34 volumio volumio[732]: info: ------------------------------ 33ms May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:34 volumio volumio[732]: info: [1747486594556] ControllerPandora::removeTrack May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::removeQueueItem May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::removeQueueItem 0 May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::saveQueue May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::volumioPushQueue May 17 12:56:34 volumio volumio[732]: info: [1747486594562] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=4498449034229913820trackId=6422679386369652951 at queue index: 0 May 17 12:56:34 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:34 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:34 volumio volumio[732]: info: sendMpdCommand status took 19 milliseconds May 17 12:56:34 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:34 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::volumioGetQueue May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::getQueue May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:34 volumio volumio[732]: info: sendMpdCommand playlistinfo took 21 milliseconds May 17 12:56:34 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:56:34 volumio volumio[732]: info: [1747486594605] ControllerPandora::pushState May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getTrack 18 May 17 12:56:34 volumio volumio[732]: verbose: STATE SERVICE {"status":"play","position":0,"seek":697,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Va Va Voom","artist":"Nicki Minaj","album":"Pink Friday ... Roman Reloaded (Deluxe Edition)","uri":"http://t1-4.p-cdn.us/access/6973261230828601962.mp3?version=5&lid=360257756&token=YPI98lQO5DOPKbpWh7dd9AIea1jBVzzXF6XGvZjJrmedbCTB6n5%2B3Y2GBFfocCb7cnRL00DPTBURP4dWu21vkw5KTo%2FMWU3IdwK9hslwkkp7ppVYZkjXjh6c1bel9yk1TpSwDxN6pgQurj1INsIFR8bihgctmfIpJTPDtpEGTcRnfBhibp9IByI0CArF7zCTgVGqI%2FKd16H0YgLkmWj%2FL9cy47X0eBbLtwq0q9JpL5155PZxqOb6GpU9dqECeyAzTdYBiVgPeXF9uWzbFYJI9Le9uqTwEsjWQ%2FqAjULJ0QsvNJu8q1oZlD%2FlR4BpxAmJKlFJwfgr3gUw0spm8gwOBg%3D%3D","trackType":"mp3"} May 17 12:56:34 volumio volumio[732]: verbose: CURRENT POSITION 18 May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::syncState stateService play May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus play May 17 12:56:34 volumio volumio[732]: info: Received an update from plugin. extracting info from payload May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::volumioGetQueue May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::getQueue May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::volumioGetQueue May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::getQueue May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:34 volumio volumio[732]: info: [1747486594828] [Pandora] PandoraHandler::fetchTracks::fetchStationPlaylist: Retrieved Today's Hits Radio playlist May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:34 volumio volumio[732]: info: [1747486594828] ControllerPandora::PandoraHandler::fetchTracks::fillNewTracks May 17 12:56:34 volumio volumio[732]: info: [1747486594829] [Pandora] PandoraHandler::fetchTracks::fillNewTracks: Fetched 4 track(s) May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::addQueueItems May 17 12:56:34 volumio volumio[732]: info: Preload queue cleared May 17 12:56:34 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=6785189262636255446 May 17 12:56:34 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=6785189262636255446 in service pandora May 17 12:56:34 volumio volumio[732]: info: [1747486594830] ControllerPandora::explodeUri May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:34 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=3673292756237649191 May 17 12:56:34 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=3673292756237649191 in service pandora May 17 12:56:34 volumio volumio[732]: info: [1747486594830] ControllerPandora::explodeUri May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:34 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=8017799996649732397 May 17 12:56:34 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=8017799996649732397 in service pandora May 17 12:56:34 volumio volumio[732]: info: [1747486594830] ControllerPandora::explodeUri May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:34 volumio volumio[732]: info: Adding Item to queue: /pandora/stationToken=4498449034229913820trackId=4380713747032797836 May 17 12:56:34 volumio volumio[732]: info: Exploding uri /pandora/stationToken=4498449034229913820trackId=4380713747032797836 in service pandora May 17 12:56:34 volumio volumio[732]: info: [1747486594831] ControllerPandora::explodeUri May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:34 volumio volumio[732]: info: CoreCommandRouter::volumioPushQueue May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::saveQueue May 17 12:56:34 volumio volumio[732]: info: CoreStateMachine::updateTrackBlock May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getTrackBlock May 17 12:56:34 volumio volumio[732]: info: [1747486594842] ControllerPandora::getStationTracks May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:34 volumio volumio[732]: info: [1747486594842] [Pandora] PandoraHandler::getSongMaxDiff: 21 May 17 12:56:34 volumio volumio[732]: info: [1747486594843] ControllerPandora::fetchAndAddTracks::getSqInfo May 17 12:56:34 volumio volumio[732]: info: [1747486594843] ControllerPandora::getStationTracks May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:34 volumio volumio[732]: info: [1747486594843] [Pandora] fetchAndAddTracks: diff2 > 0: 21 sQPos2: 18 May 17 12:56:34 volumio volumio[732]: info: [1747486594843] ControllerPandora::removeOldTrackBlock May 17 12:56:34 volumio volumio[732]: info: [1747486594843] ControllerPandora::removeStationTracks May 17 12:56:34 volumio volumio[732]: info: [1747486594843] ControllerPandora::getStationTracks May 17 12:56:34 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:34 volumio volumio[732]: info: [1747486594845] [Pandora] removeStationTracks: Removing 18 tracks from queue May 17 12:56:35 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:35 volumio volumio[732]: info: [1747486595506] ControllerPandora::removeTrack May 17 12:56:35 volumio volumio[732]: info: CoreStateMachine::removeQueueItem May 17 12:56:35 volumio volumio[732]: info: CorePlayQueue::removeQueueItem 5 May 17 12:56:35 volumio volumio[732]: info: CorePlayQueue::saveQueue May 17 12:56:35 volumio volumio[732]: info: CoreCommandRouter::volumioPushQueue May 17 12:56:35 volumio volumio[732]: info: [1747486595510] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=4498449034229913820trackId=7660779752648894819 at queue index: 5 May 17 12:56:35 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:35 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:35 volumio volumio[732]: info: sendMpdCommand status took 10 milliseconds May 17 12:56:35 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:35 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:35 volumio volumio[732]: info: sendMpdCommand playlistinfo took 1 milliseconds May 17 12:56:35 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:56:35 volumio volumio[732]: info: [1747486595523] ControllerPandora::pushState May 17 12:56:35 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:35 volumio volumio[732]: info: CorePlayQueue::getTrack 17 May 17 12:56:35 volumio volumio[732]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1634,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Va Va Voom","artist":"Nicki Minaj","album":"Pink Friday ... Roman Reloaded (Deluxe Edition)","uri":"http://t1-4.p-cdn.us/access/6973261230828601962.mp3?version=5&lid=360257756&token=YPI98lQO5DOPKbpWh7dd9AIea1jBVzzXF6XGvZjJrmedbCTB6n5%2B3Y2GBFfocCb7cnRL00DPTBURP4dWu21vkw5KTo%2FMWU3IdwK9hslwkkp7ppVYZkjXjh6c1bel9yk1TpSwDxN6pgQurj1INsIFR8bihgctmfIpJTPDtpEGTcRnfBhibp9IByI0CArF7zCTgVGqI%2FKd16H0YgLkmWj%2FL9cy47X0eBbLtwq0q9JpL5155PZxqOb6GpU9dqECeyAzTdYBiVgPeXF9uWzbFYJI9Le9uqTwEsjWQ%2FqAjULJ0QsvNJu8q1oZlD%2FlR4BpxAmJKlFJwfgr3gUw0spm8gwOBg%3D%3D","trackType":"mp3"} May 17 12:56:35 volumio volumio[732]: verbose: CURRENT POSITION 17 May 17 12:56:35 volumio volumio[732]: info: CoreStateMachine::syncState stateService play May 17 12:56:35 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus play May 17 12:56:35 volumio volumio[732]: info: Received an update from plugin. extracting info from payload May 17 12:56:35 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:35 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:35 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:35 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:35 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:35 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:56:38 volumio volumio[732]: info: CoreCommandRouter::volumioRemoveQueueItem May 17 12:56:38 volumio volumio[732]: info: CoreStateMachine::removeQueueItem May 17 12:56:38 volumio volumio[732]: info: CorePlayQueue::removeQueueItem 18 May 17 12:56:38 volumio volumio[732]: info: CorePlayQueue::saveQueue May 17 12:56:38 volumio volumio[732]: info: CoreCommandRouter::volumioPushQueue May 17 12:56:39 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:39 volumio volumio[732]: info: [1747486599102] ControllerPandora::removeTrack May 17 12:56:39 volumio volumio[732]: info: [1747486599102] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=4498449034229913820trackId=6422679386369652951 at queue index: -1 May 17 12:56:41 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:41 volumio volumio[732]: info: [1747486601307] ControllerPandora::removeTrack May 17 12:56:41 volumio volumio[732]: info: [1747486601307] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=4498449034229913820trackId=6422679386369652951 at queue index: -1 May 17 12:56:42 volumio volumio[732]: info: CoreCommandRouter::volumioRemoveQueueItem May 17 12:56:42 volumio volumio[732]: info: CoreStateMachine::removeQueueItem May 17 12:56:42 volumio volumio[732]: info: CorePlayQueue::removeQueueItem 18 May 17 12:56:42 volumio volumio[732]: info: CorePlayQueue::saveQueue May 17 12:56:42 volumio volumio[732]: info: CoreCommandRouter::volumioPushQueue May 17 12:56:43 volumio volumio[732]: info: CoreCommandRouter::volumioRemoveQueueItem May 17 12:56:43 volumio volumio[732]: info: CoreStateMachine::removeQueueItem May 17 12:56:43 volumio volumio[732]: info: CorePlayQueue::removeQueueItem 18 May 17 12:56:43 volumio volumio[732]: info: CorePlayQueue::saveQueue May 17 12:56:43 volumio volumio[732]: info: CoreCommandRouter::volumioPushQueue May 17 12:56:43 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:43 volumio volumio[732]: info: [1747486603472] ControllerPandora::removeTrack May 17 12:56:43 volumio volumio[732]: info: [1747486603473] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=4498449034229913820trackId=6422679386369652951 at queue index: -1 May 17 12:56:44 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:44 volumio volumio[732]: info: [1747486604555] ControllerPandora::removeTrack May 17 12:56:44 volumio volumio[732]: info: CoreStateMachine::removeQueueItem May 17 12:56:44 volumio volumio[732]: info: CorePlayQueue::removeQueueItem 0 May 17 12:56:44 volumio volumio[732]: info: CorePlayQueue::saveQueue May 17 12:56:44 volumio volumio[732]: info: CoreCommandRouter::volumioPushQueue May 17 12:56:44 volumio volumio[732]: info: [1747486604559] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=4498449034229913820trackId=193726920087947402 at queue index: 0 May 17 12:56:44 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:44 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:44 volumio volumio[732]: info: sendMpdCommand status took 10 milliseconds May 17 12:56:44 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:44 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:44 volumio volumio[732]: info: sendMpdCommand playlistinfo took 1 milliseconds May 17 12:56:44 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:56:44 volumio volumio[732]: info: [1747486604572] ControllerPandora::pushState May 17 12:56:44 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:44 volumio volumio[732]: info: CorePlayQueue::getTrack 16 May 17 12:56:44 volumio volumio[732]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10674,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Va Va Voom","artist":"Nicki Minaj","album":"Pink Friday ... Roman Reloaded (Deluxe Edition)","uri":"http://t1-4.p-cdn.us/access/6973261230828601962.mp3?version=5&lid=360257756&token=YPI98lQO5DOPKbpWh7dd9AIea1jBVzzXF6XGvZjJrmedbCTB6n5%2B3Y2GBFfocCb7cnRL00DPTBURP4dWu21vkw5KTo%2FMWU3IdwK9hslwkkp7ppVYZkjXjh6c1bel9yk1TpSwDxN6pgQurj1INsIFR8bihgctmfIpJTPDtpEGTcRnfBhibp9IByI0CArF7zCTgVGqI%2FKd16H0YgLkmWj%2FL9cy47X0eBbLtwq0q9JpL5155PZxqOb6GpU9dqECeyAzTdYBiVgPeXF9uWzbFYJI9Le9uqTwEsjWQ%2FqAjULJ0QsvNJu8q1oZlD%2FlR4BpxAmJKlFJwfgr3gUw0spm8gwOBg%3D%3D","trackType":"mp3"} May 17 12:56:44 volumio volumio[732]: verbose: CURRENT POSITION 16 May 17 12:56:44 volumio volumio[732]: info: CoreStateMachine::syncState stateService play May 17 12:56:44 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus play May 17 12:56:44 volumio volumio[732]: info: Received an update from plugin. extracting info from payload May 17 12:56:44 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:44 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:44 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:44 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:44 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:44 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:56:44 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:44 volumio volumio[732]: info: [1747486604844] ControllerPandora::removeTrack May 17 12:56:44 volumio volumio[732]: info: [1747486604844] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=4498449034229913820trackId=193726920087947402 at queue index: -1 May 17 12:56:45 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:45 volumio volumio[732]: info: [1747486605506] ControllerPandora::removeTrack May 17 12:56:45 volumio volumio[732]: info: CoreStateMachine::removeQueueItem May 17 12:56:45 volumio volumio[732]: info: CorePlayQueue::removeQueueItem 4 May 17 12:56:45 volumio volumio[732]: info: CorePlayQueue::saveQueue May 17 12:56:45 volumio volumio[732]: info: CoreCommandRouter::volumioPushQueue May 17 12:56:45 volumio volumio[732]: info: [1747486605509] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=4498449034229913820trackId=2965495703883065134 at queue index: 4 May 17 12:56:45 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:45 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:45 volumio volumio[732]: info: sendMpdCommand status took 12 milliseconds May 17 12:56:45 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:45 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:45 volumio volumio[732]: info: sendMpdCommand playlistinfo took 2 milliseconds May 17 12:56:45 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:56:45 volumio volumio[732]: info: [1747486605525] ControllerPandora::pushState May 17 12:56:45 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:45 volumio volumio[732]: info: CorePlayQueue::getTrack 15 May 17 12:56:45 volumio volumio[732]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11622,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Va Va Voom","artist":"Nicki Minaj","album":"Pink Friday ... Roman Reloaded (Deluxe Edition)","uri":"http://t1-4.p-cdn.us/access/6973261230828601962.mp3?version=5&lid=360257756&token=YPI98lQO5DOPKbpWh7dd9AIea1jBVzzXF6XGvZjJrmedbCTB6n5%2B3Y2GBFfocCb7cnRL00DPTBURP4dWu21vkw5KTo%2FMWU3IdwK9hslwkkp7ppVYZkjXjh6c1bel9yk1TpSwDxN6pgQurj1INsIFR8bihgctmfIpJTPDtpEGTcRnfBhibp9IByI0CArF7zCTgVGqI%2FKd16H0YgLkmWj%2FL9cy47X0eBbLtwq0q9JpL5155PZxqOb6GpU9dqECeyAzTdYBiVgPeXF9uWzbFYJI9Le9uqTwEsjWQ%2FqAjULJ0QsvNJu8q1oZlD%2FlR4BpxAmJKlFJwfgr3gUw0spm8gwOBg%3D%3D","trackType":"mp3"} May 17 12:56:45 volumio volumio[732]: verbose: CURRENT POSITION 15 May 17 12:56:45 volumio volumio[732]: info: CoreStateMachine::syncState stateService play May 17 12:56:45 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus play May 17 12:56:45 volumio volumio[732]: info: Received an update from plugin. extracting info from payload May 17 12:56:45 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:45 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:45 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:45 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:45 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:45 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:56:49 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:49 volumio volumio[732]: info: [1747486609102] ControllerPandora::removeTrack May 17 12:56:49 volumio volumio[732]: info: [1747486609102] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=4498449034229913820trackId=193726920087947402 at queue index: -1 May 17 12:56:51 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:51 volumio volumio[732]: info: [1747486611306] ControllerPandora::removeTrack May 17 12:56:51 volumio volumio[732]: info: [1747486611307] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=4498449034229913820trackId=193726920087947402 at queue index: -1 May 17 12:56:53 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:53 volumio volumio[732]: info: [1747486613474] ControllerPandora::removeTrack May 17 12:56:53 volumio volumio[732]: info: [1747486613474] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=4498449034229913820trackId=193726920087947402 at queue index: -1 May 17 12:56:54 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:54 volumio volumio[732]: info: [1747486614557] ControllerPandora::removeTrack May 17 12:56:54 volumio volumio[732]: info: CoreStateMachine::removeQueueItem May 17 12:56:54 volumio volumio[732]: info: CorePlayQueue::removeQueueItem 0 May 17 12:56:54 volumio volumio[732]: info: CorePlayQueue::saveQueue May 17 12:56:54 volumio volumio[732]: info: CoreCommandRouter::volumioPushQueue May 17 12:56:54 volumio volumio[732]: info: [1747486614562] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=4498449034229913820trackId=1787112181257610977 at queue index: 0 May 17 12:56:54 volumio volumio[732]: info: ControllerMpd::getState May 17 12:56:54 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand status May 17 12:56:54 volumio volumio[732]: info: sendMpdCommand status took 10 milliseconds May 17 12:56:54 volumio volumio[732]: verbose: ControllerMpd::parseState May 17 12:56:54 volumio volumio[732]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 12:56:54 volumio volumio[732]: info: sendMpdCommand playlistinfo took 2 milliseconds May 17 12:56:54 volumio volumio[732]: verbose: ControllerMpd::parseTrackInfo May 17 12:56:54 volumio volumio[732]: info: [1747486614576] ControllerPandora::pushState May 17 12:56:54 volumio volumio[732]: info: CoreCommandRouter::servicePushState May 17 12:56:54 volumio volumio[732]: info: CorePlayQueue::getTrack 14 May 17 12:56:54 volumio volumio[732]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20662,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Va Va Voom","artist":"Nicki Minaj","album":"Pink Friday ... Roman Reloaded (Deluxe Edition)","uri":"http://t1-4.p-cdn.us/access/6973261230828601962.mp3?version=5&lid=360257756&token=YPI98lQO5DOPKbpWh7dd9AIea1jBVzzXF6XGvZjJrmedbCTB6n5%2B3Y2GBFfocCb7cnRL00DPTBURP4dWu21vkw5KTo%2FMWU3IdwK9hslwkkp7ppVYZkjXjh6c1bel9yk1TpSwDxN6pgQurj1INsIFR8bihgctmfIpJTPDtpEGTcRnfBhibp9IByI0CArF7zCTgVGqI%2FKd16H0YgLkmWj%2FL9cy47X0eBbLtwq0q9JpL5155PZxqOb6GpU9dqECeyAzTdYBiVgPeXF9uWzbFYJI9Le9uqTwEsjWQ%2FqAjULJ0QsvNJu8q1oZlD%2FlR4BpxAmJKlFJwfgr3gUw0spm8gwOBg%3D%3D","trackType":"mp3"} May 17 12:56:54 volumio volumio[732]: verbose: CURRENT POSITION 14 May 17 12:56:54 volumio volumio[732]: info: CoreStateMachine::syncState stateService play May 17 12:56:54 volumio volumio[732]: info: CoreStateMachine::syncState currentStatus play May 17 12:56:54 volumio volumio[732]: info: Received an update from plugin. extracting info from payload May 17 12:56:54 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:54 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 12:56:54 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:54 volumio volumio[732]: info: CoreStateMachine::pushState May 17 12:56:54 volumio volumio[732]: info: CoreCommandRouter::volumioPushState May 17 12:56:54 volumio volumio[732]: info: CoreStateMachine::setConsumeUpdateService pandora May 17 12:56:54 volumio volumio[732]: info: CoreCommandRouter::volumioMoveQueue May 17 12:56:54 volumio volumio[732]: info: CoreStateMachine::moveQueueItem 22 17 May 17 12:56:54 volumio volumio[732]: info: CorePlayQueue::moveQueueItem 22 --> 17 May 17 12:56:54 volumio volumio[732]: info: CoreCommandRouter::volumioPushQueue May 17 12:56:54 volumio volumio[732]: info: CorePlayQueue::getQueue May 17 12:56:54 volumio volumio[732]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 17 12:56:54 volumio volumio[732]: TypeError: Cannot read property 'uri' of undefined May 17 12:56:54 volumio volumio[732]: at /data/plugins/music_service/pandora/index.js:569:51 May 17 12:56:54 volumio volumio[732]: at Array.findIndex () May 17 12:56:54 volumio volumio[732]: at ControllerPandora.getQueueIndex (/data/plugins/music_service/pandora/index.js:569:28) May 17 12:56:54 volumio volumio[732]: at ControllerPandora.removeTrack (/data/plugins/music_service/pandora/index.js:606:24) May 17 12:56:54 volumio volumio[732]: at Timeout._onTimeout (/data/plugins/music_service/pandora/helpers.js:62:13) May 17 12:56:54 volumio volumio[732]: at listOnTimeout (internal/timers.js:554:17) May 17 12:56:54 volumio volumio[732]: at processTimers (internal/timers.js:497:7) May 17 12:56:54 volumio volumio[732]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 17 12:56:55 volumio sudo[6286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-17 12:55 May 17 12:56:55 volumio sudo[6286]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="c87c6e2b1ae33dc5801986cbcbe970898b92e7b4" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="b949d6001d7e656b4668f31c119d2247cdf45158" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 30 Mar 2023 05:45:03 PM CEST" VOLUMIO_VERSION="3.449" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6428d8e2c06e98483f5c5d77e8f01989"