Jul 11 13:42:05 vardagsrumstereo volumio[1104]: verbose: New Socket.io Connection to 192.168.5.235 from 192.168.5.216 UA: Mozilla/5.0 (Linux; Android 15; SM-S711B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.118 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jul 11 13:42:05 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 11 13:42:05 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::volumioGetVisibleSources Jul 11 13:42:05 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 13:42:05 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::volumioGetState Jul 11 13:42:05 vardagsrumstereo volumio[1104]: info: CorePlayQueue::getTrack 0 Jul 11 13:42:05 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 11 13:42:05 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 11 13:42:05 vardagsrumstereo volumio[1104]: info: Received Get System Info Jul 11 13:42:05 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 13:42:05 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 13:42:05 vardagsrumstereo volumio[1104]: info: Discovery: Getting this device information Jul 11 13:42:05 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::volumioGetState Jul 11 13:42:05 vardagsrumstereo volumio[1104]: info: CorePlayQueue::getTrack 0 Jul 11 13:42:05 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 13:42:05 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::volumioGetState Jul 11 13:42:05 vardagsrumstereo volumio[1104]: info: CorePlayQueue::getTrack 0 Jul 11 13:42:05 vardagsrumstereo volumio[1104]: info: Listing playlists Jul 11 13:42:05 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::volumioGetQueue Jul 11 13:42:05 vardagsrumstereo volumio[1104]: info: CoreStateMachine::getQueue Jul 11 13:42:05 vardagsrumstereo volumio[1104]: info: CorePlayQueue::getQueue Jul 11 13:42:05 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 11 13:42:06 vardagsrumstereo volumio[1104]: verbose: New Socket.io Connection to 192.168.5.235:3000 from 192.168.5.216 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jul 11 13:42:06 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 13:42:06 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 13:42:06 vardagsrumstereo volumio[1104]: info: Discovery: Getting this device information Jul 11 13:42:06 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::volumioGetState Jul 11 13:42:06 vardagsrumstereo volumio[1104]: info: CorePlayQueue::getTrack 0 Jul 11 13:42:06 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 13:42:06 vardagsrumstereo volumio[1104]: verbose: New Socket.io Connection to 192.168.5.235:3000 from 192.168.5.216 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: Retrieving Cloud Streaming UI Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: Getting Tidal Cloud Configuration Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: Getting Qobuz Cloud Configuration Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: Asking plugin for UI Config Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: Getting Spotify Cloud Configuration Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: Asking plugin for UI Config Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: Saving Spotify Acccount Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: Got Tidal Cloud Configuration Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: Got it Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: Got it Jul 11 13:42:13 vardagsrumstereo volumio[1104]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 13:42:13 vardagsrumstereo volumio[1104]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::volumioGetBrowseSources Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::volumioGetBrowseSources Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::volumioGetBrowseSources Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 11 13:42:13 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 11 13:42:17 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: Retrieving Cloud Streaming UI Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: Getting Tidal Cloud Configuration Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: Getting Qobuz Cloud Configuration Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: Asking plugin for UI Config Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: Getting Spotify Cloud Configuration Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: Asking plugin for UI Config Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: Saving Spotify Acccount Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: Got Tidal Cloud Configuration Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: Got it Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: Got it Jul 11 13:42:19 vardagsrumstereo volumio[1104]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 13:42:19 vardagsrumstereo volumio[1104]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::volumioGetBrowseSources Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::volumioGetBrowseSources Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::volumioGetBrowseSources Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 11 13:42:19 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 11 13:42:26 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 11 13:42:28 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 11 13:42:28 vardagsrumstereo volumio[1104]: info: CURURI: music-library Jul 11 13:42:28 vardagsrumstereo volumio[1104]: info: Preload queue cleared Jul 11 13:42:32 vardagsrumstereo systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 11 13:42:32 vardagsrumstereo dbus-daemon[872]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.263' (uid=0 pid=20766 comm="timedatectl show --property=NTPSynchronized --valu") Jul 11 13:42:32 vardagsrumstereo systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 11 13:42:32 vardagsrumstereo dbus-daemon[872]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 11 13:42:32 vardagsrumstereo systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 11 13:42:32 vardagsrumstereo setdatetime-helper.sh[20765]: Time is already synchronized. Jul 11 13:42:32 vardagsrumstereo systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 11 13:42:32 vardagsrumstereo systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 11 13:42:55 vardagsrumstereo volumio[1104]: verbose: New Socket.io Connection to 192.168.5.235:3000 from 192.168.5.216 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jul 11 13:42:55 vardagsrumstereo volumio[1104]: verbose: New Socket.io Connection to 192.168.5.235 from 192.168.5.216 UA: Mozilla/5.0 (Linux; Android 15; SM-S711B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.118 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jul 11 13:42:55 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 11 13:42:55 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 11 13:42:55 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::volumioGetVisibleSources Jul 11 13:42:55 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 13:42:55 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::volumioGetState Jul 11 13:42:55 vardagsrumstereo volumio[1104]: info: CorePlayQueue::getTrack 0 Jul 11 13:42:55 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 11 13:42:55 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 11 13:42:55 vardagsrumstereo volumio[1104]: info: Received Get System Info Jul 11 13:42:55 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 13:42:55 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 13:42:55 vardagsrumstereo volumio[1104]: info: Discovery: Getting this device information Jul 11 13:42:55 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::volumioGetState Jul 11 13:42:55 vardagsrumstereo volumio[1104]: info: CorePlayQueue::getTrack 0 Jul 11 13:42:55 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 13:42:55 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::volumioGetState Jul 11 13:42:55 vardagsrumstereo volumio[1104]: info: CorePlayQueue::getTrack 0 Jul 11 13:42:55 vardagsrumstereo volumio[1104]: info: Listing playlists Jul 11 13:42:55 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::volumioGetQueue Jul 11 13:42:55 vardagsrumstereo volumio[1104]: info: CoreStateMachine::getQueue Jul 11 13:42:55 vardagsrumstereo volumio[1104]: info: CorePlayQueue::getQueue Jul 11 13:43:01 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 11 13:43:01 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 11 13:43:02 vardagsrumstereo systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 11 13:43:13 vardagsrumstereo volumio[1104]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/bookworm/armhf Jul 11 13:43:20 vardagsrumstereo volumio[1104]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/bookworm/armhf Jul 11 13:43:20 vardagsrumstereo volumio[1104]: info: Folder /tmp/plugins removed Jul 11 13:43:20 vardagsrumstereo volumio[1104]: info: Check plugin dependencies Jul 11 13:43:20 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 11 13:43:20 vardagsrumstereo volumio[1104]: info: Checking if plugin already exists Jul 11 13:43:20 vardagsrumstereo volumio[1104]: info: Rename folder Jul 11 13:43:20 vardagsrumstereo volumio[1104]: info: Folder /tmp/downloaded_plugin.zip removed Jul 11 13:43:20 vardagsrumstereo volumio[1104]: info: Move to category Jul 11 13:43:21 vardagsrumstereo volumio[1104]: info: Checking if install.sh is present Jul 11 13:43:21 vardagsrumstereo volumio[1104]: info: Executing install.sh Jul 11 13:43:21 vardagsrumstereo sudo[20861]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/spop/install.sh Jul 11 13:43:21 vardagsrumstereo sudo[20861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 13:43:21 vardagsrumstereo volumio[1104]: info: Installing Go-librespot Jul 11 13:43:21 vardagsrumstereo volumio[1104]: info: Checking old vollibrespot installs Jul 11 13:43:21 vardagsrumstereo systemd[1]: Reloading. Jul 11 13:43:21 vardagsrumstereo volumio[1104]: info: Downloading daemon Jul 11 13:43:23 vardagsrumstereo volumio[1104]: info: Creating directories Jul 11 13:43:23 vardagsrumstereo volumio[1104]: info: Creating data directory Jul 11 13:43:23 vardagsrumstereo volumio[1104]: info: Creating Start Script Jul 11 13:43:23 vardagsrumstereo systemd[1]: Reloading. Jul 11 13:43:23 vardagsrumstereo sudo[20861]: pam_unix(sudo:session): session closed for user root Jul 11 13:43:23 vardagsrumstereo volumio[1104]: info: Install script completed Jul 11 13:43:23 vardagsrumstereo volumio[1104]: info: Adding reference to registry Jul 11 13:43:23 vardagsrumstereo volumio[1104]: info: Done installing plugin. Jul 11 13:43:23 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 11 13:43:23 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 11 13:43:23 vardagsrumstereo volumio[1104]: Plugin install end detected on script Jul 11 13:43:23 vardagsrumstereo volumio[1104]: info: Folder /tmp/plugins removed Jul 11 13:43:23 vardagsrumstereo volumio[1104]: info: Folder /tmp/downloaded_plugin.zip removed Jul 11 13:43:23 vardagsrumstereo volumio[1104]: info: Folder /data/temp removed Jul 11 13:43:25 vardagsrumstereo volumio[1104]: info: Enabling plugin spop Jul 11 13:43:25 vardagsrumstereo volumio[1104]: info: Loading plugin "spop"... Jul 11 13:43:25 vardagsrumstereo volumio[1104]: info: PLUGIN START: spop Jul 11 13:43:25 vardagsrumstereo volumio[1104]: info: Creating Spotify config file Jul 11 13:43:25 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 13:43:25 vardagsrumstereo volumio[1104]: info: Done. Jul 11 13:43:25 vardagsrumstereo volumio[1104]: info: Spotify config file written Jul 11 13:43:25 vardagsrumstereo volumio[1104]: info: No need to fix Spotify hosts Jul 11 13:43:25 vardagsrumstereo sudo[20919]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 11 13:43:25 vardagsrumstereo sudo[20919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 13:43:25 vardagsrumstereo systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jul 11 13:43:25 vardagsrumstereo systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jul 11 13:43:25 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 11 13:43:25 vardagsrumstereo go-librespot[20921]: go-librespot daemon starting... Jul 11 13:43:25 vardagsrumstereo sudo[20919]: pam_unix(sudo:session): session closed for user root Jul 11 13:43:25 vardagsrumstereo go-librespot[20922]: time="2025-07-11T13:43:25+02:00" level=info msg="running go-librespot 0.2.0" Jul 11 13:43:25 vardagsrumstereo go-librespot[20922]: time="2025-07-11T13:43:25+02:00" level=debug msg="no app state found" Jul 11 13:43:25 vardagsrumstereo go-librespot[20922]: time="2025-07-11T13:43:25+02:00" level=debug msg="stored credentials not found" Jul 11 13:43:25 vardagsrumstereo go-librespot[20922]: time="2025-07-11T13:43:25+02:00" level=info msg="generated new device id: 4fa6f7d9b651cd9afd6edcd14993b7b520629507" Jul 11 13:43:25 vardagsrumstereo go-librespot[20922]: time="2025-07-11T13:43:25+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 11 13:43:25 vardagsrumstereo go-librespot[20922]: time="2025-07-11T13:43:25+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jul 11 13:43:25 vardagsrumstereo go-librespot[20922]: time="2025-07-11T13:43:25+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jul 11 13:43:25 vardagsrumstereo go-librespot[20922]: time="2025-07-11T13:43:25+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jul 11 13:43:25 vardagsrumstereo go-librespot[20922]: time="2025-07-11T13:43:25+02:00" level=info msg="zeroconf server listening on port 36199" Jul 11 13:43:26 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 11 13:43:28 vardagsrumstereo volumio[1104]: info: go-librespot daemon successfully initialized Jul 11 13:43:31 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 13:43:31 vardagsrumstereo volumio[1104]: info: Initializing connection to go-librespot Websocket Jul 11 13:43:31 vardagsrumstereo go-librespot[20922]: time="2025-07-11T13:43:31+02:00" level=debug msg="new websocket client" Jul 11 13:43:31 vardagsrumstereo volumio[1104]: info: Connection to go-librespot Websocket established Jul 11 13:43:34 vardagsrumstereo volumio[1104]: info: Getting Spotify volume Jul 11 13:43:34 vardagsrumstereo volumio[1104]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 11 13:43:34 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::volumioGetState Jul 11 13:43:34 vardagsrumstereo volumio[1104]: info: CorePlayQueue::getTrack 0 Jul 11 13:43:49 vardagsrumstereo volumio[1104]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Jul 11 13:43:49 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Jul 11 13:43:49 vardagsrumstereo volumio[1104]: info: Creating Spotify config file Jul 11 13:43:49 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 13:43:49 vardagsrumstereo volumio[1104]: info: Spotify config file written Jul 11 13:43:49 vardagsrumstereo sudo[20973]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 11 13:43:49 vardagsrumstereo sudo[20973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 13:43:49 vardagsrumstereo systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 11 13:43:49 vardagsrumstereo systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jul 11 13:43:49 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Killing process 20928 (go-librespot) with signal SIGKILL. Jul 11 13:43:49 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jul 11 13:43:49 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 11 13:43:49 vardagsrumstereo volumio[1104]: info: Connection to go-librespot Websocket closed Jul 11 13:43:49 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 11 13:43:49 vardagsrumstereo go-librespot[20978]: go-librespot daemon starting... Jul 11 13:43:49 vardagsrumstereo sudo[20973]: pam_unix(sudo:session): session closed for user root Jul 11 13:43:49 vardagsrumstereo dbus-daemon[872]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.265' (uid=0 pid=20976 comm="timedatectl show --property=NTPSynchronized --valu") Jul 11 13:43:49 vardagsrumstereo go-librespot[20979]: time="2025-07-11T13:43:49+02:00" level=info msg="running go-librespot 0.2.0" Jul 11 13:43:49 vardagsrumstereo go-librespot[20979]: time="2025-07-11T13:43:49+02:00" level=debug msg="app state loaded" Jul 11 13:43:49 vardagsrumstereo go-librespot[20979]: time="2025-07-11T13:43:49+02:00" level=debug msg="stored credentials not found" Jul 11 13:43:49 vardagsrumstereo go-librespot[20979]: time="2025-07-11T13:43:49+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 11 13:43:49 vardagsrumstereo systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 11 13:43:49 vardagsrumstereo dbus-daemon[872]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 11 13:43:49 vardagsrumstereo systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 11 13:43:49 vardagsrumstereo setdatetime-helper.sh[20975]: Time is already synchronized. Jul 11 13:43:49 vardagsrumstereo systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 11 13:43:49 vardagsrumstereo systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 11 13:43:49 vardagsrumstereo go-librespot[20979]: time="2025-07-11T13:43:49+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jul 11 13:43:49 vardagsrumstereo go-librespot[20979]: time="2025-07-11T13:43:49+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jul 11 13:43:49 vardagsrumstereo go-librespot[20979]: time="2025-07-11T13:43:49+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jul 11 13:43:49 vardagsrumstereo go-librespot[20979]: time="2025-07-11T13:43:49+02:00" level=info msg="zeroconf server listening on port 36241" Jul 11 13:43:52 vardagsrumstereo volumio[1104]: info: Initializing connection to go-librespot Websocket Jul 11 13:43:52 vardagsrumstereo go-librespot[20979]: time="2025-07-11T13:43:52+02:00" level=debug msg="new websocket client" Jul 11 13:43:52 vardagsrumstereo volumio[1104]: info: Connection to go-librespot Websocket established Jul 11 13:43:52 vardagsrumstereo volumio[1104]: info: go-librespot daemon successfully initialized Jul 11 13:43:54 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 11 13:43:54 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 11 13:43:55 vardagsrumstereo volumio[1104]: info: Getting Spotify volume Jul 11 13:43:55 vardagsrumstereo volumio[1104]: info: Initializing connection to go-librespot Websocket Jul 11 13:43:55 vardagsrumstereo go-librespot[20979]: time="2025-07-11T13:43:55+02:00" level=debug msg="new websocket client" Jul 11 13:43:55 vardagsrumstereo volumio[1104]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jul 11 13:43:55 vardagsrumstereo volumio[1104]: info: Connection to go-librespot Websocket established Jul 11 13:43:55 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::volumioGetState Jul 11 13:43:55 vardagsrumstereo volumio[1104]: info: CorePlayQueue::getTrack 0 Jul 11 13:43:57 vardagsrumstereo go-librespot[20979]: time="2025-07-11T13:43:57+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jul 11 13:43:57 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Jul 11 13:43:57 vardagsrumstereo volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 13:43:57 vardagsrumstereo volumio[1104]: info: Disabling plugin spop Jul 11 13:43:57 vardagsrumstereo volumio[1104]: info: Done. Jul 11 13:43:57 vardagsrumstereo volumio[1104]: info: Connection to go-librespot Websocket closed Jul 11 13:43:57 vardagsrumstereo sudo[20988]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Jul 11 13:43:57 vardagsrumstereo sudo[20988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 13:43:57 vardagsrumstereo systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jul 11 13:43:57 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Killing process 20983 (go-librespot) with signal SIGKILL. Jul 11 13:43:57 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jul 11 13:43:57 vardagsrumstereo volumio[1104]: info: Connection to go-librespot Websocket closed Jul 11 13:43:57 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 11 13:43:57 vardagsrumstereo systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jul 11 13:43:57 vardagsrumstereo systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jul 11 13:43:57 vardagsrumstereo systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jul 11 13:43:57 vardagsrumstereo systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jul 11 13:43:57 vardagsrumstereo sudo[20988]: pam_unix(sudo:session): session closed for user root Jul 11 13:43:58 vardagsrumstereo volumio[1104]: info: Getting Spotify volume Jul 11 13:43:58 vardagsrumstereo volumio[1104]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 13:43:58 vardagsrumstereo volumio[1104]: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 11 13:43:58 vardagsrumstereo volumio[1104]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Jul 11 13:43:58 vardagsrumstereo volumio[1104]: errno: -111, Jul 11 13:43:58 vardagsrumstereo volumio[1104]: code: 'ECONNREFUSED', Jul 11 13:43:58 vardagsrumstereo volumio[1104]: syscall: 'connect', Jul 11 13:43:58 vardagsrumstereo volumio[1104]: address: '127.0.0.1', Jul 11 13:43:58 vardagsrumstereo volumio[1104]: port: 9879, Jul 11 13:43:58 vardagsrumstereo volumio[1104]: response: undefined Jul 11 13:43:58 vardagsrumstereo volumio[1104]: } Jul 11 13:43:58 vardagsrumstereo volumio[1104]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 13:43:58 vardagsrumstereo sudo[21004]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-11 13:42' Jul 11 13:43:58 vardagsrumstereo sudo[21004]: 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="4f980d5cf693b4272997a2fa2da8bb8036ddf1ab" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jul 5 02:50:33 UTC 2025" VOLUMIO_VERSION="4.015" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d42ab79cc13d24482826e04fda2e8350"