-- Logs begin at Sun 2025-05-25 12:01:59 EDT, end at Sun 2025-05-25 12:07:31 EDT. -- May 25 12:06:33 dovetunes systemd-timedated[1090]: Changed local time to Sun May 25 12:06:33 2025 May 25 12:06:33 dovetunes sudo[1088]: pam_unix(sudo:session): session closed for user root May 25 12:06:33 dovetunes volumio-time-update[360]: volumio-time-update-util: System time updated successfully. May 25 12:06:33 dovetunes systemd[1]: Started Volumio Time Update Utility. May 25 12:06:33 dovetunes volumio-remote-updater[378]: [2025-05-25 12:06:33] [info] asio async_connect error: system:111 (Connection refused) May 25 12:06:33 dovetunes volumio-remote-updater[378]: [2025-05-25 12:06:33] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 25 12:06:33 dovetunes volumio-remote-updater[378]: [2025-05-25 12:06:33] [error] handle_connect error: Underlying Transport Error May 25 12:06:34 dovetunes winbindd[1085]: [2025/05/25 12:06:34.122696, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 25 12:06:34 dovetunes winbindd[1085]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 25 12:06:34 dovetunes systemd[1]: Started Samba Winbind Daemon. May 25 12:06:34 dovetunes winbindd[1085]: [2025/05/25 12:06:34.218559, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 25 12:06:34 dovetunes winbindd[1085]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 25 12:06:34 dovetunes systemd[1]: Starting Samba SMB Daemon... May 25 12:06:37 dovetunes smbd[1094]: [2025/05/25 12:06:37.864625, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 25 12:06:37 dovetunes smbd[1094]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 25 12:06:37 dovetunes systemd[1]: Started Samba SMB Daemon. May 25 12:06:37 dovetunes systemd[1]: Reached target Multi-User System. May 25 12:06:37 dovetunes systemd[1]: Reached target Graphical Interface. May 25 12:06:37 dovetunes systemd[1]: Starting Update UTMP about System Runlevel Changes... May 25 12:06:38 dovetunes systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 25 12:06:38 dovetunes systemd[1]: Started Update UTMP about System Runlevel Changes. May 25 12:06:38 dovetunes systemd[1]: Startup finished in 21.255s (kernel) + 1min 13.987s (userspace) = 1min 35.243s. May 25 12:06:40 dovetunes volumio[1065]: info: ------------------------------------------- May 25 12:06:40 dovetunes volumio[1065]: info: ----- Volumio3 ---- May 25 12:06:40 dovetunes volumio[1065]: info: ------------------------------------------- May 25 12:06:40 dovetunes volumio[1065]: info: ----- System startup ---- May 25 12:06:40 dovetunes volumio[1065]: info: ------------------------------------------- May 25 12:06:42 dovetunes systemd[1]: systemd-hostnamed.service: Succeeded. May 25 12:06:43 dovetunes volumio-remote-updater[378]: [2025-05-25 12:06:43] [connect] Successful connection May 25 12:06:44 dovetunes volumio[1065]: info: MYVOLUMIO Environment detected May 25 12:06:44 dovetunes volumio[1065]: info: Plugin folders cleanup May 25 12:06:44 dovetunes volumio[1065]: info: Scanning into folder /volumio/app/plugins/ May 25 12:06:44 dovetunes volumio[1065]: info: Scanning category audio_interface May 25 12:06:44 dovetunes volumio[1065]: info: Scanning category miscellanea May 25 12:06:44 dovetunes volumio[1065]: info: Scanning category music_service May 25 12:06:44 dovetunes volumio[1065]: info: Scanning category plugins.json May 25 12:06:44 dovetunes volumio[1065]: info: Scanning category system_controller May 25 12:06:44 dovetunes volumio[1065]: info: Scanning category user_interface May 25 12:06:45 dovetunes volumio[1065]: info: Scanning into folder /data/plugins/ May 25 12:06:45 dovetunes volumio[1065]: info: Plugin folders cleanup completed May 25 12:06:45 dovetunes volumio[1065]: info: ------------------------------------------- May 25 12:06:45 dovetunes volumio[1065]: info: ----- Core plugins startup ---- May 25 12:06:45 dovetunes volumio[1065]: info: ------------------------------------------- May 25 12:06:45 dovetunes volumio[1065]: info: Loading plugins from folder /volumio/app/plugins/ May 25 12:06:45 dovetunes volumio[1065]: info: Adding plugin upnp to MyMusic Plugins May 25 12:06:45 dovetunes volumio[1065]: info: Adding plugin airplay_emulation to MyMusic Plugins May 25 12:06:45 dovetunes volumio[1065]: info: Adding plugin upnp_browser to MyMusic Plugins May 25 12:06:45 dovetunes volumio[1065]: info: Loading plugins from folder /data/plugins/ May 25 12:06:45 dovetunes volumio[1065]: info: Loading plugin "system"... May 25 12:06:45 dovetunes volumio[1065]: info: Loading plugin "appearance"... May 25 12:06:51 dovetunes volumio[1065]: info: Loading plugin "network"... May 25 12:06:51 dovetunes volumio[1065]: info: Refreshing Cached IP Addresses May 25 12:06:51 dovetunes sudo[1160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 12:06:51 dovetunes sudo[1160]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 12:06:51 dovetunes volumio[1065]: info: Loading plugin "services"... May 25 12:06:51 dovetunes sudo[1160]: pam_unix(sudo:session): session closed for user root May 25 12:06:51 dovetunes volumio[1065]: info: Loading plugin "alsa_controller"... May 25 12:06:51 dovetunes sudo[1162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 12:06:52 dovetunes sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 12:06:52 dovetunes sudo[1162]: pam_unix(sudo:session): session closed for user root May 25 12:06:52 dovetunes sudo[1165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 25 12:06:52 dovetunes sudo[1165]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 12:06:52 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 12:06:52 dovetunes volumio[1065]: info: Loading plugin "wizard"... May 25 12:06:52 dovetunes volumio[1065]: info: Loading plugin "networkfs"... May 25 12:06:52 dovetunes volumio[1065]: info: Starting Udev Watcher for removable devices May 25 12:06:52 dovetunes volumio[1065]: info: Ignoring mount for partition: boot May 25 12:06:52 dovetunes volumio[1065]: info: Ignoring mount for partition: volumio May 25 12:06:52 dovetunes volumio[1065]: info: Ignoring mount for partition: volumio_data May 25 12:06:52 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 12:06:52 dovetunes volumio[1065]: info: Loading plugin "volumio_command_line_client"... May 25 12:06:52 dovetunes volumio[1065]: info: Loading plugin "upnp"... May 25 12:06:52 dovetunes sudo[1165]: pam_unix(sudo:session): session closed for user root May 25 12:06:53 dovetunes volumio[1065]: info: [1748189213034] Starting Upmpd Daemon May 25 12:06:53 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 12:06:53 dovetunes volumio[1065]: info: Loading plugin "my_music"... May 25 12:06:53 dovetunes volumio[1065]: info: Loading plugin "mpd"... May 25 12:06:53 dovetunes sshd[1157]: Accepted password for volumio from 10.1.0.21 port 54823 ssh2 May 25 12:06:53 dovetunes sshd[1157]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 25 12:06:53 dovetunes systemd[1]: Created slice User Slice of UID 1000. May 25 12:06:53 dovetunes systemd[1]: Starting User Runtime Directory /run/user/1000... May 25 12:06:53 dovetunes systemd-logind[364]: New session 1 of user volumio. May 25 12:06:53 dovetunes systemd[1]: Started User Runtime Directory /run/user/1000. May 25 12:06:54 dovetunes systemd[1]: Starting User Manager for UID 1000... May 25 12:06:54 dovetunes systemd[1188]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) May 25 12:06:55 dovetunes systemd[1188]: Reached target Timers. May 25 12:06:55 dovetunes systemd[1188]: Listening on GnuPG cryptographic agent (ssh-agent emulation). May 25 12:06:55 dovetunes systemd[1188]: Listening on GnuPG network certificate management daemon. May 25 12:06:55 dovetunes systemd[1188]: Starting D-Bus User Message Bus Socket. May 25 12:06:55 dovetunes systemd[1188]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). May 25 12:06:55 dovetunes systemd[1188]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). May 25 12:06:55 dovetunes systemd[1188]: Listening on GnuPG cryptographic agent and passphrase cache. May 25 12:06:55 dovetunes systemd[1188]: Listening on Sound System. May 25 12:06:55 dovetunes systemd[1188]: Reached target Paths. May 25 12:06:55 dovetunes systemd[1188]: Listening on D-Bus User Message Bus Socket. May 25 12:06:55 dovetunes systemd[1188]: Reached target Sockets. May 25 12:06:55 dovetunes systemd[1188]: Reached target Basic System. May 25 12:06:55 dovetunes systemd[1188]: Reached target Default. May 25 12:06:55 dovetunes systemd[1188]: Startup finished in 1.492s. May 25 12:06:55 dovetunes systemd[1]: Started User Manager for UID 1000. May 25 12:06:55 dovetunes systemd[1]: Started Session 1 of user volumio. May 25 12:06:56 dovetunes volumio[1065]: info: Loading plugin "upnp_browser"... May 25 12:06:58 dovetunes volumio[1065]: info: Loading plugin "alarm-clock"... May 25 12:06:58 dovetunes volumio-remote-updater[378]: [2025-05-25 12:06:58] [connect] Successful connection May 25 12:06:59 dovetunes volumio[1065]: info: Loading plugin "airplay_emulation"... May 25 12:06:59 dovetunes volumio[1065]: info: Starting Shairport Sync May 25 12:06:59 dovetunes volumio[1065]: info: Loading plugin "last_100"... May 25 12:06:59 dovetunes volumio[1065]: info: Loading plugin "webradio"... May 25 12:07:00 dovetunes volumio[1065]: info: Loading plugin "i2s_dacs"... May 25 12:07:00 dovetunes volumio[1065]: info: Loading plugin "volumiodiscovery"... May 25 12:07:00 dovetunes volumio[1065]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 12:07:00 dovetunes node[1065]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 12:07:00 dovetunes volumio[1065]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 12:07:00 dovetunes volumio[1065]: *** WARNING *** For more information see May 25 12:07:00 dovetunes volumio[1065]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 12:07:00 dovetunes volumio[1065]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 12:07:00 dovetunes volumio[1065]: *** WARNING *** For more information see May 25 12:07:00 dovetunes node[1065]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 12:07:00 dovetunes node[1065]: *** WARNING *** For more information see May 25 12:07:00 dovetunes node[1065]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 12:07:00 dovetunes node[1065]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 12:07:00 dovetunes node[1065]: *** WARNING *** For more information see May 25 12:07:00 dovetunes volumio[1065]: info: Applying required configuration parameters for plugin volumiodiscovery May 25 12:07:00 dovetunes volumio[1065]: info: Discovery: Started advertising with name: dovetunes May 25 12:07:00 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 12:07:00 dovetunes volumio[1065]: info: Loading plugin "outputs"... May 25 12:07:00 dovetunes volumio[1065]: info: Loading plugin "albumart"... May 25 12:07:00 dovetunes volumio[1065]: info: Plugin example_plugin is not enabled May 25 12:07:00 dovetunes volumio[1065]: info: Loading plugin "inputs"... May 25 12:07:00 dovetunes volumio[1065]: info: Loading plugin "updater_comm"... May 25 12:07:03 dovetunes volumio[1065]: info: Plugin mpdemulation is not enabled May 25 12:07:03 dovetunes volumio[1065]: info: Loading plugin "rest_api"... May 25 12:07:03 dovetunes systemd[1]: systemd-timedated.service: Succeeded. May 25 12:07:03 dovetunes volumio[1065]: info: Loading plugin "websocket"... May 25 12:07:03 dovetunes volumio[1065]: info: Starting Socket.io Server version 2.3.0 May 25 12:07:03 dovetunes volumio[1065]: info: Loading i18n strings for locale en May 25 12:07:03 dovetunes volumio[1065]: Updating browse sources language May 25 12:07:03 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 12:07:04 dovetunes volumio[1065]: Forking 1 albumart workers May 25 12:07:04 dovetunes ntpd[519]: Soliciting pool server 23.186.168.128 May 25 12:07:05 dovetunes ntpd[519]: Soliciting pool server 208.67.72.50 May 25 12:07:06 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 12:07:06 dovetunes volumio[1065]: info: CoreCommandRouter::initPlayerControls May 25 12:07:06 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 12:07:06 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 12:07:06 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 12:07:06 dovetunes volumio[1065]: Express server listening on port 3000 May 25 12:07:06 dovetunes volumio[1065]: [Metrics] WebUI: 30s 90.82ms May 25 12:07:06 dovetunes volumio[1065]: info: CoreStateMachine::resetVolumioState May 25 12:07:06 dovetunes volumio[1065]: info: CoreStateMachine::getcurrentVolume May 25 12:07:06 dovetunes volumio[1065]: info: CoreCommandRouter::volumioRetrievevolume May 25 12:07:06 dovetunes ntpd[519]: Soliciting pool server 198.211.103.209 May 25 12:07:06 dovetunes ntpd[519]: Soliciting pool server 66.118.231.14 May 25 12:07:07 dovetunes sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 12:07:07 dovetunes sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 12:07:07 dovetunes sudo[1261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 12:07:07 dovetunes sudo[1261]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 12:07:07 dovetunes sudo[1261]: pam_unix(sudo:session): session closed for user root May 25 12:07:07 dovetunes sudo[1259]: pam_unix(sudo:session): session closed for user root May 25 12:07:07 dovetunes volumio[1065]: info: Volumio Network Manager: Network status updated: 2 May 25 12:07:07 dovetunes ntpd[519]: Soliciting pool server 139.94.144.123 May 25 12:07:07 dovetunes ntpd[519]: Soliciting pool server 162.159.200.123 May 25 12:07:07 dovetunes ntpd[519]: Soliciting pool server 45.79.111.167 May 25 12:07:07 dovetunes ntpd[519]: Soliciting pool server 45.83.234.123 May 25 12:07:08 dovetunes ntpd[519]: Soliciting pool server 85.209.17.10 May 25 12:07:08 dovetunes ntpd[519]: Soliciting pool server 2607:f1c0:f014:9e00::1 May 25 12:07:08 dovetunes ntpd[519]: Soliciting pool server 102.129.185.135 May 25 12:07:08 dovetunes volumio[1065]: info: CoreStateMachine::pushState May 25 12:07:08 dovetunes volumio[1065]: info: CorePlayQueue::getTrack 0 May 25 12:07:08 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 12:07:08 dovetunes volumio[1065]: info: CoreCommandRouter::volumioPushState May 25 12:07:08 dovetunes volumio[1065]: info: CoreStateMachine::updateTrackBlock May 25 12:07:08 dovetunes volumio[1065]: info: CorePlayQueue::getTrackBlock May 25 12:07:08 dovetunes volumio[1065]: info: CoreCommandRouter::volumioRetrievevolume May 25 12:07:09 dovetunes volumio[1065]: info: May 25 12:07:09 dovetunes volumio[1065]: ---------------------------- Client requests Volume 30 May 25 12:07:09 dovetunes volumio[1065]: info: VolumeController::SetAlsaVolume30 May 25 12:07:09 dovetunes volumio[1065]: info: CoreStateMachine::pushState May 25 12:07:09 dovetunes volumio[1065]: info: CorePlayQueue::getTrack 0 May 25 12:07:09 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 12:07:09 dovetunes volumio[1065]: info: CoreCommandRouter::volumioPushState May 25 12:07:09 dovetunes ntpd[519]: Soliciting pool server 144.202.41.38 May 25 12:07:09 dovetunes ntpd[519]: Soliciting pool server 166.88.142.52 May 25 12:07:09 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 12:07:09 dovetunes volumio[1065]: info: Reloading queue from file May 25 12:07:10 dovetunes volumio[1065]: info: CoreStateMachine::pushState May 25 12:07:10 dovetunes volumio[1065]: info: CorePlayQueue::getTrack 0 May 25 12:07:10 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 12:07:10 dovetunes volumio[1065]: info: CoreCommandRouter::volumioPushState May 25 12:07:10 dovetunes volumio[1065]: info: CoreStateMachine::setRepeat true single undefined May 25 12:07:10 dovetunes volumio[1065]: info: CoreStateMachine::pushState May 25 12:07:10 dovetunes volumio[1065]: info: CorePlayQueue::getTrack 0 May 25 12:07:10 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 12:07:10 dovetunes volumio[1065]: info: CoreCommandRouter::volumioPushState May 25 12:07:10 dovetunes volumio[1065]: info: CoreStateMachine::setRandom true May 25 12:07:10 dovetunes volumio[1065]: info: CoreStateMachine::pushState May 25 12:07:10 dovetunes volumio[1065]: info: CorePlayQueue::getTrack 0 May 25 12:07:10 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 12:07:10 dovetunes volumio[1065]: info: CoreCommandRouter::volumioPushState May 25 12:07:10 dovetunes volumio[1065]: info: Setting Device type: Raspberry PI May 25 12:07:10 dovetunes volumio[1065]: info: May 25 12:07:10 dovetunes volumio[1065]: ---------------------------- Client requests Volumio Clear Queue May 25 12:07:10 dovetunes volumio[1065]: info: CoreCommandRouter::volumioClearQueue May 25 12:07:10 dovetunes volumio[1065]: info: CoreStateMachine::ClearQueue May 25 12:07:10 dovetunes volumio[1065]: info: CoreStateMachine::stop May 25 12:07:10 dovetunes volumio[1065]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 12:07:10 dovetunes volumio[1065]: info: CorePlayQueue::clearPlayQueue May 25 12:07:10 dovetunes volumio[1065]: info: CorePlayQueue::saveQueue May 25 12:07:10 dovetunes volumio[1065]: info: CoreCommandRouter::volumioPushState May 25 12:07:10 dovetunes volumio[1065]: info: CoreCommandRouter::volumioPushQueue May 25 12:07:10 dovetunes volumio[1065]: error: API:pushError: TypeError: callback.apply is not a function May 25 12:07:10 dovetunes volumio[1065]: info: Completed loading Core Plugins May 25 12:07:10 dovetunes volumio[1065]: info: Preparing to generate the ALSA configuration file May 25 12:07:10 dovetunes ntpd[519]: Soliciting pool server 206.226.67.233 May 25 12:07:10 dovetunes volumio[1065]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 25 12:07:10 dovetunes volumio[1065]: info: Reading ALSA contributions from plugins. May 25 12:07:10 dovetunes volumio[1065]: info: May 25 12:07:10 dovetunes volumio[1065]: ---------------------------- Client requests Repeat true May 25 12:07:10 dovetunes volumio[1065]: info: CoreCommandRouter::volumioRandom May 25 12:07:10 dovetunes volumio[1065]: info: CoreCommandRouter::writePlayerControls May 25 12:07:10 dovetunes volumio[1065]: info: CorePlayQueue::getTrack 0 May 25 12:07:10 dovetunes volumio[1065]: info: CoreStateMachine::setRepeat true single false May 25 12:07:10 dovetunes volumio[1065]: info: CoreStateMachine::pushState May 25 12:07:10 dovetunes volumio[1065]: info: CorePlayQueue::getTrack 0 May 25 12:07:10 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 12:07:10 dovetunes volumio[1065]: info: CoreCommandRouter::volumioPushState May 25 12:07:10 dovetunes volumio[1065]: info: Discovery: adding f6ee12f0-f316-4be0-969f-4566d638323a May 25 12:07:10 dovetunes volumio[1065]: info: Discovery: Found device dovetunes May 25 12:07:10 dovetunes volumio[1065]: info: CoreCommandRouter::volumioGetState May 25 12:07:10 dovetunes volumio[1065]: info: CorePlayQueue::getTrack 0 May 25 12:07:11 dovetunes sudo[1279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 25 12:07:11 dovetunes sudo[1279]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 12:07:11 dovetunes volumio[1065]: info: May 25 12:07:11 dovetunes volumio[1065]: ---------------------------- Client requests Random true May 25 12:07:11 dovetunes volumio[1065]: info: CoreCommandRouter::volumioRandom May 25 12:07:11 dovetunes volumio[1065]: info: CoreCommandRouter::writePlayerControls May 25 12:07:11 dovetunes volumio[1065]: info: CorePlayQueue::getTrack 0 May 25 12:07:11 dovetunes volumio[1065]: info: CoreStateMachine::setRandom true May 25 12:07:11 dovetunes volumio[1065]: info: CoreStateMachine::pushState May 25 12:07:11 dovetunes volumio[1065]: info: CorePlayQueue::getTrack 0 May 25 12:07:11 dovetunes volumio[1065]: info: CoreCommandRouter::volumioPushState May 25 12:07:11 dovetunes systemd[1]: Started UPnP Renderer front-end to MPD. May 25 12:07:11 dovetunes sudo[1279]: pam_unix(sudo:session): session closed for user root May 25 12:07:11 dovetunes volumio[1065]: info: Upmpdcli Daemon Started May 25 12:07:11 dovetunes volumio[1065]: info: May 25 12:07:11 dovetunes volumio[1065]: ---------------------------- Client requests Volumio Play Playlist Automagick May 25 12:07:11 dovetunes volumio[1065]: info: Play playlist Automagick May 25 12:07:11 dovetunes volumio[1065]: info: Asound.conf file unchanged, so no further update is needed May 25 12:07:11 dovetunes volumio[1065]: info: Output device has changed, restarting MPD May 25 12:07:11 dovetunes volumio[1065]: info: Output device has changed, restarting Shairport Sync May 25 12:07:11 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 12:07:11 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 12:07:11 dovetunes sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 12:07:11 dovetunes sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 12:07:11 dovetunes sudo[1283]: pam_unix(sudo:session): session closed for user root May 25 12:07:11 dovetunes sudo[1285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 12:07:11 dovetunes sudo[1285]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 12:07:12 dovetunes systemd[1]: Stopping Music Player Daemon... May 25 12:07:12 dovetunes systemd[1]: mpd.service: Succeeded. May 25 12:07:12 dovetunes systemd[1]: Stopped Music Player Daemon. May 25 12:07:12 dovetunes systemd[1]: Starting Music Player Daemon... May 25 12:07:12 dovetunes volumio[1065]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 12:07:12 dovetunes volumio[1065]: info: ___________ START PLUGINS ___________ May 25 12:07:12 dovetunes volumio[1065]: info: ControllerMpd::onStart: Initializing MPD May 25 12:07:12 dovetunes volumio[1065]: info: Creating MPD Configuration file May 25 12:07:13 dovetunes sudo[1291]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 12:07:13 dovetunes sudo[1291]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 12:07:13 dovetunes sudo[1291]: pam_unix(sudo:session): session closed for user root May 25 12:07:13 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 12:07:13 dovetunes volumio[1065]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 12:07:13 dovetunes volumio[1065]: info: [1748189233548] CoreMusicLibrary::Adding element Media Servers May 25 12:07:13 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 12:07:13 dovetunes sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 12:07:13 dovetunes sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 12:07:13 dovetunes volumio-remote-updater[378]: [2025-05-25 12:07:13] [connect] Successful connection May 25 12:07:16 dovetunes sudo[1301]: pam_unix(sudo:session): session closed for user root May 25 12:07:17 dovetunes sudo[1310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 12:07:17 dovetunes sudo[1310]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 12:07:17 dovetunes systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 25 12:07:17 dovetunes systemd[1]: mpd.service: Succeeded. May 25 12:07:17 dovetunes systemd[1]: Stopped Music Player Daemon. May 25 12:07:17 dovetunes systemd[1]: Starting Music Player Daemon... May 25 12:07:17 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 12:07:17 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 12:07:17 dovetunes volumio[1065]: Starting albumart workers May 25 12:07:18 dovetunes volumio[1065]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 12:07:18 dovetunes sudo[1314]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 12:07:18 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 12:07:18 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 12:07:18 dovetunes sudo[1314]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 12:07:18 dovetunes sudo[1314]: pam_unix(sudo:session): session closed for user root May 25 12:07:18 dovetunes volumio[1065]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 12:07:18 dovetunes volumio[1065]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 12:07:18 dovetunes volumio[1065]: info: [1748189238661] CoreMusicLibrary::Adding element Last_100 May 25 12:07:18 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 12:07:18 dovetunes volumio[1065]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 12:07:18 dovetunes volumio[1065]: info: [1748189238717] CoreMusicLibrary::Adding element Webradio May 25 12:07:18 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 12:07:18 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 12:07:18 dovetunes volumio[1065]: info: Initializing BBC Radios May 25 12:07:19 dovetunes volumio[1065]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory May 25 12:07:19 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 12:07:19 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 12:07:19 dovetunes volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 12:07:19 dovetunes volumio[1065]: info: Volumio Calling Home May 25 12:07:23 dovetunes volumio[1065]: info: MPD Permissions set May 25 12:07:23 dovetunes volumio[1065]: info: MPD Permissions set May 25 12:07:23 dovetunes volumio[1065]: info: Volumio called home May 25 12:07:24 dovetunes volumio[1065]: info: CoreCommandRouter::volumioClearQueue May 25 12:07:24 dovetunes volumio[1065]: info: CoreStateMachine::ClearQueue May 25 12:07:24 dovetunes volumio[1065]: info: CoreStateMachine::stop May 25 12:07:24 dovetunes volumio[1065]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 12:07:24 dovetunes volumio[1065]: info: CorePlayQueue::clearPlayQueue May 25 12:07:24 dovetunes volumio[1065]: info: CorePlayQueue::saveQueue May 25 12:07:24 dovetunes volumio[1065]: info: CoreCommandRouter::volumioPushState May 25 12:07:24 dovetunes volumio[1065]: info: CoreCommandRouter::volumioPushQueue May 25 12:07:24 dovetunes volumio[1065]: info: CoreCommandRouter::volumioAddQueueItems May 25 12:07:24 dovetunes volumio[1065]: info: CoreStateMachine::addQueueItems May 25 12:07:24 dovetunes volumio[1065]: info: CorePlayQueue::addQueueItems May 25 12:07:24 dovetunes volumio[1065]: info: Preload queue cleared May 25 12:07:24 dovetunes volumio[1065]: info: Adding Item to queue: mnt/INTERNAL/01 Suite for solo cello No. 4 in E flat major, BWV 1010_ 6. Gigue.m4a May 25 12:07:24 dovetunes volumio[1065]: info: Exploding uri mnt/INTERNAL/01 Suite for solo cello No. 4 in E flat major, BWV 1010_ 6. Gigue.m4a in service mpd May 25 12:07:24 dovetunes volumio[1065]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 12:07:24 dovetunes volumio[1065]: TypeError: Cannot read property 'then' of undefined May 25 12:07:24 dovetunes volumio[1065]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2528:19) May 25 12:07:24 dovetunes volumio[1065]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2326:21) May 25 12:07:24 dovetunes volumio[1065]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:16) May 25 12:07:24 dovetunes volumio[1065]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) May 25 12:07:24 dovetunes volumio[1065]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) May 25 12:07:24 dovetunes volumio[1065]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) May 25 12:07:24 dovetunes volumio[1065]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) May 25 12:07:24 dovetunes volumio[1065]: at CoreCommandRouter.addQueueItems (/volumio/app/index.js:448:28) May 25 12:07:24 dovetunes volumio[1065]: at /volumio/app/playlistManager.js:726:30 May 25 12:07:24 dovetunes volumio[1065]: at /volumio/node_modules/jsonfile/index.js:46:5 May 25 12:07:24 dovetunes volumio[1065]: at /volumio/node_modules/graceful-fs/graceful-fs.js:123:16 May 25 12:07:24 dovetunes volumio[1065]: at FSReqCallback.readFileAfterClose [as oncomplete] (internal/fs/read_file_context.js:63:3) May 25 12:07:24 dovetunes volumio[1065]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 12:07:31 dovetunes sudo[1369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-25 12:06 May 25 12:07:31 dovetunes sudo[1369]: 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"