-- Logs begin at Tue 2024-07-30 15:46:04 UTC, end at Sun 2025-06-01 09:07:26 UTC. -- Jun 01 09:06:42 opera volumio[1164]: info: CoreCommandRouter::volumioGetState Jun 01 09:06:42 opera volumio[1164]: info: CorePlayQueue::getTrack 0 Jun 01 09:06:42 opera sudo[1761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 01 09:06:42 opera sudo[1761]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 09:06:42 opera sudo[1761]: pam_unix(sudo:session): session closed for user root Jun 01 09:06:42 opera sudo[1763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 01 09:06:42 opera sudo[1763]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 09:06:42 opera sudo[1763]: pam_unix(sudo:session): session closed for user root Jun 01 09:06:42 opera sudo[1779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 01 09:06:42 opera sudo[1779]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 09:06:42 opera sudo[1779]: pam_unix(sudo:session): session closed for user root Jun 01 09:06:42 opera sudo[1781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 01 09:06:42 opera sudo[1781]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 09:06:42 opera sudo[1781]: pam_unix(sudo:session): session closed for user root Jun 01 09:06:42 opera volumio[1164]: info: CoreCommandRouter::volumioGetState Jun 01 09:06:42 opera volumio[1164]: info: CorePlayQueue::getTrack 0 Jun 01 09:06:42 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 01 09:06:42 opera volumio[1164]: error: Could not retrieve plugin audio_interface multiroom Jun 01 09:06:42 opera volumio[1164]: info: Listing playlists Jun 01 09:06:42 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 01 09:06:42 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 01 09:06:42 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 01 09:06:42 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 01 09:06:44 opera volumio[1164]: info: CoreCommandRouter::volumioGetQueue Jun 01 09:06:44 opera volumio[1164]: info: CoreStateMachine::getQueue Jun 01 09:06:44 opera volumio[1164]: info: CorePlayQueue::getQueue Jun 01 09:06:46 opera volumio[1164]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 09:06:46 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 01 09:06:46 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 09:06:46 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 01 09:06:46 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 09:06:46 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 09:06:46 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 09:06:46 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 09:06:46 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 09:06:46 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 09:06:46 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 09:06:46 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 01 09:06:46 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 01 09:06:46 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Jun 01 09:06:46 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 09:06:46 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 09:06:52 opera volumio[1164]: info: CoreCommandRouter::volumioGetVisibleSources Jun 01 09:06:52 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 09:07:00 opera volumio[1164]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 09:07:00 opera volumio[1164]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 09:07:00 opera volumio[1164]: info: Retrieving Cloud Streaming UI Jun 01 09:07:00 opera volumio[1164]: info: Getting Tidal Cloud Configuration Jun 01 09:07:00 opera volumio[1164]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 09:07:00 opera volumio[1164]: info: Getting Qobuz Cloud Configuration Jun 01 09:07:00 opera volumio[1164]: info: Asking plugin for UI Config Jun 01 09:07:00 opera volumio[1164]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 09:07:00 opera volumio[1164]: info: Getting Spotify Cloud Configuration Jun 01 09:07:00 opera volumio[1164]: info: Asking plugin for UI Config Jun 01 09:07:00 opera volumio[1164]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 09:07:00 opera volumio[1164]: error: Could not retrieve plugin music_service spotify Jun 01 09:07:00 opera volumio[1164]: info: Saving Spotify Acccount Jun 01 09:07:00 opera volumio[1164]: info: Got it Jun 01 09:07:00 opera volumio[1164]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 01 09:07:00 opera volumio[1164]: info: Got Tidal Cloud Configuration Jun 01 09:07:00 opera volumio[1164]: info: Got it Jun 01 09:07:00 opera volumio[1164]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 09:07:00 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 01 09:07:00 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 09:07:00 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 09:07:00 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 09:07:00 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 09:07:00 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 09:07:00 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 09:07:00 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 09:07:00 opera volumio[1164]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 09:07:00 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 09:07:00 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 01 09:07:04 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 09:07:05 opera volumio[1164]: info: Enabling MyMusic plugin bluetooth Jun 01 09:07:05 opera volumio[1164]: info: Enabling plugin bluetooth Jun 01 09:07:05 opera volumio[1164]: info: Loading plugin "bluetooth"... Jun 01 09:07:06 opera volumio[1164]: info: [1748768826001] Starting BluetoothController Jun 01 09:07:06 opera volumio[1164]: info: PLUGIN START: bluetooth Jun 01 09:07:06 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 01 09:07:06 opera volumio[1164]: info: Done. Jun 01 09:07:06 opera sudo[1808]: pam_unix(sudo:auth): conversation failed Jun 01 09:07:06 opera sudo[1808]: pam_unix(sudo:auth): auth could not identify password for [volumio] Jun 01 09:07:06 opera volumio[1164]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'Opera' Jun 01 09:07:06 opera volumio[1164]: We trust you have received the usual lecture from the local System Jun 01 09:07:06 opera volumio[1164]: Administrator. It usually boils down to these three things: Jun 01 09:07:06 opera volumio[1164]: #1) Respect the privacy of others. Jun 01 09:07:06 opera volumio[1164]: #2) Think before you type. Jun 01 09:07:06 opera volumio[1164]: #3) With great power comes great responsibility. Jun 01 09:07:06 opera volumio[1164]: sudo: no tty present and no askpass program specified Jun 01 09:07:08 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 09:07:09 opera sudo[1813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jun 01 09:07:09 opera sudo[1813]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 09:07:09 opera systemd[1]: Configuration file /lib/systemd/system/volumiobt.service is marked executable. Please remove executable permission bits. Proceeding anyway. Jun 01 09:07:09 opera systemd[1]: Starting Volumio Bluetooth Module... Jun 01 09:07:09 opera systemd[1]: Started Volumio Bluetooth Module. Jun 01 09:07:09 opera sudo[1813]: pam_unix(sudo:session): session closed for user root Jun 01 09:07:09 opera volumio[1164]: info: Volumio BT Module successfully started Jun 01 09:07:09 opera sudo[1822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jun 01 09:07:09 opera sudo[1822]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 09:07:09 opera volumiobt[1818]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jun 01 09:07:09 opera sudo[1822]: pam_unix(sudo:session): session closed for user root Jun 01 09:07:10 opera sudo[1832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jun 01 09:07:10 opera sudo[1832]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 09:07:10 opera volumiobt[1818]: pulseaudio: no process found Jun 01 09:07:10 opera sudo[1832]: pam_unix(sudo:session): session closed for user root Jun 01 09:07:10 opera dbus[699]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' Jun 01 09:07:10 opera systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jun 01 09:07:10 opera volumio[1164]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 01 09:07:10 opera kernel: IPVS: Creating netns size=1496 id=2 Jun 01 09:07:10 opera kernel: IPVS: ftp: loaded support on port[0] = 21 Jun 01 09:07:10 opera dbus[699]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jun 01 09:07:10 opera systemd[1]: Started RealtimeKit Scheduling Policy Service. Jun 01 09:07:10 opera rtkit-daemon[1843]: Successfully called chroot. Jun 01 09:07:10 opera rtkit-daemon[1843]: Successfully dropped privileges. Jun 01 09:07:10 opera rtkit-daemon[1843]: Successfully limited resources. Jun 01 09:07:10 opera rtkit-daemon[1843]: Running. Jun 01 09:07:10 opera rtkit-daemon[1843]: Watchdog thread running. Jun 01 09:07:10 opera rtkit-daemon[1843]: Canary thread running. Jun 01 09:07:10 opera pulseaudio[1842]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jun 01 09:07:10 opera pulseaudio[1842]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jun 01 09:07:10 opera pulseaudio[1842]: org.bluez.Manager.GetProperties() failed: org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with signature "" on interface "org.bluez.Manager" doesn't exist Jun 01 09:07:10 opera volumiobt[1818]: Applying permissions Jun 01 09:07:10 opera volumiobt[1818]: Setting BT discoverable and pairable Jun 01 09:07:10 opera bluetoothd[874]: Endpoint registered: sender=:1.22 path=/MediaEndpoint/A2DPSource Jun 01 09:07:10 opera bluetoothd[874]: Endpoint registered: sender=:1.22 path=/MediaEndpoint/A2DPSink Jun 01 09:07:10 opera volumiobt[1818]: [103B blob data] Jun 01 09:07:10 opera volumiobt[1818]: [80B blob data] Jun 01 09:07:10 opera volumiobt[1818]: [75B blob data] Jun 01 09:07:10 opera volumiobt[1818]: [76B blob data] Jun 01 09:07:10 opera volumiobt[1818]: [bluetooth]# -e power on Jun 01 09:07:10 opera volumiobt[1818]: Invalid command Jun 01 09:07:10 opera volumiobt[1818]: [bluetooth]# agent on Jun 01 09:07:10 opera volumiobt[1818]: [bluetooth]# discoverable on Jun 01 09:07:10 opera volumiobt[1818]: [bluetooth]# pairable on Jun 01 09:07:10 opera volumiobt[1818]: [bluetooth]# agent NoInputNoOutput Jun 01 09:07:10 opera volumiobt[1818]: Failed to register agent object Jun 01 09:07:10 opera volumiobt[1818]: [bluetooth]# default-agent Jun 01 09:07:10 opera volumiobt[1818]: No agent is registered Jun 01 09:07:10 opera volumiobt[1818]: [bluetooth]# quit Jun 01 09:07:10 opera volumiobt[1818]: [61B blob data] Jun 01 09:07:10 opera volumiobt[1818]: [89B blob data] Jun 01 09:07:10 opera volumiobt[1818]: [62B blob data] Jun 01 09:07:12 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 09:07:16 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 09:07:17 opera volumio[1164]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Kary%20Ng/98009ab7-8103-4b77-9583-1681011fe794.jpg' Jun 01 09:07:17 opera volumio[1164]: info: Enabling MyMusic plugin upnp Jun 01 09:07:17 opera volumio[1164]: info: Enabling plugin upnp Jun 01 09:07:17 opera volumio[1164]: info: Loading plugin "upnp"... Jun 01 09:07:17 opera volumio[1164]: info: [1748768837348] Starting Upmpd Daemon Jun 01 09:07:17 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 01 09:07:17 opera volumio[1164]: info: Done. Jun 01 09:07:17 opera volumio[1164]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%83%AD%E9%A1%B6/74557e56-8005-47cc-9bd9-144ea8d5792c.jpg' Jun 01 09:07:17 opera volumio[1164]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%A2%81%E6%99%93%E9%9B%AA/edd682e8-a807-4749-976e-207b58040abd.jpg' Jun 01 09:07:17 opera volumio[1164]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%90%B4%E9%9B%A8%E9%9C%8F/04ad3138-78a1-4e5a-b2be-1f387c5b0574.jpg' Jun 01 09:07:17 opera volumio[1164]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%8F%8C%E7%AC%99/ffabdce9-7cb5-4b3a-b9e6-80c5f2eeb294.jpg' Jun 01 09:07:20 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 09:07:24 opera volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 09:07:24 opera volumio[1164]: info: Enabling MyMusic plugin cd_controller Jun 01 09:07:24 opera volumio[1164]: info: Enabling plugin cd_controller Jun 01 09:07:24 opera volumio[1164]: info: Loading plugin "cd_controller"... Jun 01 09:07:24 opera volumio[1164]: info: PLUGIN START: cd_controller Jun 01 09:07:24 opera volumio[1164]: info: Preparing CD Folders Jun 01 09:07:24 opera volumio[1164]: info: Adding CD REST API Endpoints Jun 01 09:07:24 opera volumio[1164]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Jun 01 09:07:24 opera volumio[1164]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Jun 01 09:07:24 opera volumio[1164]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Jun 01 09:07:24 opera volumio[1164]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jun 01 09:07:24 opera volumio[1164]: info: Detecting CD presence with Flag file Jun 01 09:07:24 opera volumio[1164]: info: Done. Jun 01 09:07:26 opera volumio[1164]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 09:07:26 opera volumio[1164]: Error: Unable to resolve or reject the same promise twice Jun 01 09:07:26 opera volumio[1164]: at Promise.reject (/volumio/node_modules/kew/kew.js:189:43) Jun 01 09:07:26 opera volumio[1164]: at /volumio/app/plugins/miscellanea/albumart/albumart.js:157:15 Jun 01 09:07:26 opera volumio[1164]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:663:14) Jun 01 09:07:26 opera volumio[1164]: at emitOne (events.js:116:13) Jun 01 09:07:26 opera volumio[1164]: at ClientRequest.emit (events.js:211:7) Jun 01 09:07:26 opera volumio[1164]: at TLSSocket.socketErrorListener (_http_client.js:387:9) Jun 01 09:07:26 opera volumio[1164]: at emitOne (events.js:116:13) Jun 01 09:07:26 opera volumio[1164]: at TLSSocket.emit (events.js:211:7) Jun 01 09:07:26 opera volumio[1164]: at TLSSocket._emitTLSError (_tls_wrap.js:598:10) Jun 01 09:07:26 opera volumio[1164]: at TLSWrap.ssl.onerror (_tls_wrap.js:492:12) Jun 01 09:07:26 opera volumio[1164]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 09:07:26 opera sudo[1862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-01 09:06 Jun 01 09:07:26 opera sudo[1862]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="047a38c6e11e7058b10d3038ac9dcbff94f92828" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="opera" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Apr 8 17:29:57 CEST 2022" VOLUMIO_VERSION="1.033" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_VENDOR="Opera Consonance" VOLUMIO_MODEL="X5" VOLUMIO_VENDOR_MODEL="Opera X5" VOLUMIO_HASH="77fa3b0bf86939c934741dd9bb62adcc"