Jun 19 14:44:05 musicbox volumio[1048]: info: Jun 19 14:44:05 musicbox volumio[1048]: ---------------------------- MPD announces system playlist update Jun 19 14:44:05 musicbox volumio[1048]: info: Ignoring MPD Status Update Jun 19 14:44:05 musicbox volumio[1048]: info: Jun 19 14:44:05 musicbox volumio[1048]: ---------------------------- MPD announces state update: player Jun 19 14:44:05 musicbox volumio[1048]: info: ControllerMpd::getState Jun 19 14:44:05 musicbox volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Jun 19 14:44:05 musicbox volumio[1048]: info: Jun 19 14:44:05 musicbox volumio[1048]: ---------------------------- MPD announces system playlist update Jun 19 14:44:05 musicbox volumio[1048]: info: Ignoring MPD Status Update Jun 19 14:44:05 musicbox volumio[1048]: info: Jun 19 14:44:05 musicbox volumio[1048]: ---------------------------- MPD announces state update: player Jun 19 14:44:05 musicbox volumio[1048]: info: ControllerMpd::getState Jun 19 14:44:05 musicbox volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Jun 19 14:44:05 musicbox volumio[1048]: info: Jun 19 14:44:05 musicbox volumio[1048]: ---------------------------- MPD announces system playlist update Jun 19 14:44:05 musicbox volumio[1048]: info: Ignoring MPD Status Update Jun 19 14:44:05 musicbox volumio[1048]: info: Jun 19 14:44:05 musicbox volumio[1048]: ---------------------------- MPD announces state update: player Jun 19 14:44:05 musicbox volumio[1048]: info: ControllerMpd::getState Jun 19 14:44:05 musicbox volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Jun 19 14:44:05 musicbox volumio[1048]: info: ------------------------------ 7ms Jun 19 14:44:05 musicbox volumio[1048]: info: sendMpdCommand status took 6 milliseconds Jun 19 14:44:05 musicbox volumio[1048]: info: ------------------------------ 6ms Jun 19 14:44:05 musicbox volumio[1048]: verbose: ControllerMpd::parseState Jun 19 14:44:05 musicbox volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 14:44:05 musicbox volumio[1048]: info: sendMpdCommand status took 8 milliseconds Jun 19 14:44:05 musicbox volumio[1048]: info: ------------------------------ 7ms Jun 19 14:44:05 musicbox volumio[1048]: info: sendMpdCommand status took 5 milliseconds Jun 19 14:44:05 musicbox volumio[1048]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 19 14:44:05 musicbox volumio[1048]: verbose: ControllerMpd::parseState Jun 19 14:44:05 musicbox volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 14:44:05 musicbox volumio[1048]: verbose: ControllerMpd::parseState Jun 19 14:44:05 musicbox volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 14:44:05 musicbox volumio[1048]: verbose: ControllerMpd::parseTrackInfo Jun 19 14:44:05 musicbox volumio[1048]: info: ControllerMpd::pushState Jun 19 14:44:05 musicbox volumio[1048]: info: CoreCommandRouter::servicePushState Jun 19 14:44:05 musicbox volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 19 14:44:05 musicbox volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20631085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Oscar Peterson - A Child Is Born","artist":"Jazz Radio Bcn","album":null,"uri":"http://stream.zeno.fm/tvt6bya8hg8uv","trackType":""} Jun 19 14:44:05 musicbox volumio[1048]: verbose: CURRENT POSITION 0 Jun 19 14:44:05 musicbox volumio[1048]: info: CoreStateMachine::syncState stateService play Jun 19 14:44:05 musicbox volumio[1048]: info: CoreStateMachine::syncState currentStatus play Jun 19 14:44:05 musicbox volumio[1048]: info: Received an update from plugin. extracting info from payload Jun 19 14:44:05 musicbox volumio[1048]: info: CoreStateMachine::pushState Jun 19 14:44:05 musicbox volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 14:44:05 musicbox volumio[1048]: info: CoreCommandRouter::volumioPushState Jun 19 14:44:05 musicbox volumio[1048]: info: CoreStateMachine::pushState Jun 19 14:44:05 musicbox volumio[1048]: info: CoreCommandRouter::volumioPushState Jun 19 14:44:06 musicbox volumio[1048]: info: ------------------------------ 26ms Jun 19 14:44:06 musicbox volumio[1048]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 19 14:44:06 musicbox volumio[1048]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 19 14:44:06 musicbox volumio[1048]: verbose: ControllerMpd::parseTrackInfo Jun 19 14:44:06 musicbox volumio[1048]: verbose: ControllerMpd::parseTrackInfo Jun 19 14:44:06 musicbox volumio[1048]: info: ControllerMpd::pushState Jun 19 14:44:06 musicbox volumio[1048]: info: CoreCommandRouter::servicePushState Jun 19 14:44:06 musicbox volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 19 14:44:06 musicbox volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20631085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Oscar Peterson - A Child Is Born","artist":"Jazz Radio Bcn","album":null,"uri":"http://stream.zeno.fm/tvt6bya8hg8uv","trackType":""} Jun 19 14:44:06 musicbox volumio[1048]: verbose: CURRENT POSITION 0 Jun 19 14:44:06 musicbox volumio[1048]: info: CoreStateMachine::syncState stateService play Jun 19 14:44:06 musicbox volumio[1048]: info: CoreStateMachine::syncState currentStatus play Jun 19 14:44:06 musicbox volumio[1048]: info: Received an update from plugin. extracting info from payload Jun 19 14:44:06 musicbox volumio[1048]: info: CoreStateMachine::pushState Jun 19 14:44:06 musicbox volumio[1048]: info: CoreCommandRouter::volumioPushState Jun 19 14:44:06 musicbox volumio[1048]: info: CoreStateMachine::pushState Jun 19 14:44:06 musicbox volumio[1048]: info: CoreCommandRouter::volumioPushState Jun 19 14:44:06 musicbox volumio[1048]: info: ControllerMpd::pushState Jun 19 14:44:06 musicbox volumio[1048]: info: CoreCommandRouter::servicePushState Jun 19 14:44:06 musicbox volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 19 14:44:06 musicbox volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20631085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Oscar Peterson - A Child Is Born","artist":"Jazz Radio Bcn","album":null,"uri":"http://stream.zeno.fm/tvt6bya8hg8uv","trackType":""} Jun 19 14:44:06 musicbox volumio[1048]: verbose: CURRENT POSITION 0 Jun 19 14:44:06 musicbox volumio[1048]: info: CoreStateMachine::syncState stateService play Jun 19 14:44:06 musicbox volumio[1048]: info: CoreStateMachine::syncState currentStatus play Jun 19 14:44:06 musicbox volumio[1048]: info: Received an update from plugin. extracting info from payload Jun 19 14:44:06 musicbox volumio[1048]: info: CoreStateMachine::pushState Jun 19 14:44:06 musicbox volumio[1048]: info: CoreCommandRouter::volumioPushState Jun 19 14:44:06 musicbox volumio[1048]: info: CoreStateMachine::pushState Jun 19 14:44:06 musicbox volumio[1048]: info: CoreCommandRouter::volumioPushState Jun 19 14:44:06 musicbox volumio[1048]: info: ------------------------------ 48ms Jun 19 14:44:06 musicbox volumio[1048]: info: ------------------------------ 47ms Jun 19 14:44:53 musicbox volumio[1048]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 14:44:53 musicbox systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 19 14:44:53 musicbox dbus-daemon[554]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.6243' (uid=0 pid=13447 comm="timedatectl show --property=NTPSynchronized --valu") Jun 19 14:44:53 musicbox systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 19 14:44:54 musicbox dbus-daemon[554]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 19 14:44:54 musicbox systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 19 14:44:54 musicbox setdatetime-helper.sh[13446]: Time is already synchronized. Jun 19 14:44:54 musicbox systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 19 14:44:54 musicbox systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 19 14:45:24 musicbox systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 19 14:45:27 musicbox volumio[1048]: info: CALLMETHOD: music_service pandora setAccountOptionsConf [object Object] Jun 19 14:45:27 musicbox volumio[1048]: info: CoreCommandRouter::executeOnPlugin: pandora , setAccountOptionsConf Jun 19 14:45:27 musicbox volumio[1048]: info: [1750362327992] ControllerPandora::validateAndSetAccountOptions Jun 19 14:45:27 musicbox volumio[1048]: info: [1750362327993] ControllerPandora::PandoraHandler::setAccountOptions Jun 19 14:45:27 musicbox volumio[1048]: info: [1750362327995] ControllerPandora::flushPandora Jun 19 14:45:27 musicbox volumio[1048]: info: CorePlayQueue::getQueue Jun 19 14:45:27 musicbox volumio[1048]: info: CorePlayQueue::clearAddPlayQueue Jun 19 14:45:27 musicbox volumio[1048]: info: CorePlayQueue::saveQueue Jun 19 14:45:27 musicbox volumio[1048]: info: CoreCommandRouter::serviceClearAddPlayTracks Jun 19 14:45:27 musicbox volumio[1048]: error: WARNING: No clearAddPlayTracks method for service webradio Jun 19 14:45:27 musicbox volumio[1048]: info: CoreCommandRouter::volumioPushQueue Jun 19 14:45:28 musicbox volumio[1048]: info: [1750362328000] [Pandora] PreventAuthTimeout::init: Timer loaded Jun 19 14:45:28 musicbox volumio[1048]: info: [1750362328000] [Pandora] PreventAuthTimeout::init: Interval set to 10800000 ms Jun 19 14:45:28 musicbox volumio[1048]: info: [1750362328000] [Pandora] PreventAuthTimeout::init: Starting delayed timer after next interval Jun 19 14:45:28 musicbox volumio[1048]: info: [1750362328001] ControllerPandora::PandoraHandler::pandoraLoginAndGetStations Jun 19 14:45:28 musicbox volumio[1048]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 14:45:28 musicbox volumio[1048]: Error: error:0308010C:digital envelope routines::unsupported Jun 19 14:45:28 musicbox volumio[1048]: at Decipheriv.createCipherBase (node:internal/crypto/cipher:121:19) Jun 19 14:45:28 musicbox volumio[1048]: at Decipheriv.createCipherWithIV (node:internal/crypto/cipher:140:3) Jun 19 14:45:28 musicbox volumio[1048]: at new Decipheriv (node:internal/crypto/cipher:289:3) Jun 19 14:45:28 musicbox volumio[1048]: at Object.createDecipheriv (node:crypto:154:10) Jun 19 14:45:28 musicbox volumio[1048]: at /data/plugins/music_service/pandora/node_modules/anesidora/encryption.js:30:29 Jun 19 14:45:28 musicbox volumio[1048]: at exports.decrypt (/data/plugins/music_service/pandora/node_modules/anesidora/encryption.js:45:16) Jun 19 14:45:28 musicbox volumio[1048]: at decryptSyncTime (/data/plugins/music_service/pandora/node_modules/anesidora/anesidora.js:54:36) Jun 19 14:45:28 musicbox volumio[1048]: at /data/plugins/music_service/pandora/node_modules/anesidora/anesidora.js:72:37 Jun 19 14:45:28 musicbox volumio[1048]: at Request._callback (/data/plugins/music_service/pandora/node_modules/anesidora/anesidora.js:46:24) Jun 19 14:45:28 musicbox volumio[1048]: at self.callback (/data/plugins/music_service/pandora/node_modules/request/request.js:185:22) Jun 19 14:45:28 musicbox volumio[1048]: at Request.emit (node:events:514:28) Jun 19 14:45:28 musicbox volumio[1048]: at Request. (/data/plugins/music_service/pandora/node_modules/request/request.js:1154:10) Jun 19 14:45:28 musicbox volumio[1048]: at Request.emit (node:events:514:28) Jun 19 14:45:28 musicbox volumio[1048]: at IncomingMessage. (/data/plugins/music_service/pandora/node_modules/request/request.js:1076:12) Jun 19 14:45:28 musicbox volumio[1048]: at Object.onceWrapper (node:events:628:28) Jun 19 14:45:28 musicbox volumio[1048]: at IncomingMessage.emit (node:events:526:35) { Jun 19 14:45:28 musicbox volumio[1048]: library: 'digital envelope routines', Jun 19 14:45:28 musicbox volumio[1048]: reason: 'unsupported', Jun 19 14:45:28 musicbox volumio[1048]: code: 'ERR_OSSL_EVP_UNSUPPORTED' Jun 19 14:45:28 musicbox volumio[1048]: } Jun 19 14:45:28 musicbox volumio[1048]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 14:45:28 musicbox sudo[13595]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-19 14:44' Jun 19 14:45:28 musicbox sudo[13595]: 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="cee9bb5b0ee50e4898478097a64b5c6c77d5a212" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 14 07:24:14 UTC 2025" VOLUMIO_VERSION="4.012" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b365b054264e3ebc2709665da2b5d1ee"