-- Logs begin at Tue 2025-07-15 15:40:22 UTC, end at Mon 2025-09-01 12:42:27 UTC. -- Sep 01 12:42:14 opera systemd[1]: Time has been changed Sep 01 12:42:15 opera volumio[1331]: info: Loading plugin "volspotconnect2"... Sep 01 12:42:15 opera volumio[1331]: info: Loading plugin "updater_comm"... Sep 01 12:42:15 opera volumio[1331]: info: Plugin mpdemulation is not enabled Sep 01 12:42:15 opera volumio[1331]: info: Loading plugin "rest_api"... Sep 01 12:42:15 opera volumio[1331]: info: Loading plugin "websocket"... Sep 01 12:42:15 opera volumio[1331]: info: Loading plugin "spop"... Sep 01 12:42:16 opera volumio[1331]: info: ___________ START PLUGINS ___________ Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 12:42:16 opera volumio[1331]: info: [1756730536526] CoreMusicLibrary::Adding element Media Servers Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 12:42:16 opera volumio[1331]: info: Adding METAVOLUMIO REST API Endpoints Sep 01 12:42:16 opera volumio[1331]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Sep 01 12:42:16 opera sudo[1566]: pam_unix(sudo:auth): conversation failed Sep 01 12:42:16 opera sudo[1566]: pam_unix(sudo:auth): auth could not identify password for [volumio] Sep 01 12:42:16 opera volumio[1331]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 12:42:16 opera volumio[1331]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 12:42:16 opera volumio[1331]: info: Preparing CD Folders Sep 01 12:42:16 opera volumio[1331]: info: Adding CD REST API Endpoints Sep 01 12:42:16 opera volumio[1331]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Sep 01 12:42:16 opera volumio[1331]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Sep 01 12:42:16 opera volumio[1331]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Sep 01 12:42:16 opera volumio[1331]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Sep 01 12:42:16 opera volumio[1331]: info: Detecting CD presence with Flag file Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 12:42:16 opera volumio[1331]: info: [1756730536621] CoreMusicLibrary::Adding element Last_100 Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 12:42:16 opera volumio[1331]: info: Starting RAAT Plugin Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Sep 01 12:42:16 opera volumio[1331]: info: Additional UI Settings Added for plugin music_service/raat Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 12:42:16 opera volumio[1331]: info: Not Reporting Auto name since its the default one Sep 01 12:42:16 opera volumio[1331]: info: Streaming services startup Sep 01 12:42:16 opera volumio[1331]: info: Starting Streaming Daemon Sep 01 12:42:16 opera sudo[1583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 12:42:16 opera sudo[1583]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:42:16 opera systemd[1]: Stopping Volumio Streaming Daemon... Sep 01 12:42:16 opera systemd[1]: Starting Volumio Streaming Daemon... Sep 01 12:42:16 opera systemd[1]: Started Volumio Streaming Daemon. Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 12:42:16 opera volumio[1331]: info: [1756730536701] CoreMusicLibrary::Adding element Webradio Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 12:42:16 opera sudo[1583]: pam_unix(sudo:session): session closed for user root Sep 01 12:42:16 opera volumio-streaming-daemon[1593]: ############################ Sep 01 12:42:16 opera volumio-streaming-daemon[1593]: # Volumio Streaming Daemon # Sep 01 12:42:16 opera volumio-streaming-daemon[1593]: # Running on port 7777 # Sep 01 12:42:16 opera volumio-streaming-daemon[1593]: ############################ Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 12:42:16 opera volumio-streaming-daemon[1593]: Checking variant in static cache Sep 01 12:42:16 opera volumio-streaming-daemon[1593]: Enabled services: Sep 01 12:42:16 opera volumio-streaming-daemon[1593]: 1) tidal Sep 01 12:42:16 opera volumio-streaming-daemon[1593]: 2) qobuz Sep 01 12:42:16 opera volumio[1331]: error: Hi Res Audio Failed Login: Missing Login Data Sep 01 12:42:16 opera sudo[1605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Sep 01 12:42:16 opera sudo[1605]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:42:16 opera systemd[1]: Starting Volspotconnect2 Daemon... Sep 01 12:42:16 opera systemd[1]: Started Volspotconnect2 Daemon. Sep 01 12:42:16 opera sudo[1605]: pam_unix(sudo:session): session closed for user root Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 12:42:16 opera volumio[1611]: /bin/bash: /tmp/startconnect.sh: No such file or directory Sep 01 12:42:16 opera volumio[1331]: [SpotifyConnect] Creating VLS config file Sep 01 12:42:16 opera systemd[1]: volspotconnect2.service: main process exited, code=exited, status=127/n/a Sep 01 12:42:16 opera systemd[1]: Unit volspotconnect2.service entered failed state. Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:42:16 opera volumio[1331]: [SpotifyConnect] Starting metadata listener Sep 01 12:42:16 opera sudo[1614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Sep 01 12:42:16 opera sudo[1614]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:42:16 opera sudo[1614]: pam_unix(sudo:session): session closed for user root Sep 01 12:42:16 opera sudo[1623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Sep 01 12:42:16 opera volumio[1331]: info: Loading i18n strings for locale en Sep 01 12:42:16 opera sudo[1623]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:42:16 opera volumio[1331]: Updating browse sources language Sep 01 12:42:16 opera volumio[1331]: Cannot find translation for sourceMedia Servers Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 12:42:16 opera systemd[1]: Starting Volspotconnect2 Daemon... Sep 01 12:42:16 opera systemd[1]: Started Volspotconnect2 Daemon. Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::initPlayerControls Sep 01 12:42:16 opera sudo[1623]: pam_unix(sudo:session): session closed for user root Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 12:42:16 opera volumio[1331]: info: BOOT COMPLETED Sep 01 12:42:16 opera volumio[1331]: [Metrics] CommandRouter: 10s 348.12ms Sep 01 12:42:16 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 12:42:16 opera dhcpcd[1289]: wlan0: leased 192.168.0.101 for 7200 seconds Sep 01 12:42:16 opera avahi-daemon[1454]: Registering new address record for 192.168.0.101 on wlan0.IPv4. Sep 01 12:42:16 opera avahi-daemon[1454]: Withdrawing address record for 169.254.113.90 on wlan0. Sep 01 12:42:16 opera avahi-daemon[1454]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.113.90. Sep 01 12:42:16 opera dhcpcd[1289]: eth0: adding route to 192.168.1.0/24 Sep 01 12:42:16 opera dhcpcd[1289]: eth0: adding default route via 192.168.1.1 Sep 01 12:42:16 opera dhcpcd[1289]: wlan0: adding host route to 192.168.0.101 via 127.0.0.1 Sep 01 12:42:16 opera dhcpcd[1289]: wlan0: adding route to 192.168.0.0/24 Sep 01 12:42:16 opera dhcpcd[1289]: wlan0: adding default route via 192.168.0.1 Sep 01 12:42:16 opera dhcpcd[1289]: wlan0: deleting host route to 169.254.113.90 via 127.0.0.1 Sep 01 12:42:16 opera dhcpcd[1289]: wlan0: deleting route to 169.254.0.0/16 Sep 01 12:42:16 opera avahi-daemon[1454]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.101. Sep 01 12:42:16 opera volumio[1631]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Sep 01 12:42:16 opera volumio[1631]: Reading Config from "/tmp/volspotify.toml" Sep 01 12:42:16 opera volumio[1631]: Malformed config key: missing field `Authentication` Sep 01 12:42:16 opera dhcpcd[1289]: eth0: removing route to 192.168.1.0/24 Sep 01 12:42:16 opera dhcpcd[1289]: eth0: removing default route via 192.168.1.1 Sep 01 12:42:18 opera ntpd[825]: Listen normally on 5 wlan0 192.168.0.101 UDP 123 Sep 01 12:42:18 opera ntpd[825]: Deleting interface #4 wlan0, 169.254.113.90#123, interface stats: received=0, sent=0, dropped=0, active_time=13 secs Sep 01 12:42:18 opera ntpd[825]: peers refreshed Sep 01 12:42:18 opera nmbd[1036]: [2025/09/01 12:42:18.636569, 0] ../source3/libsmb/nmblib.c:873(send_udp) Sep 01 12:42:18 opera nmbd[1036]: Packet send failed to 169.254.255.255(138) ERRNO=Invalid argument Sep 01 12:42:18 opera systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Sep 01 12:42:18 opera systemd[1]: Stopping Volspotconnect2 Daemon... Sep 01 12:42:18 opera systemd[1]: Starting Volspotconnect2 Daemon... Sep 01 12:42:18 opera systemd[1]: Started Volspotconnect2 Daemon. Sep 01 12:42:18 opera volumio[1664]: /bin/bash: /tmp/startconnect.sh: No such file or directory Sep 01 12:42:18 opera systemd[1]: volspotconnect2.service: main process exited, code=exited, status=127/n/a Sep 01 12:42:18 opera systemd[1]: Unit volspotconnect2.service entered failed state. Sep 01 12:42:19 opera systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Sep 01 12:42:19 opera systemd[1]: Stopping Volspotconnect2 Daemon... Sep 01 12:42:19 opera systemd[1]: Starting Volspotconnect2 Daemon... Sep 01 12:42:19 opera systemd[1]: Started Volspotconnect2 Daemon. Sep 01 12:42:19 opera volumio[1667]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Sep 01 12:42:19 opera volumio[1667]: Reading Config from "/tmp/volspotify.toml" Sep 01 12:42:19 opera volumio[1667]: Malformed config key: missing field `Authentication` Sep 01 12:42:21 opera systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Sep 01 12:42:21 opera systemd[1]: Stopping Volspotconnect2 Daemon... Sep 01 12:42:21 opera systemd[1]: Starting Volspotconnect2 Daemon... Sep 01 12:42:21 opera systemd[1]: Started Volspotconnect2 Daemon. Sep 01 12:42:21 opera volumio[1671]: /bin/bash: /tmp/startconnect.sh: No such file or directory Sep 01 12:42:21 opera systemd[1]: volspotconnect2.service: main process exited, code=exited, status=127/n/a Sep 01 12:42:21 opera systemd[1]: Unit volspotconnect2.service entered failed state. Sep 01 12:42:21 opera systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Sep 01 12:42:21 opera systemd[1]: Stopping Volspotconnect2 Daemon... Sep 01 12:42:21 opera systemd[1]: Starting Volspotconnect2 Daemon... Sep 01 12:42:21 opera systemd[1]: Started Volspotconnect2 Daemon. Sep 01 12:42:21 opera volumio[1674]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Sep 01 12:42:21 opera volumio[1674]: Reading Config from "/tmp/volspotify.toml" Sep 01 12:42:21 opera volumio[1674]: Malformed config key: missing field `Authentication` Sep 01 12:42:22 opera volumio[1331]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Sep 01 12:42:22 opera volumio[1331]: info: CoreCommandRouter::Close All Modals sent Sep 01 12:42:22 opera volumio[1331]: info: CoreCommandRouter::Close All Modals sent Sep 01 12:42:22 opera volumio[1331]: Express server listening on port 3000 Sep 01 12:42:22 opera volumio[1331]: [Metrics] WebUI: 16s 383.29ms Sep 01 12:42:22 opera volumio[1331]: Volumio Calling Home Sep 01 12:42:22 opera volumio[1331]: info: CoreStateMachine::resetVolumioState Sep 01 12:42:22 opera volumio[1331]: info: CoreStateMachine::getcurrentVolume Sep 01 12:42:22 opera volumio[1331]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 12:42:22 opera volumio[1331]: info: Stopping AccessToken refresher cron for QOBUZ Sep 01 12:42:22 opera volumio[1331]: info: AccessToken refresher cron started for QOBUZ Sep 01 12:42:22 opera volumio[1331]: info: Stopping AccessToken refresher cron Sep 01 12:42:22 opera volumio[1331]: info: AccessToken refresher cron started Sep 01 12:42:22 opera volumio[1331]: info: CoreStateMachine::pushState Sep 01 12:42:22 opera volumio[1331]: info: CorePlayQueue::getTrack 0 Sep 01 12:42:22 opera volumio[1331]: info: CoreCommandRouter::volumioPushState Sep 01 12:42:22 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:42:22 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 12:42:22 opera sudo[1686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 12:42:22 opera sudo[1686]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:42:22 opera sudo[1686]: pam_unix(sudo:session): session closed for user root Sep 01 12:42:22 opera sudo[1689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 12:42:22 opera sudo[1689]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:42:22 opera sudo[1689]: pam_unix(sudo:session): session closed for user root Sep 01 12:42:22 opera sudo[1701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Sep 01 12:42:22 opera sudo[1701]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:42:22 opera systemd[1]: Configuration file /lib/systemd/system/volumiobt.service is marked executable. Please remove executable permission bits. Proceeding anyway. Sep 01 12:42:22 opera volumio[1331]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 01 12:42:22 opera systemd[1]: Starting Volumio Bluetooth Module... Sep 01 12:42:22 opera systemd[1]: Started Volumio Bluetooth Module. Sep 01 12:42:22 opera sudo[1701]: pam_unix(sudo:session): session closed for user root Sep 01 12:42:22 opera sudo[1712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Sep 01 12:42:22 opera sudo[1712]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:42:22 opera volumiobt[1709]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Sep 01 12:42:22 opera sudo[1712]: pam_unix(sudo:session): session closed for user root Sep 01 12:42:22 opera volumio[1331]: info: Setting Device type: Tinkerboard Sep 01 12:42:22 opera volumio[1331]: [SpotifyConnect] Vollibrespot Daemon service started! Sep 01 12:42:22 opera volumio[1331]: [Metrics] SpotifyConnect: 5s 917.74ms Sep 01 12:42:22 opera volumio[1331]: [SpotifyConnect] Vollibrespot Daemon Started Sep 01 12:42:22 opera volumio[1331]: [SpotifyConnect] Starting metadata listener Sep 01 12:42:22 opera volumio[1331]: [SpotifyConnect] FE => [ 1, [length]: 1 ] Sep 01 12:42:22 opera volumio[1331]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Sep 01 12:42:22 opera volumio[1331]: at Object._errnoException (util.js:1022:11) Sep 01 12:42:22 opera volumio[1331]: at _exceptionWithHostPort (util.js:1044:20) Sep 01 12:42:22 opera volumio[1331]: at _handle.lookup (dgram.js:266:18) Sep 01 12:42:22 opera volumio[1331]: at _combinedTickCallback (internal/process/next_tick.js:141:11) Sep 01 12:42:22 opera volumio[1331]: at process._tickCallback (internal/process/next_tick.js:180:9) Sep 01 12:42:22 opera volumio[1331]: [SpotifyConnect] Error [ERR_SOCKET_CANNOT_SEND]: Unable to send data Sep 01 12:42:22 opera volumio[1331]: at Socket.onListenError (dgram.js:362:22) Sep 01 12:42:22 opera volumio[1331]: at Object.onceWrapper (events.js:315:30) Sep 01 12:42:22 opera volumio[1331]: at emitOne (events.js:121:20) Sep 01 12:42:22 opera volumio[1331]: at Socket.emit (events.js:211:7) Sep 01 12:42:22 opera volumio[1331]: at _handle.lookup (dgram.js:267:14) Sep 01 12:42:22 opera volumio[1331]: at _combinedTickCallback (internal/process/next_tick.js:141:11) Sep 01 12:42:22 opera volumio[1331]: at process._tickCallback (internal/process/next_tick.js:180:9) Sep 01 12:42:22 opera volumio[1331]: info: RAAT Albumart path created successfully Sep 01 12:42:22 opera volumio[1331]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'Opera' Sep 01 12:42:22 opera volumio[1331]: sudo: no tty present and no askpass program specified Sep 01 12:42:22 opera volumio[1331]: info: MPD running with PID724 Sep 01 12:42:22 opera volumio[1331]: ,establishing connection Sep 01 12:42:22 opera sudo[1724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Sep 01 12:42:22 opera sudo[1724]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:42:22 opera sudo[1724]: pam_unix(sudo:session): session closed for user root Sep 01 12:42:22 opera volumio[1331]: info: Volumio BT Module successfully started Sep 01 12:42:22 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 12:42:22 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Sep 01 12:42:22 opera volumio[1331]: No protocol specified Sep 01 12:42:22 opera volumio[1331]: xcb_connection_has_error() returned true Sep 01 12:42:22 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:42:22 opera volumio[1331]: info: CoreCommandRouter::volumioGetState Sep 01 12:42:22 opera volumio[1331]: info: CorePlayQueue::getTrack 0 Sep 01 12:42:22 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:42:22 opera volumio[1331]: info: Reloading queue from file Sep 01 12:42:22 opera sudo[1737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Sep 01 12:42:22 opera volumio[1331]: error: updateQueue error: null Sep 01 12:42:22 opera sudo[1737]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:42:22 opera systemd[1]: Starting RAAT DAEMON... Sep 01 12:42:22 opera systemd[1]: Started RAAT DAEMON. Sep 01 12:42:22 opera sudo[1737]: pam_unix(sudo:session): session closed for user root Sep 01 12:42:22 opera volumio-remote-updater[703]: [2025-09-01 12:42:22] [connect] Successful connection Sep 01 12:42:23 opera volumio[1331]: info: CoreStateMachine::setRepeat true single undefined Sep 01 12:42:23 opera volumio[1331]: info: CoreStateMachine::pushState Sep 01 12:42:23 opera volumio[1331]: info: CorePlayQueue::getTrack 0 Sep 01 12:42:23 opera volumio[1331]: info: CoreCommandRouter::volumioPushState Sep 01 12:42:23 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:42:23 opera volumio[1331]: info: CoreStateMachine::setRandom false Sep 01 12:42:23 opera volumio[1331]: info: CoreStateMachine::pushState Sep 01 12:42:23 opera volumio[1331]: info: CorePlayQueue::getTrack 0 Sep 01 12:42:23 opera volumio[1331]: info: CoreCommandRouter::volumioPushState Sep 01 12:42:23 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:42:23 opera volumio[1331]: info: Raat Daemon started successfully Sep 01 12:42:23 opera systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Sep 01 12:42:23 opera systemd[1]: Stopping Volspotconnect2 Daemon... Sep 01 12:42:23 opera systemd[1]: Starting Volspotconnect2 Daemon... Sep 01 12:42:23 opera systemd[1]: Started Volspotconnect2 Daemon. Sep 01 12:42:23 opera volumio-remote-updater[703]: [2025-09-01 12:42:23] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1756730542 101 Sep 01 12:42:23 opera volumio[1757]: vollibrespot v0.1.9 c98fd5d 2019-11-08 (librespot 4674946 2019-11-08) -- Built On 2019-11-08 Sep 01 12:42:23 opera volumio[1757]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Sep 01 12:42:23 opera volumio[1331]: info: Executing endpoint restartRAATSocket Sep 01 12:42:23 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Sep 01 12:42:23 opera volumio[1331]: info: Volumio called home Sep 01 12:42:23 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 01 12:42:23 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 01 12:42:23 opera volumio[1331]: No protocol specified Sep 01 12:42:23 opera volumio[1331]: xcb_connection_has_error() returned true Sep 01 12:42:23 opera volumio[1331]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 01 12:42:23 opera volumio[1331]: info: Starting Shairport Sync Sep 01 12:42:23 opera volumio[1331]: info: Starting Shairport Sync Sep 01 12:42:23 opera sudo[1767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 12:42:23 opera systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Sep 01 12:42:23 opera systemd[1]: Stopping Volspotconnect2 Daemon... Sep 01 12:42:23 opera systemd[1]: Starting Volspotconnect2 Daemon... Sep 01 12:42:23 opera systemd[1]: Started Volspotconnect2 Daemon. Sep 01 12:42:23 opera sudo[1767]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:42:23 opera volumio[1771]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Sep 01 12:42:23 opera volumio[1771]: Reading Config from "/tmp/volspotify.toml" Sep 01 12:42:23 opera sudo[1776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 12:42:23 opera volumio[1771]: [Vollibrespot] : Using Alsa backend with device: plughw:5 Sep 01 12:42:23 opera sudo[1776]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:42:23 opera systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Sep 01 12:42:23 opera systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 01 12:42:23 opera sudo[1767]: pam_unix(sudo:session): session closed for user root Sep 01 12:42:23 opera systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 01 12:42:23 opera systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Sep 01 12:42:23 opera sudo[1794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 01 12:42:23 opera systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 01 12:42:23 opera sudo[1776]: pam_unix(sudo:session): session closed for user root Sep 01 12:42:23 opera volumio[1771]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Sep 01 12:42:23 opera sudo[1794]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:42:23 opera sudo[1798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Sep 01 12:42:23 opera sudo[1798]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:42:23 opera systemd[1]: Starting UPnP Renderer front-end to MPD... Sep 01 12:42:23 opera systemd[1]: Started UPnP Renderer front-end to MPD. Sep 01 12:42:23 opera sudo[1794]: pam_unix(sudo:session): session closed for user root Sep 01 12:42:23 opera volumiobt[1709]: pulseaudio: no process found Sep 01 12:42:23 opera sudo[1798]: pam_unix(sudo:session): session closed for user root Sep 01 12:42:23 opera dbus[725]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' Sep 01 12:42:23 opera systemd[1]: Starting RealtimeKit Scheduling Policy Service... Sep 01 12:42:23 opera kernel: IPVS: Creating netns size=1496 id=2 Sep 01 12:42:23 opera kernel: IPVS: ftp: loaded support on port[0] = 21 Sep 01 12:42:23 opera dbus[725]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Sep 01 12:42:23 opera systemd[1]: Started RealtimeKit Scheduling Policy Service. Sep 01 12:42:23 opera rtkit-daemon[1832]: Successfully called chroot. Sep 01 12:42:23 opera rtkit-daemon[1832]: Successfully dropped privileges. Sep 01 12:42:23 opera rtkit-daemon[1832]: Successfully limited resources. Sep 01 12:42:23 opera rtkit-daemon[1832]: Running. Sep 01 12:42:23 opera rtkit-daemon[1832]: Canary thread running. Sep 01 12:42:23 opera rtkit-daemon[1832]: Watchdog thread running. Sep 01 12:42:23 opera volumio[1331]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 01 12:42:23 opera volumio[1331]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 01 12:42:23 opera volumio[1331]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 01 12:42:24 opera pulseaudio[1831]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 01 12:42:24 opera pulseaudio[1831]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 01 12:42:24 opera bluetoothd[903]: Endpoint registered: sender=:1.20 path=/MediaEndpoint/A2DPSource Sep 01 12:42:24 opera bluetoothd[903]: Endpoint registered: sender=:1.20 path=/MediaEndpoint/A2DPSink Sep 01 12:42:24 opera pulseaudio[1831]: org.bluez.Manager.GetProperties() failed: org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with signature "" on interface "org.bluez.Manager" doesn't exist Sep 01 12:42:24 opera volumiobt[1709]: Applying permissions Sep 01 12:42:24 opera volumiobt[1709]: Setting BT discoverable and pairable Sep 01 12:42:24 opera volumiobt[1709]: [103B blob data] Sep 01 12:42:24 opera volumiobt[1709]: [84B blob data] Sep 01 12:42:24 opera volumiobt[1709]: [86B blob data] Sep 01 12:42:24 opera volumiobt[1709]: [91B blob data] Sep 01 12:42:24 opera volumiobt[1709]: [bluetooth]# -e power on Sep 01 12:42:24 opera volumiobt[1709]: Invalid command Sep 01 12:42:24 opera volumiobt[1709]: [bluetooth]# agent on Sep 01 12:42:24 opera volumiobt[1709]: [bluetooth]# discoverable on Sep 01 12:42:24 opera volumiobt[1709]: [bluetooth]# pairable on Sep 01 12:42:24 opera volumiobt[1709]: [bluetooth]# agent NoInputNoOutput Sep 01 12:42:24 opera volumiobt[1709]: Failed to register agent object Sep 01 12:42:24 opera volumiobt[1709]: [bluetooth]# default-agent Sep 01 12:42:24 opera volumiobt[1709]: No agent is registered Sep 01 12:42:24 opera volumiobt[1709]: [bluetooth]# quit Sep 01 12:42:24 opera volumiobt[1709]: [61B blob data] Sep 01 12:42:24 opera volumiobt[1709]: [89B blob data] Sep 01 12:42:24 opera volumiobt[1709]: [62B blob data] Sep 01 12:42:24 opera volumio[1331]: info: CorePlayQueue::getTrack 0 Sep 01 12:42:24 opera volumio[1331]: info: Upmpdcli Daemon Started Sep 01 12:42:24 opera volumio[1331]: info: Shairport-Sync Started Sep 01 12:42:24 opera volumio[1331]: Error adding Membership: Error: addMembership EINVAL Sep 01 12:42:24 opera volumio[1331]: info: Shairport-Sync Started Sep 01 12:42:24 opera volumio[1331]: info: CoreCommandRouter::volumioGetState Sep 01 12:42:24 opera volumio[1331]: info: CorePlayQueue::getTrack 0 Sep 01 12:42:24 opera volumio[1331]: Discovery: adding f58d2561-3b3b-40db-8522-07500e547abb Sep 01 12:42:24 opera volumio[1331]: info: mDNS: Found device Opera Sep 01 12:42:24 opera volumio[1331]: info: CoreCommandRouter::volumioGetState Sep 01 12:42:24 opera volumio[1331]: info: CorePlayQueue::getTrack 0 Sep 01 12:42:24 opera volumio[1331]: error: Could not retrieve plugin audio_interface multiroom Sep 01 12:42:24 opera volumio[1331]: error: Could not retrieve plugin audio_interface multiroom Sep 01 12:42:24 opera volumio[1331]: Discovery: this is already registered, f58d2561-3b3b-40db-8522-07500e547abb Sep 01 12:42:24 opera volumio[1331]: info: mDNS: Found device Opera Sep 01 12:42:24 opera volumio[1331]: info: CoreCommandRouter::volumioGetState Sep 01 12:42:24 opera volumio[1331]: info: CorePlayQueue::getTrack 0 Sep 01 12:42:24 opera volumio[1331]: error: Could not retrieve plugin audio_interface multiroom Sep 01 12:42:24 opera volumio[1331]: error: Could not retrieve plugin audio_interface multiroom Sep 01 12:42:25 opera volumio-streaming-daemon[1593]: Checking variant in static cache Sep 01 12:42:25 opera volumio[1331]: info: Fetching Streaming Services browse cache Sep 01 12:42:26 opera volumio[1331]: info: Checking Spotify Web API Sep 01 12:42:27 opera volumio[1331]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 12:42:27 opera volumio[1331]: { Error: addMembership ENODEV Sep 01 12:42:27 opera volumio[1331]: at _errnoException (util.js:1022:11) Sep 01 12:42:27 opera volumio[1331]: at Socket.addMembership (dgram.js:615:11) Sep 01 12:42:27 opera volumio[1331]: at Timeout.addMembership [as _onTimeout] (/volumio/node_modules/node-ssdp/lib/index.js:264:16) Sep 01 12:42:27 opera volumio[1331]: at ontimeout (timers.js:482:11) Sep 01 12:42:27 opera volumio[1331]: at tryOnTimeout (timers.js:317:5) Sep 01 12:42:27 opera volumio[1331]: at Timer.listOnTimeout (timers.js:277:5) code: 'ENODEV', errno: 'ENODEV', syscall: 'addMembership' } Sep 01 12:42:27 opera volumio[1331]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 12:42:27 opera sudo[1881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-01 12:41 Sep 01 12:42:27 opera sudo[1881]: 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"