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