-- Logs begin at Sat 2025-12-06 14:28:16 GMT, end at Sat 2025-12-06 14:38:06 GMT. -- Dec 06 14:37:03 mercedesvolumio volumio[1035]: info: CALLMETHOD: system_controller system saveGeneralSettings [object Object] Dec 06 14:37:03 mercedesvolumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Dec 06 14:37:03 mercedesvolumio volumio[1035]: info: System name has changed, restarting Shairport Sync Dec 06 14:37:03 mercedesvolumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 14:37:03 mercedesvolumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 14:37:03 mercedesvolumio volumio[1035]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 06 14:37:03 mercedesvolumio volumio[1035]: [SpotifyConnect] Creating VLS config file Dec 06 14:37:03 mercedesvolumio volumio[1035]: error: Failed to read template file: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/spop/volspotify.tmpl' Dec 06 14:37:03 mercedesvolumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 14:37:03 mercedesvolumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 14:37:03 mercedesvolumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 14:37:03 mercedesvolumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 14:37:03 mercedesvolumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 14:37:03 mercedesvolumio volumio[1035]: info: Updating MyVolumio device info Dec 06 14:37:03 mercedesvolumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 14:37:03 mercedesvolumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 14:37:03 mercedesvolumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 14:37:03 mercedesvolumio volumio[1035]: info: Discovery: Restarting Advertising due to device name change Dec 06 14:37:03 mercedesvolumio sudo[2457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 06 14:37:03 mercedesvolumio sudo[2457]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 14:37:03 mercedesvolumio sudo[2457]: pam_unix(sudo:session): session closed for user root Dec 06 14:37:03 mercedesvolumio volumio[1035]: info: Starting Shairport Sync Dec 06 14:37:03 mercedesvolumio systemd[1]: upmpdcli.service: Succeeded. Dec 06 14:37:03 mercedesvolumio sudo[2460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Dec 06 14:37:03 mercedesvolumio sudo[2460]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 14:37:03 mercedesvolumio sudo[2460]: pam_unix(sudo:session): session closed for user root Dec 06 14:37:03 mercedesvolumio volumio[1035]: info: Permissions for /etc/hosts set Dec 06 14:37:03 mercedesvolumio sudo[2463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 06 14:37:03 mercedesvolumio sudo[2463]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 14:37:03 mercedesvolumio volumio[1035]: info: Hostname now is volumiopistreamer Dec 06 14:37:03 mercedesvolumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 06 14:37:03 mercedesvolumio systemd[1]: shairport-sync.service: Succeeded. Dec 06 14:37:03 mercedesvolumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 06 14:37:03 mercedesvolumio sudo[2466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hostname volumiopistreamer Dec 06 14:37:03 mercedesvolumio sudo[2466]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 14:37:04 volumiopistreamer volumio[1035]: info: New hostname set Dec 06 14:37:04 volumiopistreamer volumio[1035]: info: Permissions for /etc/avahi/services/volumio.service Dec 06 14:37:04 volumiopistreamer volumio[1035]: info: Avahi name changed to volumiopistreamer Dec 06 14:37:03 volumiopistreamer sudo[2466]: pam_unix(sudo:session): session closed for user root Dec 06 14:37:03 volumiopistreamer sudo[2468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Dec 06 14:37:04 volumiopistreamer systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 06 14:37:03 volumiopistreamer sudo[2468]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 14:37:03 volumiopistreamer sudo[2468]: pam_unix(sudo:session): session closed for user root Dec 06 14:37:03 volumiopistreamer avahi-daemon[587]: Files changed, reloading. Dec 06 14:37:03 volumiopistreamer avahi-daemon[587]: Service group file /services/volumio.service changed, reloading. Dec 06 14:37:04 volumiopistreamer sudo[2463]: pam_unix(sudo:session): session closed for user root Dec 06 14:37:04 volumiopistreamer volumio[1035]: info: Shairport-Sync Started Dec 06 14:37:04 volumiopistreamer volumio[1035]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 06 14:37:04 volumiopistreamer volumio[1035]: [SpotifyConnect] Creating VLS config file Dec 06 14:37:04 volumiopistreamer volumio[1035]: error: Failed to read template file: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/spop/volspotify.tmpl' Dec 06 14:37:04 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 14:37:04 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 14:37:04 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 14:37:04 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 14:37:04 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 14:37:04 volumiopistreamer avahi-daemon[587]: Service "VolumioPiStreamer" (/services/volumio.service) successfully established. Dec 06 14:37:04 volumiopistreamer volumio[1035]: info: Discovery: A device disappeared from network Dec 06 14:37:04 volumiopistreamer volumio[1035]: info: Discovery: Device mercedesvolumio disappeared from network Dec 06 14:37:04 volumiopistreamer volumio[1035]: info: Discovery: A device disappeared from network Dec 06 14:37:06 volumiopistreamer sudo[2481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Dec 06 14:37:06 volumiopistreamer sudo[2481]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 14:37:06 volumiopistreamer sudo[2481]: pam_unix(sudo:session): session closed for user root Dec 06 14:37:06 volumiopistreamer volumio[1035]: info: Successfully fixed Spotify hosts Dec 06 14:37:08 volumiopistreamer volumio[1035]: info: Discovery: Started advertising with name: VolumioPiStreamer Dec 06 14:37:09 volumiopistreamer volumio[1035]: info: CALLMETHOD: system_controller system saveGeneralSettings [object Object] Dec 06 14:37:09 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Dec 06 14:37:09 volumiopistreamer volumio[1035]: info: Discovery: adding bd167acf-8d54-4aab-bb48-625b6bae041e Dec 06 14:37:09 volumiopistreamer volumio[1035]: info: Discovery: Found device VolumioPiStreamer Dec 06 14:37:09 volumiopistreamer volumio[1035]: info: CoreCommandRouter::volumioGetState Dec 06 14:37:09 volumiopistreamer volumio[1035]: info: Discovery: this is already registered, bd167acf-8d54-4aab-bb48-625b6bae041e Dec 06 14:37:09 volumiopistreamer volumio[1035]: info: Discovery: Found device VolumioPiStreamer Dec 06 14:37:09 volumiopistreamer volumio[1035]: info: CoreCommandRouter::volumioGetState Dec 06 14:37:12 volumiopistreamer sudo[2501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 06 14:37:12 volumiopistreamer sudo[2501]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 14:37:12 volumiopistreamer sudo[2501]: pam_unix(sudo:session): session closed for user root Dec 06 14:37:12 volumiopistreamer sudo[2503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 06 14:37:12 volumiopistreamer sudo[2503]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 14:37:12 volumiopistreamer sudo[2503]: pam_unix(sudo:session): session closed for user root Dec 06 14:37:12 volumiopistreamer volumio[1035]: verbose: New Socket.io Connection to 10.0.0.169 from 10.0.0.3 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:145.0) Gecko/20100101 Firefox/145.0 Engine version: 3 Transport: polling Total Clients: 5 Dec 06 14:37:12 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 06 14:37:12 volumiopistreamer volumio[1035]: info: CoreCommandRouter::volumioGetVisibleSources Dec 06 14:37:12 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 14:37:12 volumiopistreamer volumio[1035]: info: CoreCommandRouter::volumioGetState Dec 06 14:37:12 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 06 14:37:12 volumiopistreamer volumio[1035]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 14:37:12 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 06 14:37:12 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 06 14:37:12 volumiopistreamer volumio[1035]: info: Received Get System Info Dec 06 14:37:12 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 14:37:12 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 14:37:12 volumiopistreamer volumio[1035]: info: Discovery: Getting this device information Dec 06 14:37:12 volumiopistreamer volumio[1035]: info: CoreCommandRouter::volumioGetState Dec 06 14:37:12 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 14:37:12 volumiopistreamer volumio[1035]: info: CoreCommandRouter::volumioGetState Dec 06 14:37:12 volumiopistreamer volumio[1035]: info: Listing playlists Dec 06 14:37:12 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 06 14:37:12 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 06 14:37:13 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 06 14:37:13 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 06 14:37:13 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 06 14:37:13 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Dec 06 14:37:13 volumiopistreamer volumio[1035]: info: Received Get System Version Dec 06 14:37:13 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 14:37:13 volumiopistreamer sudo[2521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/samba/smb.conf Dec 06 14:37:13 volumiopistreamer sudo[2521]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 14:37:13 volumiopistreamer sudo[2521]: pam_unix(sudo:session): session closed for user root Dec 06 14:37:13 volumiopistreamer volumio[1035]: info: smb.conf Permissions set Dec 06 14:37:13 volumiopistreamer sudo[2523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/INTERNAL Dec 06 14:37:13 volumiopistreamer sudo[2523]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 14:37:13 volumiopistreamer sudo[2523]: pam_unix(sudo:session): session closed for user root Dec 06 14:37:13 volumiopistreamer sudo[2527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart nmbd.service Dec 06 14:37:13 volumiopistreamer sudo[2527]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 14:37:13 volumiopistreamer sudo[2530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 06 14:37:13 volumiopistreamer volumio[1035]: info: Internal perms successfully set Dec 06 14:37:13 volumiopistreamer sudo[2530]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 14:37:13 volumiopistreamer nmbd[726]: [2025/12/06 14:37:13.936469, 0] ../source3/nmbd/nmbd.c:58(terminate) Dec 06 14:37:13 volumiopistreamer systemd[1]: Stopping Samba NMB Daemon... Dec 06 14:37:13 volumiopistreamer nmbd[726]: Got SIGTERM: going down... Dec 06 14:37:13 volumiopistreamer sudo[2530]: pam_unix(sudo:session): session closed for user root Dec 06 14:37:13 volumiopistreamer sudo[2532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 06 14:37:13 volumiopistreamer sudo[2532]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 14:37:13 volumiopistreamer sudo[2532]: pam_unix(sudo:session): session closed for user root Dec 06 14:37:13 volumiopistreamer sudo[2536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 06 14:37:13 volumiopistreamer sudo[2536]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 14:37:14 volumiopistreamer systemd[1]: Started UPnP Renderer front-end to MPD. Dec 06 14:37:14 volumiopistreamer sudo[2536]: pam_unix(sudo:session): session closed for user root Dec 06 14:37:14 volumiopistreamer volumio[1035]: info: Upmpdcli Daemon Started Dec 06 14:37:14 volumiopistreamer volumio[2538]: writing RSA key Dec 06 14:37:14 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 06 14:37:14 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 06 14:37:14 volumiopistreamer volumio[1035]: info: Received Get System Info Dec 06 14:37:14 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 14:37:14 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 14:37:14 volumiopistreamer volumio[1035]: info: Discovery: Getting this device information Dec 06 14:37:14 volumiopistreamer volumio[1035]: info: CoreCommandRouter::volumioGetState Dec 06 14:37:14 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 14:37:15 volumiopistreamer systemd[1]: nmbd.service: Succeeded. Dec 06 14:37:15 volumiopistreamer systemd[1]: Stopped Samba NMB Daemon. Dec 06 14:37:15 volumiopistreamer systemd[1]: Starting Samba NMB Daemon... Dec 06 14:37:15 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 06 14:37:15 volumiopistreamer volumio[1035]: info: Received Get System Info Dec 06 14:37:15 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 14:37:15 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 14:37:15 volumiopistreamer volumio[1035]: info: Discovery: Getting this device information Dec 06 14:37:15 volumiopistreamer volumio[1035]: info: CoreCommandRouter::volumioGetState Dec 06 14:37:15 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 14:37:15 volumiopistreamer nmbd[2578]: [2025/12/06 14:37:15.510850, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 06 14:37:15 volumiopistreamer nmbd[2578]: started asyncdns process 2579 Dec 06 14:37:15 volumiopistreamer nmbd[2578]: [2025/12/06 14:37:15.516651, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 06 14:37:15 volumiopistreamer nmbd[2578]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Dec 06 14:37:15 volumiopistreamer systemd[1]: Started Samba NMB Daemon. Dec 06 14:37:15 volumiopistreamer sudo[2527]: pam_unix(sudo:session): session closed for user root Dec 06 14:37:15 volumiopistreamer sudo[2580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart smbd.service Dec 06 14:37:15 volumiopistreamer sudo[2580]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 14:37:15 volumiopistreamer systemd[1]: Stopping Samba SMB Daemon... Dec 06 14:37:15 volumiopistreamer systemd[1]: smbd.service: Main process exited, code=killed, status=15/TERM Dec 06 14:37:15 volumiopistreamer systemd[1]: smbd.service: Succeeded. Dec 06 14:37:15 volumiopistreamer systemd[1]: Stopped Samba SMB Daemon. Dec 06 14:37:15 volumiopistreamer systemd[1]: Starting Samba SMB Daemon... Dec 06 14:37:16 volumiopistreamer winbindd[1053]: [2025/12/06 14:37:16.713654, 0] ../source3/winbindd/winbindd_samr.c:672(sam_sid_to_name) Dec 06 14:37:16 volumiopistreamer winbindd[1053]: sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-625414232-3945875562-760679078-501 Dec 06 14:37:16 volumiopistreamer winbindd[1053]: [2025/12/06 14:37:16.714372, 0] ../source3/winbindd/winbindd_samr.c:672(sam_sid_to_name) Dec 06 14:37:16 volumiopistreamer winbindd[1053]: sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-625414232-3945875562-760679078-514 Dec 06 14:37:16 volumiopistreamer smbd[2584]: [2025/12/06 14:37:16.732341, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 06 14:37:16 volumiopistreamer smbd[2584]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 06 14:37:16 volumiopistreamer systemd[1]: Started Samba SMB Daemon. Dec 06 14:37:16 volumiopistreamer sudo[2580]: pam_unix(sudo:session): session closed for user root Dec 06 14:37:16 volumiopistreamer volumio[1035]: info: SAMBA Restarted Dec 06 14:37:22 volumiopistreamer volumio-remote-updater[590]: No test mode Dec 06 14:37:22 volumiopistreamer volumio-remote-updater[590]: No alpha test mode Dec 06 14:37:22 volumiopistreamer volumio[1035]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 06 14:37:22 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 06 14:37:22 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 06 14:37:24 volumiopistreamer volumio[1035]: info: CoreCommandRouter::Close All Modals sent Dec 06 14:37:30 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 06 14:37:30 volumiopistreamer volumio[1035]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 06 14:37:30 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 06 14:37:30 volumiopistreamer volumio[1035]: info: Received Get System Version Dec 06 14:37:30 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 14:37:30 volumiopistreamer volumio[1035]: info: Received Get System Info Dec 06 14:37:30 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 14:37:30 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 14:37:30 volumiopistreamer volumio[1035]: info: Discovery: Getting this device information Dec 06 14:37:30 volumiopistreamer volumio[1035]: info: CoreCommandRouter::volumioGetState Dec 06 14:37:30 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 14:37:40 volumiopistreamer volumio[1035]: info: CoreCommandRouter::volumioGetState Dec 06 14:37:44 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 06 14:37:44 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 06 14:37:49 volumiopistreamer volumio[1035]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 14:37:49 volumiopistreamer volumio[1035]: error: Cannot populate Spotify configuration: TypeError: Cannot read property 'bars' of undefined Dec 06 14:37:54 volumiopistreamer volumio[1035]: info: CoreCommandRouter::volumioGetState Dec 06 14:37:57 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 06 14:37:57 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 06 14:38:01 volumiopistreamer volumio[1035]: info: Enabling plugin spop Dec 06 14:38:01 volumiopistreamer volumio[1035]: info: Loading plugin "spop"... Dec 06 14:38:01 volumiopistreamer volumio[1035]: info: PLUGIN START: spop Dec 06 14:38:01 volumiopistreamer volumio[1035]: info: Checking for old spotify connect plugin installed Dec 06 14:38:01 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 06 14:38:01 volumiopistreamer volumio[1035]: [SpotifyConnect] Creating VLS config file Dec 06 14:38:01 volumiopistreamer volumio[1035]: error: Failed to read template file: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/spop/volspotify.tmpl' Dec 06 14:38:01 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 14:38:01 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 14:38:01 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 14:38:01 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 14:38:01 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 14:38:01 volumiopistreamer volumio[1035]: [SpotifyConnect] Starting metadata listener Dec 06 14:38:01 volumiopistreamer volumio[1035]: (node:1035) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'replace' of undefined Dec 06 14:38:01 volumiopistreamer volumio[1035]: at ControllerSpotify.createConfigFile (/data/plugins/music_service/spop/index.js:2607:27) Dec 06 14:38:01 volumiopistreamer volumio[1035]: at ControllerSpotify.init (/data/plugins/music_service/spop/index.js:2470:14) Dec 06 14:38:01 volumiopistreamer volumio[1035]: at Promise._successFn (/data/plugins/music_service/spop/index.js:134:14) Dec 06 14:38:01 volumiopistreamer volumio[1035]: at nextTickCallback (/data/plugins/music_service/spop/node_modules/kew/kew.js:47:28) Dec 06 14:38:01 volumiopistreamer volumio[1035]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Dec 06 14:38:01 volumiopistreamer volumio[1035]: (Use `node --trace-warnings ...` to show where the warning was created) Dec 06 14:38:01 volumiopistreamer volumio[1035]: (node:1035) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 3) Dec 06 14:38:01 volumiopistreamer volumio[1035]: (node:1035) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Dec 06 14:38:01 volumiopistreamer sudo[2721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Dec 06 14:38:01 volumiopistreamer sudo[2721]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 14:38:01 volumiopistreamer volumio[1035]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 06 14:38:01 volumiopistreamer systemd[1]: Started Volspotconnect2 Daemon. Dec 06 14:38:01 volumiopistreamer sudo[2721]: pam_unix(sudo:session): session closed for user root Dec 06 14:38:01 volumiopistreamer volumio[2723]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Dec 06 14:38:01 volumiopistreamer volumio[1035]: [SpotifyConnect] Vollibrespot Daemon service started! Dec 06 14:38:01 volumiopistreamer volumio[1035]: [Metrics] SpotifyConnect: 0s 120.28ms Dec 06 14:38:01 volumiopistreamer systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Dec 06 14:38:01 volumiopistreamer systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Dec 06 14:38:01 volumiopistreamer volumio[1035]: info: Done. Dec 06 14:38:02 volumiopistreamer volumio[1035]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Dec 06 14:38:02 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 14:38:02 volumiopistreamer volumio[1035]: [SpotifyConnect] Closing metadata listener Dec 06 14:38:02 volumiopistreamer volumio[1035]: info: Disabling plugin spop Dec 06 14:38:02 volumiopistreamer volumio[1035]: info: Done. Dec 06 14:38:02 volumiopistreamer sudo[2726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Dec 06 14:38:02 volumiopistreamer sudo[2726]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 14:38:02 volumiopistreamer systemd[1]: Stopped Volspotconnect2 Daemon. Dec 06 14:38:02 volumiopistreamer sudo[2726]: pam_unix(sudo:session): session closed for user root Dec 06 14:38:02 volumiopistreamer volumio[1035]: [SpotifyConnect] Vollibrespot Daemon service stoped! Dec 06 14:38:05 volumiopistreamer volumio[1035]: info: Enabling plugin spop Dec 06 14:38:05 volumiopistreamer volumio[1035]: info: Loading plugin "spop"... Dec 06 14:38:05 volumiopistreamer volumio[1035]: info: PLUGIN START: spop Dec 06 14:38:05 volumiopistreamer volumio[1035]: info: Checking for old spotify connect plugin installed Dec 06 14:38:05 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 06 14:38:05 volumiopistreamer volumio[1035]: [SpotifyConnect] Creating VLS config file Dec 06 14:38:05 volumiopistreamer volumio[1035]: error: Failed to read template file: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/spop/volspotify.tmpl' Dec 06 14:38:05 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 14:38:05 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 14:38:05 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 14:38:05 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 14:38:05 volumiopistreamer volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 14:38:05 volumiopistreamer volumio[1035]: [SpotifyConnect] Starting metadata listener Dec 06 14:38:05 volumiopistreamer volumio[1035]: (node:1035) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'replace' of undefined Dec 06 14:38:05 volumiopistreamer volumio[1035]: at ControllerSpotify.createConfigFile (/data/plugins/music_service/spop/index.js:2607:27) Dec 06 14:38:05 volumiopistreamer volumio[1035]: at ControllerSpotify.init (/data/plugins/music_service/spop/index.js:2470:14) Dec 06 14:38:05 volumiopistreamer volumio[1035]: at Promise._successFn (/data/plugins/music_service/spop/index.js:134:14) Dec 06 14:38:05 volumiopistreamer volumio[1035]: at nextTickCallback (/data/plugins/music_service/spop/node_modules/kew/kew.js:47:28) Dec 06 14:38:05 volumiopistreamer volumio[1035]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Dec 06 14:38:05 volumiopistreamer volumio[1035]: (node:1035) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 4) Dec 06 14:38:05 volumiopistreamer volumio[1035]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 06 14:38:05 volumiopistreamer sudo[2729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Dec 06 14:38:05 volumiopistreamer sudo[2729]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 14:38:05 volumiopistreamer systemd[1]: Started Volspotconnect2 Daemon. Dec 06 14:38:05 volumiopistreamer sudo[2729]: pam_unix(sudo:session): session closed for user root Dec 06 14:38:05 volumiopistreamer volumio[2731]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Dec 06 14:38:05 volumiopistreamer volumio[1035]: [SpotifyConnect] Vollibrespot Daemon service started! Dec 06 14:38:05 volumiopistreamer volumio[1035]: [Metrics] SpotifyConnect: 0s 158.62ms Dec 06 14:38:05 volumiopistreamer volumio[1035]: info: Done. Dec 06 14:38:05 volumiopistreamer systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Dec 06 14:38:05 volumiopistreamer systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Dec 06 14:38:05 volumiopistreamer volumio[1035]: info: Checking Spotify Web API Dec 06 14:38:05 volumiopistreamer volumio[1035]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 14:38:05 volumiopistreamer volumio[1035]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Dec 06 14:38:05 volumiopistreamer volumio[1035]: at healthCheck (dgram.js:897:11) Dec 06 14:38:05 volumiopistreamer volumio[1035]: at Socket.send (dgram.js:622:3) Dec 06 14:38:05 volumiopistreamer volumio[1035]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:121:21) Dec 06 14:38:05 volumiopistreamer volumio[1035]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2383:23) Dec 06 14:38:05 volumiopistreamer volumio[1035]: at Timeout._onTimeout (/data/plugins/music_service/spop/index.js:2475:18) Dec 06 14:38:05 volumiopistreamer volumio[1035]: at listOnTimeout (internal/timers.js:554:17) Dec 06 14:38:05 volumiopistreamer volumio[1035]: at processTimers (internal/timers.js:497:7) { Dec 06 14:38:05 volumiopistreamer volumio[1035]: code: 'ERR_SOCKET_DGRAM_NOT_RUNNING' Dec 06 14:38:05 volumiopistreamer volumio[1035]: } Dec 06 14:38:05 volumiopistreamer volumio[1035]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 14:38:06 volumiopistreamer sudo[2742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-06 14:37 Dec 06 14:38:06 volumiopistreamer sudo[2742]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"