-- Logs begin at Thu 2019-02-14 02:11:59 PST, end at Thu 2025-07-03 20:09:16 PDT. -- Jul 03 20:08:50 volumio systemd-timedated[1002]: Changed local time to Thu Jul 3 20:08:50 2025 Jul 03 20:08:50 volumio sudo[1000]: pam_unix(sudo:session): session closed for user root Jul 03 20:08:50 volumio volumio-time-update[582]: volumio-time-update-util: System time updated successfully. Jul 03 20:08:50 volumio systemd[1]: Starting Daily apt download activities... Jul 03 20:08:50 volumio systemd[1]: Started Volumio Time Update Utility. Jul 03 20:08:50 volumio nmbd[688]: [2025/07/03 20:08:50.436984, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 03 20:08:50 volumio systemd[1]: Started Samba NMB Daemon. Jul 03 20:08:50 volumio nmbd[688]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jul 03 20:08:50 volumio systemd[1]: Starting Samba Winbind Daemon... Jul 03 20:08:50 volumio winbindd[1013]: [2025/07/03 20:08:50.777646, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jul 03 20:08:50 volumio winbindd[1013]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jul 03 20:08:50 volumio winbindd[1013]: [2025/07/03 20:08:50.799929, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 03 20:08:50 volumio winbindd[1013]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jul 03 20:08:50 volumio systemd[1]: Started Samba Winbind Daemon. Jul 03 20:08:50 volumio systemd[1]: Starting Samba SMB Daemon... Jul 03 20:08:51 volumio systemd[1]: apt-daily.service: Succeeded. Jul 03 20:08:51 volumio systemd[1]: Started Daily apt download activities. Jul 03 20:08:51 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Jul 03 20:08:51 volumio volumio[967]: info: MYVOLUMIO Environment detected Jul 03 20:08:51 volumio smbd[1041]: [2025/07/03 20:08:51.486656, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 03 20:08:51 volumio smbd[1041]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 03 20:08:51 volumio systemd[1]: Started Samba SMB Daemon. Jul 03 20:08:51 volumio systemd[1]: Reached target Multi-User System. Jul 03 20:08:51 volumio systemd[1]: Reached target Graphical Interface. Jul 03 20:08:51 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 03 20:08:51 volumio sh[526]: timed out Jul 03 20:08:51 volumio dhcpcd[545]: timed out Jul 03 20:08:51 volumio sh[526]: dhcpcd exited Jul 03 20:08:51 volumio dhcpcd[545]: dhcpcd exited Jul 03 20:08:51 volumio sh[526]: ifup: failed to bring up eth0 Jul 03 20:08:51 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jul 03 20:08:51 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jul 03 20:08:51 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 03 20:08:51 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 03 20:08:51 volumio volumio[967]: info: Plugin folders cleanup Jul 03 20:08:51 volumio volumio[967]: info: Scanning into folder /volumio/app/plugins/ Jul 03 20:08:51 volumio volumio[967]: info: Scanning category audio_interface Jul 03 20:08:51 volumio volumio[967]: info: Scanning category miscellanea Jul 03 20:08:51 volumio volumio[967]: info: Scanning category music_service Jul 03 20:08:51 volumio volumio[967]: info: Scanning category plugins.json Jul 03 20:08:51 volumio volumio[967]: info: Scanning category system_controller Jul 03 20:08:51 volumio volumio[967]: info: Scanning category user_interface Jul 03 20:08:51 volumio volumio[967]: info: Scanning into folder /data/plugins/ Jul 03 20:08:51 volumio volumio[967]: info: Scanning category music_service Jul 03 20:08:51 volumio volumio[967]: info: Scanning category user_interface Jul 03 20:08:51 volumio volumio[967]: info: Plugin folders cleanup completed Jul 03 20:08:51 volumio volumio[967]: info: ------------------------------------------- Jul 03 20:08:51 volumio volumio[967]: info: ----- Core plugins startup ---- Jul 03 20:08:51 volumio volumio[967]: info: ------------------------------------------- Jul 03 20:08:51 volumio volumio[967]: info: Loading plugins from folder /volumio/app/plugins/ Jul 03 20:08:51 volumio volumio[967]: info: Adding plugin upnp to MyMusic Plugins Jul 03 20:08:51 volumio volumio[967]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 03 20:08:51 volumio volumio[967]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 03 20:08:51 volumio volumio[967]: info: Loading plugins from folder /data/plugins/ Jul 03 20:08:51 volumio volumio[967]: info: Loading plugin "system"... Jul 03 20:08:51 volumio volumio[967]: info: Loading plugin "appearance"... Jul 03 20:08:52 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Jul 03 20:08:52 volumio systemd[1]: Started Daily apt upgrade and clean activities. Jul 03 20:08:52 volumio systemd[1]: Startup finished in 15.778s (kernel) + 37.873s (userspace) = 53.652s. Jul 03 20:08:53 volumio kernel: hwmon hwmon1: Undervoltage detected! Jul 03 20:08:54 volumio volumio[967]: info: Loading plugin "network"... Jul 03 20:08:54 volumio volumio[967]: info: Refreshing Cached IP Addresses Jul 03 20:08:54 volumio sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 03 20:08:54 volumio sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 20:08:54 volumio sudo[1123]: pam_unix(sudo:session): session closed for user root Jul 03 20:08:54 volumio sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 20:08:54 volumio sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 20:08:54 volumio sudo[1125]: pam_unix(sudo:session): session closed for user root Jul 03 20:08:54 volumio volumio[967]: info: Loading plugin "services"... Jul 03 20:08:54 volumio volumio[967]: info: Loading plugin "alsa_controller"... Jul 03 20:08:54 volumio sudo[1134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 03 20:08:54 volumio sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 20:08:54 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jul 03 20:08:54 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 20:08:54 volumio volumio[967]: info: Loading plugin "wizard"... Jul 03 20:08:54 volumio volumio[967]: info: Loading plugin "networkfs"... Jul 03 20:08:55 volumio volumio[967]: info: Starting Udev Watcher for removable devices Jul 03 20:08:55 volumio sudo[1153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=pesca,password=Eaibele33#ms1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.170/Music /mnt/NAS/NAS Jul 03 20:08:55 volumio sudo[1153]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 20:08:55 volumio volumio[967]: info: Ignoring mount for partition: boot Jul 03 20:08:55 volumio volumio[967]: info: Ignoring mount for partition: volumio Jul 03 20:08:55 volumio volumio[967]: info: Ignoring mount for partition: volumio_data Jul 03 20:08:55 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 20:08:55 volumio volumio[967]: info: Loading plugin "volumio_command_line_client"... Jul 03 20:08:55 volumio volumio[967]: info: Loading plugin "upnp"... Jul 03 20:08:55 volumio volumio[967]: info: [1751598535151] Starting Upmpd Daemon Jul 03 20:08:55 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 20:08:55 volumio volumio[967]: info: Loading plugin "my_music"... Jul 03 20:08:55 volumio volumio[967]: info: Loading plugin "mpd"... Jul 03 20:08:55 volumio volumio-remote-updater[578]: [2025-07-03 20:08:55] [connect] Successful connection Jul 03 20:08:55 volumio kernel: Key type cifs.spnego registered Jul 03 20:08:55 volumio kernel: Key type cifs.idmap registered Jul 03 20:08:55 volumio 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 03 20:08:55 volumio kernel: CIFS: Attempting to mount //192.168.50.170/Music Jul 03 20:08:55 volumio kernel: cryptd: max_cpu_qlen set to 1000 Jul 03 20:08:56 volumio volumio[967]: info: Loading plugin "upnp_browser"... Jul 03 20:08:56 volumio sudo[1153]: pam_unix(sudo:session): session closed for user root Jul 03 20:08:57 volumio volumio[967]: info: Loading plugin "alarm-clock"... Jul 03 20:08:57 volumio volumio[967]: info: Loading plugin "airplay_emulation"... Jul 03 20:08:57 volumio volumio[967]: info: Starting Shairport Sync Jul 03 20:08:57 volumio volumio[967]: info: Loading plugin "last_100"... Jul 03 20:08:57 volumio volumio[967]: info: Loading plugin "webradio"... Jul 03 20:08:57 volumio volumio[967]: info: Loading plugin "i2s_dacs"... Jul 03 20:08:57 volumio volumio[967]: info: I2S DAC not set, start Auto-detection Jul 03 20:08:57 volumio volumio[967]: info: Loading plugin "volumiodiscovery"... Jul 03 20:08:58 volumio volumio[967]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 03 20:08:58 volumio volumio[967]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 20:08:58 volumio volumio[967]: *** WARNING *** For more information see Jul 03 20:08:58 volumio node[967]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 03 20:08:58 volumio volumio[967]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 03 20:08:58 volumio volumio[967]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 20:08:58 volumio volumio[967]: *** WARNING *** For more information see Jul 03 20:08:58 volumio node[967]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 20:08:58 volumio node[967]: *** WARNING *** For more information see Jul 03 20:08:58 volumio node[967]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 03 20:08:58 volumio node[967]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 20:08:58 volumio node[967]: *** WARNING *** For more information see Jul 03 20:08:58 volumio volumio[967]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 03 20:08:58 volumio volumio[967]: info: Discovery: Started advertising with name: Volumio Jul 03 20:08:58 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 20:08:58 volumio volumio[967]: info: Loading plugin "calmradio"... Jul 03 20:08:58 volumio wpa_supplicant[849]: wlan0: CTRL-EVENT-DISCONNECTED bssid=3c:7c:3f:b7:c1:c1 reason=2 Jul 03 20:08:58 volumio dhcpcd[861]: wlan0: carrier lost Jul 03 20:08:58 volumio wpa_supplicant[849]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 03 20:08:58 volumio wpa_supplicant[849]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US Jul 03 20:08:58 volumio avahi-daemon[603]: Withdrawing address record for 192.168.50.190 on wlan0. Jul 03 20:08:58 volumio avahi-daemon[603]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.50.190. Jul 03 20:08:58 volumio dhcpcd[861]: wlan0: deleting route to 192.168.50.0/24 Jul 03 20:08:58 volumio dhcpcd[861]: wlan0: deleting default route via 192.168.50.1 Jul 03 20:08:58 volumio avahi-daemon[603]: Interface wlan0.IPv4 no longer relevant for mDNS. Jul 03 20:08:59 volumio volumio[967]: info: Loading plugin "spop"... Jul 03 20:09:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jul 03 20:09:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jul 03 20:09:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jul 03 20:09:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jul 03 20:09:00 volumio volumio[967]: info: Loading plugin "youtube2"... Jul 03 20:09:01 volumio kernel: hwmon hwmon1: Voltage normalised Jul 03 20:09:02 volumio ntpd[741]: Deleting interface #3 wlan0, 192.168.50.190#123, interface stats: received=0, sent=0, dropped=0, active_time=12 secs Jul 03 20:09:03 volumio sudo[1134]: pam_unix(sudo:session): session closed for user root Jul 03 20:09:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jul 03 20:09:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jul 03 20:09:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jul 03 20:09:04 volumio volumio[967]: info: Loading plugin "ytcr"... Jul 03 20:09:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jul 03 20:09:07 volumio wpa_supplicant[849]: wlan0: Trying to associate with SSID 'Gava' Jul 03 20:09:07 volumio dhcpcd[861]: wlan0: carrier acquired Jul 03 20:09:07 volumio wpa_supplicant[849]: wlan0: Associated with 3c:7c:3f:b7:c1:c4 Jul 03 20:09:07 volumio wpa_supplicant[849]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3c:7c:3f:b7:c1:c4 completed [id=0 id_str=] Jul 03 20:09:07 volumio wpa_supplicant[849]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 03 20:09:07 volumio wpa_supplicant[849]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Jul 03 20:09:07 volumio dhcpcd[861]: wlan0: IAID eb:b4:82:6b Jul 03 20:09:07 volumio dhcpcd[861]: wlan0: carrier lost Jul 03 20:09:07 volumio dhcpcd[861]: wlan0: carrier acquired Jul 03 20:09:07 volumio dhcpcd[861]: wlan0: IAID eb:b4:82:6b Jul 03 20:09:07 volumio volumio[967]: info: Loading plugin "now_playing"... Jul 03 20:09:07 volumio dhcpcd[861]: wlan0: soliciting an IPv6 router Jul 03 20:09:07 volumio dhcpcd[861]: wlan0: soliciting a DHCP lease Jul 03 20:09:09 volumio volumio[967]: info: Loading plugin "outputs"... Jul 03 20:09:09 volumio volumio[967]: info: Loading plugin "albumart"... Jul 03 20:09:09 volumio volumio[967]: info: Plugin example_plugin is not enabled Jul 03 20:09:09 volumio volumio[967]: info: Loading plugin "inputs"... Jul 03 20:09:09 volumio volumio[967]: info: Loading plugin "updater_comm"... Jul 03 20:09:09 volumio volumio[967]: info: Plugin mpdemulation is not enabled Jul 03 20:09:09 volumio volumio[967]: info: Loading plugin "rest_api"... Jul 03 20:09:09 volumio volumio[967]: info: Loading plugin "websocket"... Jul 03 20:09:09 volumio volumio[967]: info: Starting Socket.io Server version 2.3.0 Jul 03 20:09:09 volumio volumio[967]: info: Loading plugin "80s80s"... Jul 03 20:09:09 volumio volumio[967]: Forking 3 albumart workers Jul 03 20:09:09 volumio volumio[967]: info: Applying required configuration parameters for plugin 80s80s Jul 03 20:09:09 volumio volumio[967]: info: [1751598549903] [80s80s] API delay: 30 Jul 03 20:09:09 volumio volumio[967]: info: Loading plugin "pandora"... Jul 03 20:09:10 volumio volumio-remote-updater[578]: [2025-07-03 20:09:10] [connect] Successful connection Jul 03 20:09:10 volumio volumio[967]: Starting albumart workers Jul 03 20:09:10 volumio volumio[967]: Starting albumart workers Jul 03 20:09:10 volumio volumio[967]: Starting albumart workers Jul 03 20:09:10 volumio dhcpcd[861]: wlan0: offered 192.168.50.190 from 192.168.50.1 Jul 03 20:09:10 volumio dhcpcd[861]: wlan0: probing address 192.168.50.190/24 Jul 03 20:09:11 volumio volumio[967]: info: Applying required configuration parameters for plugin pandora Jul 03 20:09:11 volumio volumio[967]: info: Loading plugin "Systeminfo"... Jul 03 20:09:12 volumio volumio[967]: info: Loading plugin "lastfm"... Jul 03 20:09:12 volumio volumio[967]: info: Loading plugin "randomizer"... Jul 03 20:09:13 volumio volumio[967]: info: Applying required configuration parameters for plugin randomizer Jul 03 20:09:13 volumio volumio[967]: info: Loading i18n strings for locale en Jul 03 20:09:13 volumio volumio[967]: Updating browse sources language Jul 03 20:09:13 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 20:09:13 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 20:09:13 volumio volumio[967]: info: CoreCommandRouter::initPlayerControls Jul 03 20:09:13 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 20:09:13 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 20:09:13 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 20:09:13 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 20:09:13 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 20:09:13 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 20:09:13 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 20:09:13 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 20:09:13 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 20:09:13 volumio volumio[967]: Express server listening on port 3000 Jul 03 20:09:13 volumio volumio[967]: [Metrics] WebUI: 24s 859.95ms Jul 03 20:09:13 volumio volumio[967]: info: CoreStateMachine::resetVolumioState Jul 03 20:09:13 volumio volumio[967]: info: CoreStateMachine::getcurrentVolume Jul 03 20:09:13 volumio volumio[967]: info: CoreCommandRouter::volumioRetrievevolume Jul 03 20:09:13 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 20:09:13 volumio sudo[1310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 03 20:09:13 volumio sudo[1310]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 20:09:13 volumio sudo[1310]: pam_unix(sudo:session): session closed for user root Jul 03 20:09:13 volumio sudo[1313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 20:09:13 volumio sudo[1313]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 20:09:13 volumio sudo[1313]: pam_unix(sudo:session): session closed for user root Jul 03 20:09:13 volumio volumio[967]: info: Volumio Network Manager: Network status updated: 2 Jul 03 20:09:14 volumio volumio-remote-updater[578]: [2025-07-03 20:09:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751598550 101 Jul 03 20:09:14 volumio volumio[967]: 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: 1 Jul 03 20:09:14 volumio volumio[967]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 03 20:09:14 volumio volumio[967]: info: VolumeController:: Volume=27 Mute =false Jul 03 20:09:14 volumio volumio[967]: info: CoreStateMachine::pushState Jul 03 20:09:14 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Jul 03 20:09:14 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:09:14 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Jul 03 20:09:14 volumio volumio[967]: info: CoreStateMachine::updateTrackBlock Jul 03 20:09:14 volumio volumio[967]: info: CorePlayQueue::getTrackBlock Jul 03 20:09:14 volumio volumio[967]: info: CoreCommandRouter::volumioRetrievevolume Jul 03 20:09:14 volumio volumio[967]: info: Reloading queue from file Jul 03 20:09:14 volumio volumio[967]: info: CoreStateMachine::setRepeat null single undefined Jul 03 20:09:14 volumio volumio[967]: info: CoreStateMachine::pushState Jul 03 20:09:14 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Jul 03 20:09:14 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:09:14 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Jul 03 20:09:14 volumio volumio[967]: info: CoreStateMachine::setRandom true Jul 03 20:09:14 volumio volumio[967]: info: CoreStateMachine::pushState Jul 03 20:09:14 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Jul 03 20:09:14 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:09:14 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Jul 03 20:09:14 volumio volumio[967]: info: Setting Device type: Raspberry PI Jul 03 20:09:14 volumio volumio[967]: info: Discovery: A device disappeared from network Jul 03 20:09:14 volumio volumio[967]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Jul 03 20:09:14 volumio sudo[1324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 03 20:09:14 volumio sudo[1324]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 20:09:14 volumio volumio[967]: info: VolumeController:: Volume=27 Mute =false Jul 03 20:09:14 volumio volumio[967]: info: CoreStateMachine::pushState Jul 03 20:09:14 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Jul 03 20:09:14 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Jul 03 20:09:14 volumio volumio[967]: info: Completed loading Core Plugins Jul 03 20:09:14 volumio volumio[967]: info: Preparing to generate the ALSA configuration file Jul 03 20:09:14 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jul 03 20:09:14 volumio sudo[1324]: pam_unix(sudo:session): session closed for user root Jul 03 20:09:14 volumio volumio[967]: info: Upmpdcli Daemon Started Jul 03 20:09:14 volumio volumio[967]: info: Asound.conf file unchanged, so no further update is needed Jul 03 20:09:14 volumio volumio[967]: info: Output device has changed, restarting MPD Jul 03 20:09:14 volumio sudo[1328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 03 20:09:14 volumio sudo[1328]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 20:09:14 volumio sudo[1328]: pam_unix(sudo:session): session closed for user root Jul 03 20:09:14 volumio volumio[967]: info: Output device has changed, restarting Shairport Sync Jul 03 20:09:14 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 20:09:14 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 20:09:14 volumio sudo[1331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 03 20:09:14 volumio sudo[1331]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 20:09:14 volumio systemd[1]: Stopping Music Player Daemon... Jul 03 20:09:14 volumio volumio[967]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 20:09:14 volumio volumio[967]: info: ___________ START PLUGINS ___________ Jul 03 20:09:14 volumio volumio[967]: info: ControllerMpd::onStart: Initializing MPD Jul 03 20:09:14 volumio volumio[967]: info: Creating MPD Configuration file Jul 03 20:09:14 volumio sudo[1337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 03 20:09:14 volumio sudo[1337]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 20:09:14 volumio sudo[1337]: pam_unix(sudo:session): session closed for user root Jul 03 20:09:14 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 20:09:14 volumio volumio[967]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 20:09:14 volumio volumio[967]: info: [1751598554830] CoreMusicLibrary::Adding element Media Servers Jul 03 20:09:14 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 20:09:14 volumio sudo[1348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 03 20:09:14 volumio sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 20:09:14 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 20:09:14 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 20:09:14 volumio systemd[1]: mpd.service: Succeeded. Jul 03 20:09:14 volumio systemd[1]: Stopped Music Player Daemon. Jul 03 20:09:14 volumio volumio[967]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 20:09:14 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 20:09:14 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 20:09:14 volumio systemd[1]: Starting Music Player Daemon... Jul 03 20:09:14 volumio volumio[967]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 20:09:14 volumio volumio[967]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 20:09:14 volumio volumio[967]: info: [1751598554995] CoreMusicLibrary::Adding element Last_100 Jul 03 20:09:14 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 20:09:14 volumio volumio[967]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 20:09:15 volumio volumio[967]: info: [1751598554999] CoreMusicLibrary::Adding element Webradio Jul 03 20:09:15 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 20:09:15 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 20:09:15 volumio volumio[967]: info: Initializing BBC Radios Jul 03 20:09:15 volumio sudo[1359]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 03 20:09:15 volumio sudo[1359]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 20:09:15 volumio sudo[1359]: pam_unix(sudo:session): session closed for user root Jul 03 20:09:15 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 20:09:15 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 20:09:15 volumio volumio[967]: info: Adding Calm Radio to Browse Sources Jul 03 20:09:15 volumio volumio[967]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 20:09:15 volumio volumio[967]: info: [1751598555091] CoreMusicLibrary::Adding element Calm Radio Jul 03 20:09:15 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 20:09:15 volumio volumio[967]: Cannot find translation for source Calm Radio Jul 03 20:09:15 volumio volumio[967]: info: Creating Spotify config file Jul 03 20:09:15 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 20:09:15 volumio volumio[967]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 20:09:15 volumio volumio[967]: info: [1751598555197] CoreMusicLibrary::Adding element YouTube2 Jul 03 20:09:15 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 20:09:15 volumio volumio[967]: Cannot find translation for source Calm Radio Jul 03 20:09:15 volumio volumio[967]: Cannot find translation for source YouTube2 Jul 03 20:09:15 volumio volumio[967]: info: [now-playing] ConfigUpdater: config is up to date. Jul 03 20:09:15 volumio volumio[967]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 20:09:15 volumio volumio[967]: info: [1751598555288] CoreMusicLibrary::Adding element 80s80s Radio Jul 03 20:09:15 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 20:09:15 volumio volumio[967]: Cannot find translation for source Calm Radio Jul 03 20:09:15 volumio volumio[967]: Cannot find translation for source YouTube2 Jul 03 20:09:15 volumio volumio[967]: Cannot find translation for source 80s80s Radio Jul 03 20:09:15 volumio volumio[967]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 20:09:15 volumio volumio[967]: info: [1751598555295] CoreMusicLibrary::Adding element Pandora Radio Jul 03 20:09:15 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 20:09:15 volumio volumio[967]: Cannot find translation for source Calm Radio Jul 03 20:09:15 volumio volumio[967]: Cannot find translation for source YouTube2 Jul 03 20:09:15 volumio volumio[967]: Cannot find translation for source 80s80s Radio Jul 03 20:09:15 volumio volumio[967]: Cannot find translation for source Pandora Radio Jul 03 20:09:15 volumio volumio[967]: info: [1751598555299] ControllerPandora::initializeMQTT Jul 03 20:09:15 volumio volumio[967]: info: [1751598555300] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Jul 03 20:09:15 volumio volumio[967]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 20:09:15 volumio volumio[967]: info: [1751598555303] CoreMusicLibrary::Adding element LastFM Jul 03 20:09:15 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 20:09:15 volumio volumio[967]: Cannot find translation for source Calm Radio Jul 03 20:09:15 volumio volumio[967]: Cannot find translation for source YouTube2 Jul 03 20:09:15 volumio volumio[967]: Cannot find translation for source 80s80s Radio Jul 03 20:09:15 volumio volumio[967]: Cannot find translation for source Pandora Radio Jul 03 20:09:15 volumio volumio[967]: Cannot find translation for source LastFM Jul 03 20:09:15 volumio volumio[967]: info: [LastFM] scrobbler initiated! Jul 03 20:09:15 volumio volumio[967]: info: [LastFM] extended logging: false Jul 03 20:09:15 volumio volumio[967]: info: [LastFM] try scrobble stream/radio plays: true Jul 03 20:09:15 volumio volumio[967]: info: [LastFM] Left init routine Jul 03 20:09:15 volumio volumio[967]: info: [LastFM] Socket already connected: true Jul 03 20:09:15 volumio volumio[967]: info: Volumio Calling Home Jul 03 20:09:15 volumio volumio[967]: info: [1751598555435] ControllerPandora::initialSetup Jul 03 20:09:15 volumio volumio[967]: info: [1751598555440] ControllerPandora::PandoraHandler::init Jul 03 20:09:15 volumio volumio[967]: info: [1751598555441] ControllerPandora::PandoraHandler::loadErrorCodes Jul 03 20:09:15 volumio volumio[967]: info: [1751598555455] [Pandora] PandoraHandler::setMaxStationTracks: 16 Jul 03 20:09:15 volumio volumio[967]: info: [1751598555456] [Pandora] PandoraHandler::setBandFilter: bandfilter is empty Jul 03 20:09:15 volumio volumio[967]: info: [1751598555457] ControllerPandora::flushPandora Jul 03 20:09:15 volumio volumio[967]: info: CorePlayQueue::getQueue Jul 03 20:09:15 volumio volumio[967]: info: CorePlayQueue::clearAddPlayQueue Jul 03 20:09:15 volumio volumio[967]: info: CorePlayQueue::saveQueue Jul 03 20:09:15 volumio volumio[967]: info: CoreCommandRouter::serviceClearAddPlayTracks Jul 03 20:09:15 volumio volumio[967]: error: WARNING: No clearAddPlayTracks method for service spop Jul 03 20:09:15 volumio volumio[967]: info: CoreCommandRouter::volumioPushQueue Jul 03 20:09:15 volumio volumio[967]: info: [1751598555491] ControllerPandora::validateAndSetAccountOptions Jul 03 20:09:15 volumio volumio[967]: info: [1751598555493] ControllerPandora::PandoraHandler::setAccountOptions Jul 03 20:09:15 volumio volumio[967]: info: [1751598555496] ControllerPandora::flushPandora Jul 03 20:09:15 volumio volumio[967]: info: CorePlayQueue::getQueue Jul 03 20:09:15 volumio volumio[967]: info: CorePlayQueue::clearAddPlayQueue Jul 03 20:09:15 volumio volumio[967]: info: CorePlayQueue::saveQueue Jul 03 20:09:15 volumio volumio[967]: info: CoreCommandRouter::serviceClearAddPlayTracks Jul 03 20:09:15 volumio volumio[967]: error: WARNING: No clearAddPlayTracks method for service spop Jul 03 20:09:15 volumio volumio[967]: info: CoreCommandRouter::volumioPushQueue Jul 03 20:09:15 volumio volumio[967]: info: [1751598555520] [Pandora] PreventAuthTimeout::init: Timer loaded Jul 03 20:09:15 volumio volumio[967]: info: [1751598555520] [Pandora] PreventAuthTimeout::init: Interval set to 10800000 ms Jul 03 20:09:15 volumio volumio[967]: info: [1751598555521] [Pandora] PreventAuthTimeout::init: Starting delayed timer after next interval Jul 03 20:09:15 volumio volumio[967]: info: [1751598555522] ControllerPandora::PandoraHandler::pandoraLoginAndGetStations Jul 03 20:09:15 volumio volumio[967]: info: [now-playing] Fetching API key... Jul 03 20:09:15 volumio volumio[967]: info: [now-playing] App is listening on port 4004. Jul 03 20:09:15 volumio volumio[967]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jul 03 20:09:15 volumio volumio[967]: Unhandled rejection Error: No sockets available, cannot start. Jul 03 20:09:15 volumio volumio[967]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 03 20:09:15 volumio volumio[967]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 03 20:09:15 volumio volumio[967]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 03 20:09:15 volumio volumio[967]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 03 20:09:15 volumio volumio[967]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 03 20:09:15 volumio volumio[967]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 03 20:09:15 volumio volumio[967]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 03 20:09:15 volumio volumio[967]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 03 20:09:15 volumio volumio[967]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 03 20:09:15 volumio volumio[967]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 03 20:09:15 volumio volumio[967]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 03 20:09:15 volumio volumio[967]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 03 20:09:15 volumio volumio[967]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 03 20:09:15 volumio volumio[967]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 03 20:09:15 volumio volumio[967]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 03 20:09:15 volumio volumio[967]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 03 20:09:15 volumio volumio[967]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.dfs.volumio.org Jul 03 20:09:15 volumio volumio[967]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 20:09:15 volumio volumio[967]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Jul 03 20:09:15 volumio volumio[967]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Jul 03 20:09:15 volumio volumio[967]: errno: -16, Jul 03 20:09:15 volumio volumio[967]: code: 'EBUSY', Jul 03 20:09:15 volumio volumio[967]: syscall: 'getaddrinfo', Jul 03 20:09:15 volumio volumio[967]: hostname: 'ws.audioscrobbler.com' Jul 03 20:09:15 volumio volumio[967]: } Jul 03 20:09:15 volumio volumio[967]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 20:09:16 volumio dhcpcd[861]: wlan0: leased 192.168.50.190 for 86400 seconds Jul 03 20:09:16 volumio avahi-daemon[603]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.50.190. Jul 03 20:09:16 volumio dhcpcd[861]: wlan0: adding route to 192.168.50.0/24 Jul 03 20:09:16 volumio dhcpcd[861]: wlan0: adding default route via 192.168.50.1 Jul 03 20:09:16 volumio avahi-daemon[603]: New relevant interface wlan0.IPv4 for mDNS. Jul 03 20:09:16 volumio avahi-daemon[603]: Registering new address record for 192.168.50.190 on wlan0.IPv4. Jul 03 20:09:16 volumio sudo[1408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-03 20:08 Jul 03 20:09:16 volumio sudo[1408]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"