Dec 14 14:04:17 musik systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 14 14:04:17 musik systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 14 14:04:17 musik systemd[1]: Started smbd.service - Samba SMB Daemon. Dec 14 14:04:17 musik systemd[1]: Reached target multi-user.target - Multi-User System. Dec 14 14:04:17 musik systemd[1]: Reached target graphical.target - Graphical Interface. Dec 14 14:04:17 musik systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Dec 14 14:04:18 musik systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Dec 14 14:04:18 musik systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Dec 14 14:04:18 musik systemd[1]: Startup finished in 17.557s (kernel) + 21.213s (userspace) = 38.770s. Dec 14 14:04:19 musik ntpd[976]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 14 14:04:19 musik ntpd[976]: DNS: Pool taking: 46.32.144.141 Dec 14 14:04:19 musik ntpd[976]: DNS: Pool taking: 217.198.219.102 Dec 14 14:04:19 musik ntpd[976]: DNS: Pool taking: 78.157.112.18 Dec 14 14:04:19 musik ntpd[976]: DNS: Pool taking: 212.237.179.56 Dec 14 14:04:19 musik ntpd[976]: DNS: Pool taking: 2001:67c:564::12 Dec 14 14:04:19 musik ntpd[976]: DNS: Pool taking: 2a06:4001:f047:f:: Dec 14 14:04:19 musik ntpd[976]: DNS: Pool taking: 2a00:1f98:1001::123 Dec 14 14:04:19 musik ntpd[976]: DNS: Pool taking: 2606:4700:f1::1 Dec 14 14:04:19 musik ntpd[976]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Dec 14 14:04:20 musik volumio-remote-updater[751]: [2025-12-14 14:04:20] [info] asio async_connect error: asio.system:111 (Connection refused) Dec 14 14:04:20 musik volumio-remote-updater[751]: [2025-12-14 14:04:20] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Dec 14 14:04:20 musik volumio-remote-updater[751]: [2025-12-14 14:04:20] [error] handle_connect error: Connection refused Dec 14 14:04:20 musik ntpd[976]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 14 14:04:22 musik volumio[1192]: info: ------------------------------------------- Dec 14 14:04:22 musik volumio[1192]: info: ----- Volumio3 ---- Dec 14 14:04:22 musik volumio[1192]: info: ------------------------------------------- Dec 14 14:04:22 musik volumio[1192]: info: ----- System startup ---- Dec 14 14:04:22 musik volumio[1192]: info: ------------------------------------------- Dec 14 14:04:23 musik systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Dec 14 14:04:23 musik systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 14 14:04:23 musik systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 14 14:04:23 musik upmpdcli[1232]: Could not open config: /tmp/upmpdcli.conf Dec 14 14:04:23 musik systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 14 14:04:23 musik systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 14 14:04:23 musik ntpd[976]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 14 14:04:23 musik ntpd[976]: DNS: Pool skipping: 87.63.200.138 Dec 14 14:04:23 musik ntpd[976]: DNS: Pool taking: 86.52.112.177 Dec 14 14:04:23 musik ntpd[976]: DNS: Pool taking: 80.209.68.118 Dec 14 14:04:23 musik ntpd[976]: DNS: Pool skipping: 87.104.58.9 Dec 14 14:04:23 musik ntpd[976]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Dec 14 14:04:24 musik volumio[1192]: info: MYVOLUMIO Environment detected Dec 14 14:04:24 musik volumio[1192]: info: Plugin folders cleanup Dec 14 14:04:24 musik volumio[1192]: info: Scanning into folder /volumio/app/plugins/ Dec 14 14:04:24 musik volumio[1192]: info: Scanning category audio_interface Dec 14 14:04:24 musik volumio[1192]: info: Scanning category miscellanea Dec 14 14:04:24 musik volumio[1192]: info: Scanning category music_service Dec 14 14:04:24 musik volumio[1192]: info: Scanning category plugins.json Dec 14 14:04:24 musik volumio[1192]: info: Scanning category system_controller Dec 14 14:04:24 musik volumio[1192]: info: Scanning category user_interface Dec 14 14:04:24 musik volumio[1192]: info: Scanning into folder /data/plugins/ Dec 14 14:04:24 musik volumio[1192]: info: Scanning category music_service Dec 14 14:04:24 musik volumio[1192]: info: Plugin folders cleanup completed Dec 14 14:04:24 musik volumio[1192]: info: ------------------------------------------- Dec 14 14:04:24 musik volumio[1192]: info: ----- Core plugins startup ---- Dec 14 14:04:24 musik volumio[1192]: info: ------------------------------------------- Dec 14 14:04:24 musik volumio[1192]: info: Loading plugins from folder /volumio/app/plugins/ Dec 14 14:04:24 musik volumio[1192]: info: Adding plugin upnp to MyMusic Plugins Dec 14 14:04:24 musik volumio[1192]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 14 14:04:24 musik volumio[1192]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 14 14:04:24 musik volumio[1192]: info: Loading plugins from folder /data/plugins/ Dec 14 14:04:24 musik volumio[1192]: info: Loading plugin "system"... Dec 14 14:04:24 musik volumio[1192]: info: Loading plugin "appearance"... Dec 14 14:04:24 musik ntpd[976]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 14 14:04:26 musik ntpd[976]: CLOCK: time stepped by 0.815792 Dec 14 14:04:26 musik ntpd[976]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 14 14:04:28 musik ntpd[976]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 14 14:04:28 musik ntpd[976]: DNS: Pool skipping: 86.52.51.137 Dec 14 14:04:28 musik ntpd[976]: DNS: Pool taking: 162.159.200.1 Dec 14 14:04:28 musik ntpd[976]: DNS: Pool taking: 185.181.223.169 Dec 14 14:04:28 musik ntpd[976]: DNS: Pool taking: 217.198.219.101 Dec 14 14:04:28 musik ntpd[976]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Dec 14 14:04:30 musik volumio[1192]: info: Loading plugin "network"... Dec 14 14:04:30 musik volumio[1192]: info: Refreshing Cached IP Addresses Dec 14 14:04:30 musik sudo[1241]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 14:04:30 musik sudo[1241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:04:30 musik volumio[1192]: info: Loading plugin "services"... Dec 14 14:04:30 musik volumio[1192]: info: Loading plugin "alsa_controller"... Dec 14 14:04:30 musik sudo[1241]: pam_unix(sudo:session): session closed for user root Dec 14 14:04:30 musik sudo[1243]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 14:04:30 musik sudo[1243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:04:30 musik sudo[1243]: pam_unix(sudo:session): session closed for user root Dec 14 14:04:30 musik sudo[1254]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 14 14:04:30 musik sudo[1254]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:04:31 musik volumio-remote-updater[751]: [2025-12-14 14:04:31] [connect] Successful connection Dec 14 14:04:31 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 14:04:31 musik volumio[1192]: info: Loading plugin "wizard"... Dec 14 14:04:31 musik volumio[1192]: info: Loading plugin "networkfs"... Dec 14 14:04:31 musik volumio[1192]: info: Starting Udev Watcher for removable devices Dec 14 14:04:31 musik volumio[1192]: info: Ignoring mount for partition: boot Dec 14 14:04:31 musik volumio[1192]: info: Ignoring mount for partition: volumio Dec 14 14:04:31 musik volumio[1192]: info: Ignoring mount for partition: volumio_data Dec 14 14:04:31 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 14 14:04:31 musik volumio[1192]: info: Loading plugin "volumio_command_line_client"... Dec 14 14:04:31 musik volumio[1192]: info: Loading plugin "upnp"... Dec 14 14:04:31 musik volumio[1192]: info: [1765717471554] Starting Upmpd Daemon Dec 14 14:04:31 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 14 14:04:31 musik volumio[1192]: info: Loading plugin "my_music"... Dec 14 14:04:31 musik volumio[1192]: info: Loading plugin "mpd"... Dec 14 14:04:31 musik sudo[1254]: pam_unix(sudo:session): session closed for user root Dec 14 14:04:32 musik volumio[1192]: info: Plugin upnp_browser is not enabled Dec 14 14:04:32 musik volumio[1192]: info: Loading plugin "alarm-clock"... Dec 14 14:04:32 musik systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 14 14:04:32 musik volumio[1192]: info: Plugin airplay_emulation is not enabled Dec 14 14:04:32 musik volumio[1192]: info: Loading plugin "last_100"... Dec 14 14:04:32 musik volumio[1192]: info: Loading plugin "webradio"... Dec 14 14:04:34 musik volumio[1192]: info: Loading plugin "i2s_dacs"... Dec 14 14:04:34 musik volumio[1192]: info: I2S DAC not set, start Auto-detection Dec 14 14:04:34 musik volumio[1192]: info: Loading plugin "volumiodiscovery"... Dec 14 14:04:34 musik volumio[1192]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 14 14:04:34 musik node[1192]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 14 14:04:34 musik volumio[1192]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 14 14:04:34 musik node[1192]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 14 14:04:34 musik volumio[1192]: *** WARNING *** For more information see Dec 14 14:04:34 musik node[1192]: *** WARNING *** For more information see Dec 14 14:04:34 musik volumio[1192]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 14 14:04:34 musik node[1192]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 14 14:04:34 musik volumio[1192]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 14 14:04:34 musik volumio[1192]: *** WARNING *** For more information see Dec 14 14:04:34 musik node[1192]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 14 14:04:34 musik node[1192]: *** WARNING *** For more information see Dec 14 14:04:34 musik volumio[1192]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 14 14:04:34 musik volumio[1192]: info: Discovery: Started advertising with name: musik Dec 14 14:04:34 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 14 14:04:34 musik volumio[1192]: info: Loading plugin "spop"... Dec 14 14:04:38 musik systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 14 14:04:39 musik systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Dec 14 14:04:39 musik systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 14 14:04:39 musik systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 14 14:04:39 musik upmpdcli[1279]: Could not open config: /tmp/upmpdcli.conf Dec 14 14:04:39 musik systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 14 14:04:39 musik systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 14 14:04:39 musik volumio[1192]: info: Loading plugin "youtube2"... Dec 14 14:04:43 musik systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 14 14:04:43 musik systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 14 14:04:43 musik systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 14 14:04:43 musik systemd[1]: welcome.service: Deactivated successfully. Dec 14 14:04:43 musik systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 14 14:04:43 musik systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 14 14:04:43 musik systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 14 14:04:43 musik welcome[1298]: Resolved ip:[1] 10.0.0.45 Dec 14 14:04:43 musik systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 14 14:04:43 musik systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 14 14:04:44 musik volumio[1192]: info: Loading plugin "outputs"... Dec 14 14:04:44 musik volumio[1192]: info: Loading plugin "albumart"... Dec 14 14:04:44 musik volumio[1192]: info: Plugin example_plugin is not enabled Dec 14 14:04:44 musik volumio[1192]: info: Loading plugin "inputs"... Dec 14 14:04:44 musik volumio[1192]: info: Loading plugin "updater_comm"... Dec 14 14:04:45 musik volumio[1192]: info: Plugin mpdemulation is not enabled Dec 14 14:04:45 musik volumio[1192]: info: Loading plugin "rest_api"... Dec 14 14:04:45 musik volumio[1192]: info: Loading plugin "websocket"... Dec 14 14:04:45 musik volumio[1192]: info: Starting Socket.io Server version 1.7.4 Dec 14 14:04:45 musik volumio[1192]: info: Loading i18n strings for locale da Dec 14 14:04:46 musik volumio-remote-updater[751]: [2025-12-14 14:04:46] [connect] Successful connection Dec 14 14:04:46 musik volumio[1192]: Updating browse sources language Dec 14 14:04:46 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 14:04:46 musik volumio[1331]: Forking 3 albumart workers Dec 14 14:04:47 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 14:04:47 musik volumio[1192]: info: CoreCommandRouter::initPlayerControls Dec 14 14:04:48 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:04:48 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:04:48 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:04:48 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:04:48 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:04:48 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:04:48 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:04:48 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:04:48 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 14:04:48 musik volumio[1192]: Express server listening on port 3000 Dec 14 14:04:48 musik volumio[1192]: [Metrics] WebUI: 26s 418.46ms Dec 14 14:04:48 musik volumio[1192]: info: CoreStateMachine::resetVolumioState Dec 14 14:04:48 musik volumio[1192]: info: CoreStateMachine::getcurrentVolume Dec 14 14:04:48 musik volumio[1192]: info: CoreCommandRouter::volumioRetrievevolume Dec 14 14:04:48 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 14:04:48 musik sudo[1379]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 14:04:49 musik sudo[1380]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 14:04:49 musik sudo[1379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:04:49 musik sudo[1380]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:04:49 musik sudo[1379]: pam_unix(sudo:session): session closed for user root Dec 14 14:04:49 musik sudo[1380]: pam_unix(sudo:session): session closed for user root Dec 14 14:04:49 musik volumio[1192]: info: Volumio Network Manager: Network status updated: 1 Dec 14 14:04:50 musik volumio[1192]: info: VolumeController:: Volume=86 Mute =false Dec 14 14:04:50 musik volumio[1192]: info: CoreStateMachine::pushState Dec 14 14:04:50 musik volumio[1192]: info: CorePlayQueue::getTrack 0 Dec 14 14:04:50 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 14:04:50 musik volumio[1192]: info: CoreCommandRouter::volumioPushState Dec 14 14:04:50 musik volumio[1192]: info: CoreStateMachine::updateTrackBlock Dec 14 14:04:50 musik volumio[1192]: info: CorePlayQueue::getTrackBlock Dec 14 14:04:50 musik volumio[1192]: info: CoreCommandRouter::volumioRetrievevolume Dec 14 14:04:50 musik volumio-remote-updater[751]: [2025-12-14 14:04:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1765717486 101 Dec 14 14:04:50 musik volumio[1192]: 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: 1 Dec 14 14:04:51 musik volumio[1192]: info: Reloading queue from file Dec 14 14:04:51 musik volumio[1192]: info: CoreStateMachine::setRepeat true single undefined Dec 14 14:04:51 musik volumio[1192]: info: CoreStateMachine::pushState Dec 14 14:04:51 musik volumio[1192]: info: CorePlayQueue::getTrack 0 Dec 14 14:04:51 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 14:04:51 musik volumio[1192]: info: CoreCommandRouter::volumioPushState Dec 14 14:04:51 musik volumio[1192]: info: CoreStateMachine::setRandom null Dec 14 14:04:51 musik volumio[1192]: info: CoreStateMachine::pushState Dec 14 14:04:51 musik volumio[1192]: info: CorePlayQueue::getTrack 0 Dec 14 14:04:51 musik volumio[1192]: info: CoreCommandRouter::volumioPushState Dec 14 14:04:51 musik volumio[1192]: info: Setting Device type: Raspberry PI Dec 14 14:04:52 musik volumio[1192]: info: VolumeController:: Volume=86 Mute =false Dec 14 14:04:52 musik volumio[1192]: info: CoreStateMachine::pushState Dec 14 14:04:52 musik volumio[1192]: info: CorePlayQueue::getTrack 0 Dec 14 14:04:52 musik volumio[1192]: info: CoreCommandRouter::volumioPushState Dec 14 14:04:52 musik volumio[1192]: info: Completed loading Core Plugins Dec 14 14:04:52 musik volumio[1192]: info: Preparing to generate the ALSA configuration file Dec 14 14:04:52 musik sudo[1410]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 14 14:04:52 musik sudo[1410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:04:52 musik volumio[1192]: info: Asound.conf file unchanged, so no further update is needed Dec 14 14:04:52 musik volumio[1192]: info: Output device has changed, restarting MPD Dec 14 14:04:52 musik volumio[1192]: info: ___________ START PLUGINS ___________ Dec 14 14:04:52 musik volumio[1192]: info: ControllerMpd::onStart: Initializing MPD Dec 14 14:04:52 musik sudo[1413]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 14:04:52 musik sudo[1413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:04:52 musik volumio[1192]: info: Creating MPD Configuration file Dec 14 14:04:52 musik sudo[1413]: pam_unix(sudo:session): session closed for user root Dec 14 14:04:53 musik sudo[1415]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 14:04:53 musik sudo[1415]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:04:53 musik volumio[1192]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 14 14:04:53 musik volumio[1192]: info: [1765717493133] CoreMusicLibrary::Adding element Last_100 Dec 14 14:04:53 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 14:04:53 musik sudo[1418]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 14:04:53 musik sudo[1418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:04:53 musik volumio[1192]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 14 14:04:53 musik volumio[1192]: info: [1765717493299] CoreMusicLibrary::Adding element Webradio Dec 14 14:04:53 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 14:04:53 musik sudo[1418]: pam_unix(sudo:session): session closed for user root Dec 14 14:04:53 musik sudo[1421]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 14:04:53 musik sudo[1421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:04:53 musik systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 14:04:53 musik systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 14:04:53 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 14:04:53 musik volumio[1192]: info: Initializing BBC Radios Dec 14 14:04:53 musik systemd[1]: mpd.service: Deactivated successfully. Dec 14 14:04:53 musik systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 14:04:53 musik systemd[1]: mpd.socket: Deactivated successfully. Dec 14 14:04:53 musik systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 14:04:53 musik systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 14:04:53 musik systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 14:04:53 musik systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 14:04:53 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 14:04:53 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 14:04:54 musik sudo[1432]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 14:04:54 musik sudo[1432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 14:04:54 musik sudo[1441]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 14 14:04:54 musik sudo[1432]: pam_unix(sudo:session): session closed for user root Dec 14 14:04:54 musik systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Dec 14 14:04:54 musik systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 14 14:04:54 musik systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 14 14:04:54 musik sudo[1410]: pam_unix(sudo:session): session closed for user root Dec 14 14:04:55 musik volumio[1192]: info: Creating Spotify config file Dec 14 14:04:55 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:04:58 musik volumio[1343]: Starting albumart workers Dec 14 14:04:59 musik volumio[1192]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 14 14:04:59 musik volumio[1192]: info: [1765717499620] CoreMusicLibrary::Adding element YouTube2 Dec 14 14:04:59 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 14:04:59 musik volumio[1192]: Cannot find translation for source YouTube2 Dec 14 14:04:59 musik volumio[1192]: info: Volumio Calling Home Dec 14 14:04:59 musik volumio[1345]: Starting albumart workers Dec 14 14:05:00 musik volumio[1344]: Starting albumart workers Dec 14 14:05:01 musik volumio[1192]: info: Discovery: adding 399e8400-6847-42b2-81a1-49ffe582c839 Dec 14 14:05:01 musik volumio[1192]: info: Discovery: Found device musik Dec 14 14:05:01 musik volumio[1192]: info: CoreCommandRouter::volumioGetState Dec 14 14:05:01 musik volumio[1192]: info: CorePlayQueue::getTrack 0 Dec 14 14:05:01 musik volumio[1192]: info: Discovery: this is already registered, 399e8400-6847-42b2-81a1-49ffe582c839 Dec 14 14:05:01 musik volumio[1192]: info: Discovery: Found device musik Dec 14 14:05:01 musik volumio[1192]: info: CoreCommandRouter::volumioGetState Dec 14 14:05:01 musik volumio[1192]: info: CorePlayQueue::getTrack 0 Dec 14 14:05:01 musik volumio[1192]: info: MPD Permissions set Dec 14 14:05:01 musik volumio[1192]: info: MPD Permissions set Dec 14 14:05:01 musik volumio[1192]: info: Upmpdcli Daemon Started Dec 14 14:05:02 musik volumio[1192]: info: Volumio called home Dec 14 14:05:02 musik volumio[1192]: info: Spotify config file written Dec 14 14:05:02 musik sudo[1477]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 14 14:05:02 musik sudo[1477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:05:02 musik 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. Dec 14 14:05:02 musik 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. Dec 14 14:05:02 musik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 14 14:05:02 musik go-librespot[1479]: go-librespot daemon starting... Dec 14 14:05:02 musik sudo[1477]: pam_unix(sudo:session): session closed for user root Dec 14 14:05:03 musik volumio[1192]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 14 14:05:03 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:05:03 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:05:03 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:05:03 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:05:03 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:05:03 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:05:03 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:05:03 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:05:03 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 14:05:03 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:05:03 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:05:03 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:05:03 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:05:03 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:05:03 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:05:03 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:05:03 musik mpd[1442]: 2025-12-14T14:05:03 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 14 14:05:03 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:05:03 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:05:03 musik systemd[1]: Started mpd.service - Music Player Daemon. Dec 14 14:05:03 musik sudo[1415]: pam_unix(sudo:session): session closed for user root Dec 14 14:05:03 musik sudo[1421]: pam_unix(sudo:session): session closed for user root Dec 14 14:05:04 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 14:05:04 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 14:05:04 musik go-librespot[1480]: time="2025-12-14T14:05:04+01:00" level=info msg="running go-librespot 0.4.0" Dec 14 14:05:04 musik go-librespot[1480]: time="2025-12-14T14:05:04+01:00" level=debug msg="app state loaded" Dec 14 14:05:04 musik go-librespot[1480]: time="2025-12-14T14:05:04+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 14 14:05:04 musik volumio[1192]: info: No need to fix Spotify hosts Dec 14 14:05:04 musik volumio[1192]: info: Completed starting Core Plugins Dec 14 14:05:04 musik volumio[1192]: info: ------------------------------------------- Dec 14 14:05:04 musik volumio[1192]: info: ----- MyVolumio plugins startup ---- Dec 14 14:05:04 musik volumio[1192]: info: ------------------------------------------- Dec 14 14:05:04 musik volumio[1192]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 14 14:05:05 musik volumio[1192]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 14 14:05:05 musik volumio[1192]: SPOTIFY: BQDleqMCn4pho8Zpwd2XVAUIGESG_69FH2ju0sdS7YEnEZAbVIKSWqscnX50n-ANdb9YhAW_QIfBVoNpRiW2Cmu1g_gbrOsUfveDzjNZO5_vRBF3Y9hYRK1E46Yt-chX0_hi8ltaYcmNu4i10Mo7SNtdDnJJOeGdNL85CEJPo7IY7EwE7FaXzscDgzUCHcsPw1pGe82oqgnpoa-qInHTNfm4mO_-tHu-BOHmpEfKijr0tgrrXidR4-R-BXFJl1ibGd00-N_Yliw-hAotDWs-_LhSKrL7kQt- Dec 14 14:05:05 musik volumio[1192]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 14 14:05:05 musik volumio[1192]: info: New Spotify access token = BQDleqMCn4pho8Zpwd2XVAUIGESG_69FH2ju0sdS7YEnEZAbVIKSWqscnX50n-ANdb9YhAW_QIfBVoNpRiW2Cmu1g_gbrOsUfveDzjNZO5_vRBF3Y9hYRK1E46Yt-chX0_hi8ltaYcmNu4i10Mo7SNtdDnJJOeGdNL85CEJPo7IY7EwE7FaXzscDgzUCHcsPw1pGe82oqgnpoa-qInHTNfm4mO_-tHu-BOHmpEfKijr0tgrrXidR4-R-BXFJl1ibGd00-N_Yliw-hAotDWs-_LhSKrL7kQt- Dec 14 14:05:05 musik volumio[1192]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 14 14:05:05 musik dhcpcd[782]: eth0: failed to renew DHCP, rebinding Dec 14 14:05:05 musik dhcpcd[782]: eth0: leased 10.0.0.45 for 60 seconds Dec 14 14:05:05 musik systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 14 14:05:05 musik systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 14 14:05:05 musik systemd[1]: welcome.service: Deactivated successfully. Dec 14 14:05:05 musik systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 14 14:05:05 musik systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 14 14:05:05 musik systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 14 14:05:05 musik welcome[1525]: Resolved ip:[1] 10.0.0.45 Dec 14 14:05:05 musik systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 14 14:05:05 musik systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 14 14:05:06 musik volumio[1192]: error: MPD error: The expression evaluated to a falsy value: Dec 14 14:05:06 musik volumio[1192]: assert.ok(self.idling) Dec 14 14:05:06 musik volumio[1192]: error: The expression evaluated to a falsy value: Dec 14 14:05:06 musik volumio[1192]: assert.ok(self.idling) Dec 14 14:05:06 musik volumio[1192]: info: MPD running with PID1442 Dec 14 14:05:06 musik volumio[1192]: ,establishing connection Dec 14 14:05:06 musik volumio[1192]: error: updateQueue error: null Dec 14 14:05:07 musik go-librespot[1480]: time="2025-12-14T14:05:07+01: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 14 14:05:07 musik go-librespot[1480]: time="2025-12-14T14:05:07+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 14 14:05:07 musik go-librespot[1480]: time="2025-12-14T14:05:07+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 14 14:05:07 musik volumio[1192]: error: updateQueue error: null Dec 14 14:05:07 musik go-librespot[1480]: time="2025-12-14T14:05:07+01:00" level=info msg="zeroconf server listening on port 38633" Dec 14 14:05:07 musik volumio[1192]: info: go-librespot daemon successfully initialized Dec 14 14:05:07 musik volumio[1192]: info: CoreCommandRouter::volumioGetState Dec 14 14:05:07 musik volumio[1192]: info: CorePlayQueue::getTrack 0 Dec 14 14:05:08 musik sudo[1550]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 14:05:08 musik sudo[1550]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:05:08 musik systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 14:05:08 musik mpd_monitor.sh[1555]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 14:05:08 musik sudo[1550]: pam_unix(sudo:session): session closed for user root Dec 14 14:05:08 musik sudo[1553]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 14:05:08 musik volumio[1192]: info: Successfully started MPD Monitor Dec 14 14:05:08 musik sudo[1553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:05:08 musik volumio[1192]: SPOTIFY: User informations: {"country":"DK","display_name":"Helena Frølich","email":"helenafs94@hotmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/1120585003"},"followers":{"href":null,"total":2},"href":"https://api.spotify.com/v1/users/1120585003","id":"1120585003","images":[{"height":300,"url":"https://scontent-tpe1-1.xx.fbcdn.net/v/t39.30808-1/271393207_10223960543834770_6533244472658331808_n.jpg?stp=dst-jpg_s320x320_tt6&_nc_cat=100&ccb=1-7&_nc_sid=79bf43&_nc_ohc=pFXYb7wqVjAQ7kNvwHXsMKE&_nc_oc=Adk_1wVvAemHCbPYeYTbdWPTjYsGC709DPEeL1fHKJr4iIvvcOYJnnlLc_DxITbj7ZMXRFdds9rebPAlAOpIH1vR&_nc_zt=24&_nc_ht=scontent-tpe1-1.xx&edm=AP4hL3IEAAAA&_nc_gid=M8smhuudMN_LDpAnzZtNig&_nc_tpa=Q5bMBQHUlAPEK44-3Fg5kXg2DnpYKhLYBeP_vL9cByHeKDsvS5L3MmXZ_3ZGEeHGwpX6sjM2dGiA&oh=00_AfmnxdV99ZXM3YEeP9x-ghxA7YE19SvmYv_CaO9Rw-7plQ&oe=69444A6E","width":300},{"height":64,"url":"https://scontent-tpe1-1.xx.fbcdn.net/v/t39.30808-1/271393207_10223960543834770_6533244472658331808_n.jpg?stp=cp0_dst-jpg_s50x50_tt6&_nc_cat=100&ccb=1-7&_nc_sid=28885b&_nc_ohc=pFXYb7wqVjAQ7kNvwHXsMKE&_nc_oc=Adk_1wVvAemHCbPYeYTbdWPTjYsGC709DPEeL1fHKJr4iIvvcOYJnnlLc_DxITbj7ZMXRFdds9rebPAlAOpIH1vR&_nc_zt=24&_nc_ht=scontent-tpe1-1.xx&edm=AP4hL3IEAAAA&_nc_gid=M8smhuudMN_LDpAnzZtNig&_nc_tpa=Q5bMBQEAlA4pEMyVDFrX-wXbkZebEv9vNgyZ77xRg9M_u0fegkVxQnIIKIeoVk_h9BX-CswV1Kev&oh=00_Afm5ZJjvhasgeadb94XbpIsW6o-pkxMd4Zhqzpjc3WoeHA&oe=69444A6E","width":64}],"product":"free","type":"user","uri":"spotify:user:1120585003"} Dec 14 14:05:08 musik volumio[1192]: info: Spotify Successfully logged in Dec 14 14:05:08 musik volumio[1192]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 14 14:05:08 musik volumio[1192]: info: [1765717508672] CoreMusicLibrary::Adding element Spotify Dec 14 14:05:08 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 14:05:08 musik volumio[1192]: Cannot find translation for source YouTube2 Dec 14 14:05:08 musik volumio[1192]: Cannot find translation for source Spotify Dec 14 14:05:08 musik systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 14 14:05:08 musik systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 14 14:05:08 musik systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 14 14:05:08 musik systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 14:05:08 musik mpd_monitor.sh[1561]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 14:05:08 musik sudo[1553]: pam_unix(sudo:session): session closed for user root Dec 14 14:05:09 musik volumio[1192]: info: Successfully started MPD Monitor Dec 14 14:05:10 musik go-librespot[1480]: time="2025-12-14T14:05:10+01:00" level=debug msg="obtained new client token: AAAWotihAWrEYQozUj9z8iRN5l4tq6XWmtjAsq12Ae+Y/q46qbKS5YLGZVuv3v6kDrdTnjSfi7Lp+QlTrFa/LZuv/OwanrbMTLqPmaF0ZiPGorcRZ2wyM4iBaPLk/Lty49lzMfQf48Gv8RxrgaitEm0XuGI78hCJ6nMngMW+JbtKRw+EYdWOQ/+lwnFr0BrQ8o+TKOuO9uCxfPJ6dZhzefz3zNp3VkJOd1vlFUarNt7dnq0t8liDtjY=" Dec 14 14:05:10 musik volumio[1192]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 14 14:05:10 musik volumio[1192]: info: Initializing connection to go-librespot Websocket Dec 14 14:05:10 musik go-librespot[1480]: time="2025-12-14T14:05:10+01:00" level=debug msg="new websocket client" Dec 14 14:05:11 musik volumio[1192]: info: Connection to go-librespot Websocket established Dec 14 14:05:13 musik systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 14 14:05:13 musik systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 14 14:05:13 musik systemd[1]: welcome.service: Deactivated successfully. Dec 14 14:05:13 musik systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 14 14:05:13 musik systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 14 14:05:13 musik systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 14 14:05:13 musik welcome[1595]: Resolved ip:[1] 10.0.0.45 Dec 14 14:05:13 musik systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 14 14:05:13 musik systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 14 14:05:13 musik go-librespot[1480]: time="2025-12-14T14:05:13+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 14 14:05:13 musik go-librespot[1480]: time="2025-12-14T14:05:13+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Dec 14 14:05:13 musik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 14 14:05:13 musik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 14 14:05:13 musik volumio[1192]: info: Connection to go-librespot Websocket closed Dec 14 14:05:14 musik volumio[1192]: info: Getting Spotify volume Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 14 14:05:14 musik volumio[1192]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 14 14:05:14 musik volumio[1192]: info: Adding plugin bluetooth to MyMusic Plugins Dec 14 14:05:14 musik volumio[1192]: info: Adding plugin multiroom to MyMusic Plugins Dec 14 14:05:14 musik volumio[1192]: info: Adding plugin metavolumio to MyMusic Plugins Dec 14 14:05:14 musik volumio[1192]: info: Adding plugin cd_controller to MyMusic Plugins Dec 14 14:05:14 musik volumio[1192]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 14 14:05:14 musik volumio[1192]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 14 14:05:14 musik volumio[1192]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 14 14:05:15 musik volumio[1192]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 14 14:05:16 musik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 14 14:05:16 musik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 14 14:05:16 musik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 14 14:05:16 musik go-librespot[1602]: go-librespot daemon starting... Dec 14 14:05:16 musik go-librespot[1603]: time="2025-12-14T14:05:16+01:00" level=info msg="running go-librespot 0.4.0" Dec 14 14:05:16 musik go-librespot[1603]: time="2025-12-14T14:05:16+01:00" level=debug msg="app state loaded" Dec 14 14:05:16 musik go-librespot[1603]: time="2025-12-14T14:05:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 14 14:05:19 musik go-librespot[1603]: time="2025-12-14T14:05:19+01: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 14 14:05:19 musik go-librespot[1603]: time="2025-12-14T14:05:19+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 14 14:05:19 musik go-librespot[1603]: time="2025-12-14T14:05:19+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 14 14:05:19 musik go-librespot[1603]: time="2025-12-14T14:05:19+01:00" level=info msg="zeroconf server listening on port 42939" Dec 14 14:05:22 musik go-librespot[1603]: time="2025-12-14T14:05:22+01:00" level=debug msg="obtained new client token: AABJzLkT99TQAfV8HlxeRWu62lL0p+bss7TTKeYR04EMnpEOeePZyr/kvZGYCl1jNlU0vPPgyj3IR10RLOyiPIP4x/D/5uIn3/16fg72+GWiW4lDRkSL242S71XLf/UTMhGHeUaYTSLVhUSNtlRprdx+aXKD7+auCoP7P6mBH3pP9mqdLs05X8yQUVjLhnfR8cbNDo3JoJ0AURyL6BNSnWBZU7ttya6hIrHDc1mRDGusDO/hYHkpuhkGBA==" Dec 14 14:05:25 musik go-librespot[1603]: time="2025-12-14T14:05:25+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 14 14:05:25 musik go-librespot[1603]: time="2025-12-14T14:05:25+01:00" level=debug msg="completed keyexchange" Dec 14 14:05:25 musik go-librespot[1603]: time="2025-12-14T14:05:25+01:00" level=debug msg="completed challenge" Dec 14 14:05:25 musik go-librespot[1603]: time="2025-12-14T14:05:25+01:00" level=info msg="authenticated AP" username="11******03" Dec 14 14:05:28 musik volumio[1192]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 14 14:05:28 musik volumio[1192]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 14 14:05:28 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 14:05:28 musik volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 14:05:28 musik volumio[1192]: info: Starting MyVolumio Remote Streaming Endpoints Dec 14 14:05:28 musik volumio[1192]: info: MyVolumio login type: Token Dec 14 14:05:29 musik volumio[1192]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 14 14:05:29 musik volumio[1192]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 14 14:05:29 musik go-librespot[1603]: time="2025-12-14T14:05:29+01:00" level=info msg="authenticated Login5" username="11******03" Dec 14 14:05:29 musik go-librespot[1603]: time="2025-12-14T14:05:29+01:00" level=debug msg="initializing zeroconf session" username="11******03" Dec 14 14:05:32 musik go-librespot[1603]: time="2025-12-14T14:05:32+01:00" level=debug msg="dealer connection opened" Dec 14 14:05:32 musik go-librespot[1603]: time="2025-12-14T14:05:32+01:00" level=trace msg="starting accesspoint recv loop" Dec 14 14:05:32 musik go-librespot[1603]: time="2025-12-14T14:05:32+01:00" level=trace msg="starting dealer recv loop" Dec 14 14:05:32 musik go-librespot[1603]: time="2025-12-14T14:05:32+01:00" level=trace msg="received accesspoint ping" Dec 14 14:05:32 musik go-librespot[1603]: time="2025-12-14T14:05:32+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 14 14:05:32 musik go-librespot[1603]: time="2025-12-14T14:05:32+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 14 14:05:32 musik go-librespot[1603]: time="2025-12-14T14:05:32+01:00" level=debug msg="received connection id: ZGRiOGU3MzktMDdi...M0M1M0U5NDYwNQ==" Dec 14 14:05:35 musik go-librespot[1603]: time="2025-12-14T14:05:35+01:00" level=debug msg="put connect state because NEW_DEVICE" Dec 14 14:05:35 musik go-librespot[1603]: time="2025-12-14T14:05:35+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 14 14:05:35 musik go-librespot[1603]: time="2025-12-14T14:05:35+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 14 14:05:35 musik go-librespot[1603]: time="2025-12-14T14:05:35+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 14 14:05:35 musik go-librespot[1603]: time="2025-12-14T14:05:35+01:00" level=trace msg="received accesspoint pong ack" Dec 14 14:05:41 musik volumio[1192]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 14 14:05:41 musik volumio[1192]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 14 14:05:41 musik volumio[1192]: info: Streaming services startup Dec 14 14:05:41 musik volumio[1192]: info: Starting Streaming Daemon Dec 14 14:05:42 musik volumio[1192]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 14 14:05:42 musik sudo[1647]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 14 14:05:42 musik sudo[1647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 14:05:42 musik sudo[1647]: pam_unix(sudo:session): session closed for user root Dec 14 14:05:42 musik volumio[1192]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 14:05:42 musik volumio[1192]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 14:05:42 musik volumio[1192]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 14 14:05:42 musik volumio[1192]: errno: -111, Dec 14 14:05:42 musik volumio[1192]: code: 'ECONNREFUSED', Dec 14 14:05:42 musik volumio[1192]: syscall: 'connect', Dec 14 14:05:42 musik volumio[1192]: address: '127.0.0.1', Dec 14 14:05:42 musik volumio[1192]: port: 9879, Dec 14 14:05:42 musik volumio[1192]: response: undefined Dec 14 14:05:42 musik volumio[1192]: } Dec 14 14:05:42 musik volumio[1192]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 14:05:43 musik systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 14 14:05:43 musik systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 14 14:05:43 musik systemd[1]: welcome.service: Deactivated successfully. Dec 14 14:05:43 musik systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 14 14:05:43 musik systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 14 14:05:43 musik systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 14 14:05:43 musik welcome[1672]: Resolved ip:[1] 10.0.0.45 Dec 14 14:05:43 musik systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 14 14:05:43 musik systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 14 14:05:53 musik sudo[1727]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-14 14:04' Dec 14 14:05:53 musik sudo[1727]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"