Jun 19 16:16:28 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service...
Jun 19 16:16:28 volumio dbus-daemon[578]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.53' (uid=0 pid=3543 comm="timedatectl show --property=NTPSynchronized --valu")
Jun 19 16:16:28 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service...
Jun 19 16:16:29 volumio dbus-daemon[578]: [system] Successfully activated service 'org.freedesktop.timedate1'
Jun 19 16:16:29 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service.
Jun 19 16:16:29 volumio setdatetime-helper.sh[3542]: Time is not synchronized. Attempting to sync...
Jun 19 16:16:29 volumio sudo[3551]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Thu, 19 Jun 2025 20:16:29 GMT#015'
Jun 19 16:16:29 volumio sudo[3551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Jun 19 16:16:29 volumio setdatetime-helper.sh[3552]: Thu Jun 19 16:16:29 EDT 2025
Jun 19 16:16:29 volumio sudo[3551]: pam_unix(sudo:session): session closed for user root
Jun 19 16:16:29 volumio systemd-journald[341]: Time jumped backwards, rotating.
Jun 19 16:16:29 volumio setdatetime-helper.sh[3542]: Time synchronized successfully.
Jun 19 16:16:29 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully.
Jun 19 16:16:29 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service.
Jun 19 16:16:34 volumio volumio[1066]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
Jun 19 16:16:34 volumio volumio[1066]: info: Downloading plugin at http://127.0.0.1:3000/plugin-serve/pandora.zip
Jun 19 16:16:34 volumio volumio[1066]: info: END DOWNLOAD: http://127.0.0.1:3000/plugin-serve/pandora.zip
Jun 19 16:16:34 volumio volumio[1066]: info: Folder /tmp/plugins removed
Jun 19 16:16:35 volumio volumio[1066]: info: Check plugin dependencies
Jun 19 16:16:35 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 19 16:16:35 volumio volumio[1066]: info: Checking if plugin already exists
Jun 19 16:16:35 volumio volumio[1066]: info: Rename folder
Jun 19 16:16:35 volumio volumio[1066]: info: Folder /tmp/downloaded_plugin.zip removed
Jun 19 16:16:35 volumio volumio[1066]: info: Move to category
Jun 19 16:16:37 volumio volumio[1066]: info: Checking if install.sh is present
Jun 19 16:16:37 volumio volumio[1066]: info: Executing install.sh
Jun 19 16:16:37 volumio sudo[3594]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/pandora/install.sh
Jun 19 16:16:37 volumio sudo[3594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 19 16:16:37 volumio sudo[3594]: pam_unix(sudo:session): session closed for user root
Jun 19 16:16:37 volumio volumio[1066]: info: Installing Pandora Dependencies
Jun 19 16:16:37 volumio volumio[1066]: Plugin install end detected on script
Jun 19 16:16:37 volumio volumio[1066]: info: Install script completed
Jun 19 16:16:37 volumio volumio[1066]: info: Adding reference to registry
Jun 19 16:16:37 volumio volumio[1066]: info: Done installing plugin.
Jun 19 16:16:37 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 19 16:16:37 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Jun 19 16:16:37 volumio volumio[1066]: info: Folder /tmp/plugins removed
Jun 19 16:16:37 volumio volumio[1066]: info: Folder /tmp/downloaded_plugin.zip removed
Jun 19 16:16:37 volumio volumio[1066]: info: Folder /data/temp removed
Jun 19 16:16:51 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 19 16:16:51 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Jun 19 16:16:55 volumio volumio[1066]: info: Enabling plugin pandora
Jun 19 16:16:55 volumio volumio[1066]: info: Loading plugin "pandora"...
Jun 19 16:16:56 volumio volumio[1066]: info: PLUGIN START: pandora
Jun 19 16:16:56 volumio volumio[1066]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 19 16:16:56 volumio volumio[1066]: info: [1750364216952] CoreMusicLibrary::Adding element Pandora Radio
Jun 19 16:16:56 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 19 16:16:56 volumio volumio[1066]: Cannot find translation for source Pandora Radio
Jun 19 16:16:56 volumio volumio[1066]: info: [1750364216960] ControllerPandora::initialSetup
Jun 19 16:16:56 volumio volumio[1066]: info: [1750364216963] ControllerPandora::checkPing
Jun 19 16:16:57 volumio ntpd[836]: CLOCK: time stepped by 0.445765
Jun 19 16:16:57 volumio ntpd[836]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes
Jun 19 16:16:59 volumio systemd[1]: systemd-timedated.service: Deactivated successfully.
Jun 19 16:17:02 volumio volumio[1066]: info: [1750364222499] [Pandora] checkPing: ***LOOKS GOOD, ICE DOWN THE BEERS IN THE COOLER***
Jun 19 16:17:02 volumio volumio[1066]: info: [1750364222502] ControllerPandora::checkHTTP
Jun 19 16:17:07 volumio volumio[1066]: info: [1750364227824] [Pandora] checkHTTP: ***CRACK THE BEERS WE HAVE INTERNET***
Jun 19 16:17:07 volumio volumio[1066]: info: [1750364227825] ControllerPandora::initializeMQTT
Jun 19 16:17:07 volumio volumio[1066]: info: [1750364227825] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration
Jun 19 16:17:07 volumio volumio[1066]: info: [1750364227826] ControllerPandora::PandoraHandler::init
Jun 19 16:17:07 volumio volumio[1066]: info: [1750364227827] ControllerPandora::PandoraHandler::loadErrorCodes
Jun 19 16:17:07 volumio volumio[1066]: info: [1750364227829] ControllerPandora::validateAndSetAccountOptions
Jun 19 16:17:07 volumio volumio[1066]: error: [1750364227829] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings.
Jun 19 16:17:07 volumio volumio[1066]: info: [1750364227831] [Pandora] PandoraHandler::setMaxStationTracks: 16
Jun 19 16:17:07 volumio volumio[1066]: info: [1750364227832] [Pandora] PandoraHandler::setBandFilter: bandfilter is empty
Jun 19 16:17:07 volumio volumio[1066]: info: [1750364227833] ControllerPandora::flushPandora
Jun 19 16:17:07 volumio volumio[1066]: info: CorePlayQueue::getQueue
Jun 19 16:17:07 volumio volumio[1066]: info: CorePlayQueue::clearPlayQueue
Jun 19 16:17:07 volumio volumio[1066]: info: CorePlayQueue::saveQueue
Jun 19 16:17:07 volumio volumio[1066]: info: CoreCommandRouter::volumioPushQueue
Jun 19 16:17:07 volumio volumio[1066]: info: Done.
Jun 19 16:17:15 volumio volumio[1066]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 19 16:17:40 volumio volumio[1066]: info: CALLMETHOD: music_service pandora setAccountOptionsConf [object Object]
Jun 19 16:17:40 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: pandora , setAccountOptionsConf
Jun 19 16:17:40 volumio volumio[1066]: info: [1750364260517] ControllerPandora::validateAndSetAccountOptions
Jun 19 16:17:40 volumio volumio[1066]: info: [1750364260518] ControllerPandora::PandoraHandler::setAccountOptions
Jun 19 16:17:40 volumio volumio[1066]: info: [1750364260520] ControllerPandora::flushPandora
Jun 19 16:17:40 volumio volumio[1066]: info: CorePlayQueue::getQueue
Jun 19 16:17:40 volumio volumio[1066]: info: CorePlayQueue::clearPlayQueue
Jun 19 16:17:40 volumio volumio[1066]: info: CorePlayQueue::saveQueue
Jun 19 16:17:40 volumio volumio[1066]: info: CoreCommandRouter::volumioPushQueue
Jun 19 16:17:40 volumio volumio[1066]: info: [1750364260527] [Pandora] PreventAuthTimeout::init: Timer loaded
Jun 19 16:17:40 volumio volumio[1066]: info: [1750364260528] [Pandora] PreventAuthTimeout::init: Interval set to 10800000 ms
Jun 19 16:17:40 volumio volumio[1066]: info: [1750364260528] [Pandora] PreventAuthTimeout::init: Starting delayed timer after next interval
Jun 19 16:17:40 volumio volumio[1066]: info: [1750364260529] ControllerPandora::PandoraHandler::pandoraLoginAndGetStations
Jun 19 16:17:40 volumio volumio[1066]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jun 19 16:17:40 volumio volumio[1066]: Error: error:0308010C:digital envelope routines::unsupported
Jun 19 16:17:40 volumio volumio[1066]: at Decipheriv.createCipherBase (node:internal/crypto/cipher:121:19)
Jun 19 16:17:40 volumio volumio[1066]: at Decipheriv.createCipherWithIV (node:internal/crypto/cipher:140:3)
Jun 19 16:17:40 volumio volumio[1066]: at new Decipheriv (node:internal/crypto/cipher:289:3)
Jun 19 16:17:40 volumio volumio[1066]: at Object.createDecipheriv (node:crypto:154:10)
Jun 19 16:17:40 volumio volumio[1066]: at /data/plugins/music_service/pandora/node_modules/anesidora/encryption.js:30:29
Jun 19 16:17:40 volumio volumio[1066]: at exports.decrypt (/data/plugins/music_service/pandora/node_modules/anesidora/encryption.js:45:16)
Jun 19 16:17:40 volumio volumio[1066]: at decryptSyncTime (/data/plugins/music_service/pandora/node_modules/anesidora/anesidora.js:54:36)
Jun 19 16:17:40 volumio volumio[1066]: at /data/plugins/music_service/pandora/node_modules/anesidora/anesidora.js:72:37
Jun 19 16:17:40 volumio volumio[1066]: at Request._callback (/data/plugins/music_service/pandora/node_modules/anesidora/anesidora.js:46:24)
Jun 19 16:17:40 volumio volumio[1066]: at self.callback (/data/plugins/music_service/pandora/node_modules/request/request.js:185:22)
Jun 19 16:17:40 volumio volumio[1066]: at Request.emit (node:events:514:28)
Jun 19 16:17:40 volumio volumio[1066]: at Request. (/data/plugins/music_service/pandora/node_modules/request/request.js:1154:10)
Jun 19 16:17:40 volumio volumio[1066]: at Request.emit (node:events:514:28)
Jun 19 16:17:40 volumio volumio[1066]: at IncomingMessage. (/data/plugins/music_service/pandora/node_modules/request/request.js:1076:12)
Jun 19 16:17:40 volumio volumio[1066]: at Object.onceWrapper (node:events:628:28)
Jun 19 16:17:40 volumio volumio[1066]: at IncomingMessage.emit (node:events:526:35) {
Jun 19 16:17:40 volumio volumio[1066]: library: 'digital envelope routines',
Jun 19 16:17:40 volumio volumio[1066]: reason: 'unsupported',
Jun 19 16:17:40 volumio volumio[1066]: code: 'ERR_OSSL_EVP_UNSUPPORTED'
Jun 19 16:17:40 volumio volumio[1066]: }
Jun 19 16:17:40 volumio volumio[1066]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jun 19 16:17:41 volumio sudo[3706]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-19 16:16'
Jun 19 16:17:41 volumio sudo[3706]: 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"