Feb 01 19:41:00 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 01 19:41:00 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 01 19:41:00 volumio sudo[2614]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:00 volumio volumio[2394]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 01 19:41:00 volumio systemd[1]: Reloading. Feb 01 19:41:00 volumio mpd[2621]: 2026-02-01T19:41:00 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 01 19:41:00 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 01 19:41:00 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 01 19:41:00 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 01 19:41:00 volumio sudo[2571]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:00 volumio sudo[2561]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:00 volumio sudo[2617]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:00 volumio volumio[2394]: info: touch_display: systemctl daemon-reload succeeded. Feb 01 19:41:00 volumio sudo[2720]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 01 19:41:00 volumio sudo[2720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:00 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 01 19:41:00 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 01 19:41:00 volumio sudo[2720]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:00 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 01 19:41:00 volumio volumio[2394]: error: MPD error: The expression evaluated to a falsy value: Feb 01 19:41:00 volumio volumio[2394]: assert.ok(self.idling) Feb 01 19:41:00 volumio volumio[2394]: error: The expression evaluated to a falsy value: Feb 01 19:41:00 volumio volumio[2394]: assert.ok(self.idling) Feb 01 19:41:00 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 01 19:41:00 volumio systemd[1]: shairport-sync.service: Consumed 1.541s CPU time. Feb 01 19:41:00 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 01 19:41:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 01 19:41:00 volumio volumio[2394]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Feb 01 19:41:00 volumio volumio[2394]: info: touch_display: Volumio Kiosk started. Feb 01 19:41:00 volumio volumio[2394]: info: CoreCommandRouter::volumioGetState Feb 01 19:41:00 volumio volumio[2394]: info: CorePlayQueue::getTrack 0 Feb 01 19:41:00 volumio volumio[2394]: info: MPD running with PID2621 Feb 01 19:41:00 volumio volumio[2394]: ,establishing connection Feb 01 19:41:00 volumio volumio[2394]: error: updateQueue error: null Feb 01 19:41:00 volumio volumio[2394]: error: updateQueue error: null Feb 01 19:41:00 volumio sudo[2612]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:00 volumio volumio[2394]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 01 19:41:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 01 19:41:00 volumio go-librespot[2724]: go-librespot daemon starting... Feb 01 19:41:00 volumio sudo[2650]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:00 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 01 19:41:00 volumio sudo[2677]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:00 volumio sudo[2674]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:00 volumio go-librespot[2725]: time="2026-02-01T19:41:00-06:00" level=info msg="running go-librespot 0.6.2" Feb 01 19:41:00 volumio go-librespot[2725]: time="2026-02-01T19:41:00-06:00" level=debug msg="app state loaded" Feb 01 19:41:00 volumio sudo[2668]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:00 volumio go-librespot[2725]: time="2026-02-01T19:41:00-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 01 19:41:00 volumio volumio[2394]: info: Shairport-Sync Started Feb 01 19:41:00 volumio volumio[2394]: Error adding Membership: Error: addMembership EINVAL Feb 01 19:41:00 volumio volumio[2394]: info: Shairport-Sync Started Feb 01 19:41:00 volumio volumio[2394]: info: Shairport-Sync Started Feb 01 19:41:00 volumio volumio[2394]: info: touch_display: X display number found: 0 Feb 01 19:41:00 volumio volumio[2394]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 01 19:41:00 volumio volumio[2394]: info: touch_display: X display number found: 0 Feb 01 19:41:00 volumio volumio[2394]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 01 19:41:02 volumio volumio[2394]: info: [jellyfin-poller] Polled http:\\192.168.68.52:8096: offline Feb 01 19:41:02 volumio sudo[2768]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 01 19:41:02 volumio sudo[2768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:02 volumio sudo[2770]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 01 19:41:02 volumio sudo[2768]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:02 volumio sudo[2770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:02 volumio sudo[2770]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:02 volumio sudo[2774]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 01 19:41:02 volumio sudo[2774]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:02 volumio sudo[2774]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:02 volumio volumio[2394]: info: Upmpdcli Daemon Started Feb 01 19:41:03 volumio volumio[2394]: info: go-librespot daemon successfully initialized Feb 01 19:41:05 volumio ntpd[1183]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Feb 01 19:41:05 volumio ntpd[1183]: DNS: dns_check: DNS error: -11, System error Feb 01 19:41:05 volumio ntpd[1183]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Feb 01 19:41:06 volumio volumio[2394]: info: Initializing connection to go-librespot Websocket Feb 01 19:41:09 volumio go-librespot[2725]: time="2026-02-01T19:41:09-06:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Feb 01 19:41:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 01 19:41:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 01 19:41:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 01 19:41:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 01 19:41:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 01 19:41:12 volumio go-librespot[2785]: go-librespot daemon starting... Feb 01 19:41:12 volumio go-librespot[2786]: time="2026-02-01T19:41:12-06:00" level=info msg="running go-librespot 0.6.2" Feb 01 19:41:12 volumio go-librespot[2786]: time="2026-02-01T19:41:12-06:00" level=debug msg="app state loaded" Feb 01 19:41:12 volumio go-librespot[2786]: time="2026-02-01T19:41:12-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 01 19:41:12 volumio volumio[2394]: info: Discovery: adding f0c727d6-ae68-4bd6-9818-59cc77d3c309 Feb 01 19:41:12 volumio volumio[2394]: info: Discovery: Found device Volumio Feb 01 19:41:12 volumio volumio[2394]: info: CoreCommandRouter::volumioGetState Feb 01 19:41:12 volumio volumio[2394]: info: CorePlayQueue::getTrack 0 Feb 01 19:41:12 volumio volumio[2394]: info: Discovery: this is already registered, f0c727d6-ae68-4bd6-9818-59cc77d3c309 Feb 01 19:41:12 volumio volumio[2394]: info: Discovery: Found device Volumio Feb 01 19:41:12 volumio volumio[2394]: info: CoreCommandRouter::volumioGetState Feb 01 19:41:12 volumio volumio[2394]: info: CorePlayQueue::getTrack 0 Feb 01 19:41:12 volumio volumio[2394]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 01 19:41:12 volumio volumio[2394]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 01 19:41:19 volumio volumio[2394]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.084&uuid=ecce845d03399a86815874e29734fcdb" http://updates.volumio.org/downloader-v1/track-device Feb 01 19:41:19 volumio volumio[2394]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 01 19:41:19 volumio volumio[2394]: Dload Upload Total Spent Left Speed Feb 01 19:41:19 volumio volumio[2394]: [843B blob data] Feb 01 19:41:19 volumio volumio[2394]: retrying in 5 seconds, trial 0 Feb 01 19:41:19 volumio volumio[2394]: info: Volumio Calling Home Feb 01 19:41:20 volumio go-librespot[2786]: time="2026-02-01T19:41:20-06:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Feb 01 19:41:20 volumio volumio[2394]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Feb 01 19:41:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 01 19:41:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 01 19:41:21 volumio bash[2321]: setdatetime-helper: all HTTPS Date fallbacks failed Feb 01 19:41:21 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 01 19:41:21 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 01 19:41:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 01 19:41:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 01 19:41:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 01 19:41:23 volumio go-librespot[2831]: go-librespot daemon starting... Feb 01 19:41:23 volumio go-librespot[2832]: time="2026-02-01T19:41:23-06:00" level=info msg="running go-librespot 0.6.2" Feb 01 19:41:23 volumio go-librespot[2832]: time="2026-02-01T19:41:23-06:00" level=debug msg="app state loaded" Feb 01 19:41:23 volumio go-librespot[2832]: time="2026-02-01T19:41:23-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 01 19:41:24 volumio volumio[2394]: info: [1769996484459] [Pandora] checkPing: Ping www.google.com, attempt 5 failed Feb 01 19:41:26 volumio go-librespot[2832]: time="2026-02-01T19:41:26-06:00" level=debug msg="new websocket client" Feb 01 19:41:26 volumio volumio[2394]: info: Connection to go-librespot Websocket established Feb 01 19:41:27 volumio volumio[2394]: info: CoreCommandRouter::volumioGetState Feb 01 19:41:27 volumio volumio[2394]: info: CorePlayQueue::getTrack 0 Feb 01 19:41:27 volumio volumio[2394]: info: CoreCommandRouter::volumioGetState Feb 01 19:41:27 volumio volumio[2394]: info: CorePlayQueue::getTrack 0 Feb 01 19:41:29 volumio volumio[2394]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 01 19:41:29 volumio volumio[2394]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 01 19:41:29 volumio volumio[2394]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 01 19:41:29 volumio volumio[2394]: info: [1769996489462] [Pandora] checkPing: Cannot ping www.google.com. Delaying Pandora start until Internet connects. Feb 01 19:41:29 volumio volumio[2394]: error: Plugin music_service pandora failed to complete 'onStart' in a timely fashion Feb 01 19:41:29 volumio volumio[2394]: info: ------------------------------------------- Feb 01 19:41:29 volumio volumio[2394]: info: ----- MyVolumio plugins startup ---- Feb 01 19:41:29 volumio volumio[2394]: info: ------------------------------------------- Feb 01 19:41:29 volumio volumio[2394]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 01 19:41:30 volumio volumio[2394]: info: Getting Spotify volume Feb 01 19:41:30 volumio volumio[2394]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 01 19:41:30 volumio volumio[2394]: info: CoreCommandRouter::volumioGetState Feb 01 19:41:30 volumio volumio[2394]: info: CorePlayQueue::getTrack 0 Feb 01 19:41:30 volumio go-librespot[2832]: time="2026-02-01T19:41:30-06:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Feb 01 19:41:30 volumio volumio[2394]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 01 19:41:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 01 19:41:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 01 19:41:30 volumio volumio[2394]: Error: socket hang up Feb 01 19:41:30 volumio volumio[2394]: at connResetException (node:internal/errors:720:14) Feb 01 19:41:30 volumio volumio[2394]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 01 19:41:30 volumio volumio[2394]: at Socket.emit (node:events:526:35) Feb 01 19:41:30 volumio volumio[2394]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 01 19:41:30 volumio volumio[2394]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 01 19:41:30 volumio volumio[2394]: code: 'ECONNRESET', Feb 01 19:41:30 volumio volumio[2394]: response: undefined Feb 01 19:41:30 volumio volumio[2394]: } Feb 01 19:41:30 volumio volumio[2394]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 01 19:41:30 volumio sudo[2857]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-01 19:40' Feb 01 19:41:30 volumio sudo[2857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:30 volumio sudo[2857]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 01 19:41:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 01 19:41:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 01 19:41:33 volumio go-librespot[2880]: go-librespot daemon starting... Feb 01 19:41:33 volumio go-librespot[2881]: time="2026-02-01T19:41:33-06:00" level=info msg="running go-librespot 0.6.2" Feb 01 19:41:33 volumio go-librespot[2881]: time="2026-02-01T19:41:33-06:00" level=debug msg="app state loaded" Feb 01 19:41:33 volumio go-librespot[2881]: time="2026-02-01T19:41:33-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 01 19:41:36 volumio volumio-remote-updater[956]: [2026-02-01 19:41:36] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 01 19:41:36 volumio volumio-remote-updater[956]: [2026-02-01 19:41:36] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 01 19:41:36 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 01 19:41:36 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 01 19:41:36 volumio systemd[1]: volumio.service: Consumed 9.233s CPU time. Feb 01 19:41:36 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 01 19:41:36 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 01 19:41:37 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Feb 01 19:41:37 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 01 19:41:37 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 01 19:41:37 volumio systemd[1]: volumio.service: Consumed 9.233s CPU time. Feb 01 19:41:37 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 01 19:41:37 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 01 19:41:37 volumio volumio[2895]: info: ------------------------------------------- Feb 01 19:41:37 volumio volumio[2895]: info: ----- Volumio3 ---- Feb 01 19:41:37 volumio volumio[2895]: info: ------------------------------------------- Feb 01 19:41:37 volumio volumio[2895]: info: ----- System startup ---- Feb 01 19:41:37 volumio volumio[2895]: info: ------------------------------------------- Feb 01 19:41:37 volumio volumio[2895]: info: MYVOLUMIO Environment detected Feb 01 19:41:37 volumio volumio[2895]: info: Plugin folders cleanup Feb 01 19:41:37 volumio volumio[2895]: info: Scanning into folder /volumio/app/plugins/ Feb 01 19:41:37 volumio volumio[2895]: info: Scanning category audio_interface Feb 01 19:41:37 volumio volumio[2895]: info: Scanning category miscellanea Feb 01 19:41:37 volumio volumio[2895]: info: Scanning category music_service Feb 01 19:41:37 volumio volumio[2895]: info: Scanning category plugins.json Feb 01 19:41:37 volumio volumio[2895]: info: Scanning category system_controller Feb 01 19:41:37 volumio volumio[2895]: info: Scanning category user_interface Feb 01 19:41:37 volumio volumio[2895]: info: Scanning into folder /data/plugins/ Feb 01 19:41:37 volumio volumio[2895]: info: Scanning category music_service Feb 01 19:41:37 volumio volumio[2895]: info: Scanning category system_hardware Feb 01 19:41:37 volumio volumio[2895]: info: Scanning category user_interface Feb 01 19:41:37 volumio volumio[2895]: info: Plugin folders cleanup completed Feb 01 19:41:37 volumio volumio[2895]: info: ------------------------------------------- Feb 01 19:41:37 volumio volumio[2895]: info: ----- Core plugins startup ---- Feb 01 19:41:37 volumio volumio[2895]: info: ------------------------------------------- Feb 01 19:41:37 volumio volumio[2895]: info: Loading plugins from folder /volumio/app/plugins/ Feb 01 19:41:37 volumio volumio[2895]: info: Adding plugin upnp to MyMusic Plugins Feb 01 19:41:37 volumio volumio[2895]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 01 19:41:37 volumio volumio[2895]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 01 19:41:37 volumio volumio[2895]: info: Loading plugins from folder /data/plugins/ Feb 01 19:41:37 volumio volumio[2895]: info: Adding plugin rtlsdr_radio to MyMusic Plugins Feb 01 19:41:37 volumio volumio[2895]: info: Loading plugin "system"... Feb 01 19:41:37 volumio volumio[2895]: info: Loading plugin "appearance"... Feb 01 19:41:38 volumio volumio[2895]: info: Loading plugin "network"... Feb 01 19:41:38 volumio volumio[2895]: info: Refreshing Cached IP Addresses Feb 01 19:41:38 volumio sudo[2923]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 01 19:41:38 volumio sudo[2923]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:38 volumio sudo[2925]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 01 19:41:38 volumio sudo[2925]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:38 volumio sudo[2923]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:38 volumio sudo[2925]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:38 volumio volumio[2895]: info: Loading plugin "services"... Feb 01 19:41:38 volumio volumio[2895]: info: Loading plugin "alsa_controller"... Feb 01 19:41:38 volumio sudo[2932]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 01 19:41:38 volumio sudo[2932]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:38 volumio volumio[2895]: warn: Unable to locate the audio output device IQaudIO Pi-DigiAMP+. Please configure a valid output device. Feb 01 19:41:38 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 01 19:41:38 volumio volumio[2895]: info: Loading plugin "wizard"... Feb 01 19:41:38 volumio volumio[2895]: info: Loading plugin "networkfs"... Feb 01 19:41:38 volumio volumio[2895]: info: Starting Udev Watcher for removable devices Feb 01 19:41:38 volumio volumio[2895]: info: Mounting Device PORN Feb 01 19:41:38 volumio sudo[2959]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/PORN -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Feb 01 19:41:38 volumio sudo[2959]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:38 volumio sudo[2959]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:38 volumio volumio[2895]: mount: /media/PORN: /dev/sda1 already mounted on /media/PORN. Feb 01 19:41:38 volumio volumio[2895]: dmesg(1) may have more information after failed mount system call. Feb 01 19:41:38 volumio volumio[2895]: error: Failed to mount PORN: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/PORN" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Feb 01 19:41:38 volumio volumio[2895]: mount: /media/PORN: /dev/sda1 already mounted on /media/PORN. Feb 01 19:41:38 volumio volumio[2895]: dmesg(1) may have more information after failed mount system call. Feb 01 19:41:38 volumio volumio[2895]: info: Ignoring mount for partition: boot Feb 01 19:41:38 volumio volumio[2895]: info: Ignoring mount for partition: volumio Feb 01 19:41:38 volumio volumio[2895]: info: Ignoring mount for partition: volumio_data Feb 01 19:41:38 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 01 19:41:38 volumio volumio[2895]: info: Loading plugin "volumio_command_line_client"... Feb 01 19:41:38 volumio volumio[2895]: info: Loading plugin "upnp"... Feb 01 19:41:38 volumio volumio[2895]: info: [1769996498229] Starting Upmpd Daemon Feb 01 19:41:38 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 01 19:41:38 volumio volumio[2895]: info: Loading plugin "my_music"... Feb 01 19:41:38 volumio volumio[2895]: info: Loading plugin "mpd"... Feb 01 19:41:38 volumio volumio[2895]: info: Loading plugin "upnp_browser"... Feb 01 19:41:38 volumio volumio[2895]: info: Starting UPNP Browser Feb 01 19:41:38 volumio volumio[2895]: info: Loading plugin "alarm-clock"... Feb 01 19:41:38 volumio volumio[2895]: info: Loading plugin "airplay_emulation"... Feb 01 19:41:38 volumio volumio[2895]: info: Starting Shairport Sync Feb 01 19:41:38 volumio volumio[2895]: info: Loading plugin "last_100"... Feb 01 19:41:38 volumio volumio[2895]: info: Loading plugin "webradio"... Feb 01 19:41:38 volumio volumio[2895]: info: Loading plugin "i2s_dacs"... Feb 01 19:41:38 volumio volumio[2895]: info: Loading plugin "volumiodiscovery"... Feb 01 19:41:38 volumio volumio[2895]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 01 19:41:38 volumio volumio[2895]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 01 19:41:38 volumio volumio[2895]: *** WARNING *** For more information see Feb 01 19:41:38 volumio volumio[2895]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 01 19:41:38 volumio volumio[2895]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 01 19:41:38 volumio volumio[2895]: *** WARNING *** For more information see Feb 01 19:41:38 volumio node[2895]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 01 19:41:38 volumio node[2895]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 01 19:41:38 volumio node[2895]: *** WARNING *** For more information see Feb 01 19:41:38 volumio node[2895]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 01 19:41:38 volumio node[2895]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 01 19:41:38 volumio node[2895]: *** WARNING *** For more information see Feb 01 19:41:38 volumio volumio[2895]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 01 19:41:38 volumio volumio[2895]: info: Discovery: Started advertising with name: Volumio Feb 01 19:41:38 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 01 19:41:38 volumio volumio[2895]: info: Loading plugin "bandcamp"... Feb 01 19:41:39 volumio volumio[2895]: info: Loading plugin "jellyfin"... Feb 01 19:41:39 volumio volumio[2895]: info: Loading plugin "spop"... Feb 01 19:41:39 volumio volumio[2895]: info: Loading plugin "outputs"... Feb 01 19:41:39 volumio volumio[2895]: info: Loading plugin "albumart"... Feb 01 19:41:39 volumio volumio[2895]: info: Plugin example_plugin is not enabled Feb 01 19:41:39 volumio volumio[2895]: info: Loading plugin "inputs"... Feb 01 19:41:39 volumio volumio[2895]: info: Loading plugin "updater_comm"... Feb 01 19:41:39 volumio volumio[2895]: info: Plugin mpdemulation is not enabled Feb 01 19:41:39 volumio volumio[2895]: info: Loading plugin "rest_api"... Feb 01 19:41:39 volumio volumio[2895]: info: Loading plugin "websocket"... Feb 01 19:41:39 volumio volumio[2895]: info: Starting Socket.io Server version 1.7.4 Feb 01 19:41:39 volumio volumio[2895]: info: Loading plugin "motherearthradio"... Feb 01 19:41:39 volumio volumio[2895]: info: Applying required configuration parameters for plugin motherearthradio Feb 01 19:41:39 volumio volumio[2895]: info: [1769996499870] [MotherEarth] API delay: 5 Feb 01 19:41:39 volumio volumio[2895]: info: Loading plugin "pandora"... Feb 01 19:41:39 volumio volumio[2963]: Forking 3 albumart workers Feb 01 19:41:40 volumio volumio[2895]: info: Applying required configuration parameters for plugin pandora Feb 01 19:41:40 volumio volumio[2895]: info: Loading plugin "rtlsdr_radio"... Feb 01 19:41:40 volumio volumio[2975]: Starting albumart workers Feb 01 19:41:40 volumio volumio[2973]: Starting albumart workers Feb 01 19:41:40 volumio volumio[2974]: Starting albumart workers Feb 01 19:41:40 volumio volumio[2895]: info: Loading plugin "rotaryencoder2"... Feb 01 19:41:40 volumio volumio[2895]: info: Loading plugin "touch_display"... Feb 01 19:41:40 volumio volumio[2895]: info: Applying required configuration parameters for plugin touch_display Feb 01 19:41:40 volumio volumio[2895]: info: Loading i18n strings for locale en Feb 01 19:41:40 volumio volumio[2895]: Updating browse sources language Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::initPlayerControls Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 01 19:41:40 volumio volumio[2895]: Express server listening on port 3000 Feb 01 19:41:40 volumio volumio[2895]: [Metrics] WebUI: 3s 271.00ms Feb 01 19:41:40 volumio volumio[2895]: info: CoreStateMachine::resetVolumioState Feb 01 19:41:40 volumio volumio[2895]: info: CoreStateMachine::getcurrentVolume Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::volumioRetrievevolume Feb 01 19:41:40 volumio volumio[2895]: info: Volumio Network Manager: Network status updated: 0 Feb 01 19:41:40 volumio volumio[2895]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '3'. Feb 01 19:41:40 volumio volumio[2895]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Feb 01 19:41:40 volumio volumio[2895]: info: VolumeController:: Volume=undefined Mute =false Feb 01 19:41:40 volumio volumio[2895]: info: CoreStateMachine::pushState Feb 01 19:41:40 volumio volumio[2895]: info: CorePlayQueue::getTrack 0 Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::volumioPushState Feb 01 19:41:40 volumio volumio[2895]: info: CoreStateMachine::updateTrackBlock Feb 01 19:41:40 volumio volumio[2895]: info: CorePlayQueue::getTrackBlock Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::volumioRetrievevolume Feb 01 19:41:40 volumio go-librespot[2881]: time="2026-02-01T19:41:40-06:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Feb 01 19:41:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 01 19:41:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 01 19:41:40 volumio volumio[2895]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 01 19:41:40 volumio volumio[2895]: info: Executing start script for DAC IQaudIO Pi-DigiAMP+ Feb 01 19:41:40 volumio volumio[2895]: info: Reloading queue from file Feb 01 19:41:40 volumio volumio[2895]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 01 19:41:40 volumio volumio[2895]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '3'. Feb 01 19:41:40 volumio volumio[2895]: info: CoreStateMachine::setRepeat null single undefined Feb 01 19:41:40 volumio volumio[2895]: info: CoreStateMachine::pushState Feb 01 19:41:40 volumio volumio[2895]: info: CorePlayQueue::getTrack 0 Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::volumioPushState Feb 01 19:41:40 volumio volumio[2895]: info: CoreStateMachine::setRandom null Feb 01 19:41:40 volumio volumio[2895]: info: CoreStateMachine::pushState Feb 01 19:41:40 volumio volumio[2895]: info: CorePlayQueue::getTrack 0 Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::volumioPushState Feb 01 19:41:40 volumio volumio[2895]: info: Setting Device type: Raspberry PI Feb 01 19:41:40 volumio volumio[2895]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 01 19:41:40 volumio volumio[2895]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Feb 01 19:41:40 volumio volumio[2895]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 01 19:41:40 volumio volumio[2895]: info: DAC script executed Feb 01 19:41:40 volumio volumio[2895]: info: Completed loading Core Plugins Feb 01 19:41:40 volumio volumio[2895]: info: Preparing to generate the ALSA configuration file Feb 01 19:41:40 volumio volumio[2895]: info: VolumeController:: Volume=undefined Mute =false Feb 01 19:41:40 volumio volumio[2895]: info: CoreStateMachine::pushState Feb 01 19:41:40 volumio volumio[2895]: info: CorePlayQueue::getTrack 0 Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::volumioPushState Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::volumioGetState Feb 01 19:41:40 volumio volumio[2895]: info: CorePlayQueue::getTrack 0 Feb 01 19:41:40 volumio volumio[2895]: info: Asound.conf file unchanged, so no further update is needed Feb 01 19:41:40 volumio volumio[2895]: info: Output device has changed, restarting MPD Feb 01 19:41:40 volumio volumio[2895]: info: Output device has changed, restarting Shairport Sync Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 01 19:41:40 volumio sudo[3044]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 01 19:41:40 volumio sudo[3044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:40 volumio sudo[3044]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:40 volumio sudo[3046]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 01 19:41:40 volumio sudo[3046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:40 volumio volumio[2895]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 01 19:41:40 volumio volumio[2895]: info: ___________ START PLUGINS ___________ Feb 01 19:41:40 volumio volumio[2895]: info: ControllerMpd::onStart: Initializing MPD Feb 01 19:41:40 volumio volumio[2895]: info: Creating MPD Configuration file Feb 01 19:41:40 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 01 19:41:40 volumio volumio[2895]: info: [1769996500928] CoreMusicLibrary::Adding element Media Servers Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 01 19:41:40 volumio volumio[2895]: info: UPNP Browser: Client initialized successfully Feb 01 19:41:40 volumio sudo[3054]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 01 19:41:40 volumio sudo[3054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 01 19:41:40 volumio sudo[3056]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 01 19:41:40 volumio sudo[3056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:40 volumio sudo[3054]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:40 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 01 19:41:40 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 01 19:41:40 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 01 19:41:40 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 01 19:41:40 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 01 19:41:40 volumio volumio[2895]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 01 19:41:40 volumio volumio[2895]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 01 19:41:40 volumio volumio[2895]: info: [1769996500960] CoreMusicLibrary::Adding element Last_100 Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 01 19:41:40 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 01 19:41:40 volumio volumio[2895]: info: [1769996500963] CoreMusicLibrary::Adding element Webradio Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 01 19:41:40 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 01 19:41:40 volumio volumio[2895]: info: Initializing BBC Radios Feb 01 19:41:40 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 01 19:41:40 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 01 19:41:40 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 01 19:41:40 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 01 19:41:40 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 01 19:41:40 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 01 19:41:40 volumio volumio[2895]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 01 19:41:40 volumio volumio[2895]: info: [1769996500997] CoreMusicLibrary::Adding element Bandcamp Discover Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 01 19:41:41 volumio volumio[2895]: Cannot find translation for source Bandcamp Discover Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 01 19:41:41 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 01 19:41:41 volumio volumio[2895]: info: Discovery: Getting this device information Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::volumioGetState Feb 01 19:41:41 volumio volumio[2895]: info: CorePlayQueue::getTrack 0 Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 01 19:41:41 volumio volumio[2895]: info: [1769996501007] CoreMusicLibrary::Adding element Jellyfin Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 01 19:41:41 volumio volumio[2895]: Cannot find translation for source Bandcamp Discover Feb 01 19:41:41 volumio volumio[2895]: Cannot find translation for source Jellyfin Feb 01 19:41:41 volumio volumio[2895]: info: [jellyfin] Initialized plugin with device info: {"host":"http://127.0.0.1","id":"f0c727d6-ae68-4bd6-9818-59cc77d3c309","name":"Volumio","serviceName":"Volumio","state":{"albumart":"http://127.0.0.1/albumart?cacheid=676&web=Metallica/Metallica/extralarge&path=%2Fmnt%2FUSB%2FPORN%2FMusic%2FAmazon%20MP3%2FMetallica%2FMetallica&metadata=false","artist":"Metallica","mute":false,"status":"stop","track":"Enter Sandman","volume":""},"type":"device"} Feb 01 19:41:41 volumio volumio[2895]: info: Creating Spotify config file Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:41 volumio sudo[3083]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 01 19:41:41 volumio sudo[3083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 01 19:41:41 volumio sudo[3083]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 01 19:41:41 volumio volumio[2895]: info: [1769996501104] CoreMusicLibrary::Adding element Mother Earth Radio Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 01 19:41:41 volumio volumio[2895]: Cannot find translation for source Bandcamp Discover Feb 01 19:41:41 volumio volumio[2895]: Cannot find translation for source Jellyfin Feb 01 19:41:41 volumio volumio[2895]: Cannot find translation for source Mother Earth Radio Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 01 19:41:41 volumio volumio[2895]: info: [1769996501105] CoreMusicLibrary::Adding element Pandora Radio Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 01 19:41:41 volumio volumio[2895]: Cannot find translation for source Bandcamp Discover Feb 01 19:41:41 volumio volumio[2895]: Cannot find translation for source Jellyfin Feb 01 19:41:41 volumio volumio[2895]: Cannot find translation for source Mother Earth Radio Feb 01 19:41:41 volumio volumio[2895]: Cannot find translation for source Pandora Radio Feb 01 19:41:41 volumio volumio[2895]: info: [1769996501106] ControllerPandora::initialSetup Feb 01 19:41:41 volumio volumio[2895]: info: [1769996501108] ControllerPandora::checkPing Feb 01 19:41:41 volumio volumio[2895]: info: [RTL-SDR Radio] Starting plugin Feb 01 19:41:41 volumio volumio[2895]: info: [RTL-SDR Radio] Loaded i18n strings for language: en Feb 01 19:41:41 volumio volumio[2895]: info: Loading i18n strings for locale en Feb 01 19:41:41 volumio sudo[3087]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Feb 01 19:41:41 volumio sudo[3087]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 01 19:41:41 volumio volumio[2895]: info: Volumio Calling Home Feb 01 19:41:41 volumio sudo[3087]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:41 volumio sudo[3091]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Feb 01 19:41:41 volumio sudo[3091]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:41 volumio sudo[3091]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:41 volumio volumio[2895]: info: [RTL-SDR Radio] snd-aloop already loaded Feb 01 19:41:41 volumio volumio[2895]: info: [RTL-SDR Radio] No stations database found, creating v2 Feb 01 19:41:41 volumio volumio[2895]: info: [RTL-SDR Radio] Database loaded at: 2026-02-02T01:41:41.167Z Feb 01 19:41:41 volumio volumio[2895]: info: [RTL-SDR Radio] Loading blocklist from: /data/plugins/music_service/rtlsdr_radio/blocklist.json Feb 01 19:41:41 volumio volumio[2895]: info: [RTL-SDR Radio] Got 21 phrases from file Feb 01 19:41:41 volumio volumio[2895]: info: [RTL-SDR Radio] Updated metadata blocklist Feb 01 19:41:41 volumio volumio[2895]: info: [RTL-SDR Radio] Metadata module now has 21 phrases Feb 01 19:41:41 volumio sudo[3101]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Feb 01 19:41:41 volumio sudo[3101]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:41 volumio sudo[3103]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Feb 01 19:41:41 volumio sudo[3103]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:41 volumio sudo[3106]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 01 19:41:41 volumio sudo[3106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:41 volumio volumio[2895]: info: [RTL-SDR Radio] Management server started on port 3456 Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 01 19:41:41 volumio volumio[2895]: info: [1769996501229] CoreMusicLibrary::Adding element FM/DAB Radio Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 01 19:41:41 volumio volumio[2895]: Cannot find translation for source Bandcamp Discover Feb 01 19:41:41 volumio volumio[2895]: Cannot find translation for source Jellyfin Feb 01 19:41:41 volumio volumio[2895]: Cannot find translation for source Mother Earth Radio Feb 01 19:41:41 volumio volumio[2895]: Cannot find translation for source Pandora Radio Feb 01 19:41:41 volumio volumio[2895]: Cannot find translation for source FM/DAB Radio Feb 01 19:41:41 volumio volumio[2895]: info: [RTL-SDR Radio] Plugin started successfully Feb 01 19:41:41 volumio systemd[1]: Reloading. Feb 01 19:41:41 volumio volumio[2895]: info: touch_display: No backlight interface detected. Feb 01 19:41:41 volumio sudo[3113]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Feb 01 19:41:41 volumio volumio[2895]: info: MPD Permissions set Feb 01 19:41:41 volumio volumio[2895]: info: MPD Permissions set Feb 01 19:41:41 volumio sudo[3113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:41 volumio volumio[2895]: info: Spotify config file written Feb 01 19:41:41 volumio sudo[3126]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=23 pin_b=24 relative_axis=true steps-per-period=2 Feb 01 19:41:41 volumio sudo[3126]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:41 volumio sudo[3133]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 01 19:41:41 volumio sudo[3133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:41 volumio sudo[3113]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:41 volumio sudo[3126]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:41:41 volumio volumio[2895]: info: No need to fix Spotify hosts Feb 01 19:41:41 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 01 19:41:41 volumio volumio[2895]: info: Listing playlists Feb 01 19:41:41 volumio volumio[2895]: info: touch_display: IframeKeyboardBridge extension installed successfully Feb 01 19:41:41 volumio volumio[2895]: info: Starting Shairport Sync Feb 01 19:41:41 volumio sudo[3152]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=25 active_low=true gpio_pull=up keycode=20 Feb 01 19:41:41 volumio sudo[3152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:41 volumio volumio[2895]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Feb 01 19:41:41 volumio sudo[3154]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 01 19:41:41 volumio sudo[3154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:41 volumio volumio[2895]: info: Starting Shairport Sync Feb 01 19:41:41 volumio volumio[2895]: info: Starting Shairport Sync Feb 01 19:41:41 volumio sudo[3160]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Feb 01 19:41:41 volumio sudo[3160]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:41 volumio sudo[3152]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:41 volumio sudo[3160]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:41 volumio sudo[3164]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 01 19:41:41 volumio sudo[3164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:41 volumio sudo[3162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 01 19:41:41 volumio sudo[3162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:41 volumio volumio[2895]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Feb 01 19:41:41 volumio sudo[3169]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 01 19:41:41 volumio sudo[3169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:41 volumio sudo[3162]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:41 volumio volumio[2895]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Feb 01 19:41:41 volumio sudo[3174]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Feb 01 19:41:41 volumio sudo[3174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:41 volumio sudo[3174]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:41 volumio sudo[3177]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Feb 01 19:41:41 volumio sudo[3177]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:41 volumio sudo[3177]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:41 volumio volumio-remote-updater[956]: [2026-02-01 19:41:41] [connect] Successful connection Feb 01 19:41:41 volumio volumio-remote-updater[956]: [2026-02-01 19:41:41] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1769996501 101 Feb 01 19:41:41 volumio volumio[2895]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 4 Feb 01 19:41:41 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 01 19:41:41 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 01 19:41:41 volumio mpd[3085]: 2026-02-01T19:41:41 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 01 19:41:41 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 01 19:41:41 volumio sudo[3046]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:41 volumio sudo[3106]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:41 volumio sudo[3056]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:41 volumio volumio[2895]: info: touch_display: systemctl daemon-reload succeeded. Feb 01 19:41:41 volumio systemd[1]: Reloading. Feb 01 19:41:41 volumio sudo[3101]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:41 volumio sudo[3184]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 01 19:41:41 volumio sudo[3184]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:42 volumio volumio[2895]: error: MPD error: The expression evaluated to a falsy value: Feb 01 19:41:42 volumio volumio[2895]: assert.ok(self.idling) Feb 01 19:41:42 volumio volumio[2895]: error: The expression evaluated to a falsy value: Feb 01 19:41:42 volumio volumio[2895]: assert.ok(self.idling) Feb 01 19:41:42 volumio volumio[2895]: error: updateQueue error: null Feb 01 19:41:42 volumio volumio[2895]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 01 19:41:42 volumio volumio[2895]: info: MPD running with PID3085 Feb 01 19:41:42 volumio volumio[2895]: ,establishing connection Feb 01 19:41:42 volumio volumio[2895]: error: updateQueue error: null Feb 01 19:41:42 volumio sudo[2932]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:42 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 01 19:41:42 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 01 19:41:42 volumio sudo[3103]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:42 volumio volumio[2895]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 01 19:41:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 01 19:41:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 01 19:41:42 volumio go-librespot[3203]: go-librespot daemon starting... Feb 01 19:41:42 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 01 19:41:42 volumio sudo[3184]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:42 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 01 19:41:42 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 01 19:41:42 volumio systemd[1]: shairport-sync.service: Consumed 1.592s CPU time. Feb 01 19:41:42 volumio sudo[3133]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:42 volumio volumio[2895]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Feb 01 19:41:42 volumio volumio[2895]: info: touch_display: Volumio Kiosk started. Feb 01 19:41:42 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 01 19:41:42 volumio sudo[3164]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:42 volumio sudo[3154]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:42 volumio go-librespot[3204]: time="2026-02-01T19:41:42-06:00" level=info msg="running go-librespot 0.6.2" Feb 01 19:41:42 volumio go-librespot[3204]: time="2026-02-01T19:41:42-06:00" level=debug msg="app state loaded" Feb 01 19:41:42 volumio volumio[2895]: info: CoreCommandRouter::volumioGetState Feb 01 19:41:42 volumio volumio[2895]: info: CorePlayQueue::getTrack 0 Feb 01 19:41:42 volumio sudo[3169]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:42 volumio go-librespot[3204]: time="2026-02-01T19:41:42-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 01 19:41:42 volumio volumio[2895]: info: Shairport-Sync Started Feb 01 19:41:42 volumio volumio[2895]: Error adding Membership: Error: addMembership EINVAL Feb 01 19:41:42 volumio volumio[2895]: info: Shairport-Sync Started Feb 01 19:41:42 volumio volumio[2895]: info: Shairport-Sync Started Feb 01 19:41:42 volumio volumio[2895]: info: touch_display: X display number found: 0 Feb 01 19:41:42 volumio volumio[2895]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 01 19:41:42 volumio volumio[2895]: info: touch_display: X display number found: 0 Feb 01 19:41:42 volumio volumio[2895]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 01 19:41:44 volumio volumio[2895]: info: [jellyfin-poller] Polled http:\\192.168.68.52:8096: offline Feb 01 19:41:45 volumio volumio[2895]: info: go-librespot daemon successfully initialized Feb 01 19:41:48 volumio sudo[3249]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 01 19:41:48 volumio sudo[3249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:48 volumio sudo[3249]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:48 volumio sudo[3251]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 01 19:41:48 volumio sudo[3251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:48 volumio sudo[3251]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:48 volumio sudo[3255]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 01 19:41:48 volumio sudo[3255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:41:48 volumio sudo[3255]: pam_unix(sudo:session): session closed for user root Feb 01 19:41:48 volumio volumio[2895]: info: Upmpdcli Daemon Started Feb 01 19:41:48 volumio volumio[2895]: info: Initializing connection to go-librespot Websocket Feb 01 19:41:48 volumio go-librespot[3204]: time="2026-02-01T19:41:48-06:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Feb 01 19:41:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 01 19:41:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 01 19:41:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 01 19:41:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 01 19:41:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 01 19:41:52 volumio go-librespot[3259]: go-librespot daemon starting... Feb 01 19:41:52 volumio go-librespot[3260]: time="2026-02-01T19:41:52-06:00" level=info msg="running go-librespot 0.6.2" Feb 01 19:41:52 volumio go-librespot[3260]: time="2026-02-01T19:41:52-06:00" level=debug msg="app state loaded" Feb 01 19:41:52 volumio go-librespot[3260]: time="2026-02-01T19:41:52-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 01 19:41:57 volumio volumio[2895]: info: Discovery: adding f0c727d6-ae68-4bd6-9818-59cc77d3c309 Feb 01 19:41:57 volumio volumio[2895]: info: Discovery: Found device Volumio Feb 01 19:41:57 volumio volumio[2895]: info: CoreCommandRouter::volumioGetState Feb 01 19:41:57 volumio volumio[2895]: info: CorePlayQueue::getTrack 0 Feb 01 19:41:57 volumio volumio[2895]: info: Discovery: this is already registered, f0c727d6-ae68-4bd6-9818-59cc77d3c309 Feb 01 19:41:57 volumio volumio[2895]: info: Discovery: Found device Volumio Feb 01 19:41:57 volumio volumio[2895]: info: CoreCommandRouter::volumioGetState Feb 01 19:41:57 volumio volumio[2895]: info: CorePlayQueue::getTrack 0 Feb 01 19:41:57 volumio volumio[2895]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 01 19:41:57 volumio volumio[2895]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 01 19:41:58 volumio volumio[2895]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.084&uuid=ecce845d03399a86815874e29734fcdb" http://updates.volumio.org/downloader-v1/track-device Feb 01 19:41:58 volumio volumio[2895]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 01 19:41:58 volumio volumio[2895]: Dload Upload Total Spent Left Speed Feb 01 19:41:58 volumio volumio[2895]: [764B blob data] Feb 01 19:41:58 volumio volumio[2895]: retrying in 5 seconds, trial 0 Feb 01 19:41:58 volumio volumio[2895]: info: Volumio Calling Home Feb 01 19:42:00 volumio go-librespot[3260]: time="2026-02-01T19:42:00-06:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Feb 01 19:42:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 01 19:42:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 01 19:42:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 01 19:42:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 01 19:42:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 01 19:42:04 volumio go-librespot[3285]: go-librespot daemon starting... Feb 01 19:42:04 volumio go-librespot[3286]: time="2026-02-01T19:42:04-06:00" level=info msg="running go-librespot 0.6.2" Feb 01 19:42:04 volumio go-librespot[3286]: time="2026-02-01T19:42:04-06:00" level=debug msg="app state loaded" Feb 01 19:42:04 volumio go-librespot[3286]: time="2026-02-01T19:42:04-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 01 19:42:04 volumio volumio[2895]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Feb 01 19:42:06 volumio volumio[2895]: info: [1769996526116] [Pandora] checkPing: Ping www.google.com, attempt 5 failed Feb 01 19:42:11 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 01 19:42:11 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 01 19:42:11 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 01 19:42:11 volumio volumio[2895]: info: [1769996531116] [Pandora] checkPing: Cannot ping www.google.com. Delaying Pandora start until Internet connects. Feb 01 19:42:11 volumio volumio[2895]: error: Plugin music_service pandora failed to complete 'onStart' in a timely fashion Feb 01 19:42:11 volumio volumio[2895]: info: ------------------------------------------- Feb 01 19:42:11 volumio volumio[2895]: info: ----- MyVolumio plugins startup ---- Feb 01 19:42:11 volumio volumio[2895]: info: ------------------------------------------- Feb 01 19:42:11 volumio volumio[2895]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 01 19:42:13 volumio go-librespot[3286]: time="2026-02-01T19:42:13-06:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Feb 01 19:42:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 01 19:42:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 01 19:42:16 volumio volumio[2895]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 01 19:42:16 volumio volumio[2895]: info: CoreCommandRouter::volumioGetState Feb 01 19:42:16 volumio volumio[2895]: info: CorePlayQueue::getTrack 0 Feb 01 19:42:16 volumio volumio[2895]: info: CoreCommandRouter::volumioGetState Feb 01 19:42:16 volumio volumio[2895]: info: CorePlayQueue::getTrack 0 Feb 01 19:42:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 01 19:42:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 01 19:42:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 01 19:42:16 volumio go-librespot[3314]: go-librespot daemon starting... Feb 01 19:42:16 volumio go-librespot[3315]: time="2026-02-01T19:42:16-06:00" level=info msg="running go-librespot 0.6.2" Feb 01 19:42:16 volumio go-librespot[3315]: time="2026-02-01T19:42:16-06:00" level=debug msg="app state loaded" Feb 01 19:42:16 volumio go-librespot[3315]: time="2026-02-01T19:42:16-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 01 19:42:17 volumio volumio[2895]: info: [jellyfin-poller] Polled http:\\192.168.68.52:8096: offline Feb 01 19:42:19 volumio volumio[2895]: info: Initializing connection to go-librespot Websocket Feb 01 19:42:20 volumio volumio[2895]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.084&uuid=ecce845d03399a86815874e29734fcdb" http://updates.volumio.org/downloader-v1/track-device Feb 01 19:42:20 volumio volumio[2895]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 01 19:42:20 volumio volumio[2895]: Dload Upload Total Spent Left Speed Feb 01 19:42:20 volumio volumio[2895]: [1001B blob data] Feb 01 19:42:20 volumio volumio[2895]: retrying in 5 seconds, trial 1 Feb 01 19:42:20 volumio volumio[2895]: info: Volumio Calling Home Feb 01 19:42:24 volumio dhcpcd[1025]: eth0: carrier acquired Feb 01 19:42:24 volumio kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Feb 01 19:42:24 volumio dhcpcd[1025]: eth0: IAID 67:45:8a:fc Feb 01 19:42:24 volumio dhcpcd[1025]: eth0: adding address fe80::2ecf:67ff:fe45:8afc Feb 01 19:42:24 volumio dhcpcd[1025]: ipv6_addaddr1: Permission denied Feb 01 19:42:25 volumio ifplugd(eth0)[1224]: Link beat detected. Feb 01 19:42:25 volumio dhcpcd[1025]: eth0: soliciting an IPv6 router Feb 01 19:42:25 volumio ifplugd(eth0)[1224]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 01 19:42:25 volumio dhcpcd[1025]: control command: dhcpcd eth0 Feb 01 19:42:25 volumio ifplugd(eth0)[1224]: client: sending commands to dhcpcd process Feb 01 19:42:25 volumio dhcpcd[1025]: control_free: No such file or directory Feb 01 19:42:25 volumio dhcpcd[1025]: eth0: rebinding lease of 192.168.68.50 Feb 01 19:42:25 volumio dhcpcd[1025]: eth0: NAK: from 192.168.68.1 Feb 01 19:42:25 volumio dhcpcd[1025]: eth0: soliciting a DHCP lease Feb 01 19:42:25 volumio ifplugd(eth0)[1224]: Program executed successfully. Feb 01 19:42:25 volumio dhcpcd[1025]: eth0: offered 192.168.68.54 from 192.168.68.1 Feb 01 19:42:25 volumio dhcpcd[1025]: eth0: probing address 192.168.68.54/22 Feb 01 19:42:26 volumio wireless.js[964]: WIRELESS.JS - INFO: === SNM TRANSITION === Feb 01 19:42:26 volumio wireless.js[964]: WIRELESS.JS - INFO: Previous ethernet state: disconnected Feb 01 19:42:26 volumio wireless.js[964]: WIRELESS.JS - INFO: New ethernet state: connected Feb 01 19:42:26 volumio wireless.js[964]: WIRELESS.JS - INFO: Single Network Mode: enabled Feb 01 19:42:26 volumio wireless.js[964]: WIRELESS.JS - INFO: First start: no Feb 01 19:42:26 volumio wireless.js[964]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) Feb 01 19:42:26 volumio wireless.js[964]: WIRELESS.JS - INFO: === END TRANSITION === Feb 01 19:42:26 volumio wireless.js[964]: WIRELESS.JS - INFO: SNM: Ethernet connected, switching to ethernet (WiFi scan mode) Feb 01 19:42:26 volumio sudo[3405]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd -k wlan0 Feb 01 19:42:26 volumio sudo[3405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 01 19:42:26 volumio dhcpcd[3406]: dhcpcd not running Feb 01 19:42:26 volumio sudo[3405]: pam_unix(sudo:session): session closed for user root Feb 01 19:42:26 volumio wireless.js[964]: dhcpcd not running Feb 01 19:42:26 volumio wireless.js[964]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Feb 01 19:42:26 volumio systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Feb 01 19:42:26 volumio dnsmasq[1948]: exiting on receipt of SIGTERM Feb 01 19:42:26 volumio systemd[1]: dnsmasq.service: Deactivated successfully. Feb 01 19:42:26 volumio systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Feb 01 19:42:26 volumio systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Feb 01 19:42:26 volumio dhcpcd[1025]: wlan0: carrier lost Feb 01 19:42:26 volumio systemd[1]: hostapd.service: Deactivated successfully. Feb 01 19:42:26 volumio systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Feb 01 19:42:26 volumio avahi-daemon[944]: Withdrawing address record for 169.254.127.4 on wlan0. Feb 01 19:42:26 volumio dhcpcd[1025]: wlan0: deleting route to 169.254.0.0/16 Feb 01 19:42:26 volumio dhcpcd[1025]: wlan0: deleting default route Feb 01 19:42:26 volumio sudo[3421]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 01 19:42:26 volumio sudo[3421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 01 19:42:26 volumio avahi-daemon[944]: Withdrawing address record for 192.168.211.1 on wlan0. Feb 01 19:42:26 volumio avahi-daemon[944]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Feb 01 19:42:26 volumio avahi-daemon[944]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 01 19:42:26 volumio sudo[3421]: pam_unix(sudo:session): session closed for user root Feb 01 19:42:26 volumio volumio[2895]: info: Discovery: A device disappeared from network Feb 01 19:42:26 volumio volumio[2895]: info: Discovery: Device volumio disappeared from network Feb 01 19:42:26 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 01 19:42:26 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Feb 01 19:42:26 volumio systemd[1]: welcome.service: Deactivated successfully. Feb 01 19:42:26 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 01 19:42:26 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 01 19:42:26 volumio sudo[3433]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 01 19:42:26 volumio sudo[3433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 01 19:42:26 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 01 19:42:27 volumio welcome[3437]: Resolved ip:[0] Feb 01 19:42:27 volumio sudo[3433]: pam_unix(sudo:session): session closed for user root Feb 01 19:42:27 volumio wireless.js[964]: WIRELESS.JS - INFO: Cleaning previous... Feb 01 19:42:27 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 01 19:42:27 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 01 19:42:27 volumio sudo[3447]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 01 19:42:27 volumio sudo[3447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 01 19:42:27 volumio sudo[3447]: pam_unix(sudo:session): session closed for user root Feb 01 19:42:27 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 01 19:42:27 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 01 19:42:27 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Feb 01 19:42:27 volumio systemd[1]: welcome.service: Deactivated successfully. Feb 01 19:42:27 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 01 19:42:27 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 01 19:42:27 volumio wireless.js[964]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Feb 01 19:42:27 volumio wireless.js[964]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 0ms Feb 01 19:42:27 volumio wireless.js[964]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: 2c:cf:67:45:8a:fd) Feb 01 19:42:27 volumio sudo[3457]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 01 19:42:27 volumio sudo[3457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:42:27 volumio sudo[3457]: pam_unix(sudo:session): session closed for user root Feb 01 19:42:27 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 01 19:42:27 volumio welcome[3454]: Resolved ip:[0] Feb 01 19:42:27 volumio sudo[3466]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 01 19:42:27 volumio sudo[3466]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 01 19:42:27 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 01 19:42:27 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 01 19:42:29 volumio ntpd[1183]: IO: Deleting interface #3 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=122 secs Feb 01 19:42:29 volumio ntpd[1183]: IO: Deleting interface #4 wlan0, 169.254.127.4#123, interface stats: received=0, sent=0, dropped=0, active_time=112 secs Feb 01 19:42:30 volumio sudo[3466]: pam_unix(sudo:session): session closed for user root Feb 01 19:42:30 volumio wireless.js[964]: WIRELESS.JS - INFO: Regdomain already correct: US Feb 01 19:42:30 volumio wireless.js[964]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Feb 01 19:42:30 volumio wireless.js[964]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Feb 01 19:42:30 volumio wireless.js[964]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Feb 01 19:42:30 volumio sudo[3492]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 01 19:42:30 volumio sudo[3492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 01 19:42:30 volumio sudo[3492]: pam_unix(sudo:session): session closed for user root Feb 01 19:42:30 volumio sudo[3495]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 01 19:42:30 volumio sudo[3495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 01 19:42:30 volumio sudo[3495]: pam_unix(sudo:session): session closed for user root Feb 01 19:42:30 volumio wpa_supplicant[3498]: Successfully initialized wpa_supplicant Feb 01 19:42:30 volumio wpa_supplicant[3498]: nl80211: kernel reports: Registration to specific type not supported Feb 01 19:42:30 volumio wpa_supplicant[3501]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Feb 01 19:42:30 volumio wireless.js[964]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 4227ms Feb 01 19:42:30 volumio wireless.js[964]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Feb 01 19:42:30 volumio wireless.js[964]: WIRELESS.JS - INFO: Notified systemd about wireless ready Feb 01 19:42:30 volumio go-librespot[3315]: time="2026-02-01T19:42:30-06:00" level=debug msg="new websocket client" Feb 01 19:42:30 volumio go-librespot[3315]: time="2026-02-01T19:42:30-06:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Feb 01 19:42:30 volumio volumio[2895]: info: Connection to go-librespot Websocket established Feb 01 19:42:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 01 19:42:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 01 19:42:30 volumio volumio[2895]: info: Connection to go-librespot Websocket closed Feb 01 19:42:31 volumio volumio[2895]: info: [1769996551120] [Pandora] checkPing: Ping www.google.com, attempt 10 failed Feb 01 19:42:31 volumio volumio[2895]: error: MyVolumio Plugin failed to start in a timely fashion Feb 01 19:42:31 volumio volumio[2895]: [Metrics] CommandRouter: 53s 550.15ms Feb 01 19:42:31 volumio volumio[2895]: info: CoreCommandRouter::volumiosetStartupVolume Feb 01 19:42:31 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 01 19:42:31 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 01 19:42:31 volumio volumio[2895]: info: CoreCommandRouter::Close All Modals sent Feb 01 19:42:31 volumio volumio[2895]: info: CoreCommandRouter::Close All Modals sent Feb 01 19:42:31 volumio volumio[2895]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Feb 01 19:42:31 volumio volumio[2895]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for IQaudIODAC Feb 01 19:42:31 volumio volumio[2895]: aplay: main:831: audio open error: No such device Feb 01 19:42:31 volumio dhcpcd[1025]: eth0: leased 192.168.68.54 for 7200 seconds Feb 01 19:42:31 volumio avahi-daemon[944]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.68.54. Feb 01 19:42:31 volumio avahi-daemon[944]: New relevant interface eth0.IPv4 for mDNS. Feb 01 19:42:31 volumio avahi-daemon[944]: Registering new address record for 192.168.68.54 on eth0.IPv4. Feb 01 19:42:31 volumio dhcpcd[1025]: eth0: adding route to 192.168.68.0/22 Feb 01 19:42:31 volumio dhcpcd[1025]: eth0: adding default route via 192.168.68.1 Feb 01 19:42:31 volumio systemd[1]: welcome.service: Deactivated successfully. Feb 01 19:42:31 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 01 19:42:31 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 01 19:42:31 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 01 19:42:31 volumio welcome[3530]: Resolved ip:[1] 192.168.68.54 Feb 01 19:42:31 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 01 19:42:31 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Feb 01 19:42:32 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 01 19:42:32 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 01 19:42:32 volumio volumio[2895]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 01 19:42:33 volumio ntpd[1183]: IO: Listen normally on 5 eth0 192.168.68.54:123 Feb 01 19:42:33 volumio ntpd[1183]: IO: new interface(s) found: waking up resolver Feb 01 19:42:33 volumio ntpd[1183]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 01 19:42:33 volumio volumio[2895]: info: Getting Spotify volume Feb 01 19:42:33 volumio volumio[2895]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 01 19:42:33 volumio volumio[2895]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 01 19:42:33 volumio volumio[2895]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 01 19:42:33 volumio volumio[2895]: errno: -111, Feb 01 19:42:33 volumio volumio[2895]: code: 'ECONNREFUSED', Feb 01 19:42:33 volumio volumio[2895]: syscall: 'connect', Feb 01 19:42:33 volumio volumio[2895]: address: '127.0.0.1', Feb 01 19:42:33 volumio volumio[2895]: port: 9879, Feb 01 19:42:33 volumio volumio[2895]: response: undefined Feb 01 19:42:33 volumio volumio[2895]: } Feb 01 19:42:33 volumio volumio[2895]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 01 19:42:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 01 19:42:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 01 19:42:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 01 19:42:33 volumio go-librespot[3570]: go-librespot daemon starting... Feb 01 19:42:33 volumio go-librespot[3571]: time="2026-02-01T19:42:33-06:00" level=info msg="running go-librespot 0.6.2" Feb 01 19:42:33 volumio go-librespot[3571]: time="2026-02-01T19:42:33-06:00" level=debug msg="app state loaded" Feb 01 19:42:33 volumio go-librespot[3571]: time="2026-02-01T19:42:33-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 01 19:42:33 volumio sudo[3579]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-01 19:41' Feb 01 19:42:33 volumio sudo[3579]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"