-- Logs begin at Wed 2025-10-29 07:23:40 UTC, end at Wed 2025-10-29 07:25:01 UTC. -- Oct 29 07:24:22 opera systemd[1]: Time has been changed Oct 29 07:24:22 opera volumio[1131]: info: Loading plugin "tidal"... Oct 29 07:24:23 opera volumio[1131]: info: Loading plugin "volspotconnect2"... Oct 29 07:24:24 opera volumio[1131]: info: Loading plugin "updater_comm"... Oct 29 07:24:24 opera volumio[1131]: info: Plugin mpdemulation is not enabled Oct 29 07:24:24 opera volumio[1131]: info: Loading plugin "rest_api"... Oct 29 07:24:24 opera volumio[1131]: info: Loading plugin "websocket"... Oct 29 07:24:24 opera volumio[1131]: info: ___________ START PLUGINS ___________ Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 29 07:24:24 opera volumio[1131]: info: [1761722664259] CoreMusicLibrary::Adding element Media Servers Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 29 07:24:24 opera volumio[1131]: info: Adding METAVOLUMIO REST API Endpoints Oct 29 07:24:24 opera volumio[1131]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 29 07:24:24 opera sudo[1437]: pam_unix(sudo:auth): conversation failed Oct 29 07:24:24 opera sudo[1437]: pam_unix(sudo:auth): auth could not identify password for [volumio] Oct 29 07:24:24 opera volumio[1131]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 29 07:24:24 opera volumio[1131]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 29 07:24:24 opera volumio[1131]: info: Preparing CD Folders Oct 29 07:24:24 opera volumio[1131]: info: Adding CD REST API Endpoints Oct 29 07:24:24 opera volumio[1131]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Oct 29 07:24:24 opera volumio[1131]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Oct 29 07:24:24 opera volumio[1131]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Oct 29 07:24:24 opera volumio[1131]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 29 07:24:24 opera volumio[1131]: info: Detecting CD presence with Flag file Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 29 07:24:24 opera volumio[1131]: info: [1761722664366] CoreMusicLibrary::Adding element Last_100 Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 29 07:24:24 opera volumio[1131]: info: Starting RAAT Plugin Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Oct 29 07:24:24 opera volumio[1131]: info: Additional UI Settings Added for plugin music_service/raat Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 07:24:24 opera volumio[1131]: info: Not Reporting Auto name since its the default one Oct 29 07:24:24 opera volumio[1131]: info: Streaming services startup Oct 29 07:24:24 opera volumio[1131]: info: Starting Streaming Daemon Oct 29 07:24:24 opera sudo[1454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 29 07:24:24 opera sudo[1454]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 07:24:24 opera systemd[1]: Stopping Volumio Streaming Daemon... Oct 29 07:24:24 opera systemd[1]: Starting Volumio Streaming Daemon... Oct 29 07:24:24 opera systemd[1]: Started Volumio Streaming Daemon. Oct 29 07:24:24 opera sudo[1454]: pam_unix(sudo:session): session closed for user root Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 29 07:24:24 opera volumio[1131]: info: [1761722664459] CoreMusicLibrary::Adding element Webradio Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 29 07:24:24 opera volumio-streaming-daemon[1464]: ############################ Oct 29 07:24:24 opera volumio-streaming-daemon[1464]: # Volumio Streaming Daemon # Oct 29 07:24:24 opera volumio-streaming-daemon[1464]: # Running on port 7777 # Oct 29 07:24:24 opera volumio-streaming-daemon[1464]: ############################ Oct 29 07:24:24 opera volumio-streaming-daemon[1464]: Checking variant in static cache Oct 29 07:24:24 opera volumio-streaming-daemon[1464]: Enabled services: Oct 29 07:24:24 opera volumio-streaming-daemon[1464]: 1) tidal Oct 29 07:24:24 opera volumio-streaming-daemon[1464]: 2) qobuz Oct 29 07:24:24 opera volumio[1131]: error: Hi Res Audio Failed Login: Missing Login Data Oct 29 07:24:24 opera volumio[1131]: info: Loading i18n strings for locale zh Oct 29 07:24:24 opera sudo[1476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Oct 29 07:24:24 opera sudo[1476]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 07:24:24 opera volumio[1131]: Updating browse sources language Oct 29 07:24:24 opera volumio[1131]: Cannot find translation for sourceMedia Servers Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 29 07:24:24 opera systemd[1]: Starting Volspotconnect2 Daemon... Oct 29 07:24:24 opera systemd[1]: Started Volspotconnect2 Daemon. Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 07:24:24 opera sudo[1476]: pam_unix(sudo:session): session closed for user root Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::initPlayerControls Oct 29 07:24:24 opera volumio[1481]: /bin/bash: /tmp/startconnect.sh: No such file or directory Oct 29 07:24:24 opera systemd[1]: volspotconnect2.service: main process exited, code=exited, status=127/n/a Oct 29 07:24:24 opera systemd[1]: Unit volspotconnect2.service entered failed state. Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 07:24:24 opera volumio[1131]: info: BOOT COMPLETED Oct 29 07:24:24 opera volumio[1131]: [Metrics] CommandRouter: 10s 284.95ms Oct 29 07:24:24 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 07:24:26 opera systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Oct 29 07:24:26 opera systemd[1]: Stopping Volspotconnect2 Daemon... Oct 29 07:24:26 opera systemd[1]: Starting Volspotconnect2 Daemon... Oct 29 07:24:26 opera systemd[1]: Started Volspotconnect2 Daemon. Oct 29 07:24:26 opera volumio[1491]: /bin/bash: /tmp/startconnect.sh: No such file or directory Oct 29 07:24:26 opera systemd[1]: volspotconnect2.service: main process exited, code=exited, status=127/n/a Oct 29 07:24:26 opera systemd[1]: Unit volspotconnect2.service entered failed state. Oct 29 07:24:29 opera systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Oct 29 07:24:29 opera systemd[1]: Stopping Volspotconnect2 Daemon... Oct 29 07:24:29 opera systemd[1]: Starting Volspotconnect2 Daemon... Oct 29 07:24:29 opera systemd[1]: Started Volspotconnect2 Daemon. Oct 29 07:24:29 opera volumio[1494]: /bin/bash: /tmp/startconnect.sh: No such file or directory Oct 29 07:24:29 opera systemd[1]: volspotconnect2.service: main process exited, code=exited, status=127/n/a Oct 29 07:24:29 opera systemd[1]: Unit volspotconnect2.service entered failed state. Oct 29 07:24:30 opera volumio[1131]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Oct 29 07:24:30 opera volumio[1131]: info: CoreCommandRouter::Close All Modals sent Oct 29 07:24:30 opera volumio[1131]: info: CoreCommandRouter::Close All Modals sent Oct 29 07:24:30 opera volumio[1131]: Express server listening on port 3000 Oct 29 07:24:30 opera volumio[1131]: [Metrics] WebUI: 16s 353.65ms Oct 29 07:24:30 opera volumio[1131]: Volumio Calling Home Oct 29 07:24:30 opera volumio[1131]: info: CoreStateMachine::resetVolumioState Oct 29 07:24:30 opera volumio[1131]: info: CoreStateMachine::getcurrentVolume Oct 29 07:24:30 opera volumio[1131]: info: CoreCommandRouter::volumioRetrievevolume Oct 29 07:24:30 opera volumio[1131]: info: Stopping AccessToken refresher cron for QOBUZ Oct 29 07:24:30 opera volumio[1131]: info: AccessToken refresher cron started for QOBUZ Oct 29 07:24:30 opera volumio[1131]: info: Stopping AccessToken refresher cron Oct 29 07:24:30 opera volumio[1131]: info: AccessToken refresher cron started Oct 29 07:24:30 opera volumio[1131]: info: CoreStateMachine::pushState Oct 29 07:24:30 opera volumio[1131]: info: CorePlayQueue::getTrack 0 Oct 29 07:24:30 opera volumio[1131]: info: CoreCommandRouter::volumioPushState Oct 29 07:24:30 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 07:24:30 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 07:24:30 opera sudo[1508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 29 07:24:30 opera sudo[1508]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 07:24:30 opera sudo[1508]: pam_unix(sudo:session): session closed for user root Oct 29 07:24:30 opera sudo[1512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 07:24:30 opera sudo[1512]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 07:24:30 opera sudo[1512]: pam_unix(sudo:session): session closed for user root Oct 29 07:24:30 opera sudo[1519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 29 07:24:30 opera sudo[1519]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 07:24:30 opera systemd[1]: Configuration file /lib/systemd/system/volumiobt.service is marked executable. Please remove executable permission bits. Proceeding anyway. Oct 29 07:24:30 opera systemd[1]: Starting Volumio Bluetooth Module... Oct 29 07:24:30 opera systemd[1]: Started Volumio Bluetooth Module. Oct 29 07:24:30 opera sudo[1519]: pam_unix(sudo:session): session closed for user root Oct 29 07:24:30 opera volumio[1131]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 29 07:24:30 opera sudo[1536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Oct 29 07:24:30 opera sudo[1536]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 07:24:30 opera volumiobt[1531]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Oct 29 07:24:30 opera sudo[1536]: pam_unix(sudo:session): session closed for user root Oct 29 07:24:30 opera volumio[1131]: info: Setting Device type: Tinkerboard Oct 29 07:24:30 opera volumio[1131]: [SpotifyConnect] Vollibrespot Daemon Started Oct 29 07:24:30 opera volumio[1131]: [SpotifyConnect] Starting metadata listener Oct 29 07:24:30 opera volumio[1131]: [SpotifyConnect] FE => [ 1, [length]: 1 ] Oct 29 07:24:30 opera volumio[1131]: info: RAAT Albumart path created successfully Oct 29 07:24:30 opera volumio[1131]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'Opera' Oct 29 07:24:30 opera volumio[1131]: We trust you have received the usual lecture from the local System Oct 29 07:24:30 opera volumio[1131]: Administrator. It usually boils down to these three things: Oct 29 07:24:30 opera volumio[1131]: #1) Respect the privacy of others. Oct 29 07:24:30 opera volumio[1131]: #2) Think before you type. Oct 29 07:24:30 opera volumio[1131]: #3) With great power comes great responsibility. Oct 29 07:24:30 opera volumio[1131]: sudo: no tty present and no askpass program specified Oct 29 07:24:30 opera volumio[1131]: error: Cannot initialize MPD Connection: MPD is not running Oct 29 07:24:30 opera sudo[1546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Oct 29 07:24:30 opera sudo[1546]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 07:24:30 opera sudo[1546]: pam_unix(sudo:session): session closed for user root Oct 29 07:24:30 opera volumio[1131]: info: Volumio BT Module successfully started Oct 29 07:24:30 opera volumio[1131]: info: Oct 29 07:24:30 opera volumio[1131]: ---------------------------- Client requests Volume 10 Oct 29 07:24:30 opera volumio[1131]: info: VolumeController::SetAlsaVolume10 Oct 29 07:24:30 opera volumio[1131]: info: CoreStateMachine::pushState Oct 29 07:24:30 opera volumio[1131]: info: CorePlayQueue::getTrack 0 Oct 29 07:24:30 opera volumio[1131]: info: CoreCommandRouter::volumioPushState Oct 29 07:24:30 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 07:24:30 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 07:24:30 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 29 07:24:30 opera volumio[1131]: No protocol specified Oct 29 07:24:30 opera volumio[1131]: xcb_connection_has_error() returned true Oct 29 07:24:30 opera volumio[1131]: info: Reloading queue from file Oct 29 07:24:30 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 07:24:30 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 07:24:30 opera sudo[1560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 29 07:24:30 opera sudo[1560]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 07:24:30 opera systemd[1]: Starting RAAT DAEMON... Oct 29 07:24:30 opera systemd[1]: Started RAAT DAEMON. Oct 29 07:24:30 opera sudo[1560]: pam_unix(sudo:session): session closed for user root Oct 29 07:24:30 opera volumio[1131]: info: Setting volume on startup at 10 Oct 29 07:24:30 opera volumio[1131]: info: CoreStateMachine::setRepeat null single undefined Oct 29 07:24:30 opera volumio[1131]: info: CoreStateMachine::pushState Oct 29 07:24:30 opera volumio[1131]: info: CorePlayQueue::getTrack 0 Oct 29 07:24:30 opera volumio[1131]: info: CoreCommandRouter::volumioPushState Oct 29 07:24:30 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 07:24:30 opera volumio[1131]: info: CoreStateMachine::setRandom false Oct 29 07:24:30 opera volumio[1131]: info: CoreStateMachine::pushState Oct 29 07:24:30 opera volumio[1131]: info: CorePlayQueue::getTrack 0 Oct 29 07:24:30 opera volumio[1131]: info: CoreCommandRouter::volumioPushState Oct 29 07:24:30 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 07:24:30 opera volumio[1131]: info: Raat Daemon started successfully Oct 29 07:24:30 opera volumio[1131]: info: Starting Shairport Sync Oct 29 07:24:30 opera volumio[1131]: info: Starting Shairport Sync Oct 29 07:24:30 opera sudo[1581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 29 07:24:30 opera sudo[1584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 29 07:24:30 opera sudo[1581]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 07:24:30 opera sudo[1584]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 07:24:30 opera sudo[1589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 29 07:24:30 opera sudo[1589]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 07:24:30 opera systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 29 07:24:30 opera systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 29 07:24:30 opera sudo[1581]: pam_unix(sudo:session): session closed for user root Oct 29 07:24:30 opera systemd[1]: Starting UPnP Renderer front-end to MPD... Oct 29 07:24:30 opera systemd[1]: Started UPnP Renderer front-end to MPD. Oct 29 07:24:30 opera systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 29 07:24:30 opera sudo[1589]: pam_unix(sudo:session): session closed for user root Oct 29 07:24:30 opera systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 29 07:24:30 opera systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 29 07:24:30 opera sudo[1584]: pam_unix(sudo:session): session closed for user root Oct 29 07:24:30 opera volumio[1131]: info: Executing endpoint restartRAATSocket Oct 29 07:24:30 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 29 07:24:30 opera volumio[1131]: Discovery: adding 730aec7a-167d-4d58-a17c-b59f3ee3fe37 Oct 29 07:24:30 opera volumio[1131]: info: mDNS: Found device Opera Oct 29 07:24:30 opera volumio[1131]: info: CoreCommandRouter::volumioGetState Oct 29 07:24:30 opera volumio[1131]: info: CorePlayQueue::getTrack 0 Oct 29 07:24:30 opera volumio[1131]: error: Could not retrieve plugin audio_interface multiroom Oct 29 07:24:30 opera volumio[1131]: error: Could not retrieve plugin audio_interface multiroom Oct 29 07:24:30 opera volumio[1131]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 29 07:24:30 opera volumio[1131]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 29 07:24:31 opera systemd[1]: Starting Music Player Daemon... Oct 29 07:24:31 opera systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Oct 29 07:24:31 opera systemd[1]: Stopping Volspotconnect2 Daemon... Oct 29 07:24:31 opera systemd[1]: Starting Volspotconnect2 Daemon... Oct 29 07:24:31 opera systemd[1]: Started Volspotconnect2 Daemon. Oct 29 07:24:31 opera volumio[1131]: info: Upmpdcli Daemon Started Oct 29 07:24:31 opera volumio[1131]: info: Shairport-Sync Started Oct 29 07:24:31 opera volumio[1131]: Error adding Membership: Error: addMembership EINVAL Oct 29 07:24:31 opera volumio[1131]: info: Shairport-Sync Started Oct 29 07:24:31 opera volumio[1131]: info: Volumio called home Oct 29 07:24:31 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 29 07:24:31 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 29 07:24:31 opera volumio[1131]: No protocol specified Oct 29 07:24:31 opera volumio[1131]: xcb_connection_has_error() returned true Oct 29 07:24:31 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 29 07:24:31 opera volumio[1131]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 29 07:24:31 opera volumio[1131]: info: CorePlayQueue::getTrack 0 Oct 29 07:24:31 opera volumio[1627]: vollibrespot v0.1.9 c98fd5d 2019-11-08 (librespot 4674946 2019-11-08) -- Built On 2019-11-08 Oct 29 07:24:31 opera volumio[1131]: info: CoreCommandRouter::volumioGetState Oct 29 07:24:31 opera volumio[1131]: info: CorePlayQueue::getTrack 0 Oct 29 07:24:31 opera volumio[1627]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Oct 29 07:24:31 opera sudo[1636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Oct 29 07:24:31 opera sudo[1636]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 07:24:31 opera volumiobt[1531]: pulseaudio: no process found Oct 29 07:24:31 opera sudo[1636]: pam_unix(sudo:session): session closed for user root Oct 29 07:24:31 opera dbus[733]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' Oct 29 07:24:31 opera systemd[1]: Starting RealtimeKit Scheduling Policy Service... Oct 29 07:24:31 opera volumio[1131]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 29 07:24:31 opera kernel: IPVS: Creating netns size=1496 id=2 Oct 29 07:24:31 opera kernel: IPVS: ftp: loaded support on port[0] = 21 Oct 29 07:24:31 opera dbus[733]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Oct 29 07:24:31 opera systemd[1]: Started RealtimeKit Scheduling Policy Service. Oct 29 07:24:31 opera rtkit-daemon[1647]: Successfully called chroot. Oct 29 07:24:31 opera rtkit-daemon[1647]: Successfully dropped privileges. Oct 29 07:24:31 opera rtkit-daemon[1647]: Successfully limited resources. Oct 29 07:24:31 opera rtkit-daemon[1647]: Running. Oct 29 07:24:31 opera rtkit-daemon[1647]: Canary thread running. Oct 29 07:24:31 opera rtkit-daemon[1647]: Watchdog thread running. Oct 29 07:24:31 opera pulseaudio[1646]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 29 07:24:31 opera pulseaudio[1646]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 29 07:24:31 opera volumiobt[1531]: Applying permissions Oct 29 07:24:31 opera volumiobt[1531]: Setting BT discoverable and pairable Oct 29 07:24:31 opera pulseaudio[1646]: org.bluez.Manager.GetProperties() failed: org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with signature "" on interface "org.bluez.Manager" doesn't exist Oct 29 07:24:31 opera bluetoothd[909]: Endpoint registered: sender=:1.19 path=/MediaEndpoint/A2DPSource Oct 29 07:24:31 opera bluetoothd[909]: Endpoint registered: sender=:1.19 path=/MediaEndpoint/A2DPSink Oct 29 07:24:31 opera volumiobt[1531]: [103B blob data] Oct 29 07:24:31 opera volumiobt[1531]: [99B blob data] Oct 29 07:24:31 opera volumiobt[1531]: [bluetooth]# -e power on Oct 29 07:24:31 opera volumiobt[1531]: Invalid command Oct 29 07:24:31 opera volumiobt[1531]: [bluetooth]# agent on Oct 29 07:24:31 opera volumiobt[1531]: [bluetooth]# discoverable on Oct 29 07:24:31 opera volumiobt[1531]: [bluetooth]# pairable on Oct 29 07:24:31 opera volumiobt[1531]: [bluetooth]# agent NoInputNoOutput Oct 29 07:24:31 opera volumiobt[1531]: Failed to register agent object Oct 29 07:24:31 opera volumiobt[1531]: [bluetooth]# default-agent Oct 29 07:24:31 opera volumiobt[1531]: No agent is registered Oct 29 07:24:31 opera volumiobt[1531]: [bluetooth]# quit Oct 29 07:24:31 opera volumiobt[1531]: [61B blob data] Oct 29 07:24:31 opera volumiobt[1531]: [89B blob data] Oct 29 07:24:31 opera volumiobt[1531]: [62B blob data] Oct 29 07:24:31 opera volumio[1131]: info: CoreCommandRouter::volumioGetVisibleSources Oct 29 07:24:31 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 29 07:24:31 opera volumio[1131]: info: CoreCommandRouter::volumioGetState Oct 29 07:24:31 opera volumio[1131]: info: CorePlayQueue::getTrack 0 Oct 29 07:24:31 opera volumio[1131]: info: CoreCommandRouter::volumioGetQueue Oct 29 07:24:31 opera volumio[1131]: info: CoreStateMachine::getQueue Oct 29 07:24:31 opera volumio[1131]: info: CorePlayQueue::getQueue Oct 29 07:24:31 opera volumio[1131]: info: Listing playlists Oct 29 07:24:32 opera volumio-streaming-daemon[1464]: Checking variant in static cache Oct 29 07:24:32 opera volumio[1131]: info: Fetching Streaming Services browse cache Oct 29 07:24:34 opera systemd[1]: Started Music Player Daemon. Oct 29 07:24:35 opera volumio-remote-updater[705]: [2025-10-29 07:24:35] [connect] Successful connection Oct 29 07:24:35 opera volumio-remote-updater[705]: [2025-10-29 07:24:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1761722675 101 Oct 29 07:24:36 opera volumio[1131]: info: CoreCommandRouter::volumioGetState Oct 29 07:24:36 opera volumio[1131]: info: CorePlayQueue::getTrack 0 Oct 29 07:24:36 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 29 07:24:36 opera volumio[1131]: error: Could not retrieve plugin audio_interface multiroom Oct 29 07:24:37 opera volumio[1131]: info: Listing playlists Oct 29 07:24:37 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 29 07:24:37 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 29 07:24:37 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 29 07:24:37 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 29 07:24:47 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 29 07:24:47 opera volumio[1131]: info: CURURI: music-library Oct 29 07:24:47 opera volumio[1131]: error: Failed to execute browseSource: TypeError: Cannot read property 'then' of undefined Oct 29 07:24:51 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 29 07:24:51 opera volumio[1131]: info: CURURI: music-library Oct 29 07:24:51 opera volumio[1131]: error: Failed to execute browseSource: TypeError: Cannot read property 'then' of undefined Oct 29 07:24:53 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 29 07:24:53 opera volumio[1131]: info: CURURI: music-library Oct 29 07:24:53 opera volumio[1131]: error: Failed to execute browseSource: TypeError: Cannot read property 'then' of undefined Oct 29 07:24:54 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 29 07:24:54 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 07:24:56 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 29 07:24:56 opera volumio[1131]: info: CURURI: music-library Oct 29 07:24:56 opera volumio[1131]: error: Failed to execute browseSource: TypeError: Cannot read property 'then' of undefined Oct 29 07:24:59 opera volumio[1131]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 29 07:24:59 opera volumio[1131]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 29 07:24:59 opera volumio[1131]: info: Retrieving Cloud Streaming UI Oct 29 07:24:59 opera volumio[1131]: info: Getting Tidal Cloud Configuration Oct 29 07:24:59 opera volumio[1131]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 29 07:24:59 opera volumio[1131]: info: Getting Qobuz Cloud Configuration Oct 29 07:24:59 opera volumio[1131]: info: Asking plugin for UI Config Oct 29 07:24:59 opera volumio[1131]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 29 07:24:59 opera volumio[1131]: info: Getting Spotify Cloud Configuration Oct 29 07:24:59 opera volumio[1131]: info: Asking plugin for UI Config Oct 29 07:24:59 opera volumio[1131]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 29 07:24:59 opera volumio[1131]: error: Could not retrieve plugin music_service spotify Oct 29 07:24:59 opera volumio[1131]: info: Saving Spotify Acccount Oct 29 07:24:59 opera volumio[1131]: info: Got it Oct 29 07:24:59 opera volumio[1131]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 29 07:24:59 opera volumio[1131]: info: Got Tidal Cloud Configuration Oct 29 07:24:59 opera volumio[1131]: info: Got it Oct 29 07:24:59 opera volumio[1131]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 29 07:24:59 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 29 07:24:59 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 29 07:24:59 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 29 07:24:59 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 29 07:24:59 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 07:24:59 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 07:24:59 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 07:24:59 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 07:24:59 opera volumio[1131]: info: CoreCommandRouter::volumioGetBrowseSources Oct 29 07:24:59 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 29 07:24:59 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 29 07:25:01 opera volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 29 07:25:01 opera volumio[1131]: verbose: ControllerMpd::sendMpdCommand update Oct 29 07:25:01 opera volumio[1131]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 07:25:01 opera volumio[1131]: TypeError: Cannot read property 'then' of undefined Oct 29 07:25:01 opera volumio[1131]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:330:6) Oct 29 07:25:01 opera volumio[1131]: at ControllerMpd.updateDb (/volumio/app/plugins/music_service/mpd/index.js:1838:15) Oct 29 07:25:01 opera volumio[1131]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1052:32) Oct 29 07:25:01 opera volumio[1131]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:911:26) Oct 29 07:25:01 opera volumio[1131]: at emitTwo (events.js:126:13) Oct 29 07:25:01 opera volumio[1131]: at Socket.emit (events.js:214:7) Oct 29 07:25:01 opera volumio[1131]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 29 07:25:01 opera volumio[1131]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Oct 29 07:25:01 opera volumio[1131]: at process._tickCallback (internal/process/next_tick.js:180:9) Oct 29 07:25:01 opera volumio[1131]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 07:25:01 opera sudo[1749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-29 07:24 Oct 29 07:25:01 opera sudo[1749]: 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"