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