-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Tue 2025-07-01 13:58:35 BST. -- Jul 01 13:57:00 volumio-summer-house wpa_supplicant[883]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jul 01 13:57:00 volumio-summer-house wpa_supplicant[883]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="VM0996341" auth_failures=1 duration=10 reason=CONN_FAILED Jul 01 13:57:00 volumio-summer-house systemd[1]: systemd-fsckd.service: Succeeded. Jul 01 13:57:01 volumio-summer-house volumio[1048]: info: ------------------------------------------- Jul 01 13:57:01 volumio-summer-house volumio[1048]: info: ----- Volumio3 ---- Jul 01 13:57:01 volumio-summer-house volumio[1048]: info: ------------------------------------------- Jul 01 13:57:01 volumio-summer-house volumio[1048]: info: ----- System startup ---- Jul 01 13:57:01 volumio-summer-house volumio[1048]: info: ------------------------------------------- Jul 01 13:57:02 volumio-summer-house volumio[1048]: info: MYVOLUMIO Environment detected Jul 01 13:57:03 volumio-summer-house volumio[1048]: info: Plugin folders cleanup Jul 01 13:57:03 volumio-summer-house volumio[1048]: info: Scanning into folder /volumio/app/plugins/ Jul 01 13:57:03 volumio-summer-house volumio[1048]: info: Scanning category audio_interface Jul 01 13:57:03 volumio-summer-house volumio[1048]: info: Scanning category miscellanea Jul 01 13:57:03 volumio-summer-house volumio[1048]: info: Scanning category music_service Jul 01 13:57:03 volumio-summer-house volumio[1048]: info: Scanning category plugins.json Jul 01 13:57:03 volumio-summer-house volumio[1048]: info: Scanning category system_controller Jul 01 13:57:03 volumio-summer-house volumio[1048]: info: Scanning category user_interface Jul 01 13:57:03 volumio-summer-house volumio[1048]: info: Scanning into folder /data/plugins/ Jul 01 13:57:03 volumio-summer-house volumio[1048]: info: Scanning category music_service Jul 01 13:57:03 volumio-summer-house volumio[1048]: info: Scanning category user_interface Jul 01 13:57:03 volumio-summer-house volumio[1048]: info: Plugin folders cleanup completed Jul 01 13:57:03 volumio-summer-house volumio[1048]: info: ------------------------------------------- Jul 01 13:57:03 volumio-summer-house volumio[1048]: info: ----- Core plugins startup ---- Jul 01 13:57:03 volumio-summer-house volumio[1048]: info: ------------------------------------------- Jul 01 13:57:03 volumio-summer-house volumio[1048]: info: Loading plugins from folder /volumio/app/plugins/ Jul 01 13:57:03 volumio-summer-house volumio[1048]: info: Adding plugin upnp to MyMusic Plugins Jul 01 13:57:03 volumio-summer-house volumio[1048]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 01 13:57:03 volumio-summer-house volumio[1048]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 01 13:57:03 volumio-summer-house volumio[1048]: info: Loading plugins from folder /data/plugins/ Jul 01 13:57:03 volumio-summer-house volumio[1048]: info: Loading plugin "system"... Jul 01 13:57:03 volumio-summer-house volumio[1048]: info: Loading plugin "appearance"... Jul 01 13:57:04 volumio-summer-house systemd[1]: systemd-hostnamed.service: Succeeded. Jul 01 13:57:04 volumio-summer-house volumio[1048]: info: Loading plugin "network"... Jul 01 13:57:04 volumio-summer-house volumio[1048]: info: Refreshing Cached IP Addresses Jul 01 13:57:04 volumio-summer-house sudo[1081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 01 13:57:04 volumio-summer-house sudo[1081]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:04 volumio-summer-house sudo[1083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 01 13:57:04 volumio-summer-house sudo[1081]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:04 volumio-summer-house sudo[1083]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:04 volumio-summer-house sudo[1083]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:04 volumio-summer-house volumio[1048]: info: Loading plugin "services"... Jul 01 13:57:04 volumio-summer-house volumio[1048]: info: Loading plugin "alsa_controller"... Jul 01 13:57:04 volumio-summer-house sudo[1092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 01 13:57:04 volumio-summer-house sudo[1092]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:04 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 01 13:57:04 volumio-summer-house volumio[1048]: info: Loading plugin "wizard"... Jul 01 13:57:04 volumio-summer-house volumio[1048]: info: Loading plugin "networkfs"... Jul 01 13:57:05 volumio-summer-house volumio[1048]: info: Starting Udev Watcher for removable devices Jul 01 13:57:05 volumio-summer-house sudo[1110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=sonos,password=sonos,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.11/ZONERIPPER/music /mnt/NAS/ZONERIPPER Jul 01 13:57:05 volumio-summer-house sudo[1110]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:05 volumio-summer-house sudo[1112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=sonos,password=sonos,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.11/music /mnt/NAS/ZONERIPPER Jul 01 13:57:05 volumio-summer-house sudo[1112]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:05 volumio-summer-house volumio[1048]: info: Ignoring mount for partition: boot Jul 01 13:57:05 volumio-summer-house volumio[1048]: info: Ignoring mount for partition: volumio Jul 01 13:57:05 volumio-summer-house volumio[1048]: info: Ignoring mount for partition: volumio_data Jul 01 13:57:05 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 01 13:57:05 volumio-summer-house volumio[1048]: info: Loading plugin "volumio_command_line_client"... Jul 01 13:57:05 volumio-summer-house volumio[1048]: info: Loading plugin "upnp"... Jul 01 13:57:05 volumio-summer-house volumio[1048]: info: [1751374625133] Starting Upmpd Daemon Jul 01 13:57:05 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 01 13:57:05 volumio-summer-house volumio[1048]: info: Loading plugin "my_music"... Jul 01 13:57:05 volumio-summer-house volumio[1048]: info: Loading plugin "mpd"... Jul 01 13:57:05 volumio-summer-house kernel: Key type cifs.spnego registered Jul 01 13:57:05 volumio-summer-house kernel: Key type cifs.idmap registered Jul 01 13:57:05 volumio-summer-house kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jul 01 13:57:05 volumio-summer-house kernel: CIFS: Attempting to mount //192.168.0.11/music Jul 01 13:57:05 volumio-summer-house volumio[1048]: info: Loading plugin "upnp_browser"... Jul 01 13:57:05 volumio-summer-house volumio-remote-updater[559]: [2025-07-01 13:57:05] [connect] Successful connection Jul 01 13:57:06 volumio-summer-house sudo[1092]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:06 volumio-summer-house volumio[1048]: info: Loading plugin "alarm-clock"... Jul 01 13:57:06 volumio-summer-house volumio[1048]: info: Loading plugin "airplay_emulation"... Jul 01 13:57:06 volumio-summer-house volumio[1048]: info: Starting Shairport Sync Jul 01 13:57:06 volumio-summer-house volumio[1048]: info: Loading plugin "last_100"... Jul 01 13:57:06 volumio-summer-house volumio[1048]: info: Loading plugin "webradio"... Jul 01 13:57:06 volumio-summer-house volumio[1048]: info: Loading plugin "i2s_dacs"... Jul 01 13:57:06 volumio-summer-house volumio[1048]: info: Loading plugin "volumiodiscovery"... Jul 01 13:57:06 volumio-summer-house volumio[1048]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 01 13:57:06 volumio-summer-house volumio[1048]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 01 13:57:06 volumio-summer-house volumio[1048]: *** WARNING *** For more information see Jul 01 13:57:06 volumio-summer-house volumio[1048]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 01 13:57:06 volumio-summer-house node[1048]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 01 13:57:06 volumio-summer-house volumio[1048]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 01 13:57:06 volumio-summer-house volumio[1048]: *** WARNING *** For more information see Jul 01 13:57:06 volumio-summer-house node[1048]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 01 13:57:06 volumio-summer-house node[1048]: *** WARNING *** For more information see Jul 01 13:57:06 volumio-summer-house node[1048]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 01 13:57:06 volumio-summer-house node[1048]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 01 13:57:06 volumio-summer-house node[1048]: *** WARNING *** For more information see Jul 01 13:57:06 volumio-summer-house volumio[1048]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 01 13:57:06 volumio-summer-house volumio[1048]: info: Discovery: Started advertising with name: Volumio Summer House Jul 01 13:57:06 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 01 13:57:06 volumio-summer-house volumio[1048]: info: Loading plugin "spop"... Jul 01 13:57:08 volumio-summer-house volumio-time-update[562]: volumio-time-update-util: Date not found in response Jul 01 13:57:08 volumio-summer-house volumio-time-update[562]: volumio-time-update-util: Retrying in 5 seconds... Jul 01 13:57:08 volumio-summer-house volumio[1048]: info: Loading plugin "now_playing"... Jul 01 13:57:10 volumio-summer-house volumio[1048]: info: Loading plugin "outputs"... Jul 01 13:57:10 volumio-summer-house volumio[1048]: info: Loading plugin "albumart"... Jul 01 13:57:10 volumio-summer-house volumio[1048]: info: Plugin example_plugin is not enabled Jul 01 13:57:10 volumio-summer-house volumio[1048]: info: Loading plugin "inputs"... Jul 01 13:57:10 volumio-summer-house volumio[1048]: info: Loading plugin "updater_comm"... Jul 01 13:57:10 volumio-summer-house volumio[1048]: info: Plugin mpdemulation is not enabled Jul 01 13:57:10 volumio-summer-house volumio[1048]: info: Loading plugin "rest_api"... Jul 01 13:57:10 volumio-summer-house volumio[1048]: info: Loading plugin "websocket"... Jul 01 13:57:10 volumio-summer-house volumio[1048]: info: Starting Socket.io Server version 2.3.0 Jul 01 13:57:10 volumio-summer-house volumio[1048]: info: Loading i18n strings for locale en Jul 01 13:57:10 volumio-summer-house volumio[1048]: Updating browse sources language Jul 01 13:57:10 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 01 13:57:10 volumio-summer-house volumio[1048]: Forking 3 albumart workers Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreCommandRouter::initPlayerControls Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 01 13:57:11 volumio-summer-house volumio[1048]: Express server listening on port 3000 Jul 01 13:57:11 volumio-summer-house volumio[1048]: [Metrics] WebUI: 10s 631.01ms Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreStateMachine::resetVolumioState Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreStateMachine::getcurrentVolume Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreCommandRouter::volumioRetrievevolume Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: Volumio Network Manager: Network status updated: 1 Jul 01 13:57:11 volumio-summer-house kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jul 01 13:57:11 volumio-summer-house kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jul 01 13:57:11 volumio-summer-house kernel: CIFS: Attempting to mount //192.168.0.11/ZONERIPPER/music Jul 01 13:57:11 volumio-summer-house sudo[1112]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:11 volumio-summer-house wpa_supplicant[883]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="VM0996341" Jul 01 13:57:11 volumio-summer-house wpa_supplicant[883]: wlan0: Trying to associate with SSID 'VM0996341' Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: Cannot mount NAS ZONERIPPER at system boot, trial number 1 ,retrying in 5 seconds Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: Reloading queue from file Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreStateMachine::setRepeat true single undefined Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreStateMachine::pushState Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CorePlayQueue::getTrack 0 Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreCommandRouter::volumioPushState Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreStateMachine::setRandom null Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreStateMachine::pushState Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CorePlayQueue::getTrack 0 Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreCommandRouter::volumioPushState Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: Setting Device type: Raspberry PI Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: VolumeController:: Volume=60 Mute =false Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreStateMachine::pushState Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CorePlayQueue::getTrack 0 Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreCommandRouter::volumioPushState Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreStateMachine::updateTrackBlock Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CorePlayQueue::getTrackBlock Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreCommandRouter::volumioRetrievevolume Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a32082 Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: VolumeController:: Volume=60 Mute =false Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreStateMachine::pushState Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CorePlayQueue::getTrack 0 Jul 01 13:57:11 volumio-summer-house volumio[1048]: info: CoreCommandRouter::volumioPushState Jul 01 13:57:12 volumio-summer-house wpa_supplicant[883]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jul 01 13:57:12 volumio-summer-house wpa_supplicant[883]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="VM0996341" auth_failures=2 duration=23 reason=CONN_FAILED Jul 01 13:57:12 volumio-summer-house volumio[1048]: Starting albumart workers Jul 01 13:57:12 volumio-summer-house volumio[1048]: Starting albumart workers Jul 01 13:57:12 volumio-summer-house volumio[1048]: Starting albumart workers Jul 01 13:57:13 volumio-summer-house volumio-time-update[562]: volumio-time-update-util: Fetching time from Volumio... Jul 01 13:57:15 volumio-summer-house sudo[1216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 01 13:57:15 volumio-summer-house sudo[1216]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:15 volumio-summer-house sudo[1216]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:15 volumio-summer-house sudo[1218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 01 13:57:15 volumio-summer-house sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:15 volumio-summer-house sudo[1218]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:15 volumio-summer-house sudo[1222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 01 13:57:15 volumio-summer-house sudo[1222]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:15 volumio-summer-house systemd[1]: Started UPnP Renderer front-end to MPD. Jul 01 13:57:15 volumio-summer-house sudo[1222]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:15 volumio-summer-house volumio[1048]: info: Upmpdcli Daemon Started Jul 01 13:57:15 volumio-summer-house volumio[1224]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 01 13:57:16 volumio-summer-house sudo[1241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=sonos,password=sonos,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.11/music /mnt/NAS/ZONERIPPER Jul 01 13:57:16 volumio-summer-house sudo[1241]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:17 volumio-summer-house sudo[1110]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:17 volumio-summer-house volumio[1048]: info: Cannot mount NAS ZONERIPPER at system boot, trial number 1 ,retrying in 5 seconds Jul 01 13:57:17 volumio-summer-house volumio[1048]: info: Completed loading Core Plugins Jul 01 13:57:17 volumio-summer-house kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jul 01 13:57:17 volumio-summer-house kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jul 01 13:57:17 volumio-summer-house kernel: CIFS: Attempting to mount //192.168.0.11/music Jul 01 13:57:17 volumio-summer-house volumio[1048]: info: Preparing to generate the ALSA configuration file Jul 01 13:57:17 volumio-summer-house volumio[1048]: info: Asound.conf file unchanged, so no further update is needed Jul 01 13:57:17 volumio-summer-house volumio[1048]: info: Output device has changed, restarting MPD Jul 01 13:57:17 volumio-summer-house volumio[1048]: info: Output device has changed, restarting Shairport Sync Jul 01 13:57:17 volumio-summer-house sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 01 13:57:17 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:17 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 13:57:17 volumio-summer-house sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:17 volumio-summer-house sudo[1246]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:17 volumio-summer-house sudo[1248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 01 13:57:17 volumio-summer-house sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:17 volumio-summer-house systemd[1]: Stopping Music Player Daemon... Jul 01 13:57:17 volumio-summer-house volumio[1048]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 01 13:57:17 volumio-summer-house volumio[1048]: info: ___________ START PLUGINS ___________ Jul 01 13:57:17 volumio-summer-house volumio[1048]: info: ControllerMpd::onStart: Initializing MPD Jul 01 13:57:17 volumio-summer-house volumio[1048]: info: Creating MPD Configuration file Jul 01 13:57:17 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 01 13:57:17 volumio-summer-house sudo[1255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 01 13:57:17 volumio-summer-house sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:17 volumio-summer-house volumio[1048]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 01 13:57:17 volumio-summer-house volumio[1048]: info: [1751374637993] CoreMusicLibrary::Adding element Media Servers Jul 01 13:57:17 volumio-summer-house sudo[1255]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:17 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 01 13:57:18 volumio-summer-house sudo[1257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 01 13:57:18 volumio-summer-house sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 13:57:18 volumio-summer-house systemd[1]: mpd.service: Succeeded. Jul 01 13:57:18 volumio-summer-house systemd[1]: Stopped Music Player Daemon. Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: [1751374638145] CoreMusicLibrary::Adding element Last_100 Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: [1751374638149] CoreMusicLibrary::Adding element Webradio Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: Initializing BBC Radios Jul 01 13:57:18 volumio-summer-house systemd[1]: Starting Music Player Daemon... Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: Creating Spotify config file Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: [now-playing] ConfigUpdater: config is up to date. Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: Volumio Calling Home Jul 01 13:57:18 volumio-summer-house sudo[1266]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 01 13:57:18 volumio-summer-house sudo[1266]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:18 volumio-summer-house sudo[1266]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: [now-playing] App is listening on port 4004. Jul 01 13:57:18 volumio-summer-house volumio[1048]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: MPD Permissions set Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: MPD Permissions set Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: Spotify config file written Jul 01 13:57:18 volumio-summer-house sudo[1285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 01 13:57:18 volumio-summer-house sudo[1285]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:18 volumio-summer-house volumio[1048]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: No need to fix Spotify hosts Jul 01 13:57:18 volumio-summer-house systemd[1]: Started go-librespot Daemon. Jul 01 13:57:18 volumio-summer-house go-librespot[1287]: go-librespot daemon starting... Jul 01 13:57:18 volumio-summer-house sudo[1285]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: Starting Shairport Sync Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: Starting Shairport Sync Jul 01 13:57:18 volumio-summer-house volumio[1048]: info: Starting Shairport Sync Jul 01 13:57:18 volumio-summer-house sudo[1298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 01 13:57:18 volumio-summer-house sudo[1298]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:18 volumio-summer-house sudo[1300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 01 13:57:18 volumio-summer-house sudo[1300]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:18 volumio-summer-house sudo[1302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 01 13:57:18 volumio-summer-house sudo[1302]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:18 volumio-summer-house systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 01 13:57:18 volumio-summer-house systemd[1]: shairport-sync.service: Succeeded. Jul 01 13:57:18 volumio-summer-house systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 01 13:57:19 volumio-summer-house systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 01 13:57:19 volumio-summer-house sudo[1302]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:19 volumio-summer-house sudo[1300]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:19 volumio-summer-house sudo[1298]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:19 volumio-summer-house volumio[1048]: info: Shairport-Sync Started Jul 01 13:57:19 volumio-summer-house volumio[1048]: Error adding Membership: Error: addMembership EINVAL Jul 01 13:57:19 volumio-summer-house volumio[1048]: info: Shairport-Sync Started Jul 01 13:57:19 volumio-summer-house volumio[1048]: info: Shairport-Sync Started Jul 01 13:57:19 volumio-summer-house go-librespot[1287]: time="2025-07-01T13:57:19+01:00" level=info msg="running go-librespot 0.2.0" Jul 01 13:57:19 volumio-summer-house go-librespot[1287]: time="2025-07-01T13:57:19+01:00" level=debug msg="app state loaded" Jul 01 13:57:19 volumio-summer-house go-librespot[1287]: time="2025-07-01T13:57:19+01:00" level=debug msg="stored credentials not found" Jul 01 13:57:19 volumio-summer-house go-librespot[1287]: time="2025-07-01T13:57:19+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 01 13:57:19 volumio-summer-house mpd[1281]: Jul 01 13:57 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 01 13:57:20 volumio-summer-house systemd[1]: Started Music Player Daemon. Jul 01 13:57:20 volumio-summer-house sudo[1257]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:20 volumio-summer-house sudo[1248]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:20 volumio-summer-house volumio[1048]: info: Completed starting Core Plugins Jul 01 13:57:20 volumio-summer-house volumio[1048]: info: ------------------------------------------- Jul 01 13:57:20 volumio-summer-house volumio[1048]: info: ----- MyVolumio plugins startup ---- Jul 01 13:57:20 volumio-summer-house volumio[1048]: info: ------------------------------------------- Jul 01 13:57:20 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 01 13:57:20 volumio-summer-house volumio[1048]: error: MPD error: The expression evaluated to a falsy value: Jul 01 13:57:20 volumio-summer-house volumio[1048]: assert.ok(self.idling) Jul 01 13:57:20 volumio-summer-house volumio[1048]: error: The expression evaluated to a falsy value: Jul 01 13:57:20 volumio-summer-house volumio[1048]: assert.ok(self.idling) Jul 01 13:57:20 volumio-summer-house volumio[1048]: info: MPD running with PID1281 Jul 01 13:57:20 volumio-summer-house volumio[1048]: ,establishing connection Jul 01 13:57:20 volumio-summer-house volumio[1048]: error: MPD error: The expression evaluated to a falsy value: Jul 01 13:57:20 volumio-summer-house volumio[1048]: assert.ok(self.idling) Jul 01 13:57:20 volumio-summer-house volumio[1048]: error: The expression evaluated to a falsy value: Jul 01 13:57:20 volumio-summer-house volumio[1048]: assert.ok(self.idling) Jul 01 13:57:20 volumio-summer-house volumio[1048]: error: updateQueue error: null Jul 01 13:57:20 volumio-summer-house volumio-remote-updater[559]: [2025-07-01 13:57:20] [connect] Successful connection Jul 01 13:57:20 volumio-summer-house volumio-remote-updater[559]: [2025-07-01 13:57:20] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751374640 101 Jul 01 13:57:20 volumio-summer-house volumio[1048]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Jul 01 13:57:21 volumio-summer-house volumio[1048]: info: go-librespot daemon successfully initialized Jul 01 13:57:22 volumio-summer-house volumio[1224]: .....................................................++++ Jul 01 13:57:22 volumio-summer-house sudo[1320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=sonos,password=sonos,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.11/ZONERIPPER/music /mnt/NAS/ZONERIPPER Jul 01 13:57:22 volumio-summer-house sudo[1320]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:24 volumio-summer-house sudo[1241]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:24 volumio-summer-house volumio[1048]: info: Cannot mount NAS ZONERIPPER at system boot, trial number 2 ,retrying in 5 seconds Jul 01 13:57:24 volumio-summer-house kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jul 01 13:57:24 volumio-summer-house kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jul 01 13:57:24 volumio-summer-house kernel: CIFS: Attempting to mount //192.168.0.11/ZONERIPPER/music Jul 01 13:57:24 volumio-summer-house volumio[1048]: info: Initializing connection to go-librespot Websocket Jul 01 13:57:25 volumio-summer-house volumio[1048]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 01 13:57:28 volumio-summer-house volumio[1224]: ...........................................++++ Jul 01 13:57:28 volumio-summer-house volumio[1224]: e is 65537 (0x010001) Jul 01 13:57:28 volumio-summer-house volumio[1224]: writing RSA key Jul 01 13:57:29 volumio-summer-house sudo[1354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=sonos,password=sonos,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.11/music /mnt/NAS/ZONERIPPER Jul 01 13:57:29 volumio-summer-house sudo[1354]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:30 volumio-summer-house sudo[1320]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:30 volumio-summer-house kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jul 01 13:57:30 volumio-summer-house kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jul 01 13:57:30 volumio-summer-house kernel: CIFS: Attempting to mount //192.168.0.11/music Jul 01 13:57:30 volumio-summer-house volumio[1048]: info: Cannot mount NAS ZONERIPPER at system boot, trial number 2 ,retrying in 5 seconds Jul 01 13:57:32 volumio-summer-house volumiologrotate[578]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Jul 01 13:57:32 volumio-summer-house volumiologrotate[578]: ls: cannot access 'SUMMER': No such file or directory Jul 01 13:57:32 volumio-summer-house volumiologrotate[578]: ls: cannot access 'HOUSE': No such file or directory Jul 01 13:57:33 volumio-summer-house volumio-time-update[562]: volumio-time-update-util: Date not found in response Jul 01 13:57:33 volumio-summer-house volumio-time-update[562]: volumio-time-update-util: Retrying in 5 seconds... Jul 01 13:57:35 volumio-summer-house wpa_supplicant[883]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="VM0996341" Jul 01 13:57:35 volumio-summer-house wpa_supplicant[883]: wlan0: Failed to initiate sched scan Jul 01 13:57:35 volumio-summer-house sudo[1418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=sonos,password=sonos,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.11/ZONERIPPER/music /mnt/NAS/ZONERIPPER Jul 01 13:57:35 volumio-summer-house sudo[1418]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:36 volumio-summer-house kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jul 01 13:57:36 volumio-summer-house kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jul 01 13:57:36 volumio-summer-house kernel: CIFS: Attempting to mount //192.168.0.11/ZONERIPPER/music Jul 01 13:57:36 volumio-summer-house sudo[1354]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:36 volumio-summer-house volumio[1048]: info: Cannot mount NAS ZONERIPPER at system boot, trial number 3 ,retrying in 5 seconds Jul 01 13:57:38 volumio-summer-house volumio-time-update[562]: volumio-time-update-util: Fetching time from Volumio... Jul 01 13:57:39 volumio-summer-house go-librespot[1287]: time="2025-07-01T13:57:39+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 01 13:57:39 volumio-summer-house systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 01 13:57:39 volumio-summer-house systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 01 13:57:40 volumio-summer-house volumio[1048]: error: MyVolumio Plugin failed to start in a timely fashion Jul 01 13:57:40 volumio-summer-house volumio[1048]: [Metrics] CommandRouter: 38s 517.19ms Jul 01 13:57:40 volumio-summer-house volumio[1048]: info: CoreCommandRouter::volumiosetStartupVolume Jul 01 13:57:40 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 13:57:40 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 13:57:40 volumio-summer-house volumio[1048]: info: CoreCommandRouter::Close All Modals sent Jul 01 13:57:40 volumio-summer-house volumio[1048]: info: CoreCommandRouter::Close All Modals sent Jul 01 13:57:40 volumio-summer-house wpa_supplicant[883]: wlan0: Trying to associate with SSID 'VM0996341' Jul 01 13:57:41 volumio-summer-house dhcpcd[1010]: wlan0: carrier acquired Jul 01 13:57:41 volumio-summer-house dhcpcd[1010]: wlan0: connected to Access Point `VM0996341' Jul 01 13:57:41 volumio-summer-house wpa_supplicant[883]: wlan0: Associated with 0a:51:09:a0:c2:50 Jul 01 13:57:41 volumio-summer-house wpa_supplicant[883]: wlan0: CTRL-EVENT-CONNECTED - Connection to 0a:51:09:a0:c2:50 completed [id=0 id_str=] Jul 01 13:57:41 volumio-summer-house wpa_supplicant[883]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 01 13:57:41 volumio-summer-house dhcpcd[1010]: wlan0: IAID eb:f8:af:46 Jul 01 13:57:41 volumio-summer-house dhcpcd[1010]: wlan0: adding address fe80::ba27:ebff:fef8:af46 Jul 01 13:57:41 volumio-summer-house dhcpcd[1010]: ipv6_addaddr1: Permission denied Jul 01 13:57:41 volumio-summer-house dhcpcd[1010]: wlan0: carrier lost Jul 01 13:57:41 volumio-summer-house systemd-udevd[1431]: Process '/sbin/crda' failed with exit code 255. Jul 01 13:57:41 volumio-summer-house dhcpcd[1010]: wlan0: carrier acquired Jul 01 13:57:41 volumio-summer-house dhcpcd[1010]: wlan0: IAID eb:f8:af:46 Jul 01 13:57:41 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 01 13:57:41 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 01 13:57:41 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 01 13:57:41 volumio-summer-house dhcpcd[1010]: wlan0: rebinding lease of 192.168.0.91 Jul 01 13:57:41 volumio-summer-house dhcpcd[1010]: wlan0: probing address 192.168.0.91/24 Jul 01 13:57:41 volumio-summer-house dhcpcd[1010]: wlan0: soliciting an IPv6 router Jul 01 13:57:41 volumio-summer-house sudo[1446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=sonos,password=sonos,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.11/music /mnt/NAS/ZONERIPPER Jul 01 13:57:41 volumio-summer-house sudo[1446]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:42 volumio-summer-house systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 01 13:57:42 volumio-summer-house systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jul 01 13:57:42 volumio-summer-house systemd[1]: Stopped go-librespot Daemon. Jul 01 13:57:42 volumio-summer-house systemd[1]: Started go-librespot Daemon. Jul 01 13:57:42 volumio-summer-house go-librespot[1450]: go-librespot daemon starting... Jul 01 13:57:42 volumio-summer-house go-librespot[1450]: time="2025-07-01T13:57:42+01:00" level=info msg="running go-librespot 0.2.0" Jul 01 13:57:42 volumio-summer-house go-librespot[1450]: time="2025-07-01T13:57:42+01:00" level=debug msg="app state loaded" Jul 01 13:57:42 volumio-summer-house go-librespot[1450]: time="2025-07-01T13:57:42+01:00" level=debug msg="stored credentials not found" Jul 01 13:57:42 volumio-summer-house go-librespot[1450]: time="2025-07-01T13:57:42+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 01 13:57:42 volumio-summer-house sudo[1418]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:42 volumio-summer-house volumio[1048]: info: Cannot mount NAS ZONERIPPER at system boot, trial number 3 ,retrying in 5 seconds Jul 01 13:57:42 volumio-summer-house kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jul 01 13:57:42 volumio-summer-house kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jul 01 13:57:42 volumio-summer-house kernel: CIFS: Attempting to mount //192.168.0.11/music Jul 01 13:57:44 volumio-summer-house wpa_supplicant[883]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 01 13:57:46 volumio-summer-house dhcpcd[1010]: wlan0: leased 192.168.0.91 for 86400 seconds Jul 01 13:57:46 volumio-summer-house avahi-daemon[561]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.91. Jul 01 13:57:46 volumio-summer-house avahi-daemon[561]: New relevant interface wlan0.IPv4 for mDNS. Jul 01 13:57:46 volumio-summer-house dhcpcd[1010]: wlan0: adding route to 192.168.0.0/24 Jul 01 13:57:46 volumio-summer-house avahi-daemon[561]: Registering new address record for 192.168.0.91 on wlan0.IPv4. Jul 01 13:57:46 volumio-summer-house dhcpcd[1010]: wlan0: adding default route via 192.168.0.1 Jul 01 13:57:46 volumio-summer-house dhcpcd[1010]: eth0: deleting default route Jul 01 13:57:46 volumio-summer-house dhcpcd[943]: eth0: pid 1010 deleted default route Jul 01 13:57:46 volumio-summer-house volumio[1048]: verbose: New Socket.io Connection to 192.168.0.91 from 192.168.0.27 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 OPR/95.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 Jul 01 13:57:46 volumio-summer-house volumio[1048]: info: CoreCommandRouter::volumioGetState Jul 01 13:57:46 volumio-summer-house volumio[1048]: info: CorePlayQueue::getTrack 0 Jul 01 13:57:46 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 01 13:57:46 volumio-summer-house volumio[1048]: info: CoreCommandRouter::volumioGetVisibleSources Jul 01 13:57:46 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 01 13:57:46 volumio-summer-house volumio[1048]: info: Listing playlists Jul 01 13:57:46 volumio-summer-house volumio[1048]: info: CoreCommandRouter::volumioGetQueue Jul 01 13:57:46 volumio-summer-house volumio[1048]: info: CoreStateMachine::getQueue Jul 01 13:57:46 volumio-summer-house volumio[1048]: info: CorePlayQueue::getQueue Jul 01 13:57:47 volumio-summer-house volumio[1048]: info: BOOT COMPLETED Jul 01 13:57:47 volumio-summer-house volumio[1048]: verbose: New Socket.io Connection to 192.168.0.91 from 192.168.0.24 UA: Mozilla/5.0 (iPad; CPU OS 10_3_3 like Mac OS X) AppleWebKit/603.3.8 (KHTML, like Gecko) Mobile/14G60 Engine version: 3 Transport: polling Total Clients: 4 Jul 01 13:57:47 volumio-summer-house sudo[1494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=sonos,password=sonos,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.11/ZONERIPPER/music /mnt/NAS/ZONERIPPER Jul 01 13:57:47 volumio-summer-house sudo[1494]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:47 volumio-summer-house volumio[1048]: verbose: New Socket.io Connection to 192.168.0.91 from 192.168.0.24 UA: Mozilla/5.0 (iPad; CPU OS 10_3_3 like Mac OS X) AppleWebKit/603.3.8 (KHTML, like Gecko) Mobile/14G60 Engine version: 3 Transport: polling Total Clients: 5 Jul 01 13:57:47 volumio-summer-house volumio[1048]: info: CoreCommandRouter::volumioGetState Jul 01 13:57:47 volumio-summer-house volumio[1048]: info: CorePlayQueue::getTrack 0 Jul 01 13:57:47 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 01 13:57:47 volumio-summer-house volumio[1048]: info: CoreCommandRouter::volumioGetVisibleSources Jul 01 13:57:47 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 01 13:57:47 volumio-summer-house volumio[1048]: info: Listing playlists Jul 01 13:57:47 volumio-summer-house volumio[1048]: info: CoreCommandRouter::volumioGetQueue Jul 01 13:57:47 volumio-summer-house volumio[1048]: info: CoreStateMachine::getQueue Jul 01 13:57:47 volumio-summer-house volumio[1048]: info: CorePlayQueue::getQueue Jul 01 13:57:48 volumio-summer-house ntpd[799]: Listen normally on 4 wlan0 192.168.0.91:123 Jul 01 13:57:48 volumio-summer-house ntpd[799]: new interface(s) found: waking up resolver Jul 01 13:57:48 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 01 13:57:48 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 13:57:48 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 01 13:57:48 volumio-summer-house volumio[1048]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.799&uuid=d9044ebfa94036443c6a0d49812727ca" http://updates.volumio.org/downloader-v1/track-device Jul 01 13:57:48 volumio-summer-house volumio[1048]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 01 13:57:48 volumio-summer-house volumio[1048]: Dload Upload Total Spent Left Speed Jul 01 13:57:48 volumio-summer-house volumio[1048]: [1.6K blob data] Jul 01 13:57:48 volumio-summer-house volumio[1048]: retrying in 5 seconds, trial 0 Jul 01 13:57:48 volumio-summer-house volumio[1048]: info: Volumio Calling Home Jul 01 13:57:48 volumio-summer-house volumio[1048]: info: Volumio called home Jul 01 13:57:48 volumio-summer-house sudo[1446]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:49 volumio-summer-house volumio[1048]: info: Cannot mount NAS ZONERIPPER at system boot, trial number 4 ,retrying in 5 seconds Jul 01 13:57:49 volumio-summer-house kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jul 01 13:57:49 volumio-summer-house kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jul 01 13:57:49 volumio-summer-house kernel: CIFS: Attempting to mount //192.168.0.11/ZONERIPPER/music Jul 01 13:57:49 volumio-summer-house go-librespot[1450]: time="2025-07-01T13:57:49+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 01 13:57:49 volumio-summer-house go-librespot[1450]: time="2025-07-01T13:57:49+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jul 01 13:57:49 volumio-summer-house go-librespot[1450]: time="2025-07-01T13:57:49+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jul 01 13:57:49 volumio-summer-house go-librespot[1450]: time="2025-07-01T13:57:49+01:00" level=info msg="zeroconf server listening on port 34803" Jul 01 13:57:49 volumio-summer-house kernel: CIFS: VFS: BAD_NETWORK_NAME: \\192.168.0.11\ZONERIPPER Jul 01 13:57:49 volumio-summer-house sudo[1494]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:49 volumio-summer-house kernel: CIFS: VFS: cifs_mount failed w/return code = -2 Jul 01 13:57:49 volumio-summer-house volumio[1048]: info: Cannot mount NAS ZONERIPPER at system boot, trial number 4 ,retrying in 5 seconds Jul 01 13:57:51 volumio-summer-house volumio[1048]: info: Discovery: adding 9f3fe488-008f-43bd-9ca6-876d2c86e8f4 Jul 01 13:57:51 volumio-summer-house volumio[1048]: info: Discovery: Found device Volumio Summer House Jul 01 13:57:51 volumio-summer-house volumio[1048]: info: CoreCommandRouter::volumioGetState Jul 01 13:57:51 volumio-summer-house volumio[1048]: info: CorePlayQueue::getTrack 0 Jul 01 13:57:52 volumio-summer-house go-librespot[1450]: time="2025-07-01T13:57:52+01:00" level=debug msg="new websocket client" Jul 01 13:57:52 volumio-summer-house volumio[1048]: info: Connection to go-librespot Websocket established Jul 01 13:57:52 volumio-summer-house volumio[1048]: info: Discovery: this is already registered, 9f3fe488-008f-43bd-9ca6-876d2c86e8f4 Jul 01 13:57:52 volumio-summer-house volumio[1048]: info: Discovery: Found device Volumio Summer House Jul 01 13:57:52 volumio-summer-house volumio[1048]: info: CoreCommandRouter::volumioGetState Jul 01 13:57:52 volumio-summer-house volumio[1048]: info: CorePlayQueue::getTrack 0 Jul 01 13:57:52 volumio-summer-house volumio[1048]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 01 13:57:52 volumio-summer-house volumio[1048]: verbose: New Socket.io Connection to 192.168.0.91 from 192.168.0.24 UA: unknown Engine version: 3 Transport: websocket Total Clients: 6 Jul 01 13:57:52 volumio-summer-house volumio[1048]: info: CoreCommandRouter::volumioGetState Jul 01 13:57:52 volumio-summer-house volumio[1048]: info: CorePlayQueue::getTrack 0 Jul 01 13:57:52 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 01 13:57:52 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 01 13:57:54 volumio-summer-house sudo[1517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=sonos,password=sonos,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.11/music /mnt/NAS/ZONERIPPER Jul 01 13:57:54 volumio-summer-house sudo[1517]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:57:54 volumio-summer-house kernel: CIFS: Attempting to mount //192.168.0.11/music Jul 01 13:57:54 volumio-summer-house sudo[1517]: pam_unix(sudo:session): session closed for user root Jul 01 13:57:54 volumio-summer-house volumio[1048]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jul 01 13:57:54 volumio-summer-house volumio[1048]: info: Volumio Network Manager: Network status updated: 3 Jul 01 13:57:54 volumio-summer-house volumio[1048]: info: CoreCommandRouter::volumioGetState Jul 01 13:57:54 volumio-summer-house volumio[1048]: info: CorePlayQueue::getTrack 0 Jul 01 13:57:57 volumio-summer-house volumio[1048]: info: Getting Spotify volume Jul 01 13:57:57 volumio-summer-house volumio[1048]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jul 01 13:57:57 volumio-summer-house volumio[1048]: info: CoreCommandRouter::volumioGetState Jul 01 13:57:57 volumio-summer-house volumio[1048]: info: CorePlayQueue::getTrack 0 Jul 01 13:57:57 volumio-summer-house volumio[1048]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jul 01 13:57:57 volumio-summer-house volumio[1048]: SPOTIFY: SPOTIFY VOLUME undefined Jul 01 13:57:57 volumio-summer-house volumio[1048]: SPOTIFY: VOLUMIO VOLUME 60 Jul 01 13:57:57 volumio-summer-house volumio[1048]: info: Aligning Spotify Volume to Volumio Volume Jul 01 13:57:57 volumio-summer-house volumio[1048]: info: CoreCommandRouter::volumioGetState Jul 01 13:57:57 volumio-summer-house volumio[1048]: info: CorePlayQueue::getTrack 0 Jul 01 13:57:57 volumio-summer-house volumio[1048]: info: Setting Spotify Volume from Volumio: 60 Jul 01 13:57:58 volumio-summer-house volumio-time-update[562]: volumio-time-update-util: Date not found in response Jul 01 13:57:58 volumio-summer-house volumio-time-update[562]: volumio-time-update-util: Retrying in 5 seconds... Jul 01 13:57:59 volumio-summer-house volumio[1048]: SPOTIFY: SETTING SPOTIFY VOLUME 60 Jul 01 13:57:59 volumio-summer-house volumio[1048]: info: Sending Spotify command with payload to local API: /player/volume Jul 01 13:58:00 volumio-summer-house ntpd[799]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jul 01 13:58:00 volumio-summer-house ntpd[799]: Soliciting pool server 217.154.60.177 Jul 01 13:58:00 volumio-summer-house ntpd[799]: Soliciting pool server 213.210.39.123 Jul 01 13:58:00 volumio-summer-house ntpd[799]: Soliciting pool server 217.114.59.66 Jul 01 13:58:01 volumio-summer-house ntpd[799]: Soliciting pool server 213.5.132.231 Jul 01 13:58:01 volumio-summer-house ntpd[799]: Soliciting pool server 149.22.188.7 Jul 01 13:58:01 volumio-summer-house ntpd[799]: Soliciting pool server 130.162.179.142 Jul 01 13:58:02 volumio-summer-house ntpd[799]: Soliciting pool server 185.53.45.4 Jul 01 13:58:02 volumio-summer-house ntpd[799]: Soliciting pool server 185.83.169.27 Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: Adding plugin bluetooth to MyMusic Plugins Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: Adding plugin multiroom to MyMusic Plugins Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: Adding plugin metavolumio to MyMusic Plugins Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: Adding plugin cd_controller to MyMusic Plugins Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 01 13:58:02 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 01 13:58:03 volumio-summer-house ntpd[799]: Soliciting pool server 63.135.73.82 Jul 01 13:58:03 volumio-summer-house ntpd[799]: Soliciting pool server 88.80.189.24 Jul 01 13:58:03 volumio-summer-house volumio-time-update[562]: volumio-time-update-util: Fetching time from Volumio... Jul 01 13:58:03 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 01 13:58:03 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 01 13:58:03 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 13:58:03 volumio-summer-house volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 13:58:03 volumio-summer-house volumio[1048]: info: Starting MyVolumio Remote Streaming Endpoints Jul 01 13:58:03 volumio-summer-house volumio[1048]: info: MyVolumio login type: Token Jul 01 13:58:03 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 01 13:58:03 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 01 13:58:03 volumio-summer-house volumio-time-update[562]: volumio-time-update-util: Setting system time to: 2025-07-01 13:58:25 Jul 01 13:58:03 volumio-summer-house sudo[1546]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-07-01 13:58:25 Jul 01 13:58:03 volumio-summer-house sudo[1546]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:58:03 volumio-summer-house dbus-daemon[566]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.15' (uid=0 pid=1547 comm="timedatectl set-time 2025-07-01 13:58:25 ") Jul 01 13:58:03 volumio-summer-house systemd[1]: Starting Time & Date Service... Jul 01 13:58:05 volumio-summer-house volumio[1048]: info: Starting Streaming Service Transparent Proxy Jul 01 13:58:05 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 01 13:58:05 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 01 13:58:05 volumio-summer-house volumio[1048]: info: Streaming services startup Jul 01 13:58:05 volumio-summer-house volumio[1048]: info: Starting Streaming Daemon Jul 01 13:58:05 volumio-summer-house sudo[1570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 01 13:58:05 volumio-summer-house sudo[1570]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:58:05 volumio-summer-house volumio[1048]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 01 13:58:05 volumio-summer-house sudo[1570]: pam_unix(sudo:session): session closed for user root Jul 01 13:58:05 volumio-summer-house volumio[1048]: error: Cannot start Volumio Streaming Daemon Jul 01 13:58:05 volumio-summer-house volumio[1048]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 01 13:58:05 volumio-summer-house volumio[1048]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 01 13:58:06 volumio-summer-house volumio[1048]: STREAMING PROXY: Starting server on port 3245 Jul 01 13:58:06 volumio-summer-house volumio[1048]: Node JS runtime: 14 Jul 01 13:58:28 volumio-summer-house ntpd[799]: receive: Unexpected origin timestamp 0xec0e59df.38ff8939 does not match aorg 0000000000.00000000 from server@217.154.60.177 xmt 0xec0e59f4.d8a01748 Jul 01 13:58:28 volumio-summer-house ntpd[799]: receive: Unexpected origin timestamp 0xec0e59df.38fbc7f5 does not match aorg 0000000000.00000000 from server@185.53.45.4 xmt 0xec0e59f4.d8296975 Jul 01 13:58:28 volumio-summer-house ntpd[799]: receive: Unexpected origin timestamp 0xec0e59df.38f638e3 does not match aorg 0000000000.00000000 from server@63.135.73.82 xmt 0xec0e59f4.d9481860 Jul 01 13:58:31 volumio-summer-house ntpd[799]: Soliciting pool server 131.111.8.61 Jul 01 13:58:31 volumio-summer-house ntpd[799]: Soliciting pool server 129.250.35.250 Jul 01 13:58:32 volumio-summer-house volumio[1048]: error: MyVolumio Custom Token format not valid, refreshing it Jul 01 13:58:33 volumio-summer-house volumio[1048]: info: MyVolumio login type: Token Jul 01 13:58:33 volumio-summer-house volumio[1048]: error: error Jul 01 13:58:33 volumio-summer-house volumio[1048]: error: error Jul 01 13:58:34 volumio-summer-house volumio[1048]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 01 13:58:35 volumio-summer-house volumio[1048]: info: MyVolumio token set successfully Jul 01 13:58:35 volumio-summer-house volumio[1048]: info: MYVOLUMIO: Adding device Jul 01 13:58:35 volumio-summer-house volumio[1048]: info: MYVOLUMIO: Evaluating Server Jul 01 13:58:35 volumio-summer-house volumio[1048]: info: MyVolumio status changed Jul 01 13:58:35 volumio-summer-house volumio[1048]: info: Streaming services startup Jul 01 13:58:35 volumio-summer-house volumio[1048]: info: Starting Streaming Daemon Jul 01 13:58:35 volumio-summer-house volumio[1048]: info: Removing browser output: myVolumio user plan is not superstar Jul 01 13:58:35 volumio-summer-house volumio[1048]: info: Removing audio output: Jul 01 13:58:35 volumio-summer-house volumio[1048]: info: Stoppping Tunnel 1 Jul 01 13:58:35 volumio-summer-house sudo[1595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 01 13:58:35 volumio-summer-house sudo[1595]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:58:35 volumio-summer-house sudo[1598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 01 13:58:35 volumio-summer-house sudo[1598]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 13:58:35 volumio-summer-house sudo[1595]: pam_unix(sudo:session): session closed for user root Jul 01 13:58:35 volumio-summer-house volumio[1048]: error: Cannot start Volumio Streaming Daemon Jul 01 13:58:35 volumio-summer-house volumio[1048]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 01 13:58:35 volumio-summer-house volumio[1048]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 01 13:58:35 volumio-summer-house sudo[1598]: pam_unix(sudo:session): session closed for user root Jul 01 13:58:35 volumio-summer-house volumio[1048]: info: Remote SSH Stopped Jul 01 13:58:35 volumio-summer-house dbus-daemon[566]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 01 13:58:35 volumio-summer-house systemd[1]: Started Time & Date Service. Jul 01 13:58:25 volumio-summer-house systemd-timedated[1548]: Changed local time to Tue Jul 1 13:58:25 2025 Jul 01 13:58:25 volumio-summer-house sudo[1546]: pam_unix(sudo:session): session closed for user root Jul 01 13:58:25 volumio-summer-house volumio-time-update[562]: volumio-time-update-util: System time updated successfully. Jul 01 13:58:25 volumio-summer-house volumio[1048]: error: Failed to ping endpoint as1.myvolumio.org : unknown error Jul 01 13:58:25 volumio-summer-house volumio[1048]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 01 13:58:25 volumio-summer-house volumio[1048]: Error: Unable to resolve or reject the same promise twice Jul 01 13:58:25 volumio-summer-house volumio[1048]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jul 01 13:58:25 volumio-summer-house volumio[1048]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Jul 01 13:58:25 volumio-summer-house volumio[1048]: at Socket.emit (events.js:327:22) Jul 01 13:58:25 volumio-summer-house volumio[1048]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 01 13:58:25 volumio-summer-house volumio[1048]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 01 13:58:25 volumio-summer-house volumio[1048]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 01 13:58:25 volumio-summer-house systemd[1]: Starting Daily apt download activities... Jul 01 13:58:25 volumio-summer-house systemd[1]: Started Volumio Time Update Utility. Jul 01 13:58:25 volumio-summer-house systemd[1]: Reached target Multi-User System. Jul 01 13:58:25 volumio-summer-house systemd[1]: Reached target Graphical Interface. Jul 01 13:58:25 volumio-summer-house systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 01 13:58:25 volumio-summer-house systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 01 13:58:25 volumio-summer-house systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 01 13:58:25 volumio-summer-house sudo[1634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-01 13:57 Jul 01 13:58:25 volumio-summer-house sudo[1634]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"