-- Logs begin at Sun 2025-09-14 07:28:31 UTC, end at Sun 2025-09-14 07:32:19 UTC. -- Sep 14 07:31:53 volumio dhcpd[1285]: DHCPDISCOVER from b8:27:eb:9b:e4:3d (volumio) via wlan0 Sep 14 07:31:54 volumio dhcpd[1285]: DHCPOFFER on 192.168.211.5 to b8:27:eb:9b:e4:3d (volumio) via wlan0 Sep 14 07:32:03 volumio volumio[1268]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Sep 14 07:32:03 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 07:32:03 volumio volumio[1268]: [SpotifyConnect] Closing metadata listener Sep 14 07:32:03 volumio volumio[1268]: info: Disabling plugin spop Sep 14 07:32:03 volumio volumio[1268]: info: Done. Sep 14 07:32:03 volumio sudo[2073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Sep 14 07:32:03 volumio sudo[2073]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 07:32:03 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Sep 14 07:32:03 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Sep 14 07:32:03 volumio sudo[2073]: pam_unix(sudo:session): session closed for user root Sep 14 07:32:03 volumio volumio[1268]: [SpotifyConnect] Vollibrespot Daemon service stoped! Sep 14 07:32:06 volumio volumio[1268]: info: Enabling plugin spop Sep 14 07:32:06 volumio volumio[1268]: info: Loading plugin "spop"... Sep 14 07:32:06 volumio volumio[1268]: info: PLUGIN START: spop Sep 14 07:32:06 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 07:32:06 volumio volumio[1268]: [SpotifyConnect] Creating VLS config file Sep 14 07:32:06 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:32:06 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:32:06 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:32:06 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:32:06 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:32:06 volumio volumio[1268]: [SpotifyConnect] Starting metadata listener Sep 14 07:32:06 volumio sudo[2089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Sep 14 07:32:06 volumio sudo[2089]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 07:32:06 volumio sudo[2092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Sep 14 07:32:06 volumio sudo[2089]: pam_unix(sudo:session): session closed for user root Sep 14 07:32:06 volumio sudo[2092]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 07:32:06 volumio systemd[1]: Starting Volspotconnect2 Daemon... Sep 14 07:32:06 volumio systemd[1]: Started Volspotconnect2 Daemon. Sep 14 07:32:06 volumio sudo[2092]: pam_unix(sudo:session): session closed for user root Sep 14 07:32:06 volumio volumio[2103]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Sep 14 07:32:06 volumio volumio[2103]: Reading Config from "/tmp/volspotify.toml" Sep 14 07:32:06 volumio volumio[2103]: [Vollibrespot] : Using Alsa backend with device: plughw:Loopback Sep 14 07:32:06 volumio volumio[1268]: [SpotifyConnect] Vollibrespot Daemon service started! Sep 14 07:32:06 volumio volumio[1268]: [Metrics] SpotifyConnect: 0s 93.09ms Sep 14 07:32:06 volumio volumio[1268]: info: Done. Sep 14 07:32:06 volumio volumio[2103]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Sep 14 07:32:10 volumio volumio[1268]: info: Checking Spotify Web API Sep 14 07:32:13 volumio volumio[1268]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Sep 14 07:32:13 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 07:32:13 volumio volumio[1268]: [SpotifyConnect] Closing metadata listener Sep 14 07:32:13 volumio volumio[1268]: info: Disabling plugin spop Sep 14 07:32:13 volumio volumio[1268]: info: Done. Sep 14 07:32:13 volumio sudo[2123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Sep 14 07:32:13 volumio sudo[2123]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 07:32:13 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Sep 14 07:32:13 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Sep 14 07:32:13 volumio sudo[2123]: pam_unix(sudo:session): session closed for user root Sep 14 07:32:13 volumio volumio[1268]: [SpotifyConnect] Vollibrespot Daemon service stoped! Sep 14 07:32:15 volumio volumio[1268]: info: Enabling plugin spop Sep 14 07:32:15 volumio volumio[1268]: info: Loading plugin "spop"... Sep 14 07:32:15 volumio volumio[1268]: info: PLUGIN START: spop Sep 14 07:32:15 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 07:32:15 volumio volumio[1268]: [SpotifyConnect] Creating VLS config file Sep 14 07:32:15 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:32:15 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:32:15 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:32:15 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:32:15 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:32:15 volumio volumio[1268]: [SpotifyConnect] Starting metadata listener Sep 14 07:32:15 volumio sudo[2137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Sep 14 07:32:15 volumio sudo[2137]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 07:32:15 volumio sudo[2137]: pam_unix(sudo:session): session closed for user root Sep 14 07:32:15 volumio sudo[2141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Sep 14 07:32:15 volumio sudo[2141]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 07:32:15 volumio systemd[1]: Starting Volspotconnect2 Daemon... Sep 14 07:32:15 volumio systemd[1]: Started Volspotconnect2 Daemon. Sep 14 07:32:15 volumio sudo[2141]: pam_unix(sudo:session): session closed for user root Sep 14 07:32:15 volumio volumio[2151]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Sep 14 07:32:15 volumio volumio[2151]: Reading Config from "/tmp/volspotify.toml" Sep 14 07:32:15 volumio volumio[2151]: [Vollibrespot] : Using Alsa backend with device: plughw:Loopback Sep 14 07:32:15 volumio volumio[1268]: [SpotifyConnect] Vollibrespot Daemon service started! Sep 14 07:32:15 volumio volumio[1268]: [Metrics] SpotifyConnect: 0s 84.30ms Sep 14 07:32:15 volumio volumio[1268]: info: Done. Sep 14 07:32:15 volumio volumio[2151]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Sep 14 07:32:16 volumio volumio[1268]: [SpotifyConnect] Stopping Vollibrespot daemon Sep 14 07:32:16 volumio volumio[1268]: [SpotifyConnect] Closing metadata listener Sep 14 07:32:16 volumio volumio[1268]: info: Disabling plugin volspotconnect2 Sep 14 07:32:16 volumio volumio[1268]: info: Done. Sep 14 07:32:16 volumio sudo[2161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect2.service Sep 14 07:32:16 volumio sudo[2161]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 07:32:16 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Sep 14 07:32:16 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Sep 14 07:32:16 volumio sudo[2161]: pam_unix(sudo:session): session closed for user root Sep 14 07:32:16 volumio volumio[1268]: [SpotifyConnect] Vollibrespot Daemon service stoped! Sep 14 07:32:17 volumio volumio[1268]: info: Enabling plugin volspotconnect2 Sep 14 07:32:17 volumio volumio[1268]: info: Loading plugin "volspotconnect2"... Sep 14 07:32:17 volumio volumio[1268]: info: PLUGIN START: volspotconnect2 Sep 14 07:32:17 volumio volumio[1268]: [SpotifyConnect] Creating VLS config file Sep 14 07:32:17 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:32:17 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:32:17 volumio volumio[1268]: [SpotifyConnect] Unimplemented debug mode!! Sep 14 07:32:17 volumio volumio[1268]: [SpotifyConnect] Starting metadata listener Sep 14 07:32:17 volumio volumio[1268]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Sep 14 07:32:17 volumio volumio[1268]: at Object._errnoException (util.js:1022:11) Sep 14 07:32:17 volumio volumio[1268]: at _exceptionWithHostPort (util.js:1044:20) Sep 14 07:32:17 volumio volumio[1268]: at _handle.lookup (dgram.js:266:18) Sep 14 07:32:17 volumio volumio[1268]: at _combinedTickCallback (internal/process/next_tick.js:141:11) Sep 14 07:32:17 volumio volumio[1268]: at process._tickCallback (internal/process/next_tick.js:180:9) Sep 14 07:32:17 volumio volumio[1268]: [SpotifyConnect] Error [ERR_SOCKET_CANNOT_SEND]: Unable to send data Sep 14 07:32:17 volumio volumio[1268]: at Socket.onListenError (dgram.js:362:22) Sep 14 07:32:17 volumio volumio[1268]: at Object.onceWrapper (events.js:315:30) Sep 14 07:32:17 volumio volumio[1268]: at emitOne (events.js:121:20) Sep 14 07:32:17 volumio volumio[1268]: at Socket.emit (events.js:211:7) Sep 14 07:32:17 volumio volumio[1268]: at _handle.lookup (dgram.js:267:14) Sep 14 07:32:17 volumio volumio[1268]: at _combinedTickCallback (internal/process/next_tick.js:141:11) Sep 14 07:32:17 volumio volumio[1268]: at process._tickCallback (internal/process/next_tick.js:180:9) Sep 14 07:32:17 volumio sudo[2171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Sep 14 07:32:17 volumio sudo[2171]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 07:32:17 volumio systemd[1]: Starting Volspotconnect2 Daemon... Sep 14 07:32:17 volumio systemd[1]: Started Volspotconnect2 Daemon. Sep 14 07:32:17 volumio sudo[2171]: pam_unix(sudo:session): session closed for user root Sep 14 07:32:17 volumio volumio[2176]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Sep 14 07:32:17 volumio volumio[2176]: Reading Config from "volspotify.toml" Sep 14 07:32:17 volumio volumio[2176]: [Vollibrespot] : Using Alsa backend with device: plughw:Loopback Sep 14 07:32:17 volumio volumio[1268]: [SpotifyConnect] Vollibrespot Daemon service started! Sep 14 07:32:17 volumio volumio[1268]: [Metrics] SpotifyConnect: 0s 66.96ms Sep 14 07:32:17 volumio volumio[1268]: info: Done. Sep 14 07:32:17 volumio volumio[2176]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Sep 14 07:32:19 volumio volumio[1268]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Sep 14 07:32:19 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 07:32:19 volumio volumio[1268]: [SpotifyConnect] Closing metadata listener Sep 14 07:32:19 volumio volumio[1268]: info: Disabling plugin spop Sep 14 07:32:19 volumio volumio[1268]: info: Done. Sep 14 07:32:19 volumio sudo[2203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Sep 14 07:32:19 volumio sudo[2203]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 07:32:19 volumio volumio[1268]: info: Checking Spotify Web API Sep 14 07:32:19 volumio volumio[1268]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 07:32:19 volumio volumio[1268]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Sep 14 07:32:19 volumio volumio[1268]: at Socket._healthCheck (dgram.js:638:11) Sep 14 07:32:19 volumio volumio[1268]: at Socket.send (dgram.js:435:8) Sep 14 07:32:19 volumio volumio[1268]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:106:21) Sep 14 07:32:19 volumio volumio[1268]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2318:23) Sep 14 07:32:19 volumio volumio[1268]: at Timeout.setTimeout [as _onTimeout] (/data/plugins/music_service/spop/index.js:2410:18) Sep 14 07:32:19 volumio volumio[1268]: at ontimeout (timers.js:482:11) Sep 14 07:32:19 volumio volumio[1268]: at tryOnTimeout (timers.js:317:5) Sep 14 07:32:19 volumio volumio[1268]: at Timer.listOnTimeout (timers.js:277:5) Sep 14 07:32:19 volumio volumio[1268]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 07:32:19 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Sep 14 07:32:19 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Sep 14 07:32:19 volumio sudo[2203]: pam_unix(sudo:session): session closed for user root Sep 14 07:32:19 volumio sudo[2220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-14 07:31 Sep 14 07:32:19 volumio sudo[2220]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="74e4cc9de715c64d553d35948d017f973a622b6d" VOLUMIO_FE_VERSION="2be6c28eb9de74ec1f9662ca333f7bd51a232c33" VOLUMIO_BE_VERSION="259a7f2894e1376413ffac331be84e0e9a6173dd" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Sep 24 22:42:43 CEST 2020" VOLUMIO_VERSION="2.834" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="e41ef0f29aa50c1af109d3064a380c69"