-- Logs begin at Sat 2025-12-06 12:42:41 PST, end at Sat 2025-12-06 18:06:16 PST. -- Dec 06 18:05:04 volumio-nanopi volumio-remote-updater[719]: [2025-12-06 18:05:04] [connect] Successful connection Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: CorePlayQueue::getQueue Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: [1765073113161] ControllerPandora::removeTrack Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: CoreStateMachine::removeQueueItem Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: CorePlayQueue::removeQueueItem 1 Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: CorePlayQueue::saveQueue Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushQueue Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: [1765073113198] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=118553743900832797trackId=8890568024179670187 at queue index: 1 Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: ControllerMpd::getState Dec 06 18:05:13 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: [1765073113204] [Pandora] ExpireOldTracks::reaper::voorhees: Expired Dark Eyes by DeVotchKa Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: CorePlayQueue::getQueue Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: [1765073113235] ControllerPandora::removeTrack Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: CoreStateMachine::removeQueueItem Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: CorePlayQueue::removeQueueItem 1 Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: CorePlayQueue::saveQueue Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushQueue Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: [1765073113250] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=118553743900832797trackId=5959697942436045811 at queue index: 1 Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: ControllerMpd::getState Dec 06 18:05:13 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: [1765073113252] [Pandora] ExpireOldTracks::reaper::voorhees: Expired This Is My Life by Firewater Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: CorePlayQueue::getQueue Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: [1765073113276] ControllerPandora::removeTrack Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: CoreStateMachine::removeQueueItem Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: CorePlayQueue::removeQueueItem 1 Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: CorePlayQueue::saveQueue Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushQueue Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: [1765073113290] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=118553743900832797trackId=5731781686353229115 at queue index: 1 Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: ControllerMpd::getState Dec 06 18:05:13 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Dec 06 18:05:13 volumio-nanopi volumio[1060]: info: [1765073113293] [Pandora] ExpireOldTracks::reaper::voorhees: Expired La Partida (the Parting) by Cafe Musique Dec 06 18:05:19 volumio-nanopi volumio-remote-updater[719]: [2025-12-06 18:05:19] [connect] Successful connection Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: [1765073128152] ControllerPandora::pandoraListener Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: ControllerMpd::getState Dec 06 18:05:28 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: Dec 06 18:05:28 volumio-nanopi volumio[1060]: ---------------------------- MPD announces state update: player Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: ControllerMpd::getState Dec 06 18:05:28 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: sendMpdCommand status took 14966 milliseconds Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: sendMpdCommand status took 14920 milliseconds Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: sendMpdCommand status took 14880 milliseconds Dec 06 18:05:28 volumio-nanopi volumio[1060]: verbose: ControllerMpd::parseState Dec 06 18:05:28 volumio-nanopi volumio[1060]: verbose: ControllerMpd::parseState Dec 06 18:05:28 volumio-nanopi volumio[1060]: verbose: ControllerMpd::parseState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: [1765073128186] ControllerPandora::pushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreCommandRouter::servicePushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreStateMachine::pushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 0 Dec 06 18:05:28 volumio-nanopi volumio[1060]: 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"} Dec 06 18:05:28 volumio-nanopi volumio[1060]: verbose: CURRENT POSITION 0 Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreStateMachine::syncState stateService stop Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreStateMachine::syncState currentStatus play Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreStateMachine::play index undefined Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreStateMachine::pushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 1 Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreStateMachine::setConsumeUpdateService pandora Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: [1765073128242] ControllerPandora::pushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreCommandRouter::servicePushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreStateMachine::pushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 1 Dec 06 18:05:28 volumio-nanopi volumio[1060]: 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"} Dec 06 18:05:28 volumio-nanopi volumio[1060]: verbose: CURRENT POSITION 1 Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreStateMachine::syncState stateService stop Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreStateMachine::pushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: No code Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreStateMachine::pushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreStateMachine::setConsumeUpdateService pandora Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: [1765073128271] ControllerPandora::pushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreCommandRouter::servicePushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreStateMachine::pushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 1 Dec 06 18:05:28 volumio-nanopi volumio[1060]: 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"} Dec 06 18:05:28 volumio-nanopi volumio[1060]: verbose: CURRENT POSITION 1 Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreStateMachine::syncState stateService stop Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreStateMachine::pushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: No code Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreStateMachine::pushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreStateMachine::setConsumeUpdateService pandora Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 1 Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreStateMachine::startPlaybackTimer Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 1 Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: [1765073128307] ControllerPandora::clearAddPlayTrack Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: ControllerMpd::clear Dec 06 18:05:28 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand clear Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreStateMachine::pushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: Dec 06 18:05:28 volumio-nanopi volumio[1060]: ---------------------------- MPD announces state update: player Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: ControllerMpd::getState Dec 06 18:05:28 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: Dec 06 18:05:28 volumio-nanopi volumio[1060]: ---------------------------- MPD announces state update: player Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: ControllerMpd::getState Dec 06 18:05:28 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: Dec 06 18:05:28 volumio-nanopi volumio[1060]: ---------------------------- MPD announces state update: player Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: ControllerMpd::getState Dec 06 18:05:28 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Dec 06 18:05:28 volumio-nanopi volumio[1060]: info: camilladsp spawned new process with pid 12488, instance 1, run: true Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: Dec 06 18:05:29 volumio-nanopi volumio[1060]: ---------------------------- MPD announces system playlist update Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: Ignoring MPD Status Update Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: sendMpdCommand status took 1039 milliseconds Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: sendMpdCommand status took 1031 milliseconds Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: sendMpdCommand clear took 888 milliseconds Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: sendMpdCommand status took 866 milliseconds Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: sendMpdCommand status took 861 milliseconds Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: sendMpdCommand status took 857 milliseconds Dec 06 18:05:29 volumio-nanopi volumio[1060]: verbose: ControllerMpd::parseState Dec 06 18:05:29 volumio-nanopi volumio[1060]: verbose: ControllerMpd::parseState Dec 06 18:05:29 volumio-nanopi volumio[1060]: verbose: ControllerMpd::parseState Dec 06 18:05:29 volumio-nanopi volumio[1060]: verbose: ControllerMpd::parseState Dec 06 18:05:29 volumio-nanopi volumio[1060]: verbose: ControllerMpd::parseState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 1 Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: [1765073129218] ControllerPandora::pushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreCommandRouter::servicePushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreStateMachine::pushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 1 Dec 06 18:05:29 volumio-nanopi volumio[1060]: 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"} Dec 06 18:05:29 volumio-nanopi volumio[1060]: verbose: CURRENT POSITION 1 Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreStateMachine::syncState stateService stop Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreStateMachine::pushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: No code Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreStateMachine::pushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreStateMachine::setConsumeUpdateService pandora Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: ControllerMpd::pushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreCommandRouter::servicePushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreStateMachine::pushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 1 Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: [1765073129260] ControllerPandora::checkForExpiredStations Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: [1765073129261] [Pandora] checkForExpiredStations: Stations expired Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: [1765073129263] ControllerPandora::PandoraHandler::fillStationData Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: ControllerMpd::pushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreCommandRouter::servicePushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreStateMachine::pushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 1 Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: ControllerMpd::pushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreCommandRouter::servicePushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreStateMachine::pushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 1 Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: ControllerMpd::pushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreCommandRouter::servicePushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreStateMachine::pushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 1 Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: ------------------------------ 1218ms Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: ------------------------------ 1046ms Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: ------------------------------ 1041ms Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: ------------------------------ 1037ms Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: Dec 06 18:05:29 volumio-nanopi volumio[1060]: ---------------------------- MPD announces system playlist update Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: Ignoring MPD Status Update Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: Dec 06 18:05:29 volumio-nanopi volumio[1060]: ---------------------------- MPD announces system playlist update Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: Ignoring MPD Status Update Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: Dec 06 18:05:29 volumio-nanopi volumio[1060]: ---------------------------- MPD announces system playlist update Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: Ignoring MPD Status Update Dec 06 18:05:29 volumio-nanopi volumio[1060]: verbose: New Socket.io Connection to 192.168.254.100:3000 from 192.168.254.114 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Dec 06 18:05:29 volumio-nanopi volumio[1060]: error: updateQueue error: null Dec 06 18:05:29 volumio-nanopi volumio[1060]: error: updateQueue error: null Dec 06 18:05:29 volumio-nanopi volumio[1060]: error: updateQueue error: null Dec 06 18:05:29 volumio-nanopi volumio[1060]: error: updateQueue error: null Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: ------------------------------ 357ms Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: ------------------------------ 164ms Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: ------------------------------ 160ms Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: ------------------------------ 157ms Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: Discovery: Getting this device information Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 06 18:05:29 volumio-nanopi volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CorePlayQueue::getQueue Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: [1765073130002] ControllerPandora::removeTrack Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreStateMachine::removeQueueItem Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CorePlayQueue::removeQueueItem 0 Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CorePlayQueue::saveQueue Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushQueue Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: [1765073130014] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=118553743900832797trackId=2763489026726000368 at queue index: 0 Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ControllerMpd::getState Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: [1765073130027] ControllerPandora::appendTracksToMpd Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: [1765073130030] [Pandora] appendTracksToMpd: Added 1 track(s) to mpd Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand addid Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand play Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Dec 06 18:05:30 volumio-nanopi volumio[1060]: ---------------------------- MPD announces system playlist update Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Ignoring MPD Status Update Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: sendMpdCommand status took 34 milliseconds Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: sendMpdCommand addid took 16 milliseconds Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: sendMpdCommand play took 15 milliseconds Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::parseState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: [1765073130063] ControllerPandora::pushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::servicePushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreStateMachine::pushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 0 Dec 06 18:05:30 volumio-nanopi volumio[1060]: 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"} Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: CURRENT POSITION 0 Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreStateMachine::syncState stateService stop Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreStateMachine::pushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: No code Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreStateMachine::pushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreStateMachine::setConsumeUpdateService pandora Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: MPD COMMAND [object Object] Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: MPD COMMAND [object Object] Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: MPD COMMAND [object Object] Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: [1765073130100] ControllerPandora::pushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::servicePushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 0 Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"The Weed Smoker's Dream","name":"The Weed Smoker's Dream","artist":"Hugh Laurie","album":"Didn't It Rain","albumart":"http://cont-3.p-cdn.us/images/54/09/18/64/365c4889b313ccae83aa6182/1080W_1080H.jpg","realUri":"http://audio-sv5-t1-1-v4v6.pandora.com/access/4179617872096272581.mp3?version=5&lid=1075995428&token=%2BY%2Fstu6vkEw7HiQJy3HLc3meLOEIcP%2FYU3qKQW%2Fcn0vCxSrJGrbidgrdCr7dGexpvFEW0D%2FS48SPoqodXDw8WSCQRn9bRzQd8yh0F89aLXjxSB%2FygYqmh%2BYWY4SU%2FkBLDcH7F8Q4mAVMYXk%2Fff3xAIQL%2BfUdjn54ryXAF4yq8jJcMFE1GLN%2BoWpsYeeg6NZH60Ylpuhq0Of8obQZATkmNNnKLz%2BXDly9VGrB4R0ce%2FWKIYl%2FPuCuvwKPwNXm7kaEe2%2BLghSRsvZOWUmesCfZoGu4m3qOObAlbIsD1tyDHVVKiPt1hH9go4LDBiM%2FGB4h9naxQf7Trgm3SILP1SfHLg%3D%3D","isStreaming":true,"duration":258,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://audio-sv5-t1-1-v4v6.pandora.com/access/4179617872096272581.mp3?version=5&lid=1075995428&token=%2BY%2Fstu6vkEw7HiQJy3HLc3meLOEIcP%2FYU3qKQW%2Fcn0vCxSrJGrbidgrdCr7dGexpvFEW0D%2FS48SPoqodXDw8WSCQRn9bRzQd8yh0F89aLXjxSB%2FygYqmh%2BYWY4SU%2FkBLDcH7F8Q4mAVMYXk%2Fff3xAIQL%2BfUdjn54ryXAF4yq8jJcMFE1GLN%2BoWpsYeeg6NZH60Ylpuhq0Of8obQZATkmNNnKLz%2BXDly9VGrB4R0ce%2FWKIYl%2FPuCuvwKPwNXm7kaEe2%2BLghSRsvZOWUmesCfZoGu4m3qOObAlbIsD1tyDHVVKiPt1hH9go4LDBiM%2FGB4h9naxQf7Trgm3SILP1SfHLg%3D%3D","seek":0,"status":"play"} Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: CURRENT POSITION 0 Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreStateMachine::syncState stateService play Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreStateMachine::setConsumeUpdateService pandora Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: [1765073130119] ControllerPandora::fetchAndAddTracks Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: [1765073130122] ControllerPandora::getStationTracks Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CorePlayQueue::getQueue Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: [1765073130128] [Pandora] PandoraHandler::getSongMaxDiff: -4 Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: [1765073130133] ControllerPandora::fetchAndAddTracks::getSqInfo Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: [1765073130134] ControllerPandora::getStationTracks Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CorePlayQueue::getQueue Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CorePlayQueue::getQueue Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: [1765073130143] [Pandora] fetchAndAddTracks: diff1: -4 sQPos1: 0 Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: [1765073130145] [Pandora] fetchAndAddTracks: Fetching tracks Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: [1765073130149] ControllerPandora::PandoraHandler::fillStationData Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Dec 06 18:05:30 volumio-nanopi volumio[1060]: ---------------------------- MPD announces system playlist update Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Ignoring MPD Status Update Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Dec 06 18:05:30 volumio-nanopi volumio[1060]: ---------------------------- MPD announces system playlist update Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Ignoring MPD Status Update Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Dec 06 18:05:30 volumio-nanopi volumio[1060]: ---------------------------- MPD announces system playlist update Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Ignoring MPD Status Update Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Dec 06 18:05:30 volumio-nanopi volumio[1060]: ---------------------------- MPD announces system playlist update Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Ignoring MPD Status Update Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: [1765073130247] ControllerPandora::pandoraListener Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ControllerMpd::getState Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Dec 06 18:05:30 volumio-nanopi volumio[1060]: ---------------------------- MPD announces state update: player Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Dec 06 18:05:30 volumio-nanopi volumio[1060]: ---------------------------- MPD announces state update: player Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ------------------------------ 208ms Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ------------------------------ 95ms Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ControllerMpd::getState Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ------------------------------ 96ms Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ------------------------------ 92ms Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ControllerMpd::getState Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Dec 06 18:05:30 volumio-nanopi volumio[1060]: ---------------------------- MPD announces system playlist update Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Ignoring MPD Status Update Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Dec 06 18:05:30 volumio-nanopi volumio[1060]: ---------------------------- MPD announces state update: player Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ControllerMpd::getState Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Dec 06 18:05:30 volumio-nanopi volumio[1060]: ---------------------------- MPD announces system playlist update Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Ignoring MPD Status Update Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Dec 06 18:05:30 volumio-nanopi volumio[1060]: ---------------------------- MPD announces state update: player Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ControllerMpd::getState Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Dec 06 18:05:30 volumio-nanopi volumio[1060]: ---------------------------- MPD announces system playlist update Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Ignoring MPD Status Update Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Dec 06 18:05:30 volumio-nanopi volumio[1060]: ---------------------------- MPD announces state update: player Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ControllerMpd::getState Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: FusionDsp - Loudness is ON true Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: sendMpdCommand status took 154 milliseconds Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ------------------------------ 164ms Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: sendMpdCommand status took 143 milliseconds Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: sendMpdCommand status took 142 milliseconds Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ------------------------------ 140ms Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: sendMpdCommand status took 136 milliseconds Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ------------------------------ 135ms Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: sendMpdCommand status took 130 milliseconds Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ------------------------------ 130ms Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: sendMpdCommand status took 126 milliseconds Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::parseState Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::parseState Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::parseState Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::parseState Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::parseState Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::parseState Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Dec 06 18:05:30 volumio-nanopi volumio[1060]: ---------------------------- MPD announces state update: player Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ControllerMpd::getState Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Dec 06 18:05:30 volumio-nanopi volumio[1060]: ---------------------------- MPD announces state update: player Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ControllerMpd::getState Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Dec 06 18:05:30 volumio-nanopi volumio[1060]: ---------------------------- MPD announces state update: player Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ControllerMpd::getState Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: sendMpdCommand playlistinfo took 66 milliseconds Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: sendMpdCommand playlistinfo took 63 milliseconds Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: sendMpdCommand playlistinfo took 60 milliseconds Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: sendMpdCommand playlistinfo took 57 milliseconds Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: sendMpdCommand playlistinfo took 53 milliseconds Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: sendMpdCommand playlistinfo took 50 milliseconds Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: sendMpdCommand status took 36 milliseconds Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: sendMpdCommand status took 30 milliseconds Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: sendMpdCommand status took 26 milliseconds Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::parseTrackInfo Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::parseTrackInfo Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::parseState Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::parseState Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::parseState Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 0 Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: [1765073130540] ControllerPandora::pushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::servicePushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 0 Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1360,"duration":258,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Weed Smoker's Dream","artist":"Hugh Laurie","album":"Didn't It Rain","uri":"http://audio-sv5-t1-1-v4v6.pandora.com/access/4179617872096272581.mp3?version=5&lid=1075995428&token=%2BY%2Fstu6vkEw7HiQJy3HLc3meLOEIcP%2FYU3qKQW%2Fcn0vCxSrJGrbidgrdCr7dGexpvFEW0D%2FS48SPoqodXDw8WSCQRn9bRzQd8yh0F89aLXjxSB%2FygYqmh%2BYWY4SU%2FkBLDcH7F8Q4mAVMYXk%2Fff3xAIQL%2BfUdjn54ryXAF4yq8jJcMFE1GLN%2BoWpsYeeg6NZH60Ylpuhq0Of8obQZATkmNNnKLz%2BXDly9VGrB4R0ce%2FWKIYl%2FPuCuvwKPwNXm7kaEe2%2BLghSRsvZOWUmesCfZoGu4m3qOObAlbIsD1tyDHVVKiPt1hH9go4LDBiM%2FGB4h9naxQf7Trgm3SILP1SfHLg%3D%3D","trackType":"mp3"} Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: CURRENT POSITION 0 Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreStateMachine::syncState stateService play Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreStateMachine::syncState currentStatus play Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Received an update from plugin. extracting info from payload Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreStateMachine::pushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreStateMachine::pushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreStateMachine::setConsumeUpdateService pandora Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ControllerMpd::pushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::servicePushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 0 Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ControllerMpd::pushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::servicePushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 0 Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ControllerMpd::pushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::servicePushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 0 Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ControllerMpd::pushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::servicePushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 0 Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ControllerMpd::pushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::servicePushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 0 Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ------------------------------ 348ms Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ------------------------------ 346ms Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ------------------------------ 324ms Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ------------------------------ 314ms Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ------------------------------ 305ms Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: sendMpdCommand playlistinfo took 112 milliseconds Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: sendMpdCommand playlistinfo took 112 milliseconds Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: ControllerMpd::parseTrackInfo Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ControllerMpd::pushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::servicePushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 0 Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ControllerMpd::pushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::servicePushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 0 Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ControllerMpd::pushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::servicePushState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CorePlayQueue::getTrack 0 Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ------------------------------ 200ms Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ------------------------------ 191ms Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: ------------------------------ 187ms Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: New Socket.io Connection to 192.168.254.100 from 192.168.254.114 UA: Mozilla/5.0 (Linux; Android 14; T614D Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.171 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Dec 06 18:05:30 volumio-nanopi volumio[1060]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: [1765073130921] ControllerPandora::PandoraHandler::fetchTracks::fetchStationPlaylist Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioGetVisibleSources Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Received Get System Info Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: Discovery: Getting this device information Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 18:05:30 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: Listing playlists Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioGetQueue Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreStateMachine::getQueue Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CorePlayQueue::getQueue Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:31 volumio-nanopi volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 18:05:34 volumio-nanopi volumio-remote-updater[719]: [2025-12-06 18:05:34] [connect] Successful connection Dec 06 18:05:49 volumio-nanopi volumio-remote-updater[719]: [2025-12-06 18:05:49] [connect] Successful connection Dec 06 18:06:04 volumio-nanopi volumio-remote-updater[719]: [2025-12-06 18:06:04] [connect] Successful connection Dec 06 18:06:11 volumio-nanopi volumio[1060]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 18:06:11 volumio-nanopi volumio[1060]: SyntaxError: Unexpected end of JSON input Dec 06 18:06:11 volumio-nanopi volumio[1060]: at JSON.parse () Dec 06 18:06:11 volumio-nanopi volumio[1060]: at Request._callback (/data/plugins/music_service/pandora/node_modules/anesidora/anesidora.js:41:31) Dec 06 18:06:11 volumio-nanopi volumio[1060]: at Request.self.callback (/data/plugins/music_service/pandora/node_modules/request/request.js:185:22) Dec 06 18:06:11 volumio-nanopi volumio[1060]: at Request.emit (events.js:400:28) Dec 06 18:06:11 volumio-nanopi volumio[1060]: at Request. (/data/plugins/music_service/pandora/node_modules/request/request.js:1154:10) Dec 06 18:06:11 volumio-nanopi volumio[1060]: at Request.emit (events.js:400:28) Dec 06 18:06:11 volumio-nanopi volumio[1060]: at IncomingMessage. (/data/plugins/music_service/pandora/node_modules/request/request.js:1076:12) Dec 06 18:06:11 volumio-nanopi volumio[1060]: at Object.onceWrapper (events.js:519:28) Dec 06 18:06:11 volumio-nanopi volumio[1060]: at IncomingMessage.emit (events.js:412:35) Dec 06 18:06:11 volumio-nanopi volumio[1060]: at IncomingMessage. (/data/plugins/music_service/pandora/node_modules/request/request.js:993:23) Dec 06 18:06:11 volumio-nanopi volumio[1060]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 18:06:16 volumio-nanopi sudo[12626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-06 18:05 Dec 06 18:06:16 volumio-nanopi sudo[12626]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 17 Nov 2025 09:38:04 PM CET" VOLUMIO_VERSION="3.875" VOLUMIO_HARDWARE="orangepipc" VOLUMIO_DEVICENAME="Orange Pi PC" VOLUMIO_HASH="f14a1b6604a50dfb96eb025c18ac7629"