-- Logs begin at Mon 2025-09-08 16:35:19 EDT, end at Mon 2025-09-08 16:38:38 EDT. -- Sep 08 16:37:01 volumio-mac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 16:37:01 volumio-mac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Sep 08 16:37:01 volumio-mac systemd[1]: Stopped go-librespot Daemon. Sep 08 16:37:01 volumio-mac systemd[1]: Started go-librespot Daemon. Sep 08 16:37:01 volumio-mac go-librespot[2561]: go-librespot daemon starting... Sep 08 16:37:01 volumio-mac go-librespot[2561]: time="2025-09-08T16:37:01-04:00" level=info msg="running go-librespot 0.2.0" Sep 08 16:37:01 volumio-mac go-librespot[2561]: time="2025-09-08T16:37:01-04:00" level=debug msg="app state loaded" Sep 08 16:37:01 volumio-mac go-librespot[2561]: time="2025-09-08T16:37:01-04:00" level=debug msg="stored credentials not found" Sep 08 16:37:01 volumio-mac go-librespot[2561]: time="2025-09-08T16:37:01-04:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 08 16:37:02 volumio-mac dhcpcd[1093]: wlan0: using IPv4LL address 169.254.40.250 Sep 08 16:37:02 volumio-mac dhcpcd[1093]: wlan0: adding route to 169.254.0.0/16 Sep 08 16:37:02 volumio-mac avahi-daemon[925]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.40.250. Sep 08 16:37:02 volumio-mac avahi-daemon[925]: New relevant interface wlan0.IPv4 for mDNS. Sep 08 16:37:02 volumio-mac avahi-daemon[925]: Registering new address record for 169.254.40.250 on wlan0.IPv4. Sep 08 16:37:03 volumio-mac ntpd[1100]: Listen normally on 4 wlan0 169.254.40.250:123 Sep 08 16:37:03 volumio-mac ntpd[1100]: new interface(s) found: waking up resolver Sep 08 16:37:06 volumio-mac volumio-remote-updater[922]: [2025-09-08 16:37:06] [connect] Successful connection Sep 08 16:37:08 volumio-mac ntpd[1100]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 08 16:37:11 volumio-mac systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 08 16:37:11 volumio-mac systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 08 16:37:11 volumio-mac systemd[1]: Started dynamicswap service. Sep 08 16:37:11 volumio-mac systemd[1]: dynamicswap.service: Succeeded. Sep 08 16:37:11 volumio-mac systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 08 16:37:11 volumio-mac systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Sep 08 16:37:11 volumio-mac systemd[1]: Started dynamicswap service. Sep 08 16:37:11 volumio-mac systemd[1]: Stopped Volumio Backend Module. Sep 08 16:37:11 volumio-mac systemd[1]: Started Volumio Backend Module. Sep 08 16:37:11 volumio-mac systemd[1]: dynamicswap.service: Succeeded. Sep 08 16:37:11 volumio-mac volumio[2612]: info: ------------------------------------------- Sep 08 16:37:11 volumio-mac volumio[2612]: info: ----- Volumio3 ---- Sep 08 16:37:11 volumio-mac volumio[2612]: info: ------------------------------------------- Sep 08 16:37:11 volumio-mac volumio[2612]: info: ----- System startup ---- Sep 08 16:37:11 volumio-mac volumio[2612]: info: ------------------------------------------- Sep 08 16:37:12 volumio-mac volumio[2612]: info: MYVOLUMIO Environment detected Sep 08 16:37:12 volumio-mac volumio[2612]: info: Plugin folders cleanup Sep 08 16:37:12 volumio-mac volumio[2612]: info: Scanning into folder /volumio/app/plugins/ Sep 08 16:37:12 volumio-mac volumio[2612]: info: Scanning category audio_interface Sep 08 16:37:12 volumio-mac volumio[2612]: info: Scanning category miscellanea Sep 08 16:37:12 volumio-mac volumio[2612]: info: Scanning category music_service Sep 08 16:37:12 volumio-mac volumio[2612]: info: Scanning category plugins.json Sep 08 16:37:12 volumio-mac volumio[2612]: info: Scanning category system_controller Sep 08 16:37:12 volumio-mac volumio[2612]: info: Scanning category user_interface Sep 08 16:37:12 volumio-mac volumio[2612]: info: Scanning into folder /data/plugins/ Sep 08 16:37:12 volumio-mac volumio[2612]: info: Scanning category music_service Sep 08 16:37:12 volumio-mac volumio[2612]: info: Scanning category system_controller Sep 08 16:37:12 volumio-mac volumio[2612]: info: Scanning category system_hardware Sep 08 16:37:12 volumio-mac volumio[2612]: info: Scanning category user_interface Sep 08 16:37:12 volumio-mac volumio[2612]: info: Plugin folders cleanup completed Sep 08 16:37:12 volumio-mac volumio[2612]: info: ------------------------------------------- Sep 08 16:37:12 volumio-mac volumio[2612]: info: ----- Core plugins startup ---- Sep 08 16:37:12 volumio-mac volumio[2612]: info: ------------------------------------------- Sep 08 16:37:12 volumio-mac volumio[2612]: info: Loading plugins from folder /volumio/app/plugins/ Sep 08 16:37:12 volumio-mac volumio[2612]: info: Adding plugin upnp to MyMusic Plugins Sep 08 16:37:12 volumio-mac volumio[2612]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 08 16:37:12 volumio-mac volumio[2612]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 08 16:37:12 volumio-mac volumio[2612]: info: Loading plugins from folder /data/plugins/ Sep 08 16:37:12 volumio-mac volumio[2612]: info: Loading plugin "system"... Sep 08 16:37:12 volumio-mac volumio[2612]: info: Loading plugin "appearance"... Sep 08 16:37:12 volumio-mac volumio[2612]: info: Loading plugin "network"... Sep 08 16:37:12 volumio-mac volumio[2612]: info: Refreshing Cached IP Addresses Sep 08 16:37:12 volumio-mac sudo[2638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 08 16:37:12 volumio-mac sudo[2638]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:37:12 volumio-mac sudo[2638]: pam_unix(sudo:session): session closed for user root Sep 08 16:37:12 volumio-mac sudo[2640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 08 16:37:12 volumio-mac sudo[2640]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:37:12 volumio-mac sudo[2640]: pam_unix(sudo:session): session closed for user root Sep 08 16:37:12 volumio-mac volumio[2612]: info: Loading plugin "services"... Sep 08 16:37:12 volumio-mac volumio[2612]: info: Loading plugin "alsa_controller"... Sep 08 16:37:12 volumio-mac sudo[2653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 08 16:37:12 volumio-mac sudo[2653]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:37:12 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 16:37:12 volumio-mac volumio[2612]: info: Loading plugin "wizard"... Sep 08 16:37:12 volumio-mac volumio[2612]: info: Loading plugin "networkfs"... Sep 08 16:37:12 volumio-mac volumio[2612]: info: Starting Udev Watcher for removable devices Sep 08 16:37:12 volumio-mac sudo[2666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Music,password=QkPK+myDyxRwA6u,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.15/Music /mnt/NAS/happyshowNAS Sep 08 16:37:12 volumio-mac sudo[2666]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:37:12 volumio-mac volumio[2612]: info: Ignoring mount for partition: boot Sep 08 16:37:12 volumio-mac volumio[2612]: info: Ignoring mount for partition: volumio Sep 08 16:37:12 volumio-mac volumio[2612]: info: Ignoring mount for partition: volumio_data Sep 08 16:37:12 volumio-mac kernel: CIFS: Attempting to mount //192.168.50.15/Music Sep 08 16:37:12 volumio-mac volumio[2612]: info: Ignoring mount for partition: volumioHD Sep 08 16:37:12 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 16:37:12 volumio-mac volumio[2612]: info: Loading plugin "volumio_command_line_client"... Sep 08 16:37:12 volumio-mac volumio[2612]: info: Loading plugin "upnp"... Sep 08 16:37:12 volumio-mac volumio[2612]: info: [1757363832361] Starting Upmpd Daemon Sep 08 16:37:12 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 16:37:12 volumio-mac volumio[2612]: info: Loading plugin "my_music"... Sep 08 16:37:12 volumio-mac volumio[2612]: info: Loading plugin "mpd"... Sep 08 16:37:12 volumio-mac volumio[2612]: info: Plugin upnp_browser is not enabled Sep 08 16:37:12 volumio-mac volumio[2612]: info: Loading plugin "alarm-clock"... Sep 08 16:37:12 volumio-mac volumio[2612]: info: Loading plugin "airplay_emulation"... Sep 08 16:37:12 volumio-mac volumio[2612]: info: Starting Shairport Sync Sep 08 16:37:12 volumio-mac volumio[2612]: info: Loading plugin "last_100"... Sep 08 16:37:12 volumio-mac volumio[2612]: info: Loading plugin "webradio"... Sep 08 16:37:12 volumio-mac volumio[2612]: info: Loading plugin "i2s_dacs"... Sep 08 16:37:12 volumio-mac volumio[2612]: info: I2S DAC not set, start Auto-detection Sep 08 16:37:12 volumio-mac volumio[2612]: info: Loading plugin "volumiodiscovery"... Sep 08 16:37:12 volumio-mac volumio[2612]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 08 16:37:12 volumio-mac volumio[2612]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 16:37:12 volumio-mac volumio[2612]: *** WARNING *** For more information see Sep 08 16:37:12 volumio-mac volumio[2612]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 08 16:37:12 volumio-mac node[2612]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 08 16:37:12 volumio-mac volumio[2612]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 16:37:12 volumio-mac volumio[2612]: *** WARNING *** For more information see Sep 08 16:37:12 volumio-mac node[2612]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 16:37:12 volumio-mac node[2612]: *** WARNING *** For more information see Sep 08 16:37:12 volumio-mac node[2612]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 08 16:37:12 volumio-mac node[2612]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 16:37:12 volumio-mac node[2612]: *** WARNING *** For more information see Sep 08 16:37:12 volumio-mac volumio[2612]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 08 16:37:12 volumio-mac volumio[2612]: info: Discovery: Started advertising with name: Volumio-Mac Sep 08 16:37:12 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 16:37:12 volumio-mac volumio[2612]: info: Loading plugin "spop"... Sep 08 16:37:12 volumio-mac volumio[2612]: info: Loading plugin "ytcr"... Sep 08 16:37:13 volumio-mac volumio[2612]: info: Loading plugin "ytmusic"... Sep 08 16:37:13 volumio-mac volumio[2612]: info: Loading plugin "outputs"... Sep 08 16:37:13 volumio-mac volumio[2612]: info: Loading plugin "albumart"... Sep 08 16:37:13 volumio-mac volumio[2612]: info: Plugin example_plugin is not enabled Sep 08 16:37:13 volumio-mac volumio[2612]: info: Loading plugin "inputs"... Sep 08 16:37:13 volumio-mac volumio[2612]: info: Loading plugin "updater_comm"... Sep 08 16:37:13 volumio-mac volumio[2612]: info: Plugin mpdemulation is not enabled Sep 08 16:37:13 volumio-mac volumio[2612]: info: Loading plugin "rest_api"... Sep 08 16:37:13 volumio-mac volumio[2612]: info: Loading plugin "websocket"... Sep 08 16:37:13 volumio-mac volumio[2612]: info: Starting Socket.io Server version 2.3.0 Sep 08 16:37:13 volumio-mac volumio[2612]: info: Loading plugin "80s80s"... Sep 08 16:37:14 volumio-mac volumio[2612]: Forking 7 albumart workers Sep 08 16:37:14 volumio-mac volumio[2612]: info: Applying required configuration parameters for plugin 80s80s Sep 08 16:37:14 volumio-mac volumio[2612]: info: [1757363834057] [80s80s] API delay: 30 Sep 08 16:37:14 volumio-mac volumio[2612]: info: Loading plugin "radio_paradise"... Sep 08 16:37:14 volumio-mac volumio[2612]: info: Applying required configuration parameters for plugin radio_paradise Sep 08 16:37:14 volumio-mac volumio[2612]: info: [1757363834116] [RadioParadise] API delay: 5 Sep 08 16:37:14 volumio-mac volumio[2612]: info: Loading plugin "swarmstation"... Sep 08 16:37:14 volumio-mac volumio[2612]: Starting albumart workers Sep 08 16:37:14 volumio-mac volumio[2612]: info: Applying required configuration parameters for plugin swarmstation Sep 08 16:37:14 volumio-mac volumio[2612]: info: [1757363834185] [Swarmstation] API delay: 5 Sep 08 16:37:14 volumio-mac volumio[2612]: info: Loading plugin "backup_restore"... Sep 08 16:37:14 volumio-mac volumio[2612]: Starting albumart workers Sep 08 16:37:14 volumio-mac volumio[2612]: Starting albumart workers Sep 08 16:37:14 volumio-mac volumio[2612]: Starting albumart workers Sep 08 16:37:14 volumio-mac volumio[2612]: Starting albumart workers Sep 08 16:37:14 volumio-mac volumio[2612]: Starting albumart workers Sep 08 16:37:14 volumio-mac volumio[2612]: info: Applying required configuration parameters for plugin backup_restore Sep 08 16:37:14 volumio-mac volumio[2612]: info: Loading plugin "music_services_shield"... Sep 08 16:37:14 volumio-mac volumio[2612]: Starting albumart workers Sep 08 16:37:14 volumio-mac volumio[2612]: info: Applying required configuration parameters for plugin music_services_shield Sep 08 16:37:14 volumio-mac volumio[2612]: info: Loading plugin "Systeminfo"... Sep 08 16:37:14 volumio-mac volumio[2612]: info: Loading plugin "lastfm"... Sep 08 16:37:14 volumio-mac volumio[2612]: info: Loading i18n strings for locale en Sep 08 16:37:14 volumio-mac volumio[2612]: Updating browse sources language Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::initPlayerControls Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: Express server listening on port 3000 Sep 08 16:37:14 volumio-mac volumio[2612]: [Metrics] WebUI: 2s 725.77ms Sep 08 16:37:14 volumio-mac volumio[2612]: info: Setting Device type: x86 Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreStateMachine::resetVolumioState Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreStateMachine::getcurrentVolume Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::volumioRetrievevolume Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 08 16:37:14 volumio-mac volumio[2612]: info: Completed loading Core Plugins Sep 08 16:37:14 volumio-mac volumio[2612]: info: Preparing to generate the ALSA configuration file Sep 08 16:37:14 volumio-mac volumio[2612]: info: Volumio Network Manager: Network status updated: 3 Sep 08 16:37:14 volumio-mac volumio[2612]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Sep 08 16:37:14 volumio-mac volumio[2612]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 08 16:37:14 volumio-mac volumio[2612]: info: Reloading queue from file Sep 08 16:37:14 volumio-mac volumio[2612]: info: VolumeController:: Volume=100 Mute =false Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreStateMachine::pushState Sep 08 16:37:14 volumio-mac volumio[2612]: info: CorePlayQueue::getTrack 0 Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::volumioPushState Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreStateMachine::updateTrackBlock Sep 08 16:37:14 volumio-mac volumio[2612]: info: CorePlayQueue::getTrackBlock Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::volumioRetrievevolume Sep 08 16:37:14 volumio-mac volumio[2612]: info: Asound.conf file unchanged, so no further update is needed Sep 08 16:37:14 volumio-mac volumio[2612]: info: Output device has changed, restarting MPD Sep 08 16:37:14 volumio-mac sudo[2795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 16:37:14 volumio-mac sudo[2795]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:37:14 volumio-mac sudo[2795]: pam_unix(sudo:session): session closed for user root Sep 08 16:37:14 volumio-mac volumio[2612]: info: Output device has changed, restarting Shairport Sync Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 16:37:14 volumio-mac sudo[2798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 16:37:14 volumio-mac sudo[2798]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:37:14 volumio-mac systemd[1]: musicservicesshield.service: Succeeded. Sep 08 16:37:14 volumio-mac systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Sep 08 16:37:14 volumio-mac systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Sep 08 16:37:14 volumio-mac systemd[1]: Stopping Music Player Daemon... Sep 08 16:37:14 volumio-mac volumio[2612]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 16:37:14 volumio-mac volumio[2612]: info: ___________ START PLUGINS ___________ Sep 08 16:37:14 volumio-mac volumio[2612]: info: ControllerMpd::onStart: Initializing MPD Sep 08 16:37:14 volumio-mac volumio[2612]: info: Creating MPD Configuration file Sep 08 16:37:14 volumio-mac sudo[2804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 16:37:14 volumio-mac sudo[2804]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:37:14 volumio-mac systemd[1]: mpd.service: Succeeded. Sep 08 16:37:14 volumio-mac sudo[2804]: pam_unix(sudo:session): session closed for user root Sep 08 16:37:14 volumio-mac systemd[1]: Stopped Music Player Daemon. Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 16:37:14 volumio-mac sudo[2808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 16:37:14 volumio-mac sudo[2808]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:37:14 volumio-mac volumio[2612]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 16:37:14 volumio-mac volumio[2612]: info: [1757363834722] CoreMusicLibrary::Adding element Last_100 Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 16:37:14 volumio-mac volumio[2612]: info: [1757363834723] CoreMusicLibrary::Adding element Webradio Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 16:37:14 volumio-mac volumio[2612]: info: Initializing BBC Radios Sep 08 16:37:14 volumio-mac systemd[1]: Starting Music Player Daemon... Sep 08 16:37:14 volumio-mac systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 08 16:37:14 volumio-mac systemd[1]: mpd.service: Succeeded. Sep 08 16:37:14 volumio-mac systemd[1]: Stopped Music Player Daemon. Sep 08 16:37:14 volumio-mac systemd[1]: Starting Music Player Daemon... Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 16:37:14 volumio-mac sudo[2818]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 08 16:37:14 volumio-mac sudo[2818]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:37:14 volumio-mac volumio[2612]: info: Creating Spotify config file Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:37:14 volumio-mac sudo[2818]: pam_unix(sudo:session): session closed for user root Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 16:37:14 volumio-mac volumio[2612]: info: [1757363834784] CoreMusicLibrary::Adding element YouTube Music Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 16:37:14 volumio-mac volumio[2612]: Cannot find translation for source YouTube Music Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 16:37:14 volumio-mac volumio[2612]: info: [1757363834786] CoreMusicLibrary::Adding element 80s80s Radio Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 16:37:14 volumio-mac volumio[2612]: Cannot find translation for source YouTube Music Sep 08 16:37:14 volumio-mac volumio[2612]: Cannot find translation for source 80s80s Radio Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 16:37:14 volumio-mac volumio[2612]: info: [1757363834787] CoreMusicLibrary::Adding element Radio Paradise Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 16:37:14 volumio-mac volumio[2612]: Cannot find translation for source YouTube Music Sep 08 16:37:14 volumio-mac volumio[2612]: Cannot find translation for source 80s80s Radio Sep 08 16:37:14 volumio-mac volumio[2612]: Cannot find translation for source Radio Paradise Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 16:37:14 volumio-mac volumio[2612]: info: [1757363834788] CoreMusicLibrary::Adding element SWARM Station Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 16:37:14 volumio-mac volumio[2612]: Cannot find translation for source YouTube Music Sep 08 16:37:14 volumio-mac volumio[2612]: Cannot find translation for source 80s80s Radio Sep 08 16:37:14 volumio-mac volumio[2612]: Cannot find translation for source Radio Paradise Sep 08 16:37:14 volumio-mac volumio[2612]: Cannot find translation for source SWARM Station Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 16:37:14 volumio-mac volumio[2612]: info: [1757363834865] CoreMusicLibrary::Adding element LastFM Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 16:37:14 volumio-mac volumio[2612]: Cannot find translation for source YouTube Music Sep 08 16:37:14 volumio-mac volumio[2612]: Cannot find translation for source 80s80s Radio Sep 08 16:37:14 volumio-mac volumio[2612]: Cannot find translation for source Radio Paradise Sep 08 16:37:14 volumio-mac volumio[2612]: Cannot find translation for source SWARM Station Sep 08 16:37:14 volumio-mac volumio[2612]: Cannot find translation for source LastFM Sep 08 16:37:14 volumio-mac volumio[2612]: info: [LastFM] scrobbler initiated! Sep 08 16:37:14 volumio-mac volumio[2612]: info: [LastFM] extended logging: false Sep 08 16:37:14 volumio-mac volumio[2612]: info: [LastFM] try scrobble stream/radio plays: false Sep 08 16:37:14 volumio-mac volumio[2612]: info: [LastFM] Left init routine Sep 08 16:37:14 volumio-mac volumio[2612]: info: [LastFM] Socket already connected: true Sep 08 16:37:14 volumio-mac volumio[2612]: info: Volumio Calling Home Sep 08 16:37:14 volumio-mac sudo[2855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Sep 08 16:37:14 volumio-mac sudo[2855]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreStateMachine::setRepeat null single undefined Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreStateMachine::pushState Sep 08 16:37:14 volumio-mac volumio[2612]: info: CorePlayQueue::getTrack 0 Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::volumioPushState Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreStateMachine::setRandom false Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreStateMachine::pushState Sep 08 16:37:14 volumio-mac volumio[2612]: info: CorePlayQueue::getTrack 0 Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::volumioPushState Sep 08 16:37:14 volumio-mac volumio[2612]: info: MPD Permissions set Sep 08 16:37:14 volumio-mac volumio[2612]: info: MPD Permissions set Sep 08 16:37:14 volumio-mac volumio[2612]: info: Spotify config file written Sep 08 16:37:14 volumio-mac sudo[2875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 08 16:37:14 volumio-mac sudo[2875]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:37:14 volumio-mac volumio[2612]: info: VolumeController:: Volume=100 Mute =false Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::volumioGetState Sep 08 16:37:14 volumio-mac volumio[2612]: info: CorePlayQueue::getTrack 0 Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreStateMachine::pushState Sep 08 16:37:14 volumio-mac volumio[2612]: info: CorePlayQueue::getTrack 0 Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::volumioPushState Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:37:14 volumio-mac systemd[1]: Stopping go-librespot Daemon... Sep 08 16:37:14 volumio-mac systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 08 16:37:14 volumio-mac systemd[1]: go-librespot-daemon.service: Succeeded. Sep 08 16:37:14 volumio-mac systemd[1]: Stopped go-librespot Daemon. Sep 08 16:37:14 volumio-mac systemd[1]: Started go-librespot Daemon. Sep 08 16:37:14 volumio-mac sudo[2875]: pam_unix(sudo:session): session closed for user root Sep 08 16:37:14 volumio-mac go-librespot[2878]: go-librespot daemon starting... Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 08 16:37:14 volumio-mac go-librespot[2878]: time="2025-09-08T16:37:14-04:00" level=info msg="running go-librespot 0.2.0" Sep 08 16:37:14 volumio-mac go-librespot[2878]: time="2025-09-08T16:37:14-04:00" level=debug msg="app state loaded" Sep 08 16:37:14 volumio-mac go-librespot[2878]: time="2025-09-08T16:37:14-04:00" level=debug msg="stored credentials not found" Sep 08 16:37:14 volumio-mac go-librespot[2878]: time="2025-09-08T16:37:14-04:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:37:14 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:37:15 volumio-mac mpd[2828]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 08 16:37:15 volumio-mac mpd[2828]: output: No 'audio_output' defined in config file Sep 08 16:37:15 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 16:37:15 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 08 16:37:15 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:37:15 volumio-mac volumio[2612]: info: No need to fix Spotify hosts Sep 08 16:37:15 volumio-mac sudo[2855]: pam_unix(sudo:session): session closed for user root Sep 08 16:37:15 volumio-mac volumio[2612]: info: Starting Shairport Sync Sep 08 16:37:15 volumio-mac volumio[2612]: info: Starting Shairport Sync Sep 08 16:37:15 volumio-mac sudo[2901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Sep 08 16:37:15 volumio-mac sudo[2901]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:37:15 volumio-mac sudo[2903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 16:37:15 volumio-mac sudo[2903]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:37:15 volumio-mac mpd[2828]: output: Successfully detected a sndio audio device Sep 08 16:37:15 volumio-mac volumio[2612]: info: Starting Shairport Sync Sep 08 16:37:15 volumio-mac mpd[2828]: zeroconf: No global port, disabling zeroconf Sep 08 16:37:15 volumio-mac systemd[1]: Started Music Player Daemon. Sep 08 16:37:15 volumio-mac sudo[2798]: pam_unix(sudo:session): session closed for user root Sep 08 16:37:15 volumio-mac sudo[2808]: pam_unix(sudo:session): session closed for user root Sep 08 16:37:15 volumio-mac sudo[2909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 16:37:15 volumio-mac sudo[2909]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:37:15 volumio-mac sudo[2915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 16:37:15 volumio-mac sudo[2915]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:37:15 volumio-mac volumio[2612]: error: MPD error: The expression evaluated to a falsy value: Sep 08 16:37:15 volumio-mac volumio[2612]: assert.ok(self.idling) Sep 08 16:37:15 volumio-mac volumio[2612]: error: The expression evaluated to a falsy value: Sep 08 16:37:15 volumio-mac volumio[2612]: assert.ok(self.idling) Sep 08 16:37:15 volumio-mac volumio[2612]: error: updateQueue error: null Sep 08 16:37:15 volumio-mac volumio[2612]: info: MPD running with PID2828 Sep 08 16:37:15 volumio-mac volumio[2612]: ,establishing connection Sep 08 16:37:15 volumio-mac volumio[2612]: error: updateQueue error: null Sep 08 16:37:15 volumio-mac systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Sep 08 16:37:15 volumio-mac systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 16:37:15 volumio-mac systemd[1]: shairport-sync.service: Succeeded. Sep 08 16:37:15 volumio-mac systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 16:37:15 volumio-mac systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 08 16:37:15 volumio-mac sudo[2903]: pam_unix(sudo:session): session closed for user root Sep 08 16:37:15 volumio-mac sudo[2909]: pam_unix(sudo:session): session closed for user root Sep 08 16:37:15 volumio-mac volumio[2612]: info: Shairport-Sync Started Sep 08 16:37:15 volumio-mac sudo[2915]: pam_unix(sudo:session): session closed for user root Sep 08 16:37:15 volumio-mac volumio[2612]: Error adding Membership: Error: addMembership EINVAL Sep 08 16:37:15 volumio-mac volumio[2612]: info: Shairport-Sync Started Sep 08 16:37:15 volumio-mac volumio[2612]: info: Shairport-Sync Started Sep 08 16:37:15 volumio-mac volumio[2914]: cset: --> shielding system active with Sep 08 16:37:15 volumio-mac volumio[2914]: cset: "system" cpuset of CPUSPEC(1-7) with 272 tasks running Sep 08 16:37:15 volumio-mac volumio[2914]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Sep 08 16:37:15 volumio-mac systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Sep 08 16:37:15 volumio-mac systemd[1]: Reloading. Sep 08 16:37:15 volumio-mac systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 08 16:37:15 volumio-mac sudo[2901]: pam_unix(sudo:session): session closed for user root Sep 08 16:37:15 volumio-mac volumio[2612]: info: succeeded Sep 08 16:37:16 volumio-mac volumio[2612]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Sep 08 16:37:16 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 08 16:37:16 volumio-mac volumio[2612]: info: CoreCommandRouter::volumioGetVisibleSources Sep 08 16:37:16 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 16:37:16 volumio-mac volumio[2612]: info: CoreCommandRouter::volumioGetState Sep 08 16:37:16 volumio-mac volumio[2612]: info: CorePlayQueue::getTrack 0 Sep 08 16:37:16 volumio-mac volumio[2612]: info: CoreCommandRouter::volumioGetState Sep 08 16:37:16 volumio-mac volumio[2612]: info: CorePlayQueue::getTrack 0 Sep 08 16:37:16 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 08 16:37:16 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 08 16:37:16 volumio-mac volumio[2612]: info: Received Get System Info Sep 08 16:37:16 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 16:37:16 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 16:37:16 volumio-mac volumio[2612]: info: Discovery: Getting this device information Sep 08 16:37:16 volumio-mac volumio[2612]: info: CoreCommandRouter::volumioGetState Sep 08 16:37:16 volumio-mac volumio[2612]: info: CorePlayQueue::getTrack 0 Sep 08 16:37:16 volumio-mac volumio[2612]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 16:37:16 volumio-mac volumio[2612]: info: CoreCommandRouter::volumioGetState Sep 08 16:37:16 volumio-mac volumio[2612]: info: CorePlayQueue::getTrack 0 Sep 08 16:37:16 volumio-mac volumio[2612]: info: Listing playlists Sep 08 16:37:16 volumio-mac volumio-time-update[906]: volumio-time-update-util: Date not found in response Sep 08 16:37:16 volumio-mac volumio-time-update[906]: volumio-time-update-util: Retrying in 5 seconds... Sep 08 16:37:16 volumio-mac sudo[2653]: pam_unix(sudo:session): session closed for user root Sep 08 16:37:18 volumio-mac volumio[2612]: info: go-librespot daemon successfully initialized Sep 08 16:37:18 volumio-mac sudo[2666]: pam_unix(sudo:session): session closed for user root Sep 08 16:37:18 volumio-mac kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 08 16:37:18 volumio-mac kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Sep 08 16:37:18 volumio-mac volumio[2612]: info: Cannot mount NAS happyshowNAS at system boot, trial number 1 ,retrying in 5 seconds Sep 08 16:37:20 volumio-mac mpd[2828]: exception: No database Sep 08 16:37:20 volumio-mac mpd[2828]: exception: No database Sep 08 16:37:20 volumio-mac mpd[2828]: exception: No database Sep 08 16:37:20 volumio-mac volumio[2612]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Sep 08 16:37:21 volumio-mac volumio[2612]: info: Initializing connection to go-librespot Websocket Sep 08 16:37:21 volumio-mac volumio-remote-updater[922]: [2025-09-08 16:37:21] [connect] Successful connection Sep 08 16:37:21 volumio-mac volumio-remote-updater[922]: [2025-09-08 16:37:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757363841 101 Sep 08 16:37:21 volumio-mac volumio[2612]: 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: 3 Sep 08 16:37:21 volumio-mac volumio-time-update[906]: volumio-time-update-util: Fetching time from Volumio... Sep 08 16:37:22 volumio-mac sudo[3014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 08 16:37:22 volumio-mac sudo[3014]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:37:22 volumio-mac sudo[3014]: pam_unix(sudo:session): session closed for user root Sep 08 16:37:22 volumio-mac sudo[3017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 08 16:37:22 volumio-mac sudo[3017]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:37:22 volumio-mac sudo[3017]: pam_unix(sudo:session): session closed for user root Sep 08 16:37:22 volumio-mac sudo[3020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 08 16:37:22 volumio-mac sudo[3020]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:37:22 volumio-mac sudo[3020]: pam_unix(sudo:session): session closed for user root Sep 08 16:37:22 volumio-mac volumio[2612]: info: Upmpdcli Daemon Started Sep 08 16:37:23 volumio-mac sudo[3023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Music,password=QkPK+myDyxRwA6u,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.15/Music /mnt/NAS/happyshowNAS Sep 08 16:37:23 volumio-mac sudo[3023]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:37:23 volumio-mac kernel: CIFS: Attempting to mount //192.168.50.15/Music Sep 08 16:37:28 volumio-mac ntpd[1100]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 08 16:37:29 volumio-mac sudo[3023]: pam_unix(sudo:session): session closed for user root Sep 08 16:37:29 volumio-mac volumio[2612]: info: Cannot mount NAS happyshowNAS at system boot, trial number 2 ,retrying in 5 seconds Sep 08 16:37:29 volumio-mac kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 08 16:37:29 volumio-mac kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Sep 08 16:37:34 volumio-mac sudo[3047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Music,password=QkPK+myDyxRwA6u,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.15/Music /mnt/NAS/happyshowNAS Sep 08 16:37:34 volumio-mac sudo[3047]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:37:34 volumio-mac kernel: CIFS: Attempting to mount //192.168.50.15/Music Sep 08 16:37:34 volumio-mac volumio[2612]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Sep 08 16:37:34 volumio-mac volumio[2612]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 08 16:37:34 volumio-mac volumio[2612]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 16:37:34 volumio-mac volumio[2612]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Sep 08 16:37:34 volumio-mac volumio[2612]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Sep 08 16:37:34 volumio-mac volumio[2612]: errno: -3007, Sep 08 16:37:34 volumio-mac volumio[2612]: code: 'ENOTFOUND', Sep 08 16:37:34 volumio-mac volumio[2612]: syscall: 'getaddrinfo', Sep 08 16:37:34 volumio-mac volumio[2612]: hostname: 'ws.audioscrobbler.com' Sep 08 16:37:34 volumio-mac volumio[2612]: } Sep 08 16:37:34 volumio-mac volumio[2612]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 16:37:35 volumio-mac sudo[3060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-08 16:36 Sep 08 16:37:35 volumio-mac sudo[3060]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:37:35 volumio-mac go-librespot[2878]: time="2025-09-08T16:37:35-04: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" Sep 08 16:37:35 volumio-mac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 16:37:35 volumio-mac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 16:37:35 volumio-mac sudo[3060]: pam_unix(sudo:session): session closed for user root Sep 08 16:37:38 volumio-mac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 16:37:38 volumio-mac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Sep 08 16:37:38 volumio-mac systemd[1]: Stopped go-librespot Daemon. Sep 08 16:37:38 volumio-mac systemd[1]: Started go-librespot Daemon. Sep 08 16:37:38 volumio-mac go-librespot[3067]: go-librespot daemon starting... Sep 08 16:37:38 volumio-mac go-librespot[3067]: time="2025-09-08T16:37:38-04:00" level=info msg="running go-librespot 0.2.0" Sep 08 16:37:38 volumio-mac go-librespot[3067]: time="2025-09-08T16:37:38-04:00" level=debug msg="app state loaded" Sep 08 16:37:38 volumio-mac go-librespot[3067]: time="2025-09-08T16:37:38-04:00" level=debug msg="stored credentials not found" Sep 08 16:37:38 volumio-mac go-librespot[3067]: time="2025-09-08T16:37:38-04:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 08 16:37:40 volumio-mac sudo[3047]: pam_unix(sudo:session): session closed for user root Sep 08 16:37:40 volumio-mac kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 08 16:37:40 volumio-mac kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Sep 08 16:37:41 volumio-mac volumio-time-update[906]: volumio-time-update-util: Date not found in response Sep 08 16:37:41 volumio-mac volumio-time-update[906]: volumio-time-update-util: Retrying in 5 seconds... Sep 08 16:37:46 volumio-mac volumio-time-update[906]: volumio-time-update-util: Fetching time from Volumio... Sep 08 16:37:48 volumio-mac ntpd[1100]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 08 16:37:51 volumio-mac volumio-remote-updater[922]: [2025-09-08 16:37:51] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Sep 08 16:37:56 volumio-mac volumio-remote-updater[922]: [2025-09-08 16:37:56] [connect] Successful connection Sep 08 16:37:58 volumio-mac go-librespot[3067]: time="2025-09-08T16:37:58-04: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" Sep 08 16:37:58 volumio-mac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 16:37:58 volumio-mac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 16:38:01 volumio-mac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 16:38:01 volumio-mac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Sep 08 16:38:01 volumio-mac systemd[1]: Stopped go-librespot Daemon. Sep 08 16:38:01 volumio-mac systemd[1]: Started go-librespot Daemon. Sep 08 16:38:01 volumio-mac go-librespot[3112]: go-librespot daemon starting... Sep 08 16:38:01 volumio-mac go-librespot[3112]: time="2025-09-08T16:38:01-04:00" level=info msg="running go-librespot 0.2.0" Sep 08 16:38:01 volumio-mac go-librespot[3112]: time="2025-09-08T16:38:01-04:00" level=debug msg="app state loaded" Sep 08 16:38:01 volumio-mac go-librespot[3112]: time="2025-09-08T16:38:01-04:00" level=debug msg="stored credentials not found" Sep 08 16:38:01 volumio-mac go-librespot[3112]: time="2025-09-08T16:38:01-04:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 08 16:38:06 volumio-mac volumio-time-update[906]: volumio-time-update-util: Date not found in response Sep 08 16:38:06 volumio-mac volumio-time-update[906]: volumio-time-update-util: Retrying in 5 seconds... Sep 08 16:38:08 volumio-mac ntpd[1100]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 08 16:38:08 volumio-mac volumio-remote-updater[922]: [2025-09-08 16:38:08] [connect] Successful connection Sep 08 16:38:11 volumio-mac volumio-time-update[906]: volumio-time-update-util: Fetching time from Volumio... Sep 08 16:38:14 volumio-mac systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 08 16:38:14 volumio-mac systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 08 16:38:14 volumio-mac systemd[1]: Started dynamicswap service. Sep 08 16:38:14 volumio-mac systemd[1]: dynamicswap.service: Succeeded. Sep 08 16:38:15 volumio-mac systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 08 16:38:15 volumio-mac systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Sep 08 16:38:15 volumio-mac systemd[1]: Started dynamicswap service. Sep 08 16:38:15 volumio-mac systemd[1]: Stopped Volumio Backend Module. Sep 08 16:38:15 volumio-mac systemd[1]: Started Volumio Backend Module. Sep 08 16:38:15 volumio-mac systemd[1]: dynamicswap.service: Succeeded. Sep 08 16:38:15 volumio-mac volumio[3162]: info: ------------------------------------------- Sep 08 16:38:15 volumio-mac volumio[3162]: info: ----- Volumio3 ---- Sep 08 16:38:15 volumio-mac volumio[3162]: info: ------------------------------------------- Sep 08 16:38:15 volumio-mac volumio[3162]: info: ----- System startup ---- Sep 08 16:38:15 volumio-mac volumio[3162]: info: ------------------------------------------- Sep 08 16:38:15 volumio-mac volumio[3162]: info: MYVOLUMIO Environment detected Sep 08 16:38:15 volumio-mac volumio[3162]: info: Plugin folders cleanup Sep 08 16:38:15 volumio-mac volumio[3162]: info: Scanning into folder /volumio/app/plugins/ Sep 08 16:38:15 volumio-mac volumio[3162]: info: Scanning category audio_interface Sep 08 16:38:15 volumio-mac volumio[3162]: info: Scanning category miscellanea Sep 08 16:38:15 volumio-mac volumio[3162]: info: Scanning category music_service Sep 08 16:38:15 volumio-mac volumio[3162]: info: Scanning category plugins.json Sep 08 16:38:15 volumio-mac volumio[3162]: info: Scanning category system_controller Sep 08 16:38:15 volumio-mac volumio[3162]: info: Scanning category user_interface Sep 08 16:38:15 volumio-mac volumio[3162]: info: Scanning into folder /data/plugins/ Sep 08 16:38:15 volumio-mac volumio[3162]: info: Scanning category music_service Sep 08 16:38:15 volumio-mac volumio[3162]: info: Scanning category system_controller Sep 08 16:38:15 volumio-mac volumio[3162]: info: Scanning category system_hardware Sep 08 16:38:15 volumio-mac volumio[3162]: info: Scanning category user_interface Sep 08 16:38:15 volumio-mac volumio[3162]: info: Plugin folders cleanup completed Sep 08 16:38:15 volumio-mac volumio[3162]: info: ------------------------------------------- Sep 08 16:38:15 volumio-mac volumio[3162]: info: ----- Core plugins startup ---- Sep 08 16:38:15 volumio-mac volumio[3162]: info: ------------------------------------------- Sep 08 16:38:15 volumio-mac volumio[3162]: info: Loading plugins from folder /volumio/app/plugins/ Sep 08 16:38:15 volumio-mac volumio[3162]: info: Adding plugin upnp to MyMusic Plugins Sep 08 16:38:15 volumio-mac volumio[3162]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 08 16:38:15 volumio-mac volumio[3162]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 08 16:38:15 volumio-mac volumio[3162]: info: Loading plugins from folder /data/plugins/ Sep 08 16:38:15 volumio-mac volumio[3162]: info: Loading plugin "system"... Sep 08 16:38:15 volumio-mac volumio[3162]: info: Loading plugin "appearance"... Sep 08 16:38:15 volumio-mac volumio[3162]: info: Loading plugin "network"... Sep 08 16:38:15 volumio-mac volumio[3162]: info: Refreshing Cached IP Addresses Sep 08 16:38:15 volumio-mac sudo[3188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 08 16:38:15 volumio-mac sudo[3188]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:38:15 volumio-mac sudo[3188]: pam_unix(sudo:session): session closed for user root Sep 08 16:38:15 volumio-mac sudo[3190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 08 16:38:15 volumio-mac sudo[3190]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:38:15 volumio-mac sudo[3190]: pam_unix(sudo:session): session closed for user root Sep 08 16:38:15 volumio-mac volumio[3162]: info: Loading plugin "services"... Sep 08 16:38:15 volumio-mac volumio[3162]: info: Loading plugin "alsa_controller"... Sep 08 16:38:15 volumio-mac sudo[3199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 08 16:38:15 volumio-mac sudo[3199]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:38:15 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 16:38:15 volumio-mac volumio[3162]: info: Loading plugin "wizard"... Sep 08 16:38:15 volumio-mac volumio[3162]: info: Loading plugin "networkfs"... Sep 08 16:38:15 volumio-mac volumio[3162]: info: Starting Udev Watcher for removable devices Sep 08 16:38:15 volumio-mac sudo[3216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Music,password=QkPK+myDyxRwA6u,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.15/Music /mnt/NAS/happyshowNAS Sep 08 16:38:15 volumio-mac sudo[3216]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:38:15 volumio-mac volumio[3162]: info: Ignoring mount for partition: boot Sep 08 16:38:15 volumio-mac volumio[3162]: info: Ignoring mount for partition: volumio Sep 08 16:38:15 volumio-mac volumio[3162]: info: Ignoring mount for partition: volumio_data Sep 08 16:38:15 volumio-mac kernel: CIFS: Attempting to mount //192.168.50.15/Music Sep 08 16:38:15 volumio-mac volumio[3162]: info: Ignoring mount for partition: volumioHD Sep 08 16:38:15 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 16:38:15 volumio-mac volumio[3162]: info: Loading plugin "volumio_command_line_client"... Sep 08 16:38:15 volumio-mac volumio[3162]: info: Loading plugin "upnp"... Sep 08 16:38:15 volumio-mac volumio[3162]: info: [1757363895870] Starting Upmpd Daemon Sep 08 16:38:15 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 16:38:15 volumio-mac volumio[3162]: info: Loading plugin "my_music"... Sep 08 16:38:15 volumio-mac volumio[3162]: info: Loading plugin "mpd"... Sep 08 16:38:15 volumio-mac volumio[3162]: info: Plugin upnp_browser is not enabled Sep 08 16:38:15 volumio-mac volumio[3162]: info: Loading plugin "alarm-clock"... Sep 08 16:38:15 volumio-mac volumio[3162]: info: Loading plugin "airplay_emulation"... Sep 08 16:38:15 volumio-mac volumio[3162]: info: Starting Shairport Sync Sep 08 16:38:15 volumio-mac volumio[3162]: info: Loading plugin "last_100"... Sep 08 16:38:15 volumio-mac volumio[3162]: info: Loading plugin "webradio"... Sep 08 16:38:15 volumio-mac volumio[3162]: info: Loading plugin "i2s_dacs"... Sep 08 16:38:15 volumio-mac volumio[3162]: info: I2S DAC not set, start Auto-detection Sep 08 16:38:16 volumio-mac volumio[3162]: info: Loading plugin "volumiodiscovery"... Sep 08 16:38:16 volumio-mac volumio[3162]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 08 16:38:16 volumio-mac node[3162]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 08 16:38:16 volumio-mac node[3162]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 16:38:16 volumio-mac volumio[3162]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 16:38:16 volumio-mac volumio[3162]: *** WARNING *** For more information see Sep 08 16:38:16 volumio-mac volumio[3162]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 08 16:38:16 volumio-mac volumio[3162]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 16:38:16 volumio-mac volumio[3162]: *** WARNING *** For more information see Sep 08 16:38:16 volumio-mac node[3162]: *** WARNING *** For more information see Sep 08 16:38:16 volumio-mac node[3162]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 08 16:38:16 volumio-mac node[3162]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 16:38:16 volumio-mac node[3162]: *** WARNING *** For more information see Sep 08 16:38:16 volumio-mac volumio[3162]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 08 16:38:16 volumio-mac volumio[3162]: info: Discovery: Started advertising with name: Volumio-Mac Sep 08 16:38:16 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 16:38:16 volumio-mac volumio[3162]: info: Loading plugin "spop"... Sep 08 16:38:16 volumio-mac volumio[3162]: info: Loading plugin "ytcr"... Sep 08 16:38:16 volumio-mac volumio[3162]: info: Loading plugin "ytmusic"... Sep 08 16:38:17 volumio-mac volumio[3162]: info: Loading plugin "outputs"... Sep 08 16:38:17 volumio-mac volumio[3162]: info: Loading plugin "albumart"... Sep 08 16:38:17 volumio-mac volumio[3162]: info: Plugin example_plugin is not enabled Sep 08 16:38:17 volumio-mac volumio[3162]: info: Loading plugin "inputs"... Sep 08 16:38:17 volumio-mac volumio[3162]: info: Loading plugin "updater_comm"... Sep 08 16:38:17 volumio-mac volumio[3162]: info: Plugin mpdemulation is not enabled Sep 08 16:38:17 volumio-mac volumio[3162]: info: Loading plugin "rest_api"... Sep 08 16:38:17 volumio-mac volumio[3162]: info: Loading plugin "websocket"... Sep 08 16:38:17 volumio-mac volumio[3162]: info: Starting Socket.io Server version 2.3.0 Sep 08 16:38:17 volumio-mac volumio[3162]: info: Loading plugin "80s80s"... Sep 08 16:38:17 volumio-mac volumio[3162]: Forking 7 albumart workers Sep 08 16:38:17 volumio-mac volumio[3162]: info: Applying required configuration parameters for plugin 80s80s Sep 08 16:38:17 volumio-mac volumio[3162]: info: [1757363897549] [80s80s] API delay: 30 Sep 08 16:38:17 volumio-mac volumio[3162]: info: Loading plugin "radio_paradise"... Sep 08 16:38:17 volumio-mac volumio[3162]: info: Applying required configuration parameters for plugin radio_paradise Sep 08 16:38:17 volumio-mac volumio[3162]: info: [1757363897607] [RadioParadise] API delay: 5 Sep 08 16:38:17 volumio-mac volumio[3162]: info: Loading plugin "swarmstation"... Sep 08 16:38:17 volumio-mac volumio[3162]: Starting albumart workers Sep 08 16:38:17 volumio-mac volumio[3162]: info: Applying required configuration parameters for plugin swarmstation Sep 08 16:38:17 volumio-mac volumio[3162]: info: [1757363897667] [Swarmstation] API delay: 5 Sep 08 16:38:17 volumio-mac volumio[3162]: info: Loading plugin "backup_restore"... Sep 08 16:38:17 volumio-mac volumio[3162]: Starting albumart workers Sep 08 16:38:17 volumio-mac volumio[3162]: Starting albumart workers Sep 08 16:38:17 volumio-mac volumio[3162]: Starting albumart workers Sep 08 16:38:17 volumio-mac volumio[3162]: Starting albumart workers Sep 08 16:38:17 volumio-mac volumio[3162]: Starting albumart workers Sep 08 16:38:17 volumio-mac volumio[3162]: Starting albumart workers Sep 08 16:38:17 volumio-mac volumio[3162]: info: Applying required configuration parameters for plugin backup_restore Sep 08 16:38:17 volumio-mac volumio[3162]: info: Loading plugin "music_services_shield"... Sep 08 16:38:17 volumio-mac volumio[3162]: info: Applying required configuration parameters for plugin music_services_shield Sep 08 16:38:17 volumio-mac volumio[3162]: info: Loading plugin "Systeminfo"... Sep 08 16:38:17 volumio-mac volumio[3162]: info: Loading plugin "lastfm"... Sep 08 16:38:18 volumio-mac volumio[3162]: info: Loading i18n strings for locale en Sep 08 16:38:18 volumio-mac volumio[3162]: Updating browse sources language Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::initPlayerControls Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: Express server listening on port 3000 Sep 08 16:38:18 volumio-mac volumio[3162]: [Metrics] WebUI: 2s 728.85ms Sep 08 16:38:18 volumio-mac volumio[3162]: info: Setting Device type: x86 Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreStateMachine::resetVolumioState Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreStateMachine::getcurrentVolume Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::volumioRetrievevolume Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 08 16:38:18 volumio-mac volumio[3162]: info: Completed loading Core Plugins Sep 08 16:38:18 volumio-mac volumio[3162]: info: Preparing to generate the ALSA configuration file Sep 08 16:38:18 volumio-mac volumio[3162]: info: Volumio Network Manager: Network status updated: 3 Sep 08 16:38:18 volumio-mac volumio[3162]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Sep 08 16:38:18 volumio-mac volumio[3162]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Sep 08 16:38:18 volumio-mac volumio[3162]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 08 16:38:18 volumio-mac volumio[3162]: info: Reloading queue from file Sep 08 16:38:18 volumio-mac volumio[3162]: info: Asound.conf file unchanged, so no further update is needed Sep 08 16:38:18 volumio-mac volumio[3162]: info: Output device has changed, restarting MPD Sep 08 16:38:18 volumio-mac sudo[3345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 16:38:18 volumio-mac sudo[3345]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:38:18 volumio-mac sudo[3345]: pam_unix(sudo:session): session closed for user root Sep 08 16:38:18 volumio-mac volumio[3162]: info: Output device has changed, restarting Shairport Sync Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 16:38:18 volumio-mac sudo[3349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 16:38:18 volumio-mac sudo[3349]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:38:18 volumio-mac systemd[1]: musicservicesshield.service: Succeeded. Sep 08 16:38:18 volumio-mac systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Sep 08 16:38:18 volumio-mac systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Sep 08 16:38:18 volumio-mac systemd[1]: Stopping Music Player Daemon... Sep 08 16:38:18 volumio-mac systemd[1]: mpd.service: Succeeded. Sep 08 16:38:18 volumio-mac systemd[1]: Stopped Music Player Daemon. Sep 08 16:38:18 volumio-mac volumio[3162]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 16:38:18 volumio-mac volumio[3162]: info: ___________ START PLUGINS ___________ Sep 08 16:38:18 volumio-mac volumio[3162]: info: ControllerMpd::onStart: Initializing MPD Sep 08 16:38:18 volumio-mac volumio[3162]: info: Creating MPD Configuration file Sep 08 16:38:18 volumio-mac sudo[3356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 16:38:18 volumio-mac sudo[3356]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 16:38:18 volumio-mac sudo[3356]: pam_unix(sudo:session): session closed for user root Sep 08 16:38:18 volumio-mac systemd[1]: Starting Music Player Daemon... Sep 08 16:38:18 volumio-mac sudo[3359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 16:38:18 volumio-mac sudo[3359]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:38:18 volumio-mac systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 08 16:38:18 volumio-mac systemd[1]: mpd.service: Succeeded. Sep 08 16:38:18 volumio-mac systemd[1]: Stopped Music Player Daemon. Sep 08 16:38:18 volumio-mac systemd[1]: Starting Music Player Daemon... Sep 08 16:38:18 volumio-mac volumio[3162]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 16:38:18 volumio-mac volumio[3162]: info: [1757363898224] CoreMusicLibrary::Adding element Last_100 Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 16:38:18 volumio-mac volumio[3162]: info: [1757363898225] CoreMusicLibrary::Adding element Webradio Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 16:38:18 volumio-mac volumio[3162]: info: Initializing BBC Radios Sep 08 16:38:18 volumio-mac sudo[3365]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 08 16:38:18 volumio-mac sudo[3365]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:38:18 volumio-mac sudo[3365]: pam_unix(sudo:session): session closed for user root Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: Creating Spotify config file Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 16:38:18 volumio-mac volumio[3162]: info: [1757363898286] CoreMusicLibrary::Adding element YouTube Music Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 16:38:18 volumio-mac volumio[3162]: Cannot find translation for source YouTube Music Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 16:38:18 volumio-mac volumio[3162]: info: [1757363898287] CoreMusicLibrary::Adding element 80s80s Radio Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 16:38:18 volumio-mac volumio[3162]: Cannot find translation for source YouTube Music Sep 08 16:38:18 volumio-mac volumio[3162]: Cannot find translation for source 80s80s Radio Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 16:38:18 volumio-mac volumio[3162]: info: [1757363898289] CoreMusicLibrary::Adding element Radio Paradise Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 16:38:18 volumio-mac volumio[3162]: Cannot find translation for source YouTube Music Sep 08 16:38:18 volumio-mac volumio[3162]: Cannot find translation for source 80s80s Radio Sep 08 16:38:18 volumio-mac volumio[3162]: Cannot find translation for source Radio Paradise Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 16:38:18 volumio-mac volumio[3162]: info: [1757363898290] CoreMusicLibrary::Adding element SWARM Station Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 16:38:18 volumio-mac volumio[3162]: Cannot find translation for source YouTube Music Sep 08 16:38:18 volumio-mac volumio[3162]: Cannot find translation for source 80s80s Radio Sep 08 16:38:18 volumio-mac volumio[3162]: Cannot find translation for source Radio Paradise Sep 08 16:38:18 volumio-mac volumio[3162]: Cannot find translation for source SWARM Station Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 16:38:18 volumio-mac volumio[3162]: info: [1757363898365] CoreMusicLibrary::Adding element LastFM Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 16:38:18 volumio-mac volumio[3162]: Cannot find translation for source YouTube Music Sep 08 16:38:18 volumio-mac volumio[3162]: Cannot find translation for source 80s80s Radio Sep 08 16:38:18 volumio-mac volumio[3162]: Cannot find translation for source Radio Paradise Sep 08 16:38:18 volumio-mac volumio[3162]: Cannot find translation for source SWARM Station Sep 08 16:38:18 volumio-mac volumio[3162]: Cannot find translation for source LastFM Sep 08 16:38:18 volumio-mac volumio[3162]: info: [LastFM] scrobbler initiated! Sep 08 16:38:18 volumio-mac volumio[3162]: info: [LastFM] extended logging: false Sep 08 16:38:18 volumio-mac volumio[3162]: info: [LastFM] try scrobble stream/radio plays: false Sep 08 16:38:18 volumio-mac volumio[3162]: info: [LastFM] Left init routine Sep 08 16:38:18 volumio-mac volumio[3162]: info: [LastFM] Socket already connected: true Sep 08 16:38:18 volumio-mac volumio[3162]: info: Volumio Calling Home Sep 08 16:38:18 volumio-mac sudo[3406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Sep 08 16:38:18 volumio-mac sudo[3406]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreStateMachine::setRepeat null single undefined Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreStateMachine::pushState Sep 08 16:38:18 volumio-mac volumio[3162]: info: CorePlayQueue::getTrack 0 Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::volumioPushState Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreStateMachine::setRandom false Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreStateMachine::pushState Sep 08 16:38:18 volumio-mac volumio[3162]: info: CorePlayQueue::getTrack 0 Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::volumioPushState Sep 08 16:38:18 volumio-mac volumio[3162]: info: VolumeController:: Volume=100 Mute =false Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::volumioGetState Sep 08 16:38:18 volumio-mac volumio[3162]: info: CorePlayQueue::getTrack 0 Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreStateMachine::pushState Sep 08 16:38:18 volumio-mac volumio[3162]: info: CorePlayQueue::getTrack 0 Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::volumioPushState Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreStateMachine::updateTrackBlock Sep 08 16:38:18 volumio-mac volumio[3162]: info: CorePlayQueue::getTrackBlock Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::volumioRetrievevolume Sep 08 16:38:18 volumio-mac volumio[3162]: info: MPD Permissions set Sep 08 16:38:18 volumio-mac volumio[3162]: info: MPD Permissions set Sep 08 16:38:18 volumio-mac volumio[3162]: info: Spotify config file written Sep 08 16:38:18 volumio-mac sudo[3427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 08 16:38:18 volumio-mac sudo[3427]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:38:18 volumio-mac systemd[1]: Stopping go-librespot Daemon... Sep 08 16:38:18 volumio-mac systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 08 16:38:18 volumio-mac systemd[1]: go-librespot-daemon.service: Succeeded. Sep 08 16:38:18 volumio-mac systemd[1]: Stopped go-librespot Daemon. Sep 08 16:38:18 volumio-mac mpd[3371]: Sep 08 16:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 08 16:38:18 volumio-mac systemd[1]: Started go-librespot Daemon. Sep 08 16:38:18 volumio-mac go-librespot[3429]: go-librespot daemon starting... Sep 08 16:38:18 volumio-mac sudo[3427]: pam_unix(sudo:session): session closed for user root Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac go-librespot[3429]: time="2025-09-08T16:38:18-04:00" level=info msg="running go-librespot 0.2.0" Sep 08 16:38:18 volumio-mac go-librespot[3429]: time="2025-09-08T16:38:18-04:00" level=debug msg="app state loaded" Sep 08 16:38:18 volumio-mac go-librespot[3429]: time="2025-09-08T16:38:18-04:00" level=debug msg="stored credentials not found" Sep 08 16:38:18 volumio-mac go-librespot[3429]: time="2025-09-08T16:38:18-04:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 08 16:38:18 volumio-mac sudo[3406]: pam_unix(sudo:session): session closed for user root Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 16:38:18 volumio-mac volumio[3162]: info: No need to fix Spotify hosts Sep 08 16:38:18 volumio-mac volumio[3162]: info: VolumeController:: Volume=100 Mute =false Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::volumioGetState Sep 08 16:38:18 volumio-mac volumio[3162]: info: CorePlayQueue::getTrack 0 Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreStateMachine::pushState Sep 08 16:38:18 volumio-mac volumio[3162]: info: CorePlayQueue::getTrack 0 Sep 08 16:38:18 volumio-mac volumio[3162]: info: CoreCommandRouter::volumioPushState Sep 08 16:38:18 volumio-mac volumio[3162]: info: Starting Shairport Sync Sep 08 16:38:18 volumio-mac volumio[3162]: info: Starting Shairport Sync Sep 08 16:38:18 volumio-mac sudo[3453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Sep 08 16:38:18 volumio-mac sudo[3453]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:38:18 volumio-mac sudo[3455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 16:38:18 volumio-mac sudo[3455]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:38:18 volumio-mac volumio[3162]: info: Starting Shairport Sync Sep 08 16:38:18 volumio-mac systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 16:38:18 volumio-mac sudo[3461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 16:38:18 volumio-mac systemd[1]: shairport-sync.service: Succeeded. Sep 08 16:38:18 volumio-mac sudo[3461]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:38:18 volumio-mac systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 16:38:18 volumio-mac sudo[3465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 16:38:18 volumio-mac sudo[3465]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:38:18 volumio-mac systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 08 16:38:18 volumio-mac sudo[3455]: pam_unix(sudo:session): session closed for user root Sep 08 16:38:18 volumio-mac volumio[3162]: info: Shairport-Sync Started Sep 08 16:38:18 volumio-mac volumio[3162]: Error adding Membership: Error: addMembership EINVAL Sep 08 16:38:18 volumio-mac systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 16:38:18 volumio-mac systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 08 16:38:18 volumio-mac systemd[1]: shairport-sync.service: Succeeded. Sep 08 16:38:18 volumio-mac systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 16:38:18 volumio-mac systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 08 16:38:18 volumio-mac sudo[3461]: pam_unix(sudo:session): session closed for user root Sep 08 16:38:18 volumio-mac sudo[3465]: pam_unix(sudo:session): session closed for user root Sep 08 16:38:18 volumio-mac volumio[3162]: info: Shairport-Sync Started Sep 08 16:38:18 volumio-mac volumio[3162]: info: Shairport-Sync Started Sep 08 16:38:18 volumio-mac systemd[1]: Started Music Player Daemon. Sep 08 16:38:18 volumio-mac sudo[3349]: pam_unix(sudo:session): session closed for user root Sep 08 16:38:18 volumio-mac sudo[3359]: pam_unix(sudo:session): session closed for user root Sep 08 16:38:18 volumio-mac systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Sep 08 16:38:18 volumio-mac volumio[3162]: error: MPD error: The expression evaluated to a falsy value: Sep 08 16:38:18 volumio-mac volumio[3162]: assert.ok(self.idling) Sep 08 16:38:18 volumio-mac volumio[3162]: error: The expression evaluated to a falsy value: Sep 08 16:38:18 volumio-mac volumio[3162]: assert.ok(self.idling) Sep 08 16:38:18 volumio-mac volumio[3162]: error: updateQueue error: null Sep 08 16:38:18 volumio-mac volumio[3162]: info: MPD running with PID3371 Sep 08 16:38:18 volumio-mac volumio[3162]: ,establishing connection Sep 08 16:38:18 volumio-mac volumio[3162]: error: updateQueue error: null Sep 08 16:38:18 volumio-mac volumio[3472]: cset: --> shielding system active with Sep 08 16:38:18 volumio-mac volumio[3472]: cset: "system" cpuset of CPUSPEC(1-7) with 264 tasks running Sep 08 16:38:18 volumio-mac volumio[3472]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Sep 08 16:38:18 volumio-mac systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Sep 08 16:38:18 volumio-mac systemd[1]: Reloading. Sep 08 16:38:18 volumio-mac systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 08 16:38:19 volumio-mac sudo[3453]: pam_unix(sudo:session): session closed for user root Sep 08 16:38:19 volumio-mac volumio[3162]: info: succeeded Sep 08 16:38:20 volumio-mac sudo[3199]: pam_unix(sudo:session): session closed for user root Sep 08 16:38:21 volumio-mac volumio[3162]: info: go-librespot daemon successfully initialized Sep 08 16:38:21 volumio-mac volumio[3162]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Sep 08 16:38:21 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 08 16:38:21 volumio-mac volumio[3162]: info: CoreCommandRouter::volumioGetVisibleSources Sep 08 16:38:21 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 16:38:21 volumio-mac volumio[3162]: info: CoreCommandRouter::volumioGetState Sep 08 16:38:21 volumio-mac volumio[3162]: info: CorePlayQueue::getTrack 0 Sep 08 16:38:21 volumio-mac volumio[3162]: info: CoreCommandRouter::volumioGetState Sep 08 16:38:21 volumio-mac volumio[3162]: info: CorePlayQueue::getTrack 0 Sep 08 16:38:21 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 08 16:38:21 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 08 16:38:21 volumio-mac volumio[3162]: info: Received Get System Info Sep 08 16:38:21 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 16:38:21 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 16:38:21 volumio-mac volumio[3162]: info: Discovery: Getting this device information Sep 08 16:38:21 volumio-mac volumio[3162]: info: CoreCommandRouter::volumioGetState Sep 08 16:38:21 volumio-mac volumio[3162]: info: CorePlayQueue::getTrack 0 Sep 08 16:38:21 volumio-mac volumio[3162]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 16:38:21 volumio-mac volumio[3162]: info: CoreCommandRouter::volumioGetState Sep 08 16:38:21 volumio-mac volumio[3162]: info: CorePlayQueue::getTrack 0 Sep 08 16:38:21 volumio-mac volumio[3162]: info: Listing playlists Sep 08 16:38:22 volumio-mac sudo[3216]: pam_unix(sudo:session): session closed for user root Sep 08 16:38:22 volumio-mac kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 08 16:38:22 volumio-mac kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Sep 08 16:38:22 volumio-mac volumio[3162]: info: Cannot mount NAS happyshowNAS at system boot, trial number 1 ,retrying in 5 seconds Sep 08 16:38:23 volumio-mac volumio-remote-updater[922]: [2025-09-08 16:38:23] [connect] Successful connection Sep 08 16:38:23 volumio-mac volumio-remote-updater[922]: [2025-09-08 16:38:23] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757363903 101 Sep 08 16:38:23 volumio-mac volumio[3162]: 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: 4 Sep 08 16:38:23 volumio-mac volumio[3162]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 08 16:38:24 volumio-mac volumio[3162]: info: Initializing connection to go-librespot Websocket Sep 08 16:38:25 volumio-mac sudo[3566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 08 16:38:25 volumio-mac sudo[3566]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:38:25 volumio-mac sudo[3566]: pam_unix(sudo:session): session closed for user root Sep 08 16:38:25 volumio-mac sudo[3569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 08 16:38:25 volumio-mac sudo[3569]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:38:25 volumio-mac sudo[3569]: pam_unix(sudo:session): session closed for user root Sep 08 16:38:27 volumio-mac sudo[3572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Music,password=QkPK+myDyxRwA6u,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.15/Music /mnt/NAS/happyshowNAS Sep 08 16:38:27 volumio-mac sudo[3572]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:38:27 volumio-mac kernel: CIFS: Attempting to mount //192.168.50.15/Music Sep 08 16:38:27 volumio-mac sudo[3577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 08 16:38:27 volumio-mac sudo[3577]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:38:27 volumio-mac sudo[3577]: pam_unix(sudo:session): session closed for user root Sep 08 16:38:28 volumio-mac volumio[3162]: Cannot compose Albumart path Sep 08 16:38:28 volumio-mac ntpd[1100]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 08 16:38:28 volumio-mac volumio[3162]: info: Upmpdcli Daemon Started Sep 08 16:38:31 volumio-mac volumio-time-update[906]: volumio-time-update-util: Date not found in response Sep 08 16:38:31 volumio-mac volumio-time-update[906]: volumio-time-update-util: Retrying in 5 seconds... Sep 08 16:38:33 volumio-mac sudo[3572]: pam_unix(sudo:session): session closed for user root Sep 08 16:38:33 volumio-mac volumio[3162]: info: Cannot mount NAS happyshowNAS at system boot, trial number 2 ,retrying in 5 seconds Sep 08 16:38:33 volumio-mac kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 08 16:38:33 volumio-mac kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Sep 08 16:38:34 volumio-mac dhcpcd[1093]: eth0: offered 192.168.50.21 from 192.168.50.1 Sep 08 16:38:34 volumio-mac dhcpcd[1093]: eth0: probing address 192.168.50.21/24 Sep 08 16:38:36 volumio-mac volumio-time-update[906]: volumio-time-update-util: Fetching time from Volumio... Sep 08 16:38:38 volumio-mac sudo[3600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Music,password=QkPK+myDyxRwA6u,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.15/Music /mnt/NAS/happyshowNAS Sep 08 16:38:38 volumio-mac sudo[3600]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:38:38 volumio-mac volumio[3162]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Sep 08 16:38:38 volumio-mac kernel: CIFS: Attempting to mount //192.168.50.15/Music Sep 08 16:38:38 volumio-mac volumio[3162]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 08 16:38:38 volumio-mac volumio[3162]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 16:38:38 volumio-mac volumio[3162]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Sep 08 16:38:38 volumio-mac volumio[3162]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Sep 08 16:38:38 volumio-mac volumio[3162]: errno: -3007, Sep 08 16:38:38 volumio-mac volumio[3162]: code: 'ENOTFOUND', Sep 08 16:38:38 volumio-mac volumio[3162]: syscall: 'getaddrinfo', Sep 08 16:38:38 volumio-mac volumio[3162]: hostname: 'ws.audioscrobbler.com' Sep 08 16:38:38 volumio-mac volumio[3162]: } Sep 08 16:38:38 volumio-mac volumio[3162]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 16:38:38 volumio-mac dhcpcd[1378]: eth0: offered 192.168.50.21 from 192.168.50.1 Sep 08 16:38:38 volumio-mac dhcpcd[1378]: eth0: probing address 192.168.50.21/24 Sep 08 16:38:38 volumio-mac sudo[3613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-08 16:37 Sep 08 16:38:38 volumio-mac sudo[3613]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 16:38:38 volumio-mac go-librespot[3429]: time="2025-09-08T16:38:38-04: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" Sep 08 16:38:38 volumio-mac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 16:38:38 volumio-mac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="95d9088e5dcb3a55aabfe7f209b9ae00946f3ec5" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Aug 2025 06:37:19 PM CEST" VOLUMIO_VERSION="3.839" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="3616d4b5f30ddd7d34bf1bdb3bca86e3"