Aug 25 00:28:00 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24.
Aug 25 00:28:00 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:00 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:00 kitchen go-librespot[14740]: go-librespot daemon starting...
Aug 25 00:28:00 kitchen go-librespot[14741]: time="2026-08-25T00:28:00+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:28:00 kitchen go-librespot[14741]: time="2026-08-25T00:28:00+01:00" level=debug msg="app state loaded"
Aug 25 00:28:00 kitchen go-librespot[14741]: time="2026-08-25T00:28:00+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:28:00 kitchen go-librespot[14741]: time="2026-08-25T00:28:00+01: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"
Aug 25 00:28:00 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:28:00 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:28:03 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25.
Aug 25 00:28:03 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:03 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:03 kitchen go-librespot[14749]: go-librespot daemon starting...
Aug 25 00:28:03 kitchen go-librespot[14750]: time="2026-08-25T00:28:03+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:28:03 kitchen go-librespot[14750]: time="2026-08-25T00:28:03+01:00" level=debug msg="app state loaded"
Aug 25 00:28:03 kitchen go-librespot[14750]: time="2026-08-25T00:28:03+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:28:03 kitchen go-librespot[14750]: time="2026-08-25T00:28:03+01: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"
Aug 25 00:28:03 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:28:03 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:28:06 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26.
Aug 25 00:28:06 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:07 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:07 kitchen go-librespot[14772]: go-librespot daemon starting...
Aug 25 00:28:07 kitchen go-librespot[14773]: time="2026-08-25T00:28:07+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:28:07 kitchen go-librespot[14773]: time="2026-08-25T00:28:07+01:00" level=debug msg="app state loaded"
Aug 25 00:28:07 kitchen go-librespot[14773]: time="2026-08-25T00:28:07+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:28:07 kitchen go-librespot[14773]: time="2026-08-25T00:28:07+01: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"
Aug 25 00:28:07 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:28:07 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:28:08 kitchen volumio5-onboarding[1615]: time=2026-08-25T00:28:08.355+01:00 level=ERROR msg="failed reading message" error="read tcp 127.0.0.1:60834->127.0.0.1:3000: read: connection reset by peer"
Aug 25 00:28:08 kitchen volumio-remote-updater[719]: [2026-08-25 00:28:08] [error] handle_read_frame error: asio.system:104 (Connection reset by peer)
Aug 25 00:28:08 kitchen volumio-remote-updater[719]: [2026-08-25 00:28:08] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006]
Aug 25 00:28:08 kitchen systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:28:08 kitchen volumio5-onboarding[1615]: time=2026-08-25T00:28:08.396+01:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:59628->127.0.0.1:3000: read: connection reset by peer"
Aug 25 00:28:08 kitchen systemd[1]: volumio.service: Failed with result 'exit-code'.
Aug 25 00:28:08 kitchen systemd[1]: volumio.service: Consumed 26.027s CPU time.
Aug 25 00:28:08 kitchen systemd[1]: Started dynamicswap.service - dynamicswap service.
Aug 25 00:28:08 kitchen systemd[1]: dynamicswap.service: Deactivated successfully.
Aug 25 00:28:08 kitchen systemd[1]: volumio.service: Scheduled restart job, restart counter is at 36.
Aug 25 00:28:08 kitchen systemd[1]: Started dynamicswap.service - dynamicswap service.
Aug 25 00:28:08 kitchen systemd[1]: Stopped volumio.service - Volumio Backend Module.
Aug 25 00:28:08 kitchen systemd[1]: volumio.service: Consumed 26.027s CPU time.
Aug 25 00:28:08 kitchen systemd[1]: Started volumio.service - Volumio Backend Module.
Aug 25 00:28:08 kitchen systemd[1]: dynamicswap.service: Deactivated successfully.
Aug 25 00:28:09 kitchen volumio5-onboarding[1615]: time=2026-08-25T00:28:09.398+01:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused"
Aug 25 00:28:10 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27.
Aug 25 00:28:10 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:10 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:10 kitchen go-librespot[14803]: go-librespot daemon starting...
Aug 25 00:28:10 kitchen go-librespot[14804]: time="2026-08-25T00:28:10+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:28:10 kitchen go-librespot[14804]: time="2026-08-25T00:28:10+01:00" level=debug msg="app state loaded"
Aug 25 00:28:10 kitchen go-librespot[14804]: time="2026-08-25T00:28:10+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:28:10 kitchen go-librespot[14804]: time="2026-08-25T00:28:10+01: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"
Aug 25 00:28:10 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:28:10 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:28:10 kitchen volumio[14788]: info: -------------------------------------------
Aug 25 00:28:10 kitchen volumio[14788]: info: ----- Volumio3 ----
Aug 25 00:28:10 kitchen volumio[14788]: info: -------------------------------------------
Aug 25 00:28:10 kitchen volumio[14788]: info: ----- System startup ----
Aug 25 00:28:10 kitchen volumio[14788]: info: -------------------------------------------
Aug 25 00:28:11 kitchen volumio[14788]: info: MYVOLUMIO Environment detected
Aug 25 00:28:11 kitchen volumio[14788]: info: Plugin folders cleanup
Aug 25 00:28:11 kitchen volumio[14788]: info: Scanning into folder /volumio/app/plugins/
Aug 25 00:28:11 kitchen volumio[14788]: info: Scanning category audio_interface
Aug 25 00:28:11 kitchen volumio[14788]: info: Scanning category miscellanea
Aug 25 00:28:11 kitchen volumio[14788]: info: Scanning category music_service
Aug 25 00:28:11 kitchen volumio[14788]: info: Scanning category plugins.json
Aug 25 00:28:11 kitchen volumio[14788]: info: Scanning category system_controller
Aug 25 00:28:11 kitchen volumio[14788]: info: Scanning category user_interface
Aug 25 00:28:11 kitchen volumio[14788]: info: Scanning into folder /data/plugins/
Aug 25 00:28:11 kitchen volumio[14788]: info: Scanning category audio_interface
Aug 25 00:28:11 kitchen volumio[14788]: info: Scanning category miscellanea
Aug 25 00:28:11 kitchen volumio[14788]: info: Scanning category music_service
Aug 25 00:28:11 kitchen volumio[14788]: info: Scanning category system_hardware
Aug 25 00:28:11 kitchen volumio[14788]: info: Scanning category user_interface
Aug 25 00:28:11 kitchen volumio[14788]: info: Plugin folders cleanup completed
Aug 25 00:28:11 kitchen volumio[14788]: info: -------------------------------------------
Aug 25 00:28:11 kitchen volumio[14788]: info: ----- Core plugins startup ----
Aug 25 00:28:11 kitchen volumio[14788]: info: -------------------------------------------
Aug 25 00:28:11 kitchen volumio[14788]: info: Loading plugins from folder /volumio/app/plugins/
Aug 25 00:28:11 kitchen volumio[14788]: info: Adding plugin upnp to MyMusic Plugins
Aug 25 00:28:11 kitchen volumio[14788]: info: Adding plugin airplay_emulation to MyMusic Plugins
Aug 25 00:28:11 kitchen volumio[14788]: info: Adding plugin upnp_browser to MyMusic Plugins
Aug 25 00:28:11 kitchen volumio[14788]: info: Loading plugins from folder /data/plugins/
Aug 25 00:28:11 kitchen volumio[14788]: info: Loading plugin "system"...
Aug 25 00:28:11 kitchen volumio[14788]: info: Loading plugin "ampstatusled"...
Aug 25 00:28:11 kitchen volumio[14788]: info: Applying required configuration parameters for plugin ampstatusled
Aug 25 00:28:11 kitchen volumio[14788]: info: Loading plugin "planet_radio"...
Aug 25 00:28:12 kitchen volumio[14788]: info: Loading plugin "appearance"...
Aug 25 00:28:12 kitchen volumio[14788]: info: Loading plugin "network"...
Aug 25 00:28:12 kitchen volumio[14788]: info: Refreshing Cached IP Addresses
Aug 25 00:28:12 kitchen sudo[14830]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Aug 25 00:28:12 kitchen sudo[14830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 00:28:12 kitchen volumio[14788]: info: Loading plugin "services"...
Aug 25 00:28:12 kitchen sudo[14832]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 25 00:28:12 kitchen sudo[14832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 00:28:12 kitchen volumio[14788]: info: Loading plugin "volumio5onboarding"...
Aug 25 00:28:12 kitchen sudo[14830]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:12 kitchen sudo[14832]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:12 kitchen volumio[14788]: info: Loading plugin "alsa_controller"...
Aug 25 00:28:12 kitchen sudo[14839]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Aug 25 00:28:12 kitchen sudo[14839]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 00:28:12 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 25 00:28:12 kitchen volumio[14788]: info: Loading plugin "wizard"...
Aug 25 00:28:12 kitchen volumio[14788]: info: Loading plugin "networkfs"...
Aug 25 00:28:12 kitchen volumio[14788]: info: Starting Udev Watcher for removable devices
Aug 25 00:28:12 kitchen volumio[14788]: info: Ignoring mount for partition: boot
Aug 25 00:28:12 kitchen volumio[14788]: info: Ignoring mount for partition: volumio
Aug 25 00:28:12 kitchen volumio[14788]: info: Ignoring mount for partition: volumio_data
Aug 25 00:28:12 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Aug 25 00:28:12 kitchen volumio[14788]: info: Loading plugin "volumio_command_line_client"...
Aug 25 00:28:12 kitchen volumio[14788]: info: Loading plugin "upnp"...
Aug 25 00:28:12 kitchen volumio[14788]: info: [1787614092764] Starting Upmpd Daemon
Aug 25 00:28:12 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Aug 25 00:28:12 kitchen volumio[14788]: info: Loading plugin "my_music"...
Aug 25 00:28:12 kitchen volumio[14788]: info: Loading plugin "mpd"...
Aug 25 00:28:12 kitchen volumio[14788]: info: Loading plugin "upnp_browser"...
Aug 25 00:28:13 kitchen volumio-remote-updater[719]: [2026-08-25 00:28:13] [connect] Successful connection
Aug 25 00:28:13 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28.
Aug 25 00:28:13 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:13 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:13 kitchen go-librespot[14859]: go-librespot daemon starting...
Aug 25 00:28:13 kitchen go-librespot[14860]: time="2026-08-25T00:28:13+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:28:13 kitchen go-librespot[14860]: time="2026-08-25T00:28:13+01:00" level=debug msg="app state loaded"
Aug 25 00:28:13 kitchen go-librespot[14860]: time="2026-08-25T00:28:13+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:28:13 kitchen go-librespot[14860]: time="2026-08-25T00:28:13+01: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"
Aug 25 00:28:13 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:28:13 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:28:13 kitchen volumio[14788]: info: Starting UPNP Browser
Aug 25 00:28:13 kitchen volumio[14788]: info: Loading plugin "alarm-clock"...
Aug 25 00:28:13 kitchen volumio[14788]: info: Loading plugin "airplay_emulation"...
Aug 25 00:28:13 kitchen volumio[14788]: info: Starting Shairport Sync
Aug 25 00:28:13 kitchen volumio[14788]: info: Loading plugin "last_100"...
Aug 25 00:28:13 kitchen volumio[14788]: info: Loading plugin "webradio"...
Aug 25 00:28:13 kitchen volumio[14788]: info: Loading plugin "i2s_dacs"...
Aug 25 00:28:13 kitchen volumio[14788]: info: I2S DAC not set, start Auto-detection
Aug 25 00:28:13 kitchen volumio[14788]: info: Loading plugin "volumiodiscovery"...
Aug 25 00:28:13 kitchen volumio[14788]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Aug 25 00:28:13 kitchen volumio[14788]: *** WARNING *** Please fix your application to use the native API of Avahi!
Aug 25 00:28:13 kitchen volumio[14788]: *** WARNING *** For more information see
Aug 25 00:28:13 kitchen volumio[14788]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Aug 25 00:28:13 kitchen volumio[14788]: *** WARNING *** Please fix your application to use the native API of Avahi!
Aug 25 00:28:13 kitchen node[14788]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Aug 25 00:28:13 kitchen volumio[14788]: *** WARNING *** For more information see
Aug 25 00:28:13 kitchen node[14788]: *** WARNING *** Please fix your application to use the native API of Avahi!
Aug 25 00:28:13 kitchen node[14788]: *** WARNING *** For more information see
Aug 25 00:28:13 kitchen node[14788]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Aug 25 00:28:13 kitchen node[14788]: *** WARNING *** Please fix your application to use the native API of Avahi!
Aug 25 00:28:13 kitchen node[14788]: *** WARNING *** For more information see
Aug 25 00:28:13 kitchen volumio[14788]: info: Applying required configuration parameters for plugin volumiodiscovery
Aug 25 00:28:13 kitchen volumio[14788]: info: Discovery: Started advertising with name: kitchen
Aug 25 00:28:14 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Aug 25 00:28:14 kitchen volumio[14788]: info: Loading plugin "spop"...
Aug 25 00:28:14 kitchen volumio[14788]: info: Loading plugin "now_playing"...
Aug 25 00:28:15 kitchen sudo[14839]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:15 kitchen volumio[14788]: info: Loading plugin "outputs"...
Aug 25 00:28:15 kitchen volumio[14788]: info: Loading plugin "albumart"...
Aug 25 00:28:15 kitchen volumio[14788]: info: Plugin example_plugin is not enabled
Aug 25 00:28:15 kitchen volumio[14788]: info: Loading plugin "inputs"...
Aug 25 00:28:15 kitchen volumio[14788]: info: Loading plugin "updater_comm"...
Aug 25 00:28:15 kitchen volumio[14788]: info: Plugin mpdemulation is not enabled
Aug 25 00:28:15 kitchen volumio[14788]: info: Loading plugin "rest_api"...
Aug 25 00:28:15 kitchen volumio[14788]: info: Loading plugin "websocket"...
Aug 25 00:28:15 kitchen volumio[14788]: info: Starting Socket.io Server version 1.7.4
Aug 25 00:28:15 kitchen volumio[14788]: info: Loading plugin "fusiondsp"...
Aug 25 00:28:15 kitchen volumio[14788]: info: Applying required configuration parameters for plugin fusiondsp
Aug 25 00:28:15 kitchen volumio[14870]: Forking 3 albumart workers
Aug 25 00:28:15 kitchen volumio[14788]: info: Loading plugin "gpio_control"...
Aug 25 00:28:15 kitchen volumio[14788]: gpiox_arm32 V2.2.1
Aug 25 00:28:16 kitchen volumio[14788]: info: Applying required configuration parameters for plugin gpio_control
Aug 25 00:28:16 kitchen volumio[14788]: info: Loading i18n strings for locale en
Aug 25 00:28:16 kitchen volumio[14788]: Updating browse sources language
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::initPlayerControls
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 25 00:28:16 kitchen volumio[14788]: Express server listening on port 3000
Aug 25 00:28:16 kitchen volumio[14788]: [Metrics] WebUI: 6s 14.62ms
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreStateMachine::resetVolumioState
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreStateMachine::getcurrentVolume
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::volumioRetrievevolume
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:16 kitchen volumio[14788]: info: Volumio Network Manager: Network status updated: 1
Aug 25 00:28:16 kitchen volumio[14788]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1
Aug 25 00:28:16 kitchen volumio[14788]: info: VolumeController:: Volume=39 Mute =false
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreStateMachine::pushState
Aug 25 00:28:16 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::volumioPushState
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreStateMachine::updateTrackBlock
Aug 25 00:28:16 kitchen volumio[14788]: info: CorePlayQueue::getTrackBlock
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::volumioRetrievevolume
Aug 25 00:28:16 kitchen volumio[14881]: Starting albumart workers
Aug 25 00:28:16 kitchen volumio[14788]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1
Aug 25 00:28:16 kitchen volumio[14788]: info: Received Get System Info
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 25 00:28:16 kitchen volumio[14788]: info: Discovery: Getting this device information
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::volumioGetState
Aug 25 00:28:16 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 25 00:28:16 kitchen volumio[14880]: Starting albumart workers
Aug 25 00:28:16 kitchen volumio[14882]: Starting albumart workers
Aug 25 00:28:16 kitchen volumio[14788]: info: Reloading queue from file
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::volumioGetState
Aug 25 00:28:16 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Aug 25 00:28:16 kitchen volumio-remote-updater[719]: [2026-08-25 00:28:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1787614093 101
Aug 25 00:28:16 kitchen volumio[14788]: 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: 2
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreStateMachine::setRepeat null single undefined
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreStateMachine::pushState
Aug 25 00:28:16 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::volumioPushState
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreStateMachine::setRandom true
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreStateMachine::pushState
Aug 25 00:28:16 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::volumioPushState
Aug 25 00:28:16 kitchen volumio[14788]: info: Setting Device type: Raspberry PI
Aug 25 00:28:16 kitchen volumio[14788]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb
Aug 25 00:28:16 kitchen volumio[14788]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115
Aug 25 00:28:16 kitchen volumio[14788]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI
Aug 25 00:28:16 kitchen volumio[14788]: verbose: New Socket.io Connection to 10.10.14.233:3000 from 10.10.14.230 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
Aug 25 00:28:16 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29.
Aug 25 00:28:16 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:16 kitchen volumio[14788]: info: Listing playlists
Aug 25 00:28:16 kitchen volumio[14788]: info: Listing playlists
Aug 25 00:28:16 kitchen volumio[14788]: verbose: New Socket.io Connection to 10.10.14.233:3000 from 10.10.14.231 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Aug 25 00:28:16 kitchen volumio[14788]: info: VolumeController:: Volume=39 Mute =false
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreStateMachine::pushState
Aug 25 00:28:16 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::volumioPushState
Aug 25 00:28:16 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:16 kitchen volumio[14788]: verbose: New Socket.io Connection to 10.10.14.233:3000 from 10.10.14.232 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Aug 25 00:28:16 kitchen go-librespot[14924]: go-librespot daemon starting...
Aug 25 00:28:16 kitchen volumio[14788]: verbose: New Socket.io Connection to 10.10.14.233:3000 from 10.10.14.232 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
Aug 25 00:28:16 kitchen go-librespot[14928]: time="2026-08-25T00:28:16+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:28:16 kitchen volumio[14788]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7
Aug 25 00:28:16 kitchen go-librespot[14928]: time="2026-08-25T00:28:16+01:00" level=debug msg="app state loaded"
Aug 25 00:28:16 kitchen go-librespot[14928]: time="2026-08-25T00:28:16+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:28:16 kitchen go-librespot[14928]: time="2026-08-25T00:28:16+01: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"
Aug 25 00:28:16 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:28:16 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:28:16 kitchen volumio[14788]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Aug 25 00:28:16 kitchen volumio[14788]: info: Completed loading Core Plugins
Aug 25 00:28:16 kitchen volumio[14788]: info: Preparing to generate the ALSA configuration file
Aug 25 00:28:16 kitchen volumio[14788]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Aug 25 00:28:16 kitchen volumio[14788]: info: Reading ALSA contributions from plugins.
Aug 25 00:28:16 kitchen volumio[14788]: info: Discovery: adding f55c6533-6551-464e-bd65-f211402d8c6b
Aug 25 00:28:16 kitchen volumio[14788]: info: Discovery: Found device parasound
Aug 25 00:28:16 kitchen volumio[14788]: info: Discovery: Connecting to remote: 10.10.14.230
Aug 25 00:28:16 kitchen volumio[14788]: info: Discovery: adding ee470186-635c-4790-ad82-1fd1ecf80732
Aug 25 00:28:16 kitchen volumio[14788]: info: Discovery: Found device kitchen
Aug 25 00:28:16 kitchen volumio[14788]: info: CoreCommandRouter::volumioGetState
Aug 25 00:28:16 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:28:17 kitchen volumio[14788]: info: Discovery: this is already registered, ee470186-635c-4790-ad82-1fd1ecf80732
Aug 25 00:28:17 kitchen volumio[14788]: info: Discovery: Found device kitchen
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::volumioGetState
Aug 25 00:28:17 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:28:17 kitchen volumio[14788]: info: Discovery: adding cfe335bd-a299-4755-85a2-d2077f7e159b
Aug 25 00:28:17 kitchen volumio[14788]: info: Discovery: Found device bathroom-big
Aug 25 00:28:17 kitchen volumio[14788]: info: Discovery: Connecting to remote: 10.10.14.231
Aug 25 00:28:17 kitchen volumio[14788]: info: Discovery: adding ed74942c-4575-419e-9ad3-0a50acc2b9ac
Aug 25 00:28:17 kitchen volumio[14788]: info: Discovery: Found device bathroom-small
Aug 25 00:28:17 kitchen volumio[14788]: info: Discovery: Connecting to remote: 10.10.14.232
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::volumioGetState
Aug 25 00:28:17 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:28:17 kitchen volumio[14788]: info: Discovery: Connected to remote: 10.10.14.230
Aug 25 00:28:17 kitchen volumio[14788]: info: Asound.conf file unchanged, so no further update is needed
Aug 25 00:28:17 kitchen volumio[14788]: info: Output device has changed, restarting MPD
Aug 25 00:28:17 kitchen volumio[14788]: info: Output device has changed, restarting Shairport Sync
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:17 kitchen sudo[14958]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Aug 25 00:28:17 kitchen sudo[14958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 00:28:17 kitchen sudo[14958]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:17 kitchen sudo[14960]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 25 00:28:17 kitchen sudo[14960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 00:28:17 kitchen volumio[14788]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 25 00:28:17 kitchen volumio[14788]: info: ___________ START PLUGINS ___________
Aug 25 00:28:17 kitchen volumio[14788]: info: [AmpStatusLED] Togle GPIO LED: Ready
Aug 25 00:28:17 kitchen volumio[14788]: info: [PlanetRadio] Adding Planet Radio to browse sources
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Aug 25 00:28:17 kitchen volumio[14788]: info: [1787614097277] CoreMusicLibrary::Adding element Planet Radio
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 25 00:28:17 kitchen volumio[14788]: Cannot find translation for source Planet Radio
Aug 25 00:28:17 kitchen systemd[1]: Stopping mpd.service - Music Player Daemon...
Aug 25 00:28:17 kitchen volumio[14788]: info: ControllerMpd::onStart: Initializing MPD
Aug 25 00:28:17 kitchen volumio[14788]: info: Creating MPD Configuration file
Aug 25 00:28:17 kitchen sudo[14968]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service
Aug 25 00:28:17 kitchen sudo[14968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 00:28:17 kitchen systemd[1]: mpd.service: Deactivated successfully.
Aug 25 00:28:17 kitchen systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 25 00:28:17 kitchen systemd[1]: mpd.service: Consumed 1.527s CPU time.
Aug 25 00:28:17 kitchen systemd[1]: mpd.socket: Deactivated successfully.
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 25 00:28:17 kitchen systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 25 00:28:17 kitchen systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Aug 25 00:28:17 kitchen volumio[14788]: info: [1787614097322] CoreMusicLibrary::Adding element Media Servers
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 25 00:28:17 kitchen volumio[14788]: Cannot find translation for source Planet Radio
Aug 25 00:28:17 kitchen sudo[14970]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Aug 25 00:28:17 kitchen volumio[14788]: info: UPNP Browser: Client initialized successfully
Aug 25 00:28:17 kitchen sudo[14973]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 25 00:28:17 kitchen sudo[14970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 00:28:17 kitchen sudo[14973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 00:28:17 kitchen sudo[14970]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Aug 25 00:28:17 kitchen volumio[14788]: info: [1787614097377] CoreMusicLibrary::Adding element Last_100
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 25 00:28:17 kitchen volumio[14788]: Cannot find translation for source Planet Radio
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Aug 25 00:28:17 kitchen volumio[14788]: info: [1787614097380] CoreMusicLibrary::Adding element Webradio
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 25 00:28:17 kitchen volumio[14788]: Cannot find translation for source Planet Radio
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 25 00:28:17 kitchen systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 25 00:28:17 kitchen volumio[14788]: info: Initializing BBC Radios
Aug 25 00:28:17 kitchen systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 25 00:28:17 kitchen systemd[1]: mpd.service: Deactivated successfully.
Aug 25 00:28:17 kitchen systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 25 00:28:17 kitchen systemd[1]: mpd.socket: Deactivated successfully.
Aug 25 00:28:17 kitchen sudo[14968]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:17 kitchen systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 25 00:28:17 kitchen systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 25 00:28:17 kitchen systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:17 kitchen systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 25 00:28:17 kitchen volumio[14788]: info: Creating Spotify config file
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:17 kitchen sudo[15000]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 25 00:28:17 kitchen sudo[15000]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 25 00:28:17 kitchen sudo[15000]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:17 kitchen volumio[14788]: info: [now-playing] ConfigUpdater: config is up to date.
Aug 25 00:28:17 kitchen volumio[14788]: info: FusionDsp - CamillaDSP binary found.
Aug 25 00:28:17 kitchen volumio[14788]: info: Loading i18n strings for locale en
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: FusionDsp - mixtype--------------------- Hardware
Aug 25 00:28:17 kitchen volumio[14788]: info: Volumio Calling Home
Aug 25 00:28:17 kitchen volumio5-onboarding[1615]: time=2026-08-25T00:28:17.637+01:00 level=INFO msg="service successfully established" component=discovery/localnet
Aug 25 00:28:17 kitchen volumio[14788]: info: Preparing to generate the ALSA configuration file
Aug 25 00:28:17 kitchen volumio[14788]: info: [now-playing] App is listening on port 4004.
Aug 25 00:28:17 kitchen volumio[14788]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds
Aug 25 00:28:17 kitchen volumio[14788]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Aug 25 00:28:17 kitchen volumio[14788]: info: Reading ALSA contributions from plugins.
Aug 25 00:28:17 kitchen volumio[14788]: info: MPD Permissions set
Aug 25 00:28:17 kitchen volumio[14788]: info: MPD Permissions set
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::volumioGetState
Aug 25 00:28:17 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:28:17 kitchen volumio[14788]: info: Spotify config file written
Aug 25 00:28:17 kitchen volumio[14788]: info: [AmpStatusLED] CurState: stop PrevState: na
Aug 25 00:28:17 kitchen volumio[14788]: info: [AmpStatusLED] Togle GPIO LED: Ready
Aug 25 00:28:17 kitchen volumio[14788]: info: [AmpStatusLED] CurState: stop PrevState: stop
Aug 25 00:28:17 kitchen sudo[15008]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Aug 25 00:28:17 kitchen sudo[15008]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:17 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:17 kitchen volumio[14788]: info: No need to fix Spotify hosts
Aug 25 00:28:17 kitchen volumio[14788]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
Aug 25 00:28:17 kitchen volumio[14788]: info: Starting Shairport Sync
Aug 25 00:28:17 kitchen volumio[14788]: info: Starting Shairport Sync
Aug 25 00:28:17 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:17 kitchen volumio[14788]: info: Starting Shairport Sync
Aug 25 00:28:17 kitchen go-librespot[15015]: go-librespot daemon starting...
Aug 25 00:28:17 kitchen sudo[15008]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:17 kitchen sudo[15022]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 25 00:28:17 kitchen sudo[15022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 00:28:18 kitchen volumio[14788]: info: CoreCommandRouter::volumioGetState
Aug 25 00:28:18 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:28:18 kitchen go-librespot[15025]: time="2026-08-25T00:28:18+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:28:18 kitchen go-librespot[15025]: time="2026-08-25T00:28:18+01:00" level=debug msg="app state loaded"
Aug 25 00:28:18 kitchen volumio[14788]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10
Aug 25 00:28:18 kitchen sudo[15030]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 25 00:28:18 kitchen sudo[15030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 00:28:18 kitchen sudo[15024]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 25 00:28:18 kitchen sudo[15024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 00:28:18 kitchen go-librespot[15025]: time="2026-08-25T00:28:18+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:28:18 kitchen volumio[14788]: info: Asound.conf file unchanged, so no further update is needed
Aug 25 00:28:18 kitchen volumio[14788]: info: Output device has changed, restarting MPD
Aug 25 00:28:18 kitchen go-librespot[15025]: time="2026-08-25T00:28:18+01: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"
Aug 25 00:28:18 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:28:18 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:28:18 kitchen volumio[14788]: info: Output device has changed, restarting Shairport Sync
Aug 25 00:28:18 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:18 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:18 kitchen sudo[15040]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Aug 25 00:28:18 kitchen sudo[15040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 00:28:18 kitchen systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Aug 25 00:28:18 kitchen sudo[15040]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:18 kitchen systemd[1]: shairport-sync.service: Deactivated successfully.
Aug 25 00:28:18 kitchen systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 25 00:28:18 kitchen systemd[1]: shairport-sync.service: Consumed 2.499s CPU time.
Aug 25 00:28:18 kitchen sudo[15043]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 25 00:28:18 kitchen sudo[15043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 00:28:18 kitchen systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 25 00:28:18 kitchen sudo[15022]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:18 kitchen sudo[15024]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:18 kitchen volumio[14788]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 25 00:28:18 kitchen sudo[15030]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:18 kitchen systemd[1]: mpd.service: Deactivated successfully.
Aug 25 00:28:18 kitchen systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 25 00:28:18 kitchen systemd[1]: mpd.socket: Deactivated successfully.
Aug 25 00:28:18 kitchen systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 25 00:28:18 kitchen systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 25 00:28:18 kitchen systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 25 00:28:18 kitchen systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 25 00:28:18 kitchen volumio[14788]: info: MPD Permissions set
Aug 25 00:28:18 kitchen volumio[14788]: info: Shairport-Sync Started
Aug 25 00:28:18 kitchen volumio[14788]: Error adding Membership: Error: addMembership EINVAL
Aug 25 00:28:18 kitchen volumio[14788]: info: Shairport-Sync Started
Aug 25 00:28:18 kitchen volumio[14788]: info: Shairport-Sync Started
Aug 25 00:28:18 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:18 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:18 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:18 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:18 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:18 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:18 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:18 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:18 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:18 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:18 kitchen volumio[14788]: info: Starting Shairport Sync
Aug 25 00:28:18 kitchen sudo[15072]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 25 00:28:18 kitchen sudo[15072]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 00:28:18 kitchen systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Aug 25 00:28:18 kitchen systemd[1]: shairport-sync.service: Deactivated successfully.
Aug 25 00:28:18 kitchen systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 25 00:28:18 kitchen sudo[15063]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 25 00:28:18 kitchen sudo[15063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 25 00:28:18 kitchen sudo[15063]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:18 kitchen volumio[14788]: info: CoreCommandRouter::volumioGetState
Aug 25 00:28:18 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:28:18 kitchen systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 25 00:28:18 kitchen sudo[15072]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:18 kitchen volumio[14788]: info: Shairport-Sync Started
Aug 25 00:28:18 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket
Aug 25 00:28:18 kitchen volumio[14788]: info: Discovery: Connected to remote: 10.10.14.231
Aug 25 00:28:18 kitchen volumio[14788]: info: Discovery: Connected to remote: 10.10.14.232
Aug 25 00:28:18 kitchen volumio[14788]: info: CoreCommandRouter::volumioGetState
Aug 25 00:28:18 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:28:19 kitchen volumio[14788]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Aug 25 00:28:19 kitchen volumio[14788]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo
Aug 25 00:28:19 kitchen volumio[14788]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Aug 25 00:28:19 kitchen volumio[14788]: info: camilladsp service started and running in background, instance 1
Aug 25 00:28:19 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:19 kitchen volumio[14788]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 384000 768000 <-AAAAAAAAAAAAA
Aug 25 00:28:19 kitchen volumio[14788]: info: FusionDsp loaded
Aug 25 00:28:19 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 25 00:28:19 kitchen volumio[14788]: error: FusionDsp - Socket connection not established
Aug 25 00:28:19 kitchen volumio[14788]: info: FusionDsp - Reporting Fusion DSP Enabled
Aug 25 00:28:19 kitchen volumio[14788]: info: Adding Signal Path Element [object Object]
Aug 25 00:28:19 kitchen volumio[14788]: info: Adding fusiondspeq DSP Signal Path Element
Aug 25 00:28:19 kitchen volumio[14788]: info: FusionDsp - ---- installed callbackRead
Aug 25 00:28:19 kitchen volumio[14788]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Aug 25 00:28:19 kitchen volumio[14788]: info: FusionDsp - Nulleq applied
Aug 25 00:28:19 kitchen sudo[15105]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Aug 25 00:28:19 kitchen sudo[15105]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 00:28:19 kitchen sudo[15105]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:19 kitchen mpd[15076]: 2026-08-25T00:28:19 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Aug 25 00:28:19 kitchen systemd[1]: Started mpd.service - Music Player Daemon.
Aug 25 00:28:19 kitchen sudo[15043]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:19 kitchen sudo[14973]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:19 kitchen sudo[14960]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:19 kitchen volumio[14788]: info: Completed starting Core Plugins
Aug 25 00:28:19 kitchen volumio[14788]: info: -------------------------------------------
Aug 25 00:28:19 kitchen volumio[14788]: info: ----- MyVolumio plugins startup ----
Aug 25 00:28:19 kitchen volumio[14788]: info: -------------------------------------------
Aug 25 00:28:19 kitchen volumio[14788]: info: [MyVolumio PluginManager] Fetching plans data....
Aug 25 00:28:19 kitchen volumio[14788]: error: MPD error: The expression evaluated to a falsy value:
Aug 25 00:28:19 kitchen volumio[14788]: assert.ok(self.idling)
Aug 25 00:28:19 kitchen volumio[14788]: error: The expression evaluated to a falsy value:
Aug 25 00:28:19 kitchen volumio[14788]: assert.ok(self.idling)
Aug 25 00:28:19 kitchen volumio[14788]: error: MPD error: The expression evaluated to a falsy value:
Aug 25 00:28:19 kitchen volumio[14788]: assert.ok(self.idling)
Aug 25 00:28:19 kitchen volumio[14788]: error: The expression evaluated to a falsy value:
Aug 25 00:28:19 kitchen volumio[14788]: assert.ok(self.idling)
Aug 25 00:28:19 kitchen volumio[14788]: info: MPD running with PID15076
Aug 25 00:28:19 kitchen volumio[14788]: ,establishing connection
Aug 25 00:28:19 kitchen volumio[14788]: error: updateQueue error: null
Aug 25 00:28:19 kitchen volumio[14788]: error: updateQueue error: null
Aug 25 00:28:21 kitchen volumio[14788]: info: go-librespot daemon successfully initialized
Aug 25 00:28:21 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30.
Aug 25 00:28:21 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:21 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:21 kitchen go-librespot[15113]: go-librespot daemon starting...
Aug 25 00:28:21 kitchen go-librespot[15114]: time="2026-08-25T00:28:21+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:28:21 kitchen go-librespot[15114]: time="2026-08-25T00:28:21+01:00" level=debug msg="app state loaded"
Aug 25 00:28:21 kitchen go-librespot[15114]: time="2026-08-25T00:28:21+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:28:21 kitchen go-librespot[15114]: time="2026-08-25T00:28:21+01: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"
Aug 25 00:28:21 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:28:21 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:28:22 kitchen sudo[15139]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Aug 25 00:28:22 kitchen sudo[15139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 00:28:22 kitchen sudo[15141]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 25 00:28:22 kitchen sudo[15141]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 00:28:22 kitchen sudo[15139]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:22 kitchen sudo[15141]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:22 kitchen sudo[15143]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Aug 25 00:28:22 kitchen sudo[15143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 00:28:22 kitchen sudo[15143]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:22 kitchen volumio[14788]: info: Upmpdcli Daemon Started
Aug 25 00:28:23 kitchen volumio[14788]: info: CoreCommandRouter::volumioGetState
Aug 25 00:28:23 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:28:24 kitchen volumio[14788]: info: Initializing connection to go-librespot Websocket
Aug 25 00:28:24 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31.
Aug 25 00:28:24 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:24 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:24 kitchen go-librespot[15147]: go-librespot daemon starting...
Aug 25 00:28:24 kitchen go-librespot[15148]: time="2026-08-25T00:28:24+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:28:24 kitchen go-librespot[15148]: time="2026-08-25T00:28:24+01:00" level=debug msg="app state loaded"
Aug 25 00:28:24 kitchen go-librespot[15148]: time="2026-08-25T00:28:24+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:28:24 kitchen go-librespot[15148]: time="2026-08-25T00:28:24+01: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"
Aug 25 00:28:24 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:28:24 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:28:24 kitchen volumio[14788]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Aug 25 00:28:27 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32.
Aug 25 00:28:27 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:27 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:27 kitchen go-librespot[15156]: go-librespot daemon starting...
Aug 25 00:28:27 kitchen go-librespot[15157]: time="2026-08-25T00:28:27+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:28:27 kitchen go-librespot[15157]: time="2026-08-25T00:28:27+01:00" level=debug msg="app state loaded"
Aug 25 00:28:27 kitchen go-librespot[15157]: time="2026-08-25T00:28:27+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:28:33 kitchen volumio[14788]: info: CoreCommandRouter::volumioGetState
Aug 25 00:28:33 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:28:33 kitchen volumio[14788]: info: Listing playlists
Aug 25 00:28:33 kitchen volumio[14788]: info: Listing playlists
Aug 25 00:28:37 kitchen volumio[14788]: info: Volumio called home
Aug 25 00:28:38 kitchen go-librespot[15157]: time="2026-08-25T00:28:38+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 25 00:28:38 kitchen go-librespot[15157]: time="2026-08-25T00:28:38+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Aug 25 00:28:38 kitchen go-librespot[15157]: time="2026-08-25T00:28:38+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Aug 25 00:28:38 kitchen go-librespot[15157]: time="2026-08-25T00:28:38+01:00" level=info msg="zeroconf server listening on port 37201"
Aug 25 00:28:38 kitchen go-librespot[15157]: time="2026-08-25T00:28:38+01:00" level=debug msg="obtained new client token: AAGf3MfH5xrH+jaxvupFX9KdEYJGMJcHqbtT3UxIS4YWlA5KQl1yXXDlqFZW77RaU0w24a0/LcJZ75Ijl+3x4yrf3voNpMiBJ0BXsvfWc3G4oAulLmzGZ3rqCVVk2TlsRjQo9TZhDxnESD/xZKAtZzZ/gUKJlOkyy6aygMxPwcACuF14DO6+YPeFTTRmn7tpSOj+1IUou/PRf1JGxXWiYkICg0M46GAEV87gcgcQ0p9e2CoogJwIAQ=="
Aug 25 00:28:38 kitchen go-librespot[15157]: time="2026-08-25T00:28:38+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Aug 25 00:28:38 kitchen go-librespot[15157]: time="2026-08-25T00:28:38+01:00" level=debug msg="completed keyexchange"
Aug 25 00:28:38 kitchen go-librespot[15157]: time="2026-08-25T00:28:38+01:00" level=debug msg="completed challenge"
Aug 25 00:28:38 kitchen go-librespot[15157]: time="2026-08-25T00:28:38+01:00" level=info msg="authenticated AP" username="du*****el"
Aug 25 00:28:38 kitchen go-librespot[15157]: time="2026-08-25T00:28:38+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Aug 25 00:28:38 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:28:38 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:28:38 kitchen systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service...
Aug 25 00:28:39 kitchen volumio[14788]: error: MyVolumio Plugin failed to start in a timely fashion
Aug 25 00:28:39 kitchen volumio[14788]: [Metrics] CommandRouter: 29s 113.14ms
Aug 25 00:28:39 kitchen volumio[14788]: info: CoreCommandRouter::volumiosetStartupVolume
Aug 25 00:28:39 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:28:39 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:39 kitchen volumio[14788]: info: CoreCommandRouter::Close All Modals sent
Aug 25 00:28:39 kitchen volumio[14788]: info: CoreCommandRouter::Close All Modals sent
Aug 25 00:28:39 kitchen volumio[14788]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Aug 25 00:28:39 kitchen volumio[14788]: info: FusionDsp - ---- read samplerate from file: 44100
Aug 25 00:28:39 kitchen volumio[14788]: info: camilladsp stopping service pid 15098...
Aug 25 00:28:39 kitchen volumio[14788]: info: camilladsp service terminated, instance 1
Aug 25 00:28:39 kitchen volumio[14788]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Aug 25 00:28:39 kitchen volumio[14788]: info: FusionDsp - Nulleq applied
Aug 25 00:28:39 kitchen volumio[14788]: info: camilladsp service started and running in background, instance 1
Aug 25 00:28:40 kitchen volumio[14788]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io
Aug 25 00:28:40 kitchen volumio[14788]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 25 00:28:40 kitchen volumio[14788]: info: CoreCommandRouter::volumioGetState
Aug 25 00:28:40 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:28:40 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Aug 25 00:28:40 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Aug 25 00:28:40 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Aug 25 00:28:41 kitchen volumio[14788]: info: Adding plugin bluetooth to MyMusic Plugins
Aug 25 00:28:41 kitchen volumio[14788]: info: Adding plugin multiroom to MyMusic Plugins
Aug 25 00:28:41 kitchen volumio[14788]: info: Adding plugin metavolumio to MyMusic Plugins
Aug 25 00:28:41 kitchen volumio[14788]: info: Adding plugin cd_controller to MyMusic Plugins
Aug 25 00:28:41 kitchen volumio[14788]: info: Adding plugin qobuzconnect to MyMusic Plugins
Aug 25 00:28:41 kitchen volumio[14788]: info: Adding plugin smart_inputs to MyMusic Plugins
Aug 25 00:28:41 kitchen volumio[14788]: info: Adding plugin tidalconnect to MyMusic Plugins
Aug 25 00:28:41 kitchen volumio[14788]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Aug 25 00:28:41 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33.
Aug 25 00:28:41 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:42 kitchen volumio[14788]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Aug 25 00:28:42 kitchen volumio[14788]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Aug 25 00:28:42 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:42 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:42 kitchen volumio[14788]: info: Starting MyVolumio Remote Streaming Endpoints
Aug 25 00:28:42 kitchen volumio[14788]: info: MyVolumio login type: Token
Aug 25 00:28:42 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:42 kitchen go-librespot[15234]: go-librespot daemon starting...
Aug 25 00:28:42 kitchen go-librespot[15235]: time="2026-08-25T00:28:42+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:28:42 kitchen go-librespot[15235]: time="2026-08-25T00:28:42+01:00" level=debug msg="app state loaded"
Aug 25 00:28:42 kitchen volumio[14788]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Aug 25 00:28:42 kitchen volumio[14788]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Aug 25 00:28:42 kitchen go-librespot[15235]: time="2026-08-25T00:28:42+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:28:42 kitchen go-librespot[15235]: time="2026-08-25T00:28:42+01: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"
Aug 25 00:28:42 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:28:42 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:28:43 kitchen volumio[14788]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Aug 25 00:28:43 kitchen volumio[14788]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Aug 25 00:28:43 kitchen volumio[14788]: info: Streaming services startup
Aug 25 00:28:43 kitchen volumio[14788]: info: Starting Streaming Daemon
Aug 25 00:28:43 kitchen sudo[15264]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Aug 25 00:28:43 kitchen sudo[15264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 00:28:43 kitchen volumio[14788]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Aug 25 00:28:43 kitchen sudo[15264]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:43 kitchen volumio[14788]: error: Cannot start Volumio Streaming Daemon
Aug 25 00:28:43 kitchen volumio[14788]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Aug 25 00:28:43 kitchen volumio[14788]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Aug 25 00:28:43 kitchen volumio[14788]: error: MyVolumio Custom Token format not valid, refreshing it
Aug 25 00:28:43 kitchen volumio[14788]: info: CoreCommandRouter::volumioGetState
Aug 25 00:28:43 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:28:43 kitchen volumio[14788]: info: Initializing connection to go-librespot Websocket
Aug 25 00:28:43 kitchen volumio[14788]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 25 00:28:43 kitchen volumio[14788]: info: MyVolumio login type: Token
Aug 25 00:28:44 kitchen volumio[14788]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Aug 25 00:28:45 kitchen kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 1
Aug 25 00:28:45 kitchen kernel: xhci_hcd 0000:01:00.0: @000000040cd0e170 00000000 00000000 0e000000 02028000
Aug 25 00:28:45 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34.
Aug 25 00:28:45 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:45 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:45 kitchen go-librespot[15285]: go-librespot daemon starting...
Aug 25 00:28:45 kitchen go-librespot[15286]: time="2026-08-25T00:28:45+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:28:45 kitchen go-librespot[15286]: time="2026-08-25T00:28:45+01:00" level=debug msg="app state loaded"
Aug 25 00:28:45 kitchen go-librespot[15286]: time="2026-08-25T00:28:45+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:28:45 kitchen go-librespot[15286]: time="2026-08-25T00:28:45+01: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"
Aug 25 00:28:45 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:28:45 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:28:45 kitchen bash[15182]: setdatetime-helper: all HTTPS Date fallbacks failed
Aug 25 00:28:45 kitchen systemd[1]: setdatetime-helper.service: Deactivated successfully.
Aug 25 00:28:45 kitchen systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Aug 25 00:28:46 kitchen volumio[14788]: info: MyVolumio token set successfully
Aug 25 00:28:46 kitchen volumio[14788]: info: MYVOLUMIO: Adding device
Aug 25 00:28:46 kitchen volumio[14788]: info: MYVOLUMIO: Evaluating Server
Aug 25 00:28:46 kitchen volumio[14788]: info: MyVolumio status changed
Aug 25 00:28:46 kitchen volumio[14788]: info: Streaming services startup
Aug 25 00:28:46 kitchen volumio[14788]: info: Starting Streaming Daemon
Aug 25 00:28:46 kitchen volumio[14788]: info: Removing browser output: myVolumio user plan is not superstar
Aug 25 00:28:46 kitchen volumio[14788]: info: Removing audio output:
Aug 25 00:28:46 kitchen volumio[14788]: info: Stoppping Tunnel 1
Aug 25 00:28:46 kitchen sudo[15316]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Aug 25 00:28:46 kitchen sudo[15316]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 00:28:46 kitchen sudo[15318]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Aug 25 00:28:46 kitchen sudo[15318]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 00:28:46 kitchen systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 25 00:28:46 kitchen systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 25 00:28:46 kitchen sudo[15316]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:46 kitchen volumio[14788]: error: Cannot start Volumio Streaming Daemon
Aug 25 00:28:46 kitchen volumio[14788]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Aug 25 00:28:46 kitchen volumio[14788]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Aug 25 00:28:46 kitchen systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 25 00:28:46 kitchen systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 25 00:28:46 kitchen systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 25 00:28:46 kitchen systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 25 00:28:46 kitchen systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 25 00:28:46 kitchen systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 25 00:28:46 kitchen sudo[15318]: pam_unix(sudo:session): session closed for user root
Aug 25 00:28:46 kitchen volumio[14788]: info: Remote SSH Stopped
Aug 25 00:28:46 kitchen volumio[14788]: info: Setting Geolocation for MyVolumio to eu3
Aug 25 00:28:46 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:46 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:46 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:46 kitchen volumio[14788]: info: Initializing connection to go-librespot Websocket
Aug 25 00:28:46 kitchen volumio[14788]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 25 00:28:46 kitchen volumio[14788]: info: BOOT COMPLETED
Aug 25 00:28:46 kitchen volumio[14788]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Aug 25 00:28:47 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 25 00:28:47 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:47 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Aug 25 00:28:47 kitchen volumio[14788]: info: Updating MyVolumio device info
Aug 25 00:28:47 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:47 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:47 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:47 kitchen volumio-remote-updater[719]: Test mode disabled
Aug 25 00:28:47 kitchen volumio-remote-updater[719]: Alpha mode disabled
Aug 25 00:28:47 kitchen volumio-remote-updater[719]: Alpha legacy test mode disabled
Aug 25 00:28:47 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Aug 25 00:28:47 kitchen volumio[14788]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Fix for missing PWM backlight module
- Fix for crash loop on Onboarding plugin
NEW ADDITIONS
- Bump RPi/CM4 kernel to 6.12.74
","title":"Update v4.119","updateavailable":true}
Aug 25 00:28:47 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Aug 25 00:28:47 kitchen volumio[14788]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Aug 25 00:28:48 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35.
Aug 25 00:28:48 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:48 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:48 kitchen go-librespot[15325]: go-librespot daemon starting...
Aug 25 00:28:48 kitchen go-librespot[15326]: time="2026-08-25T00:28:48+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:28:48 kitchen go-librespot[15326]: time="2026-08-25T00:28:48+01:00" level=debug msg="app state loaded"
Aug 25 00:28:48 kitchen go-librespot[15326]: time="2026-08-25T00:28:48+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:28:48 kitchen go-librespot[15326]: time="2026-08-25T00:28:48+01: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"
Aug 25 00:28:48 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:28:48 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:28:49 kitchen volumio[14788]: info: Initializing connection to go-librespot Websocket
Aug 25 00:28:49 kitchen volumio[14788]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 25 00:28:50 kitchen volumio[14788]: info: MYVOLUMIO: Adding device
Aug 25 00:28:50 kitchen volumio[14788]: info: MYVOLUMIO: Evaluating Server
Aug 25 00:28:51 kitchen volumio[14788]: info: Setting Geolocation for MyVolumio to eu3
Aug 25 00:28:51 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:51 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:51 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:51 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36.
Aug 25 00:28:51 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:51 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:51 kitchen go-librespot[15352]: go-librespot daemon starting...
Aug 25 00:28:51 kitchen volumio[14788]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Aug 25 00:28:51 kitchen go-librespot[15353]: time="2026-08-25T00:28:51+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:28:51 kitchen go-librespot[15353]: time="2026-08-25T00:28:51+01:00" level=debug msg="app state loaded"
Aug 25 00:28:51 kitchen go-librespot[15353]: time="2026-08-25T00:28:51+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:28:51 kitchen go-librespot[15353]: time="2026-08-25T00:28:51+01: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"
Aug 25 00:28:51 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:28:51 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:28:52 kitchen volumio[14788]: info: Updating MyVolumio device info
Aug 25 00:28:52 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:52 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:52 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 00:28:52 kitchen volumio[14788]: info: Initializing connection to go-librespot Websocket
Aug 25 00:28:52 kitchen volumio[14788]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 25 00:28:52 kitchen volumio[14788]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Aug 25 00:28:53 kitchen volumio[14788]: info: CoreCommandRouter::volumioGetState
Aug 25 00:28:53 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:28:53 kitchen volumio[14788]: info: Listing playlists
Aug 25 00:28:53 kitchen volumio[14788]: info: Listing playlists
Aug 25 00:28:54 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37.
Aug 25 00:28:54 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:55 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:55 kitchen go-librespot[15376]: go-librespot daemon starting...
Aug 25 00:28:55 kitchen go-librespot[15377]: time="2026-08-25T00:28:55+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:28:55 kitchen go-librespot[15377]: time="2026-08-25T00:28:55+01:00" level=debug msg="app state loaded"
Aug 25 00:28:55 kitchen go-librespot[15377]: time="2026-08-25T00:28:55+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:28:55 kitchen go-librespot[15377]: time="2026-08-25T00:28:55+01: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"
Aug 25 00:28:55 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:28:55 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:28:55 kitchen volumio[14788]: info: Initializing connection to go-librespot Websocket
Aug 25 00:28:55 kitchen volumio[14788]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 25 00:28:57 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Aug 25 00:28:57 kitchen volumio[14788]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11
Aug 25 00:28:57 kitchen volumio[14788]: info: CoreCommandRouter::volumioGetState
Aug 25 00:28:57 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:28:58 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38.
Aug 25 00:28:58 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:58 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:28:58 kitchen go-librespot[15386]: go-librespot daemon starting...
Aug 25 00:28:58 kitchen go-librespot[15387]: time="2026-08-25T00:28:58+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:28:58 kitchen go-librespot[15387]: time="2026-08-25T00:28:58+01:00" level=debug msg="app state loaded"
Aug 25 00:28:58 kitchen go-librespot[15387]: time="2026-08-25T00:28:58+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:28:58 kitchen go-librespot[15387]: time="2026-08-25T00:28:58+01: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"
Aug 25 00:28:58 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:28:58 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:28:58 kitchen volumio[14788]: info: Initializing connection to go-librespot Websocket
Aug 25 00:28:58 kitchen volumio[14788]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 25 00:29:01 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39.
Aug 25 00:29:01 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:01 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:01 kitchen go-librespot[15395]: go-librespot daemon starting...
Aug 25 00:29:01 kitchen go-librespot[15396]: time="2026-08-25T00:29:01+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:29:01 kitchen go-librespot[15396]: time="2026-08-25T00:29:01+01:00" level=debug msg="app state loaded"
Aug 25 00:29:01 kitchen go-librespot[15396]: time="2026-08-25T00:29:01+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:29:01 kitchen go-librespot[15396]: time="2026-08-25T00:29:01+01: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"
Aug 25 00:29:01 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:29:01 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:29:01 kitchen volumio[14788]: info: Initializing connection to go-librespot Websocket
Aug 25 00:29:01 kitchen volumio[14788]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 25 00:29:02 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:29:02 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 00:29:02 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Aug 25 00:29:02 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Aug 25 00:29:02 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Aug 25 00:29:02 kitchen volumio[14788]: info: CoreCommandRouter::volumioGetBrowseSources
Aug 25 00:29:02 kitchen volumio[14788]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 25 00:29:03 kitchen volumio[14788]: info: CoreCommandRouter::volumioGetState
Aug 25 00:29:03 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:29:04 kitchen volumio[14788]: info: Initializing connection to go-librespot Websocket
Aug 25 00:29:04 kitchen volumio[14788]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 25 00:29:04 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40.
Aug 25 00:29:04 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:04 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:04 kitchen go-librespot[15418]: go-librespot daemon starting...
Aug 25 00:29:04 kitchen go-librespot[15419]: time="2026-08-25T00:29:04+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:29:04 kitchen go-librespot[15419]: time="2026-08-25T00:29:04+01:00" level=debug msg="app state loaded"
Aug 25 00:29:04 kitchen go-librespot[15419]: time="2026-08-25T00:29:04+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:29:04 kitchen go-librespot[15419]: time="2026-08-25T00:29:04+01: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"
Aug 25 00:29:04 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:29:04 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:29:07 kitchen volumio[14788]: info: Initializing connection to go-librespot Websocket
Aug 25 00:29:07 kitchen volumio[14788]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 25 00:29:07 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41.
Aug 25 00:29:07 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:08 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:08 kitchen go-librespot[15427]: go-librespot daemon starting...
Aug 25 00:29:08 kitchen go-librespot[15428]: time="2026-08-25T00:29:08+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:29:08 kitchen go-librespot[15428]: time="2026-08-25T00:29:08+01:00" level=debug msg="app state loaded"
Aug 25 00:29:08 kitchen go-librespot[15428]: time="2026-08-25T00:29:08+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:29:08 kitchen go-librespot[15428]: time="2026-08-25T00:29:08+01: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"
Aug 25 00:29:08 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:29:08 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:29:10 kitchen volumio[14788]: info: Initializing connection to go-librespot Websocket
Aug 25 00:29:10 kitchen volumio[14788]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 25 00:29:11 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42.
Aug 25 00:29:11 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:11 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:11 kitchen go-librespot[15436]: go-librespot daemon starting...
Aug 25 00:29:11 kitchen go-librespot[15437]: time="2026-08-25T00:29:11+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:29:11 kitchen go-librespot[15437]: time="2026-08-25T00:29:11+01:00" level=debug msg="app state loaded"
Aug 25 00:29:11 kitchen go-librespot[15437]: time="2026-08-25T00:29:11+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:29:11 kitchen go-librespot[15437]: time="2026-08-25T00:29:11+01: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"
Aug 25 00:29:11 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:29:11 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:29:13 kitchen volumio[14788]: info: CoreCommandRouter::volumioGetState
Aug 25 00:29:13 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:29:13 kitchen volumio[14788]: info: Listing playlists
Aug 25 00:29:13 kitchen volumio[14788]: info: Listing playlists
Aug 25 00:29:13 kitchen volumio[14788]: info: Initializing connection to go-librespot Websocket
Aug 25 00:29:13 kitchen volumio[14788]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 25 00:29:14 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43.
Aug 25 00:29:14 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:14 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:14 kitchen go-librespot[15459]: go-librespot daemon starting...
Aug 25 00:29:14 kitchen go-librespot[15460]: time="2026-08-25T00:29:14+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:29:14 kitchen go-librespot[15460]: time="2026-08-25T00:29:14+01:00" level=debug msg="app state loaded"
Aug 25 00:29:14 kitchen go-librespot[15460]: time="2026-08-25T00:29:14+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:29:14 kitchen go-librespot[15460]: time="2026-08-25T00:29:14+01: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"
Aug 25 00:29:14 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:29:14 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:29:16 kitchen volumio[14788]: info: Initializing connection to go-librespot Websocket
Aug 25 00:29:16 kitchen volumio[14788]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 25 00:29:17 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44.
Aug 25 00:29:17 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:17 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:17 kitchen go-librespot[15471]: go-librespot daemon starting...
Aug 25 00:29:17 kitchen go-librespot[15472]: time="2026-08-25T00:29:17+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:29:17 kitchen go-librespot[15472]: time="2026-08-25T00:29:17+01:00" level=debug msg="app state loaded"
Aug 25 00:29:17 kitchen go-librespot[15472]: time="2026-08-25T00:29:17+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:29:17 kitchen go-librespot[15472]: time="2026-08-25T00:29:17+01: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"
Aug 25 00:29:17 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:29:17 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:29:19 kitchen volumio[14788]: info: Initializing connection to go-librespot Websocket
Aug 25 00:29:19 kitchen volumio[14788]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 25 00:29:20 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45.
Aug 25 00:29:20 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:21 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:21 kitchen go-librespot[15480]: go-librespot daemon starting...
Aug 25 00:29:21 kitchen go-librespot[15481]: time="2026-08-25T00:29:21+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:29:21 kitchen go-librespot[15481]: time="2026-08-25T00:29:21+01:00" level=debug msg="app state loaded"
Aug 25 00:29:21 kitchen go-librespot[15481]: time="2026-08-25T00:29:21+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:29:21 kitchen go-librespot[15481]: time="2026-08-25T00:29:21+01: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"
Aug 25 00:29:21 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:29:21 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:29:22 kitchen volumio[14788]: info: Initializing connection to go-librespot Websocket
Aug 25 00:29:22 kitchen volumio[14788]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 25 00:29:23 kitchen volumio[14788]: info: CoreCommandRouter::volumioGetState
Aug 25 00:29:23 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:29:24 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46.
Aug 25 00:29:24 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:24 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:24 kitchen go-librespot[15507]: go-librespot daemon starting...
Aug 25 00:29:24 kitchen go-librespot[15508]: time="2026-08-25T00:29:24+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:29:24 kitchen go-librespot[15508]: time="2026-08-25T00:29:24+01:00" level=debug msg="app state loaded"
Aug 25 00:29:24 kitchen go-librespot[15508]: time="2026-08-25T00:29:24+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:29:24 kitchen go-librespot[15508]: time="2026-08-25T00:29:24+01: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"
Aug 25 00:29:24 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:29:24 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:29:25 kitchen volumio[14788]: info: Initializing connection to go-librespot Websocket
Aug 25 00:29:25 kitchen volumio[14788]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 25 00:29:27 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47.
Aug 25 00:29:27 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:27 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:27 kitchen go-librespot[15518]: go-librespot daemon starting...
Aug 25 00:29:27 kitchen go-librespot[15519]: time="2026-08-25T00:29:27+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:29:27 kitchen go-librespot[15519]: time="2026-08-25T00:29:27+01:00" level=debug msg="app state loaded"
Aug 25 00:29:27 kitchen go-librespot[15519]: time="2026-08-25T00:29:27+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:29:27 kitchen go-librespot[15519]: time="2026-08-25T00:29:27+01: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"
Aug 25 00:29:27 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:29:27 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:29:28 kitchen volumio[14788]: info: Initializing connection to go-librespot Websocket
Aug 25 00:29:28 kitchen volumio[14788]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 25 00:29:30 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48.
Aug 25 00:29:30 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:30 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:30 kitchen go-librespot[15527]: go-librespot daemon starting...
Aug 25 00:29:30 kitchen go-librespot[15528]: time="2026-08-25T00:29:30+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:29:30 kitchen go-librespot[15528]: time="2026-08-25T00:29:30+01:00" level=debug msg="app state loaded"
Aug 25 00:29:30 kitchen go-librespot[15528]: time="2026-08-25T00:29:30+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:29:30 kitchen go-librespot[15528]: time="2026-08-25T00:29:30+01: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"
Aug 25 00:29:30 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:29:30 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:29:31 kitchen volumio[14788]: info: Initializing connection to go-librespot Websocket
Aug 25 00:29:31 kitchen volumio[14788]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 25 00:29:33 kitchen volumio[14788]: info: CoreCommandRouter::volumioGetState
Aug 25 00:29:33 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:29:33 kitchen volumio[14788]: info: Listing playlists
Aug 25 00:29:33 kitchen volumio[14788]: info: Listing playlists
Aug 25 00:29:33 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49.
Aug 25 00:29:33 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:34 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:34 kitchen go-librespot[15551]: go-librespot daemon starting...
Aug 25 00:29:34 kitchen go-librespot[15552]: time="2026-08-25T00:29:34+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:29:34 kitchen go-librespot[15552]: time="2026-08-25T00:29:34+01:00" level=debug msg="app state loaded"
Aug 25 00:29:34 kitchen go-librespot[15552]: time="2026-08-25T00:29:34+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:29:34 kitchen go-librespot[15552]: time="2026-08-25T00:29:34+01: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"
Aug 25 00:29:34 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:29:34 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:29:34 kitchen volumio[14788]: info: Initializing connection to go-librespot Websocket
Aug 25 00:29:34 kitchen volumio[14788]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 25 00:29:37 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50.
Aug 25 00:29:37 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:37 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:37 kitchen go-librespot[15560]: go-librespot daemon starting...
Aug 25 00:29:37 kitchen go-librespot[15561]: time="2026-08-25T00:29:37+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:29:37 kitchen go-librespot[15561]: time="2026-08-25T00:29:37+01:00" level=debug msg="app state loaded"
Aug 25 00:29:37 kitchen go-librespot[15561]: time="2026-08-25T00:29:37+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:29:37 kitchen go-librespot[15561]: time="2026-08-25T00:29:37+01: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"
Aug 25 00:29:37 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:29:37 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:29:37 kitchen volumio[14788]: info: Initializing connection to go-librespot Websocket
Aug 25 00:29:37 kitchen volumio[14788]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 25 00:29:40 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51.
Aug 25 00:29:40 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:40 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:40 kitchen go-librespot[15569]: go-librespot daemon starting...
Aug 25 00:29:40 kitchen go-librespot[15570]: time="2026-08-25T00:29:40+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:29:40 kitchen go-librespot[15570]: time="2026-08-25T00:29:40+01:00" level=debug msg="app state loaded"
Aug 25 00:29:40 kitchen go-librespot[15570]: time="2026-08-25T00:29:40+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:29:40 kitchen volumio[14788]: info: Initializing connection to go-librespot Websocket
Aug 25 00:29:40 kitchen go-librespot[15570]: time="2026-08-25T00:29:40+01:00" level=debug msg="new websocket client"
Aug 25 00:29:40 kitchen volumio[14788]: info: Connection to go-librespot Websocket established
Aug 25 00:29:40 kitchen go-librespot[15570]: time="2026-08-25T00:29:40+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Aug 25 00:29:40 kitchen go-librespot[15570]: time="2026-08-25T00:29:40+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Aug 25 00:29:40 kitchen go-librespot[15570]: time="2026-08-25T00:29:40+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Aug 25 00:29:40 kitchen go-librespot[15570]: time="2026-08-25T00:29:40+01:00" level=info msg="zeroconf server listening on port 34177"
Aug 25 00:29:40 kitchen go-librespot[15570]: time="2026-08-25T00:29:40+01:00" level=debug msg="obtained new client token: AAHNuLwY0ixSNDtbPUQ3SVHQVHPjGbzWTBtVHM1cn9hivVjFJHojE9ERCgvJjOw4ZCI95NH8Wg1bsx77GEJCpO3F3MkfcE6isqbSd6DEmKPkMA7VjsPSFxcSzUvNNcNEPnK+AZQrPB645pBIYVA2BzJ0EhkECWbM3OHaojCvUbS8jdvB8sVtN7Tl9ksjmNhSfcp8vYrFFcDMZRQpQnTSw2bWF0iGhNnMPMN4kSh6m3GF5YxcaRbivA=="
Aug 25 00:29:40 kitchen go-librespot[15570]: time="2026-08-25T00:29:40+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Aug 25 00:29:40 kitchen go-librespot[15570]: time="2026-08-25T00:29:40+01:00" level=debug msg="completed keyexchange"
Aug 25 00:29:40 kitchen go-librespot[15570]: time="2026-08-25T00:29:40+01:00" level=debug msg="completed challenge"
Aug 25 00:29:41 kitchen go-librespot[15570]: time="2026-08-25T00:29:41+01:00" level=info msg="authenticated AP" username="du*****el"
Aug 25 00:29:41 kitchen go-librespot[15570]: time="2026-08-25T00:29:41+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Aug 25 00:29:41 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:29:41 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:29:41 kitchen volumio[14788]: info: Connection to go-librespot Websocket closed
Aug 25 00:29:43 kitchen volumio[14788]: info: CoreCommandRouter::volumioGetState
Aug 25 00:29:43 kitchen volumio[14788]: info: CorePlayQueue::getTrack 0
Aug 25 00:29:43 kitchen volumio[14788]: info: Getting Spotify volume
Aug 25 00:29:43 kitchen volumio[14788]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 25 00:29:43 kitchen volumio[14788]: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 25 00:29:43 kitchen volumio[14788]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Aug 25 00:29:43 kitchen volumio[14788]: errno: -111,
Aug 25 00:29:43 kitchen volumio[14788]: code: 'ECONNREFUSED',
Aug 25 00:29:43 kitchen volumio[14788]: syscall: 'connect',
Aug 25 00:29:43 kitchen volumio[14788]: address: '127.0.0.1',
Aug 25 00:29:43 kitchen volumio[14788]: port: 9879,
Aug 25 00:29:43 kitchen volumio[14788]: response: undefined
Aug 25 00:29:43 kitchen volumio[14788]: }
Aug 25 00:29:43 kitchen volumio[14788]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 25 00:29:44 kitchen systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52.
Aug 25 00:29:44 kitchen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:44 kitchen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 25 00:29:44 kitchen go-librespot[15605]: go-librespot daemon starting...
Aug 25 00:29:44 kitchen go-librespot[15606]: time="2026-08-25T00:29:44+01:00" level=info msg="running go-librespot 0.6.2"
Aug 25 00:29:44 kitchen go-librespot[15606]: time="2026-08-25T00:29:44+01:00" level=debug msg="app state loaded"
Aug 25 00:29:44 kitchen go-librespot[15606]: time="2026-08-25T00:29:44+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 25 00:29:44 kitchen go-librespot[15606]: time="2026-08-25T00:29:44+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 25 00:29:44 kitchen go-librespot[15606]: time="2026-08-25T00:29:44+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Aug 25 00:29:44 kitchen go-librespot[15606]: time="2026-08-25T00:29:44+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Aug 25 00:29:44 kitchen go-librespot[15606]: time="2026-08-25T00:29:44+01:00" level=info msg="zeroconf server listening on port 46087"
Aug 25 00:29:44 kitchen go-librespot[15606]: time="2026-08-25T00:29:44+01:00" level=debug msg="obtained new client token: AAEn+b0qvh9exzDTM4fayGPGv7BXXLucMyk0J21eFOZUxiHHdYddc7mzmKhlmY5AeyYjiCxp9lut7rOc7UtoROUOUGG8om3Ls9kgR4aKbe1zwIMD9Dk9V0BmVJdsY1w1FRGn6kIUsM/CIQn0R4n0j5j0KhAxRtFqp++gPFP97Nf7EGAMKc5vvQr5mS9ss5YPUmxlptliR/cB1BqhjDG0xIwL6LlCqgsR6rGbD/PsYBE6pYVSgSJlWw=="
Aug 25 00:29:44 kitchen go-librespot[15606]: time="2026-08-25T00:29:44+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Aug 25 00:29:44 kitchen go-librespot[15606]: time="2026-08-25T00:29:44+01:00" level=debug msg="completed keyexchange"
Aug 25 00:29:44 kitchen go-librespot[15606]: time="2026-08-25T00:29:44+01:00" level=debug msg="completed challenge"
Aug 25 00:29:44 kitchen go-librespot[15606]: time="2026-08-25T00:29:44+01:00" level=info msg="authenticated AP" username="du*****el"
Aug 25 00:29:44 kitchen go-librespot[15606]: time="2026-08-25T00:29:44+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Aug 25 00:29:44 kitchen systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 00:29:44 kitchen systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 25 00:29:44 kitchen sudo[15617]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-25 00:28'
Aug 25 00:29:44 kitchen sudo[15617]: 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="dc61260dec5515dafd2b634881860b4c46c919ff"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026"
VOLUMIO_VERSION="4.103"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"