Apr 15 04:33:01 volumio volumio[1165]: info: CorePlayQueue::getQueue Apr 15 04:33:01 volumio volumio[1165]: info: [1776241981653] ControllerPandora::removeTrack Apr 15 04:33:01 volumio volumio[1165]: info: [1776241981653] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=214949027819556862trackId=7087562247065495403 at queue index: 22 Apr 15 04:33:11 volumio volumio[1165]: info: CorePlayQueue::getQueue Apr 15 04:33:11 volumio volumio[1165]: info: [1776241991653] ControllerPandora::removeTrack Apr 15 04:33:11 volumio volumio[1165]: info: [1776241991653] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=214949027819556862trackId=3515407146609115569 at queue index: 23 Apr 15 04:33:21 volumio go-librespot[1612]: time="2026-04-15T04:33:21-04:00" level=trace msg="sent dealer ping" Apr 15 04:33:21 volumio go-librespot[1612]: time="2026-04-15T04:33:21-04:00" level=trace msg="received dealer pong" Apr 15 04:33:21 volumio volumio[1165]: info: CorePlayQueue::getQueue Apr 15 04:33:21 volumio volumio[1165]: info: [1776242001653] ControllerPandora::removeTrack Apr 15 04:33:21 volumio volumio[1165]: info: [1776242001653] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=214949027819556862trackId=3771710494042315123 at queue index: 24 Apr 15 04:33:31 volumio volumio[1165]: info: CorePlayQueue::getQueue Apr 15 04:33:31 volumio volumio[1165]: info: [1776242011653] ControllerPandora::removeTrack Apr 15 04:33:31 volumio volumio[1165]: info: [1776242011653] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=214949027819556862trackId=1435087910529758938 at queue index: 25 Apr 15 04:33:41 volumio volumio[1165]: info: CorePlayQueue::getQueue Apr 15 04:33:41 volumio volumio[1165]: info: [1776242021653] ControllerPandora::removeTrack Apr 15 04:33:41 volumio volumio[1165]: info: [1776242021653] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=214949027819556862trackId=6612703041083572850 at queue index: 26 Apr 15 04:33:51 volumio go-librespot[1612]: time="2026-04-15T04:33:51-04:00" level=trace msg="sent dealer ping" Apr 15 04:33:51 volumio go-librespot[1612]: time="2026-04-15T04:33:51-04:00" level=trace msg="received dealer pong" Apr 15 04:33:51 volumio volumio[1165]: info: CorePlayQueue::getQueue Apr 15 04:33:51 volumio volumio[1165]: info: [1776242031653] ControllerPandora::removeTrack Apr 15 04:33:51 volumio volumio[1165]: info: [1776242031653] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=214949027819556862trackId=2302153526079628244 at queue index: 27 Apr 15 04:34:01 volumio volumio[1165]: info: CorePlayQueue::getQueue Apr 15 04:34:01 volumio volumio[1165]: info: [1776242041653] ControllerPandora::removeTrack Apr 15 04:34:01 volumio volumio[1165]: info: [1776242041653] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=214949027819556862trackId=2854073661325021745 at queue index: 28 Apr 15 04:34:11 volumio volumio[1165]: info: CorePlayQueue::getQueue Apr 15 04:34:11 volumio volumio[1165]: info: [1776242051652] ControllerPandora::removeTrack Apr 15 04:34:11 volumio volumio[1165]: info: [1776242051652] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=214949027819556862trackId=2264771579011181127 at queue index: 29 Apr 15 04:34:21 volumio go-librespot[1612]: time="2026-04-15T04:34:21-04:00" level=trace msg="sent dealer ping" Apr 15 04:34:21 volumio go-librespot[1612]: time="2026-04-15T04:34:21-04:00" level=trace msg="received dealer pong" Apr 15 04:34:21 volumio volumio[1165]: info: CorePlayQueue::getQueue Apr 15 04:34:21 volumio volumio[1165]: info: [1776242061653] ControllerPandora::removeTrack Apr 15 04:34:21 volumio volumio[1165]: info: [1776242061653] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=214949027819556862trackId=5809241870458040212 at queue index: 30 Apr 15 04:34:23 volumio volumio[1165]: info: CorePlayQueue::getTrack 14 Apr 15 04:34:23 volumio volumio[1165]: info: CorePlayQueue::getTrack 15 Apr 15 04:34:23 volumio volumio[1165]: info: Prefetching next song Apr 15 04:34:28 volumio volumio[1165]: info: Apr 15 04:34:28 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Apr 15 04:34:28 volumio volumio[1165]: info: Ignoring MPD Status Update Apr 15 04:34:28 volumio volumio[1165]: info: Apr 15 04:34:28 volumio volumio[1165]: ---------------------------- MPD announces state update: player Apr 15 04:34:28 volumio volumio[1165]: info: ControllerMpd::getState Apr 15 04:34:28 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Apr 15 04:34:28 volumio volumio[1165]: info: Apr 15 04:34:28 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Apr 15 04:34:28 volumio volumio[1165]: info: Ignoring MPD Status Update Apr 15 04:34:28 volumio volumio[1165]: info: Apr 15 04:34:28 volumio volumio[1165]: ---------------------------- MPD announces state update: player Apr 15 04:34:28 volumio volumio[1165]: info: ControllerMpd::getState Apr 15 04:34:28 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Apr 15 04:34:28 volumio volumio[1165]: info: Apr 15 04:34:28 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Apr 15 04:34:28 volumio volumio[1165]: info: Ignoring MPD Status Update Apr 15 04:34:28 volumio volumio[1165]: info: [1776242068964] ControllerPandora::pandoraListener Apr 15 04:34:28 volumio volumio[1165]: info: ControllerMpd::getState Apr 15 04:34:28 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Apr 15 04:34:28 volumio volumio[1165]: info: Apr 15 04:34:28 volumio volumio[1165]: ---------------------------- MPD announces state update: player Apr 15 04:34:28 volumio volumio[1165]: error: updateQueue error: null Apr 15 04:34:28 volumio volumio[1165]: error: updateQueue error: null Apr 15 04:34:28 volumio volumio[1165]: info: ControllerMpd::getState Apr 15 04:34:28 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Apr 15 04:34:28 volumio volumio[1165]: info: ------------------------------ 4ms Apr 15 04:34:28 volumio volumio[1165]: info: sendMpdCommand status took 3 milliseconds Apr 15 04:34:28 volumio volumio[1165]: info: ------------------------------ 3ms Apr 15 04:34:28 volumio volumio[1165]: info: sendMpdCommand status took 2 milliseconds Apr 15 04:34:28 volumio volumio[1165]: verbose: ControllerMpd::parseState Apr 15 04:34:28 volumio volumio[1165]: verbose: ControllerMpd::parseState Apr 15 04:34:28 volumio volumio[1165]: info: ControllerMpd::pushState Apr 15 04:34:28 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Apr 15 04:34:28 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 15 04:34:28 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 04:34:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 15 04:34:28 volumio volumio[1165]: info: CorePlayQueue::getTrack 14 Apr 15 04:34:28 volumio volumio[1165]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 15 04:34:28 volumio volumio[1165]: verbose: CURRENT POSITION 14 Apr 15 04:34:28 volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Apr 15 04:34:28 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Apr 15 04:34:28 volumio volumio[1165]: info: CoreStateMachine::play index undefined Apr 15 04:34:28 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 04:34:28 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 15 04:34:28 volumio volumio[1165]: info: CorePlayQueue::getTrack 15 Apr 15 04:34:28 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 04:34:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 15 04:34:28 volumio volumio[1165]: info: ControllerMpd::pushState Apr 15 04:34:28 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Apr 15 04:34:28 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 15 04:34:28 volumio volumio[1165]: info: CorePlayQueue::getTrack 15 Apr 15 04:34:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 15 04:34:28 volumio volumio[1165]: info: CorePlayQueue::getTrack 15 Apr 15 04:34:28 volumio volumio[1165]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current pandora Received mpd Apr 15 04:34:28 volumio volumio[1165]: info: CorePlayQueue::getTrack 15 Apr 15 04:34:28 volumio volumio[1165]: info: CoreStateMachine::startPlaybackTimer Apr 15 04:34:28 volumio volumio[1165]: info: CorePlayQueue::getTrack 15 Apr 15 04:34:28 volumio volumio[1165]: info: [1776242068975] ControllerPandora::clearAddPlayTrack Apr 15 04:34:28 volumio volumio[1165]: info: ControllerMpd::clear Apr 15 04:34:28 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand clear Apr 15 04:34:28 volumio volumio[1165]: info: ------------------------------ 15ms Apr 15 04:34:28 volumio volumio[1165]: info: ------------------------------ 13ms Apr 15 04:34:28 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 15 04:34:28 volumio volumio[1165]: info: CorePlayQueue::getTrack 15 Apr 15 04:34:28 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 04:34:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 15 04:34:28 volumio volumio[1165]: info: Apr 15 04:34:28 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Apr 15 04:34:28 volumio volumio[1165]: info: Ignoring MPD Status Update Apr 15 04:34:28 volumio volumio[1165]: info: Apr 15 04:34:28 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Apr 15 04:34:28 volumio volumio[1165]: info: Ignoring MPD Status Update Apr 15 04:34:28 volumio volumio[1165]: error: updateQueue error: null Apr 15 04:34:28 volumio volumio[1165]: info: Apr 15 04:34:28 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Apr 15 04:34:28 volumio volumio[1165]: info: Ignoring MPD Status Update Apr 15 04:34:28 volumio volumio[1165]: error: updateQueue error: null Apr 15 04:34:28 volumio volumio[1165]: error: updateQueue error: null Apr 15 04:34:28 volumio volumio[1165]: info: sendMpdCommand status took 20 milliseconds Apr 15 04:34:28 volumio volumio[1165]: info: ------------------------------ 21ms Apr 15 04:34:28 volumio volumio[1165]: info: sendMpdCommand status took 19 milliseconds Apr 15 04:34:28 volumio volumio[1165]: info: sendMpdCommand clear took 9 milliseconds Apr 15 04:34:28 volumio volumio[1165]: info: ------------------------------ 3ms Apr 15 04:34:28 volumio volumio[1165]: info: ------------------------------ 2ms Apr 15 04:34:28 volumio volumio[1165]: verbose: ControllerMpd::parseState Apr 15 04:34:28 volumio volumio[1165]: verbose: ControllerMpd::parseState Apr 15 04:34:28 volumio volumio[1165]: info: CorePlayQueue::getTrack 15 Apr 15 04:34:28 volumio volumio[1165]: info: [1776242068985] ControllerPandora::pushState Apr 15 04:34:28 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Apr 15 04:34:28 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 15 04:34:28 volumio volumio[1165]: info: CorePlayQueue::getTrack 15 Apr 15 04:34:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 15 04:34:28 volumio volumio[1165]: info: CorePlayQueue::getTrack 15 Apr 15 04:34:28 volumio volumio[1165]: 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"} Apr 15 04:34:28 volumio volumio[1165]: verbose: CURRENT POSITION 15 Apr 15 04:34:28 volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Apr 15 04:34:28 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Apr 15 04:34:28 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 15 04:34:28 volumio volumio[1165]: info: CorePlayQueue::getTrack 15 Apr 15 04:34:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 15 04:34:28 volumio volumio[1165]: info: No code Apr 15 04:34:28 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 15 04:34:28 volumio volumio[1165]: info: CorePlayQueue::getTrack 15 Apr 15 04:34:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 15 04:34:28 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 15 04:34:28 volumio volumio[1165]: info: ControllerMpd::pushState Apr 15 04:34:28 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Apr 15 04:34:28 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 15 04:34:28 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 04:34:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 15 04:34:28 volumio volumio[1165]: info: CorePlayQueue::getTrack 15 Apr 15 04:34:28 volumio volumio[1165]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 15 04:34:28 volumio volumio[1165]: verbose: CURRENT POSITION 15 Apr 15 04:34:28 volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Apr 15 04:34:28 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Apr 15 04:34:28 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 15 04:34:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 15 04:34:28 volumio volumio[1165]: info: No code Apr 15 04:34:28 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 15 04:34:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 15 04:34:28 volumio volumio[1165]: info: [1776242068990] ControllerPandora::checkForExpiredStations Apr 15 04:34:28 volumio volumio[1165]: info: ------------------------------ 30ms Apr 15 04:34:28 volumio volumio[1165]: info: CorePlayQueue::getQueue Apr 15 04:34:28 volumio volumio[1165]: info: [1776242068995] ControllerPandora::removeTrack Apr 15 04:34:28 volumio volumio[1165]: info: CoreStateMachine::removeQueueItem Apr 15 04:34:28 volumio volumio[1165]: info: CorePlayQueue::removeQueueItem 14 Apr 15 04:34:28 volumio volumio[1165]: info: CorePlayQueue::saveQueue Apr 15 04:34:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Apr 15 04:34:28 volumio volumio[1165]: info: [1776242068997] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=214949027819556862trackId=2858548549929497547 at queue index: 14 Apr 15 04:34:28 volumio volumio[1165]: info: ControllerMpd::getState Apr 15 04:34:28 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Apr 15 04:34:29 volumio volumio[1165]: info: [1776242069000] ControllerPandora::appendTracksToMpd Apr 15 04:34:29 volumio volumio[1165]: info: [1776242069000] [Pandora] appendTracksToMpd: Added 1 track(s) to mpd Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand addid Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand play Apr 15 04:34:29 volumio volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 15 04:34:29 volumio volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 15 04:34:29 volumio volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 15 04:34:29 volumio volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 15 04:34:29 volumio volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 15 04:34:29 volumio volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 15 04:34:29 volumio volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 15 04:34:29 volumio volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 15 04:34:29 volumio volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 15 04:34:29 volumio volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 15 04:34:29 volumio volumio[1165]: info: Apr 15 04:34:29 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Apr 15 04:34:29 volumio volumio[1165]: info: Ignoring MPD Status Update Apr 15 04:34:29 volumio volumio[1165]: info: Apr 15 04:34:29 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Apr 15 04:34:29 volumio volumio[1165]: info: Ignoring MPD Status Update Apr 15 04:34:29 volumio volumio[1165]: error: updateQueue error: null Apr 15 04:34:29 volumio volumio[1165]: info: Apr 15 04:34:29 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Apr 15 04:34:29 volumio volumio[1165]: info: Ignoring MPD Status Update Apr 15 04:34:29 volumio volumio[1165]: info: ------------------------------ 34ms Apr 15 04:34:29 volumio volumio[1165]: info: sendMpdCommand status took 20 milliseconds Apr 15 04:34:29 volumio volumio[1165]: info: sendMpdCommand addid took 17 milliseconds Apr 15 04:34:29 volumio volumio[1165]: info: sendMpdCommand play took 17 milliseconds Apr 15 04:34:29 volumio volumio[1165]: info: ------------------------------ 4ms Apr 15 04:34:29 volumio volumio[1165]: info: ------------------------------ 3ms Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::parseState Apr 15 04:34:29 volumio volumio[1165]: info: [1776242069019] ControllerPandora::pushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 04:34:29 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 15 04:34:29 volumio volumio[1165]: info: CorePlayQueue::getTrack 14 Apr 15 04:34:29 volumio volumio[1165]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received pandora Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 15 04:34:29 volumio volumio[1165]: verbose: MPD COMMAND [object Object] Apr 15 04:34:29 volumio volumio[1165]: verbose: MPD COMMAND [object Object] Apr 15 04:34:29 volumio volumio[1165]: verbose: MPD COMMAND [object Object] Apr 15 04:34:29 volumio volumio[1165]: info: [1776242069021] ControllerPandora::pushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Apr 15 04:34:29 volumio volumio[1165]: info: CorePlayQueue::getTrack 14 Apr 15 04:34:29 volumio volumio[1165]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received pandora Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 15 04:34:29 volumio volumio[1165]: info: [1776242069023] ControllerPandora::fetchAndAddTracks Apr 15 04:34:29 volumio volumio[1165]: info: [1776242069023] ControllerPandora::getStationTracks Apr 15 04:34:29 volumio volumio[1165]: info: CorePlayQueue::getQueue Apr 15 04:34:29 volumio volumio[1165]: info: [1776242069024] [Pandora] PandoraHandler::getSongMaxDiff: 2 Apr 15 04:34:29 volumio volumio[1165]: info: [1776242069024] ControllerPandora::fetchAndAddTracks::getSqInfo Apr 15 04:34:29 volumio volumio[1165]: info: [1776242069024] ControllerPandora::getStationTracks Apr 15 04:34:29 volumio volumio[1165]: info: CorePlayQueue::getQueue Apr 15 04:34:29 volumio volumio[1165]: info: CorePlayQueue::getQueue Apr 15 04:34:29 volumio volumio[1165]: info: [1776242069025] [Pandora] fetchAndAddTracks: diff1: 2 sQPos1: 0 Apr 15 04:34:29 volumio volumio[1165]: info: [1776242069025] [Pandora] fetchAndAddTracks: Not fetching tracks: qPos == 0 && diff1 >= 0 Apr 15 04:34:29 volumio volumio[1165]: info: Apr 15 04:34:29 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Apr 15 04:34:29 volumio volumio[1165]: info: Ignoring MPD Status Update Apr 15 04:34:29 volumio volumio[1165]: info: Apr 15 04:34:29 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Apr 15 04:34:29 volumio volumio[1165]: info: Ignoring MPD Status Update Apr 15 04:34:29 volumio volumio[1165]: info: Apr 15 04:34:29 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Apr 15 04:34:29 volumio volumio[1165]: info: Ignoring MPD Status Update Apr 15 04:34:29 volumio volumio[1165]: info: ------------------------------ 12ms Apr 15 04:34:29 volumio volumio[1165]: info: ------------------------------ 2ms Apr 15 04:34:29 volumio volumio[1165]: info: ------------------------------ 1ms Apr 15 04:34:29 volumio volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 15 04:34:29 volumio volumio[1165]: info: ------------------------------ 3ms Apr 15 04:34:29 volumio volumio[1165]: info: Apr 15 04:34:29 volumio volumio[1165]: ---------------------------- MPD announces state update: player Apr 15 04:34:29 volumio volumio[1165]: info: ControllerMpd::getState Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Apr 15 04:34:29 volumio volumio[1165]: info: Apr 15 04:34:29 volumio volumio[1165]: ---------------------------- MPD announces state update: player Apr 15 04:34:29 volumio volumio[1165]: info: ControllerMpd::getState Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Apr 15 04:34:29 volumio volumio[1165]: info: [1776242069100] ControllerPandora::pandoraListener Apr 15 04:34:29 volumio volumio[1165]: info: ControllerMpd::getState Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Apr 15 04:34:29 volumio volumio[1165]: info: Apr 15 04:34:29 volumio volumio[1165]: ---------------------------- MPD announces state update: player Apr 15 04:34:29 volumio volumio[1165]: info: Apr 15 04:34:29 volumio volumio[1165]: ---------------------------- MPD announces state update: player Apr 15 04:34:29 volumio volumio[1165]: info: ControllerMpd::getState Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Apr 15 04:34:29 volumio volumio[1165]: info: sendMpdCommand status took 2 milliseconds Apr 15 04:34:29 volumio volumio[1165]: info: ControllerMpd::getState Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::parseState Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 04:34:29 volumio volumio[1165]: info: Apr 15 04:34:29 volumio volumio[1165]: ---------------------------- MPD announces state update: player Apr 15 04:34:29 volumio volumio[1165]: info: ControllerMpd::getState Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Apr 15 04:34:29 volumio volumio[1165]: info: Apr 15 04:34:29 volumio volumio[1165]: ---------------------------- MPD announces state update: player Apr 15 04:34:29 volumio volumio[1165]: info: ControllerMpd::getState Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Apr 15 04:34:29 volumio volumio[1165]: info: sendMpdCommand status took 3 milliseconds Apr 15 04:34:29 volumio volumio[1165]: info: sendMpdCommand status took 3 milliseconds Apr 15 04:34:29 volumio volumio[1165]: info: sendMpdCommand status took 3 milliseconds Apr 15 04:34:29 volumio volumio[1165]: info: sendMpdCommand status took 2 milliseconds Apr 15 04:34:29 volumio volumio[1165]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 15 04:34:29 volumio volumio[1165]: info: sendMpdCommand status took 2 milliseconds Apr 15 04:34:29 volumio volumio[1165]: info: sendMpdCommand status took 2 milliseconds Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::parseState Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::parseState Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::parseState Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::parseState Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::parseState Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::parseState Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 04:34:29 volumio volumio[1165]: info: ControllerMpd::pushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Apr 15 04:34:29 volumio volumio[1165]: info: CorePlayQueue::getTrack 14 Apr 15 04:34:29 volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":172,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Starting Fresh","artist":"Bright Lens","album":"Bright Lens Ambient Vol. 2","uri":"http://t1-5.p-cdn.us/access/1053207124386947712.mp3?version=5&lid=1500937832&token=n4d286TtMIm3k9CRaOx1jBzNIiG1zAPSFfEnLH0BIl2nh%2BitMVvpTKll2CfYiH8lAU1Jf8fTVXy5G5zzPViNmRzUhAJyBrcFxTyHCdeqDliQD5bBFw2CqlnqKbqjTP7PVAS%2B9hQUsVFlSK9SCgXxEo7%2BHMnlgrbLv9KfNC%2BqI4mF4Yh4AiR%2F4wh1eV2KnO4w%2ByxJ1U0RMaesS8kW1mog1hlqymyl3RCi%2BzCy7Vhbe98l5sRYIvEIwZmk8nQac7I3f%2FHIwpsSa%2BbuEJPwgMYXff7DYFHCVyBr%2FiA5X%2BwPnzUJ8KHKTH9%2B4L6bM6BDufiW0LxZA0Yf9TGPl7w6lp84NA%3D%3D","trackType":""} Apr 15 04:34:29 volumio volumio[1165]: verbose: CURRENT POSITION 14 Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Apr 15 04:34:29 volumio volumio[1165]: info: ------------------------------ 9ms Apr 15 04:34:29 volumio volumio[1165]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 15 04:34:29 volumio volumio[1165]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 15 04:34:29 volumio volumio[1165]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 15 04:34:29 volumio volumio[1165]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 15 04:34:29 volumio volumio[1165]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 15 04:34:29 volumio volumio[1165]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Apr 15 04:34:29 volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Apr 15 04:34:29 volumio volumio[1165]: info: ControllerMpd::pushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Apr 15 04:34:29 volumio volumio[1165]: info: CorePlayQueue::getTrack 14 Apr 15 04:34:29 volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":172,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Starting Fresh","artist":"Bright Lens","album":"Bright Lens Ambient Vol. 2","uri":"http://t1-5.p-cdn.us/access/1053207124386947712.mp3?version=5&lid=1500937832&token=n4d286TtMIm3k9CRaOx1jBzNIiG1zAPSFfEnLH0BIl2nh%2BitMVvpTKll2CfYiH8lAU1Jf8fTVXy5G5zzPViNmRzUhAJyBrcFxTyHCdeqDliQD5bBFw2CqlnqKbqjTP7PVAS%2B9hQUsVFlSK9SCgXxEo7%2BHMnlgrbLv9KfNC%2BqI4mF4Yh4AiR%2F4wh1eV2KnO4w%2ByxJ1U0RMaesS8kW1mog1hlqymyl3RCi%2BzCy7Vhbe98l5sRYIvEIwZmk8nQac7I3f%2FHIwpsSa%2BbuEJPwgMYXff7DYFHCVyBr%2FiA5X%2BwPnzUJ8KHKTH9%2B4L6bM6BDufiW0LxZA0Yf9TGPl7w6lp84NA%3D%3D","trackType":""} Apr 15 04:34:29 volumio volumio[1165]: verbose: CURRENT POSITION 14 Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Apr 15 04:34:29 volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 04:34:29 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 15 04:34:29 volumio volumio[1165]: info: CorePlayQueue::getTrack 14 Apr 15 04:34:29 volumio volumio[1165]: info: [1776242069110] ControllerPandora::pushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Apr 15 04:34:29 volumio volumio[1165]: info: CorePlayQueue::getTrack 14 Apr 15 04:34:29 volumio volumio[1165]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received pandora Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 15 04:34:29 volumio volumio[1165]: info: ControllerMpd::pushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Apr 15 04:34:29 volumio volumio[1165]: info: CorePlayQueue::getTrack 14 Apr 15 04:34:29 volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":172,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Starting Fresh","artist":"Bright Lens","album":"Bright Lens Ambient Vol. 2","uri":"http://t1-5.p-cdn.us/access/1053207124386947712.mp3?version=5&lid=1500937832&token=n4d286TtMIm3k9CRaOx1jBzNIiG1zAPSFfEnLH0BIl2nh%2BitMVvpTKll2CfYiH8lAU1Jf8fTVXy5G5zzPViNmRzUhAJyBrcFxTyHCdeqDliQD5bBFw2CqlnqKbqjTP7PVAS%2B9hQUsVFlSK9SCgXxEo7%2BHMnlgrbLv9KfNC%2BqI4mF4Yh4AiR%2F4wh1eV2KnO4w%2ByxJ1U0RMaesS8kW1mog1hlqymyl3RCi%2BzCy7Vhbe98l5sRYIvEIwZmk8nQac7I3f%2FHIwpsSa%2BbuEJPwgMYXff7DYFHCVyBr%2FiA5X%2BwPnzUJ8KHKTH9%2B4L6bM6BDufiW0LxZA0Yf9TGPl7w6lp84NA%3D%3D","trackType":""} Apr 15 04:34:29 volumio volumio[1165]: verbose: CURRENT POSITION 14 Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Apr 15 04:34:29 volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 15 04:34:29 volumio volumio[1165]: info: ControllerMpd::pushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Apr 15 04:34:29 volumio volumio[1165]: info: CorePlayQueue::getTrack 14 Apr 15 04:34:29 volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":172,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Starting Fresh","artist":"Bright Lens","album":"Bright Lens Ambient Vol. 2","uri":"http://t1-5.p-cdn.us/access/1053207124386947712.mp3?version=5&lid=1500937832&token=n4d286TtMIm3k9CRaOx1jBzNIiG1zAPSFfEnLH0BIl2nh%2BitMVvpTKll2CfYiH8lAU1Jf8fTVXy5G5zzPViNmRzUhAJyBrcFxTyHCdeqDliQD5bBFw2CqlnqKbqjTP7PVAS%2B9hQUsVFlSK9SCgXxEo7%2BHMnlgrbLv9KfNC%2BqI4mF4Yh4AiR%2F4wh1eV2KnO4w%2ByxJ1U0RMaesS8kW1mog1hlqymyl3RCi%2BzCy7Vhbe98l5sRYIvEIwZmk8nQac7I3f%2FHIwpsSa%2BbuEJPwgMYXff7DYFHCVyBr%2FiA5X%2BwPnzUJ8KHKTH9%2B4L6bM6BDufiW0LxZA0Yf9TGPl7w6lp84NA%3D%3D","trackType":""} Apr 15 04:34:29 volumio volumio[1165]: verbose: CURRENT POSITION 14 Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Apr 15 04:34:29 volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 15 04:34:29 volumio volumio[1165]: info: ControllerMpd::pushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Apr 15 04:34:29 volumio volumio[1165]: info: CorePlayQueue::getTrack 14 Apr 15 04:34:29 volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":172,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Starting Fresh","artist":"Bright Lens","album":"Bright Lens Ambient Vol. 2","uri":"http://t1-5.p-cdn.us/access/1053207124386947712.mp3?version=5&lid=1500937832&token=n4d286TtMIm3k9CRaOx1jBzNIiG1zAPSFfEnLH0BIl2nh%2BitMVvpTKll2CfYiH8lAU1Jf8fTVXy5G5zzPViNmRzUhAJyBrcFxTyHCdeqDliQD5bBFw2CqlnqKbqjTP7PVAS%2B9hQUsVFlSK9SCgXxEo7%2BHMnlgrbLv9KfNC%2BqI4mF4Yh4AiR%2F4wh1eV2KnO4w%2ByxJ1U0RMaesS8kW1mog1hlqymyl3RCi%2BzCy7Vhbe98l5sRYIvEIwZmk8nQac7I3f%2FHIwpsSa%2BbuEJPwgMYXff7DYFHCVyBr%2FiA5X%2BwPnzUJ8KHKTH9%2B4L6bM6BDufiW0LxZA0Yf9TGPl7w6lp84NA%3D%3D","trackType":""} Apr 15 04:34:29 volumio volumio[1165]: verbose: CURRENT POSITION 14 Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Apr 15 04:34:29 volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 15 04:34:29 volumio volumio[1165]: info: ControllerMpd::pushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Apr 15 04:34:29 volumio volumio[1165]: info: CorePlayQueue::getTrack 14 Apr 15 04:34:29 volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":172,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Starting Fresh","artist":"Bright Lens","album":"Bright Lens Ambient Vol. 2","uri":"http://t1-5.p-cdn.us/access/1053207124386947712.mp3?version=5&lid=1500937832&token=n4d286TtMIm3k9CRaOx1jBzNIiG1zAPSFfEnLH0BIl2nh%2BitMVvpTKll2CfYiH8lAU1Jf8fTVXy5G5zzPViNmRzUhAJyBrcFxTyHCdeqDliQD5bBFw2CqlnqKbqjTP7PVAS%2B9hQUsVFlSK9SCgXxEo7%2BHMnlgrbLv9KfNC%2BqI4mF4Yh4AiR%2F4wh1eV2KnO4w%2ByxJ1U0RMaesS8kW1mog1hlqymyl3RCi%2BzCy7Vhbe98l5sRYIvEIwZmk8nQac7I3f%2FHIwpsSa%2BbuEJPwgMYXff7DYFHCVyBr%2FiA5X%2BwPnzUJ8KHKTH9%2B4L6bM6BDufiW0LxZA0Yf9TGPl7w6lp84NA%3D%3D","trackType":""} Apr 15 04:34:29 volumio volumio[1165]: verbose: CURRENT POSITION 14 Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Apr 15 04:34:29 volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreStateMachine::pushState Apr 15 04:34:29 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Apr 15 04:34:29 volumio volumio[1165]: info: ------------------------------ 41ms Apr 15 04:34:29 volumio volumio[1165]: info: ------------------------------ 41ms Apr 15 04:34:29 volumio volumio[1165]: info: ------------------------------ 43ms Apr 15 04:34:29 volumio volumio[1165]: info: ------------------------------ 42ms Apr 15 04:34:29 volumio volumio[1165]: info: ------------------------------ 43ms Apr 15 04:34:29 volumio volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 15 04:34:29 volumio volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 15 04:34:29 volumio volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 15 04:34:29 volumio volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 15 04:34:29 volumio volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 15 04:34:29 volumio volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 15 04:34:29 volumio volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 15 04:34:29 volumio volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 15 04:34:29 volumio volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 15 04:34:29 volumio volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 15 04:34:31 volumio volumio[1165]: info: CorePlayQueue::getQueue Apr 15 04:34:31 volumio volumio[1165]: info: [1776242071653] ControllerPandora::removeTrack Apr 15 04:34:31 volumio volumio[1165]: info: [1776242071653] [Pandora] removeTrack: Not removing track with uri: /pandora/stationToken=214949027819556862trackId=6137445566032852969 at queue index: 30 Apr 15 04:34:36 volumio volumio[1165]: info: [ytmusic] Refresh session PO token Apr 15 04:34:36 volumio volumio[1165]: info: [ytmusic] Obtained session PO token using visitorData (expires in 43101 seconds) Apr 15 04:34:36 volumio volumio[1165]: info: [ytmusic] Going to refresh session PO token in 43001 seconds Apr 15 04:34:36 volumio volumio[1165]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 04:34:36 volumio volumio[1165]: TypeError: Chaining cycle detected for promise # Apr 15 04:34:36 volumio volumio[1165]: at #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:128:21) Apr 15 04:34:36 volumio volumio[1165]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 15 04:34:36 volumio volumio[1165]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 04:34:36 volumio sudo[13693]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-15 04:33' Apr 15 04:34:36 volumio sudo[13693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"