May 22 09:17:02 volumio volumio[1165]: info: PLUGIN START: youtube2
May 22 09:17:02 volumio volumio[1165]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 22 09:17:02 volumio volumio[1165]: info: [1747919822096] CoreMusicLibrary::Adding element YouTube2
May 22 09:17:02 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 22 09:17:02 volumio volumio[1165]: Cannot find translation for source Pandora Radio
May 22 09:17:02 volumio volumio[1165]: Cannot find translation for source Radio Paradise
May 22 09:17:02 volumio volumio[1165]: Cannot find translation for source YouTube2
May 22 09:17:02 volumio volumio[1165]: info: Done.
May 22 09:17:02 volumio volumio[1165]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ytmusic/volumio/bookworm/armhf
May 22 09:17:03 volumio volumio[1165]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ytmusic/volumio/bookworm/armhf
May 22 09:17:03 volumio volumio[1165]: info: Folder /tmp/plugins removed
May 22 09:17:03 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service...
May 22 09:17:03 volumio dbus-daemon[697]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.37' (uid=0 pid=2883 comm="timedatectl show --property=NTPSynchronized --valu")
May 22 09:17:03 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service...
May 22 09:17:04 volumio dbus-daemon[697]: [system] Successfully activated service 'org.freedesktop.timedate1'
May 22 09:17:04 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service.
May 22 09:17:04 volumio setdatetime-helper.sh[2880]: Time is not synchronized. Attempting to sync...
May 22 09:17:04 volumio sudo[2891]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Thu, 22 May 2025 13:17:04 GMT#015'
May 22 09:17:04 volumio sudo[2891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
May 22 09:17:04 volumio setdatetime-helper.sh[2892]: Thu May 22 09:17:04 EDT 2025
May 22 09:17:04 volumio systemd-journald[380]: Time jumped backwards, rotating.
May 22 09:17:04 volumio sudo[2891]: pam_unix(sudo:session): session closed for user root
May 22 09:17:04 volumio setdatetime-helper.sh[2880]: Time synchronized successfully.
May 22 09:17:04 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully.
May 22 09:17:04 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service.
May 22 09:17:04 volumio volumio[1165]: info: Check plugin dependencies
May 22 09:17:05 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 22 09:17:05 volumio volumio[1165]: info: Checking if plugin already exists
May 22 09:17:05 volumio volumio[1165]: info: Rename folder
May 22 09:17:05 volumio volumio[1165]: info: Folder /tmp/downloaded_plugin.zip removed
May 22 09:17:05 volumio volumio[1165]: info: Move to category
May 22 09:17:18 volumio volumio[1165]: info: Checking if install.sh is present
May 22 09:17:18 volumio volumio[1165]: info: Executing install.sh
May 22 09:17:18 volumio sudo[2921]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/ytmusic/install.sh
May 22 09:17:18 volumio sudo[2921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
May 22 09:17:18 volumio sudo[2921]: pam_unix(sudo:session): session closed for user root
May 22 09:17:18 volumio volumio[1165]: info: YouTube Music plugin installed
May 22 09:17:18 volumio volumio[1165]: Plugin install end detected on script
May 22 09:17:18 volumio volumio[1165]: info: Install script completed
May 22 09:17:18 volumio volumio[1165]: info: Adding reference to registry
May 22 09:17:18 volumio volumio[1165]: info: Done installing plugin.
May 22 09:17:18 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 22 09:17:18 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
May 22 09:17:18 volumio volumio[1165]: info: Folder /tmp/plugins removed
May 22 09:17:18 volumio volumio[1165]: info: Folder /tmp/downloaded_plugin.zip removed
May 22 09:17:18 volumio volumio[1165]: info: Folder /data/temp removed
May 22 09:17:19 volumio volumio[1165]: info: Enabling plugin ytmusic
May 22 09:17:19 volumio volumio[1165]: info: Loading plugin "ytmusic"...
May 22 09:17:21 volumio volumio[1165]: info: PLUGIN START: ytmusic
May 22 09:17:21 volumio volumio[1165]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 22 09:17:21 volumio volumio[1165]: info: [1747919841420] CoreMusicLibrary::Adding element YouTube Music
May 22 09:17:21 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 22 09:17:21 volumio volumio[1165]: Cannot find translation for source Pandora Radio
May 22 09:17:21 volumio volumio[1165]: Cannot find translation for source Radio Paradise
May 22 09:17:21 volumio volumio[1165]: Cannot find translation for source YouTube2
May 22 09:17:21 volumio volumio[1165]: Cannot find translation for source YouTube Music
May 22 09:17:21 volumio volumio[1165]: info: Done.
May 22 09:17:30 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
May 22 09:17:33 volumio systemd[1]: systemd-timedated.service: Deactivated successfully.
May 22 09:17:56 volumio ntpd[970]: CLOCK: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
May 22 09:18:24 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service...
May 22 09:18:24 volumio dbus-daemon[697]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.39' (uid=0 pid=3028 comm="timedatectl show --property=NTPSynchronized --valu")
May 22 09:18:24 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service...
May 22 09:18:25 volumio dbus-daemon[697]: [system] Successfully activated service 'org.freedesktop.timedate1'
May 22 09:18:25 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service.
May 22 09:18:25 volumio setdatetime-helper.sh[3027]: Time is not synchronized. Attempting to sync...
May 22 09:18:25 volumio sudo[3036]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Thu, 22 May 2025 13:18:25 GMT#015'
May 22 09:18:25 volumio sudo[3036]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
May 22 09:18:25 volumio setdatetime-helper.sh[3037]: Thu May 22 09:18:25 EDT 2025
May 22 09:18:25 volumio sudo[3036]: pam_unix(sudo:session): session closed for user root
May 22 09:18:25 volumio setdatetime-helper.sh[3027]: Time synchronized successfully.
May 22 09:18:25 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully.
May 22 09:18:25 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service.
May 22 09:18:25 volumio systemd-journald[380]: Time jumped backwards, rotating.
May 22 09:18:28 volumio volumio[1165]: info: CALLMETHOD: music_service pandora setAccountOptionsConf [object Object]
May 22 09:18:28 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: pandora , setAccountOptionsConf
May 22 09:18:28 volumio volumio[1165]: info: [1747919908911] ControllerPandora::validateAndSetAccountOptions
May 22 09:18:28 volumio volumio[1165]: info: [1747919908911] ControllerPandora::PandoraHandler::setAccountOptions
May 22 09:18:28 volumio volumio[1165]: info: [1747919908913] ControllerPandora::flushPandora
May 22 09:18:28 volumio volumio[1165]: info: CorePlayQueue::getQueue
May 22 09:18:28 volumio volumio[1165]: info: CorePlayQueue::clearPlayQueue
May 22 09:18:28 volumio volumio[1165]: info: CorePlayQueue::saveQueue
May 22 09:18:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue
May 22 09:18:28 volumio volumio[1165]: info: [1747919908918] [Pandora] PreventAuthTimeout::init: Timer loaded
May 22 09:18:28 volumio volumio[1165]: info: [1747919908918] [Pandora] PreventAuthTimeout::init: Interval set to 10800000 ms
May 22 09:18:28 volumio volumio[1165]: info: [1747919908918] [Pandora] PreventAuthTimeout::init: Starting delayed timer after next interval
May 22 09:18:28 volumio volumio[1165]: info: [1747919908919] ControllerPandora::PandoraHandler::pandoraLoginAndGetStations
May 22 09:18:29 volumio volumio[1165]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 22 09:18:29 volumio volumio[1165]: Error: error:0308010C:digital envelope routines::unsupported
May 22 09:18:29 volumio volumio[1165]: at Decipheriv.createCipherBase (node:internal/crypto/cipher:121:19)
May 22 09:18:29 volumio volumio[1165]: at Decipheriv.createCipherWithIV (node:internal/crypto/cipher:140:3)
May 22 09:18:29 volumio volumio[1165]: at new Decipheriv (node:internal/crypto/cipher:289:3)
May 22 09:18:29 volumio volumio[1165]: at Object.createDecipheriv (node:crypto:154:10)
May 22 09:18:29 volumio volumio[1165]: at /data/plugins/music_service/pandora/node_modules/anesidora/encryption.js:30:29
May 22 09:18:29 volumio volumio[1165]: at exports.decrypt (/data/plugins/music_service/pandora/node_modules/anesidora/encryption.js:45:16)
May 22 09:18:29 volumio volumio[1165]: at decryptSyncTime (/data/plugins/music_service/pandora/node_modules/anesidora/anesidora.js:54:36)
May 22 09:18:29 volumio volumio[1165]: at /data/plugins/music_service/pandora/node_modules/anesidora/anesidora.js:72:37
May 22 09:18:29 volumio volumio[1165]: at Request._callback (/data/plugins/music_service/pandora/node_modules/anesidora/anesidora.js:46:24)
May 22 09:18:29 volumio volumio[1165]: at self.callback (/data/plugins/music_service/pandora/node_modules/request/request.js:185:22)
May 22 09:18:29 volumio volumio[1165]: at Request.emit (node:events:514:28)
May 22 09:18:29 volumio volumio[1165]: at Request. (/data/plugins/music_service/pandora/node_modules/request/request.js:1154:10)
May 22 09:18:29 volumio volumio[1165]: at Request.emit (node:events:514:28)
May 22 09:18:29 volumio volumio[1165]: at IncomingMessage. (/data/plugins/music_service/pandora/node_modules/request/request.js:1076:12)
May 22 09:18:29 volumio volumio[1165]: at Object.onceWrapper (node:events:628:28)
May 22 09:18:29 volumio volumio[1165]: at IncomingMessage.emit (node:events:526:35) {
May 22 09:18:29 volumio volumio[1165]: library: 'digital envelope routines',
May 22 09:18:29 volumio volumio[1165]: reason: 'unsupported',
May 22 09:18:29 volumio volumio[1165]: code: 'ERR_OSSL_EVP_UNSUPPORTED'
May 22 09:18:29 volumio volumio[1165]: }
May 22 09:18:29 volumio volumio[1165]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 22 09:18:29 volumio sudo[3054]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-22 09:17'
May 22 09:18:29 volumio sudo[3054]: 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="26d819930af572ef184e636a18ef1127de78f391"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed May 21 13:47:54 UTC 2025"
VOLUMIO_VERSION="0.068"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="82c7b61aa1619bd495d6c4f648975de8"