Jun 06 13:09:08 musicbox volumio[5336]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/pandora/volumio/bookworm/armhf Jun 06 13:09:10 musicbox volumio[5336]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/pandora/volumio/bookworm/armhf Jun 06 13:09:10 musicbox volumio[5336]: info: Folder /tmp/plugins removed Jun 06 13:09:11 musicbox volumio[5336]: info: Check plugin dependencies Jun 06 13:09:11 musicbox volumio[5336]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 06 13:09:11 musicbox volumio[5336]: info: Checking if plugin already exists Jun 06 13:09:11 musicbox volumio[5336]: info: Rename folder Jun 06 13:09:11 musicbox volumio[5336]: info: Folder /tmp/downloaded_plugin.zip removed Jun 06 13:09:11 musicbox volumio[5336]: info: Move to category Jun 06 13:09:13 musicbox volumio[5336]: info: Checking if install.sh is present Jun 06 13:09:13 musicbox volumio[5336]: info: Executing install.sh Jun 06 13:09:13 musicbox sudo[7333]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/pandora/install.sh Jun 06 13:09:13 musicbox sudo[7333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 13:09:13 musicbox sudo[7333]: pam_unix(sudo:session): session closed for user root Jun 06 13:09:13 musicbox volumio[5336]: info: Installing Pandora Dependencies Jun 06 13:09:13 musicbox volumio[5336]: Plugin install end detected on script Jun 06 13:09:13 musicbox volumio[5336]: info: Install script completed Jun 06 13:09:13 musicbox volumio[5336]: info: Adding reference to registry Jun 06 13:09:13 musicbox volumio[5336]: info: Done installing plugin. Jun 06 13:09:13 musicbox volumio[5336]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 06 13:09:13 musicbox volumio[5336]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 06 13:09:13 musicbox volumio[5336]: info: Folder /tmp/plugins removed Jun 06 13:09:13 musicbox volumio[5336]: info: Folder /tmp/downloaded_plugin.zip removed Jun 06 13:09:13 musicbox volumio[5336]: info: Folder /data/temp removed Jun 06 13:09:16 musicbox volumio[5336]: info: Enabling plugin pandora Jun 06 13:09:16 musicbox volumio[5336]: info: Loading plugin "pandora"... Jun 06 13:09:18 musicbox volumio[5336]: info: PLUGIN START: pandora Jun 06 13:09:18 musicbox volumio[5336]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 13:09:18 musicbox volumio[5336]: info: [1749233358029] CoreMusicLibrary::Adding element Pandora Radio Jun 06 13:09:18 musicbox volumio[5336]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 13:09:18 musicbox volumio[5336]: Cannot find translation for source YouTube Music Jun 06 13:09:18 musicbox volumio[5336]: Cannot find translation for source Pandora Radio Jun 06 13:09:18 musicbox volumio[5336]: info: [1749233358038] ControllerPandora::initializeMQTT Jun 06 13:09:18 musicbox volumio[5336]: info: [1749233358039] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Jun 06 13:09:18 musicbox volumio[5336]: info: [1749233358041] ControllerPandora::initialSetup Jun 06 13:09:18 musicbox volumio[5336]: info: [1749233358045] ControllerPandora::PandoraHandler::init Jun 06 13:09:18 musicbox volumio[5336]: info: [1749233358046] ControllerPandora::PandoraHandler::loadErrorCodes Jun 06 13:09:18 musicbox volumio[5336]: info: [1749233358048] [Pandora] PandoraHandler::setMaxStationTracks: 16 Jun 06 13:09:18 musicbox volumio[5336]: info: [1749233358050] [Pandora] PandoraHandler::setBandFilter: bandfilter is empty Jun 06 13:09:18 musicbox volumio[5336]: info: [1749233358051] ControllerPandora::flushPandora Jun 06 13:09:18 musicbox volumio[5336]: info: CorePlayQueue::getQueue Jun 06 13:09:18 musicbox volumio[5336]: info: CorePlayQueue::clearAddPlayQueue Jun 06 13:09:18 musicbox volumio[5336]: info: CorePlayQueue::saveQueue Jun 06 13:09:18 musicbox volumio[5336]: info: CoreCommandRouter::serviceClearAddPlayTracks Jun 06 13:09:18 musicbox volumio[5336]: error: WARNING: No clearAddPlayTracks method for service webradio Jun 06 13:09:18 musicbox volumio[5336]: info: CoreCommandRouter::volumioPushQueue Jun 06 13:09:18 musicbox volumio[5336]: info: [1749233358061] ControllerPandora::validateAndSetAccountOptions Jun 06 13:09:18 musicbox volumio[5336]: error: [1749233358063] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Jun 06 13:09:18 musicbox volumio[5336]: info: Done. Jun 06 13:09:26 musicbox systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 06 13:09:40 musicbox volumio[5336]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 06 13:10:04 musicbox systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 06 13:10:04 musicbox dbus-daemon[570]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.269' (uid=0 pid=7510 comm="timedatectl show --property=NTPSynchronized --valu") Jun 06 13:10:04 musicbox systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 06 13:10:04 musicbox volumio[5336]: info: Jun 06 13:10:04 musicbox volumio[5336]: ---------------------------- MPD announces system playlist update Jun 06 13:10:04 musicbox volumio[5336]: info: Ignoring MPD Status Update Jun 06 13:10:04 musicbox volumio[5336]: info: Jun 06 13:10:04 musicbox volumio[5336]: ---------------------------- MPD announces system playlist update Jun 06 13:10:04 musicbox volumio[5336]: info: Ignoring MPD Status Update Jun 06 13:10:04 musicbox volumio[5336]: info: Jun 06 13:10:04 musicbox volumio[5336]: ---------------------------- MPD announces system playlist update Jun 06 13:10:04 musicbox volumio[5336]: info: Ignoring MPD Status Update Jun 06 13:10:04 musicbox volumio[5336]: info: Jun 06 13:10:04 musicbox volumio[5336]: ---------------------------- MPD announces state update: player Jun 06 13:10:04 musicbox volumio[5336]: info: ------------------------------ 11ms Jun 06 13:10:04 musicbox volumio[5336]: info: ControllerMpd::getState Jun 06 13:10:04 musicbox volumio[5336]: verbose: ControllerMpd::sendMpdCommand status Jun 06 13:10:04 musicbox volumio[5336]: info: ------------------------------ 10ms Jun 06 13:10:04 musicbox volumio[5336]: info: Jun 06 13:10:04 musicbox volumio[5336]: ---------------------------- MPD announces state update: player Jun 06 13:10:04 musicbox volumio[5336]: info: ControllerMpd::getState Jun 06 13:10:04 musicbox volumio[5336]: verbose: ControllerMpd::sendMpdCommand status Jun 06 13:10:04 musicbox volumio[5336]: info: Jun 06 13:10:04 musicbox volumio[5336]: ---------------------------- MPD announces state update: player Jun 06 13:10:04 musicbox volumio[5336]: info: ControllerMpd::getState Jun 06 13:10:04 musicbox volumio[5336]: verbose: ControllerMpd::sendMpdCommand status Jun 06 13:10:04 musicbox volumio[5336]: info: ------------------------------ 20ms Jun 06 13:10:04 musicbox volumio[5336]: info: sendMpdCommand status took 18 milliseconds Jun 06 13:10:04 musicbox volumio[5336]: info: sendMpdCommand status took 11 milliseconds Jun 06 13:10:04 musicbox volumio[5336]: info: sendMpdCommand status took 7 milliseconds Jun 06 13:10:04 musicbox volumio[5336]: verbose: ControllerMpd::parseState Jun 06 13:10:04 musicbox volumio[5336]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 13:10:04 musicbox volumio[5336]: verbose: ControllerMpd::parseState Jun 06 13:10:04 musicbox volumio[5336]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 13:10:04 musicbox volumio[5336]: verbose: ControllerMpd::parseState Jun 06 13:10:04 musicbox volumio[5336]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 13:10:04 musicbox dbus-daemon[570]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 06 13:10:04 musicbox systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 06 13:10:04 musicbox volumio[5336]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 06 13:10:04 musicbox volumio[5336]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 06 13:10:04 musicbox volumio[5336]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 06 13:10:04 musicbox volumio[5336]: verbose: ControllerMpd::parseTrackInfo Jun 06 13:10:04 musicbox volumio[5336]: verbose: ControllerMpd::parseTrackInfo Jun 06 13:10:04 musicbox volumio[5336]: verbose: ControllerMpd::parseTrackInfo Jun 06 13:10:04 musicbox volumio[5336]: info: ControllerMpd::pushState Jun 06 13:10:04 musicbox volumio[5336]: info: CoreCommandRouter::servicePushState Jun 06 13:10:04 musicbox volumio[5336]: info: CorePlayQueue::getTrack 0 Jun 06 13:10:04 musicbox volumio[5336]: verbose: STATE SERVICE {"status":"play","position":0,"seek":151111,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"47 Kbps","isStreaming":false,"title":"Benny Turner - Homesick Blues","artist":"A Fine Jazz Gumbo Radio","album":null,"uri":"https://streaming.smartradio.ch:9502/stream","trackType":""} Jun 06 13:10:04 musicbox volumio[5336]: verbose: CURRENT POSITION 0 Jun 06 13:10:04 musicbox volumio[5336]: info: CoreStateMachine::syncState stateService play Jun 06 13:10:04 musicbox volumio[5336]: info: CoreStateMachine::syncState currentStatus play Jun 06 13:10:04 musicbox setdatetime-helper.sh[7509]: Time is already synchronized. Jun 06 13:10:04 musicbox volumio[5336]: info: Received an update from plugin. extracting info from payload Jun 06 13:10:04 musicbox volumio[5336]: info: CoreStateMachine::pushState Jun 06 13:10:04 musicbox volumio[5336]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 13:10:04 musicbox volumio[5336]: info: CoreCommandRouter::volumioPushState Jun 06 13:10:04 musicbox systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 06 13:10:04 musicbox systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 06 13:10:04 musicbox volumio[5336]: info: CoreStateMachine::pushState Jun 06 13:10:04 musicbox volumio[5336]: info: CoreCommandRouter::volumioPushState Jun 06 13:10:04 musicbox volumio[5336]: info: ControllerMpd::pushState Jun 06 13:10:04 musicbox volumio[5336]: info: CoreCommandRouter::servicePushState Jun 06 13:10:04 musicbox volumio[5336]: info: CorePlayQueue::getTrack 0 Jun 06 13:10:04 musicbox volumio[5336]: verbose: STATE SERVICE {"status":"play","position":0,"seek":151111,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"47 Kbps","isStreaming":false,"title":"Benny Turner - Homesick Blues","artist":"A Fine Jazz Gumbo Radio","album":null,"uri":"https://streaming.smartradio.ch:9502/stream","trackType":""} Jun 06 13:10:04 musicbox volumio[5336]: verbose: CURRENT POSITION 0 Jun 06 13:10:04 musicbox volumio[5336]: info: CoreStateMachine::syncState stateService play Jun 06 13:10:04 musicbox volumio[5336]: info: CoreStateMachine::syncState currentStatus play Jun 06 13:10:04 musicbox volumio[5336]: info: Received an update from plugin. extracting info from payload Jun 06 13:10:04 musicbox volumio[5336]: info: CoreStateMachine::pushState Jun 06 13:10:04 musicbox volumio[5336]: info: CoreCommandRouter::volumioPushState Jun 06 13:10:04 musicbox volumio[5336]: info: CoreStateMachine::pushState Jun 06 13:10:04 musicbox volumio[5336]: info: CoreCommandRouter::volumioPushState Jun 06 13:10:04 musicbox volumio[5336]: info: ControllerMpd::pushState Jun 06 13:10:04 musicbox volumio[5336]: info: CoreCommandRouter::servicePushState Jun 06 13:10:04 musicbox volumio[5336]: info: CorePlayQueue::getTrack 0 Jun 06 13:10:04 musicbox volumio[5336]: verbose: STATE SERVICE {"status":"play","position":0,"seek":151111,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"47 Kbps","isStreaming":false,"title":"Benny Turner - Homesick Blues","artist":"A Fine Jazz Gumbo Radio","album":null,"uri":"https://streaming.smartradio.ch:9502/stream","trackType":""} Jun 06 13:10:04 musicbox volumio[5336]: verbose: CURRENT POSITION 0 Jun 06 13:10:04 musicbox volumio[5336]: info: CoreStateMachine::syncState stateService play Jun 06 13:10:04 musicbox volumio[5336]: info: CoreStateMachine::syncState currentStatus play Jun 06 13:10:04 musicbox volumio[5336]: info: Received an update from plugin. extracting info from payload Jun 06 13:10:04 musicbox volumio[5336]: info: CoreStateMachine::pushState Jun 06 13:10:04 musicbox volumio[5336]: info: CoreCommandRouter::volumioPushState Jun 06 13:10:04 musicbox volumio[5336]: info: CoreStateMachine::pushState Jun 06 13:10:04 musicbox volumio[5336]: info: CoreCommandRouter::volumioPushState Jun 06 13:10:04 musicbox volumio[5336]: info: ------------------------------ 87ms Jun 06 13:10:04 musicbox volumio[5336]: info: ------------------------------ 80ms Jun 06 13:10:04 musicbox volumio[5336]: info: ------------------------------ 77ms Jun 06 13:10:27 musicbox volumio[5336]: info: CALLMETHOD: music_service pandora setAccountOptionsConf [object Object] Jun 06 13:10:27 musicbox volumio[5336]: info: CoreCommandRouter::executeOnPlugin: pandora , setAccountOptionsConf Jun 06 13:10:27 musicbox volumio[5336]: info: [1749233427266] ControllerPandora::validateAndSetAccountOptions Jun 06 13:10:27 musicbox volumio[5336]: info: [1749233427267] ControllerPandora::PandoraHandler::setAccountOptions Jun 06 13:10:27 musicbox volumio[5336]: info: [1749233427270] ControllerPandora::flushPandora Jun 06 13:10:27 musicbox volumio[5336]: info: CorePlayQueue::getQueue Jun 06 13:10:27 musicbox volumio[5336]: info: CorePlayQueue::clearAddPlayQueue Jun 06 13:10:27 musicbox volumio[5336]: info: CorePlayQueue::saveQueue Jun 06 13:10:27 musicbox volumio[5336]: info: CoreCommandRouter::serviceClearAddPlayTracks Jun 06 13:10:27 musicbox volumio[5336]: error: WARNING: No clearAddPlayTracks method for service webradio Jun 06 13:10:27 musicbox volumio[5336]: info: CoreCommandRouter::volumioPushQueue Jun 06 13:10:27 musicbox volumio[5336]: info: [1749233427277] [Pandora] PreventAuthTimeout::init: Timer loaded Jun 06 13:10:27 musicbox volumio[5336]: info: [1749233427277] [Pandora] PreventAuthTimeout::init: Interval set to 10800000 ms Jun 06 13:10:27 musicbox volumio[5336]: info: [1749233427278] [Pandora] PreventAuthTimeout::init: Starting delayed timer after next interval Jun 06 13:10:27 musicbox volumio[5336]: info: [1749233427278] ControllerPandora::PandoraHandler::pandoraLoginAndGetStations Jun 06 13:10:27 musicbox volumio[5336]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 06 13:10:27 musicbox volumio[5336]: Error: error:0308010C:digital envelope routines::unsupported Jun 06 13:10:27 musicbox volumio[5336]: at Decipheriv.createCipherBase (node:internal/crypto/cipher:121:19) Jun 06 13:10:27 musicbox volumio[5336]: at Decipheriv.createCipherWithIV (node:internal/crypto/cipher:140:3) Jun 06 13:10:27 musicbox volumio[5336]: at new Decipheriv (node:internal/crypto/cipher:289:3) Jun 06 13:10:27 musicbox volumio[5336]: at Object.createDecipheriv (node:crypto:154:10) Jun 06 13:10:27 musicbox volumio[5336]: at /data/plugins/music_service/pandora/node_modules/anesidora/encryption.js:30:29 Jun 06 13:10:27 musicbox volumio[5336]: at exports.decrypt (/data/plugins/music_service/pandora/node_modules/anesidora/encryption.js:45:16) Jun 06 13:10:27 musicbox volumio[5336]: at decryptSyncTime (/data/plugins/music_service/pandora/node_modules/anesidora/anesidora.js:54:36) Jun 06 13:10:27 musicbox volumio[5336]: at /data/plugins/music_service/pandora/node_modules/anesidora/anesidora.js:72:37 Jun 06 13:10:27 musicbox volumio[5336]: at Request._callback (/data/plugins/music_service/pandora/node_modules/anesidora/anesidora.js:46:24) Jun 06 13:10:27 musicbox volumio[5336]: at self.callback (/data/plugins/music_service/pandora/node_modules/request/request.js:185:22) Jun 06 13:10:27 musicbox volumio[5336]: at Request.emit (node:events:514:28) Jun 06 13:10:27 musicbox volumio[5336]: at Request. (/data/plugins/music_service/pandora/node_modules/request/request.js:1154:10) Jun 06 13:10:27 musicbox volumio[5336]: at Request.emit (node:events:514:28) Jun 06 13:10:27 musicbox volumio[5336]: at IncomingMessage. (/data/plugins/music_service/pandora/node_modules/request/request.js:1076:12) Jun 06 13:10:27 musicbox volumio[5336]: at Object.onceWrapper (node:events:628:28) Jun 06 13:10:27 musicbox volumio[5336]: at IncomingMessage.emit (node:events:526:35) { Jun 06 13:10:27 musicbox volumio[5336]: library: 'digital envelope routines', Jun 06 13:10:27 musicbox volumio[5336]: reason: 'unsupported', Jun 06 13:10:27 musicbox volumio[5336]: code: 'ERR_OSSL_EVP_UNSUPPORTED' Jun 06 13:10:27 musicbox volumio[5336]: } Jun 06 13:10:27 musicbox volumio[5336]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 06 13:10:28 musicbox sudo[7606]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-06 13:09' Jun 06 13:10:28 musicbox sudo[7606]: 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="a42e8353c03f8616e137cf760ea618e88c395461" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Jun 2 16:20:51 UTC 2025" VOLUMIO_VERSION="4.010" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f51ec0ad91e50bdfdb863e902a9e8fa2"