-- Logs begin at Thu 2019-02-14 10:12:06 GMT, end at Sun 2025-12-28 19:21:24 GMT. -- Dec 28 19:20:00 back-room volumio[1001]: info: ------------------------------------------- Dec 28 19:20:00 back-room volumio[1001]: info: ----- Volumio3 ---- Dec 28 19:20:00 back-room volumio[1001]: info: ------------------------------------------- Dec 28 19:20:00 back-room volumio[1001]: info: ----- System startup ---- Dec 28 19:20:00 back-room volumio[1001]: info: ------------------------------------------- Dec 28 19:20:00 back-room volumio-remote-updater[608]: [2025-12-28 19:20:00] [connect] Successful connection Dec 28 19:20:01 back-room sh[529]: timed out Dec 28 19:20:01 back-room dhcpcd[579]: timed out Dec 28 19:20:01 back-room sh[529]: dhcpcd exited Dec 28 19:20:01 back-room dhcpcd[579]: dhcpcd exited Dec 28 19:20:01 back-room sh[529]: ifup: failed to bring up eth0 Dec 28 19:20:01 back-room systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Dec 28 19:20:01 back-room systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Dec 28 19:20:01 back-room volumio-time-update[613]: volumio-time-update-util: Fetching time from Volumio... Dec 28 19:20:01 back-room volumio[1001]: info: MYVOLUMIO Environment detected Dec 28 19:20:01 back-room volumio-time-update[613]: volumio-time-update-util: Setting system time to: 2025-12-28 19:21:14 Dec 28 19:20:01 back-room sudo[1047]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-12-28 19:21:14 Dec 28 19:20:01 back-room sudo[1047]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 19:20:01 back-room volumio[1001]: info: Plugin folders cleanup Dec 28 19:20:01 back-room volumio[1001]: info: Scanning into folder /volumio/app/plugins/ Dec 28 19:20:01 back-room volumio[1001]: info: Scanning category audio_interface Dec 28 19:20:01 back-room volumio[1001]: info: Scanning category miscellanea Dec 28 19:20:01 back-room volumio[1001]: info: Scanning category music_service Dec 28 19:20:01 back-room volumio[1001]: info: Scanning category plugins.json Dec 28 19:20:01 back-room volumio[1001]: info: Scanning category system_controller Dec 28 19:20:01 back-room volumio[1001]: info: Scanning category user_interface Dec 28 19:20:01 back-room volumio[1001]: info: Scanning into folder /data/plugins/ Dec 28 19:20:01 back-room volumio[1001]: info: Scanning category music_service Dec 28 19:20:01 back-room volumio[1001]: info: Scanning category system_controller Dec 28 19:20:01 back-room volumio[1001]: info: Plugin folders cleanup completed Dec 28 19:20:01 back-room volumio[1001]: info: ------------------------------------------- Dec 28 19:20:01 back-room volumio[1001]: info: ----- Core plugins startup ---- Dec 28 19:20:01 back-room volumio[1001]: info: ------------------------------------------- Dec 28 19:20:01 back-room volumio[1001]: info: Loading plugins from folder /volumio/app/plugins/ Dec 28 19:20:01 back-room dbus-daemon[605]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.8' (uid=0 pid=1048 comm="timedatectl set-time 2025-12-28 19:21:14 ") Dec 28 19:20:01 back-room volumio[1001]: info: Adding plugin upnp to MyMusic Plugins Dec 28 19:20:01 back-room volumio[1001]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 28 19:20:01 back-room volumio[1001]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 28 19:20:01 back-room volumio[1001]: info: Loading plugins from folder /data/plugins/ Dec 28 19:20:01 back-room volumio[1001]: info: Loading plugin "system"... Dec 28 19:20:01 back-room volumio[1001]: info: Loading plugin "appearance"... Dec 28 19:20:01 back-room systemd[1]: Starting Time & Date Service... Dec 28 19:20:01 back-room dbus-daemon[605]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 28 19:20:01 back-room systemd[1]: Started Time & Date Service. Dec 28 19:21:14 back-room systemd-timedated[1049]: Changed local time to Sun Dec 28 19:21:14 2025 Dec 28 19:21:14 back-room sudo[1047]: pam_unix(sudo:session): session closed for user root Dec 28 19:21:14 back-room volumio-time-update[613]: volumio-time-update-util: System time updated successfully. Dec 28 19:21:14 back-room systemd[1]: Started Volumio Time Update Utility. Dec 28 19:21:14 back-room systemd[1]: Reached target Multi-User System. Dec 28 19:21:14 back-room systemd[1]: Reached target Graphical Interface. Dec 28 19:21:14 back-room systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 28 19:21:14 back-room systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 28 19:21:14 back-room systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 28 19:21:14 back-room systemd[1]: Startup finished in 14.818s (kernel) + 1min 30.320s (userspace) = 1min 45.138s. Dec 28 19:21:15 back-room volumio[1001]: info: Loading plugin "network"... Dec 28 19:21:15 back-room volumio[1001]: info: Refreshing Cached IP Addresses Dec 28 19:21:15 back-room sudo[1055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 28 19:21:15 back-room sudo[1055]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 19:21:15 back-room sudo[1057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 28 19:21:15 back-room sudo[1055]: pam_unix(sudo:session): session closed for user root Dec 28 19:21:15 back-room sudo[1057]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 19:21:15 back-room volumio[1001]: info: Loading plugin "services"... Dec 28 19:21:15 back-room sudo[1057]: pam_unix(sudo:session): session closed for user root Dec 28 19:21:15 back-room volumio[1001]: info: Loading plugin "alsa_controller"... Dec 28 19:21:15 back-room sudo[1066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 28 19:21:15 back-room sudo[1066]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 19:21:15 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 19:21:15 back-room volumio[1001]: info: Loading plugin "wizard"... Dec 28 19:21:15 back-room volumio[1001]: info: Loading plugin "networkfs"... Dec 28 19:21:15 back-room volumio[1001]: info: Starting Udev Watcher for removable devices Dec 28 19:21:15 back-room volumio[1001]: info: Ignoring mount for partition: boot Dec 28 19:21:15 back-room volumio[1001]: info: Ignoring mount for partition: volumio Dec 28 19:21:15 back-room volumio[1001]: info: Ignoring mount for partition: volumio_data Dec 28 19:21:15 back-room volumio[1001]: info: Mounting Device 1A9C-D582 Dec 28 19:21:15 back-room sudo[1084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/1A9C-D582 -o noatime Dec 28 19:21:15 back-room sudo[1084]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 19:21:16 back-room sudo[1066]: pam_unix(sudo:session): session closed for user root Dec 28 19:21:16 back-room sudo[1084]: pam_unix(sudo:session): session closed for user root Dec 28 19:21:16 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 19:21:16 back-room volumio[1001]: info: Loading plugin "volumio_command_line_client"... Dec 28 19:21:16 back-room volumio[1001]: info: Loading plugin "upnp"... Dec 28 19:21:16 back-room volumio[1001]: info: [1766949676886] Starting Upmpd Daemon Dec 28 19:21:16 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 19:21:16 back-room volumio[1001]: info: Loading plugin "my_music"... Dec 28 19:21:16 back-room volumio[1001]: info: Loading plugin "mpd"... Dec 28 19:21:17 back-room volumio[1001]: info: Loading plugin "upnp_browser"... Dec 28 19:21:17 back-room volumio[1001]: info: Starting UPNP Browser Dec 28 19:21:17 back-room volumio[1001]: info: Loading plugin "alarm-clock"... Dec 28 19:21:18 back-room volumio[1001]: info: Loading plugin "airplay_emulation"... Dec 28 19:21:18 back-room volumio[1001]: info: Starting Shairport Sync Dec 28 19:21:18 back-room volumio[1001]: info: Loading plugin "last_100"... Dec 28 19:21:18 back-room volumio[1001]: info: Loading plugin "webradio"... Dec 28 19:21:18 back-room volumio[1001]: info: Loading plugin "i2s_dacs"... Dec 28 19:21:18 back-room volumio[1001]: info: I2S DAC not set, start Auto-detection Dec 28 19:21:18 back-room volumio[1001]: info: Loading plugin "volumiodiscovery"... Dec 28 19:21:18 back-room volumio[1001]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 28 19:21:18 back-room volumio[1001]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 19:21:18 back-room volumio[1001]: *** WARNING *** For more information see Dec 28 19:21:18 back-room node[1001]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 28 19:21:18 back-room volumio[1001]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 28 19:21:18 back-room volumio[1001]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 19:21:18 back-room volumio[1001]: *** WARNING *** For more information see Dec 28 19:21:18 back-room node[1001]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 19:21:18 back-room node[1001]: *** WARNING *** For more information see Dec 28 19:21:18 back-room node[1001]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 28 19:21:18 back-room node[1001]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 19:21:18 back-room node[1001]: *** WARNING *** For more information see Dec 28 19:21:18 back-room volumio[1001]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 28 19:21:18 back-room volumio[1001]: info: Discovery: Started advertising with name: Back room Dec 28 19:21:18 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 19:21:18 back-room volumio[1001]: info: Loading plugin "autostart"... Dec 28 19:21:18 back-room volumio[1001]: info: Applying required configuration parameters for plugin autostart Dec 28 19:21:18 back-room volumio[1001]: info: AutoStart - onVolumioStart - read config.json Dec 28 19:21:18 back-room volumio[1001]: info: Loading plugin "outputs"... Dec 28 19:21:18 back-room volumio[1001]: info: Loading plugin "albumart"... Dec 28 19:21:19 back-room volumio[1001]: info: Plugin example_plugin is not enabled Dec 28 19:21:19 back-room volumio[1001]: info: Loading plugin "inputs"... Dec 28 19:21:19 back-room volumio[1001]: info: Loading plugin "updater_comm"... Dec 28 19:21:19 back-room volumio[1001]: info: Plugin mpdemulation is not enabled Dec 28 19:21:19 back-room volumio[1001]: info: Loading plugin "rest_api"... Dec 28 19:21:19 back-room volumio[1001]: info: Loading plugin "websocket"... Dec 28 19:21:19 back-room volumio[1001]: info: Starting Socket.io Server version 2.3.0 Dec 28 19:21:19 back-room volumio[1001]: info: Loading plugin "minidlna"... Dec 28 19:21:19 back-room volumio[1001]: Forking 3 albumart workers Dec 28 19:21:19 back-room volumio[1001]: info: Applying required configuration parameters for plugin minidlna Dec 28 19:21:19 back-room volumio[1001]: info: Loading i18n strings for locale en Dec 28 19:21:19 back-room volumio[1001]: Updating browse sources language Dec 28 19:21:19 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::initPlayerControls Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 19:21:20 back-room volumio[1001]: Express server listening on port 3000 Dec 28 19:21:20 back-room volumio[1001]: [Metrics] WebUI: 9s 74.00ms Dec 28 19:21:20 back-room volumio[1001]: info: CoreStateMachine::resetVolumioState Dec 28 19:21:20 back-room volumio[1001]: info: CoreStateMachine::getcurrentVolume Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 19:21:20 back-room volumio[1001]: info: Volumio Network Manager: Network status updated: 2 Dec 28 19:21:20 back-room volumio[1001]: Starting albumart workers Dec 28 19:21:20 back-room volumio[1001]: info: CoreStateMachine::pushState Dec 28 19:21:20 back-room volumio[1001]: info: CorePlayQueue::getTrack 0 Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::volumioPushState Dec 28 19:21:20 back-room volumio[1001]: info: CoreStateMachine::updateTrackBlock Dec 28 19:21:20 back-room volumio[1001]: info: CorePlayQueue::getTrackBlock Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 19:21:20 back-room volumio[1001]: info: CoreStateMachine::setRepeat true single undefined Dec 28 19:21:20 back-room volumio[1001]: info: CoreStateMachine::pushState Dec 28 19:21:20 back-room volumio[1001]: info: CorePlayQueue::getTrack 0 Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::volumioPushState Dec 28 19:21:20 back-room volumio[1001]: info: CoreStateMachine::setRandom true Dec 28 19:21:20 back-room volumio[1001]: info: CoreStateMachine::pushState Dec 28 19:21:20 back-room volumio[1001]: info: CorePlayQueue::getTrack 0 Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::volumioPushState Dec 28 19:21:20 back-room volumio[1001]: info: Setting Device type: Raspberry PI Dec 28 19:21:20 back-room volumio[1001]: info: CoreStateMachine::pushState Dec 28 19:21:20 back-room volumio[1001]: info: CorePlayQueue::getTrack 0 Dec 28 19:21:20 back-room volumio[1001]: Starting albumart workers Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::volumioPushState Dec 28 19:21:20 back-room volumio[1001]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 28 19:21:20 back-room volumio[1001]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Dec 28 19:21:20 back-room volumio[1001]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 28 19:21:20 back-room volumio[1001]: Starting albumart workers Dec 28 19:21:20 back-room volumio[1001]: info: Reloading queue from file Dec 28 19:21:20 back-room volumio[1001]: info: Completed loading Core Plugins Dec 28 19:21:20 back-room volumio[1001]: info: Preparing to generate the ALSA configuration file Dec 28 19:21:20 back-room volumio[1001]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 28 19:21:20 back-room volumio[1001]: info: Reading ALSA contributions from plugins. Dec 28 19:21:20 back-room volumio[1001]: info: Discovery: adding b8200e7b-7d8a-43ea-8fda-906432a65a33 Dec 28 19:21:20 back-room volumio[1001]: info: Discovery: Found device Back room Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::volumioGetState Dec 28 19:21:20 back-room volumio[1001]: info: CorePlayQueue::getTrack 0 Dec 28 19:21:20 back-room volumio[1001]: info: Asound.conf file unchanged, so no further update is needed Dec 28 19:21:20 back-room volumio[1001]: info: Output device has changed, restarting MPD Dec 28 19:21:20 back-room volumio[1001]: info: Output device has changed, restarting Shairport Sync Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:20 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 19:21:20 back-room sudo[1140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 19:21:20 back-room sudo[1140]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 19:21:20 back-room sudo[1140]: pam_unix(sudo:session): session closed for user root Dec 28 19:21:21 back-room sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 19:21:21 back-room sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 19:21:21 back-room volumio[1001]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 19:21:21 back-room volumio[1001]: info: ___________ START PLUGINS ___________ Dec 28 19:21:21 back-room volumio[1001]: info: ControllerMpd::onStart: Initializing MPD Dec 28 19:21:21 back-room volumio[1001]: info: Creating MPD Configuration file Dec 28 19:21:21 back-room systemd[1]: Listening on mpd.socket. Dec 28 19:21:21 back-room sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 19:21:21 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 19:21:21 back-room sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 19:21:21 back-room volumio[1001]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 19:21:21 back-room volumio[1001]: info: [1766949681155] CoreMusicLibrary::Adding element Media Servers Dec 28 19:21:21 back-room systemd[1]: Starting Music Player Daemon... Dec 28 19:21:21 back-room sudo[1150]: pam_unix(sudo:session): session closed for user root Dec 28 19:21:21 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 19:21:21 back-room volumio[1001]: info: UPNP Browser: Client initialized successfully Dec 28 19:21:21 back-room sudo[1153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 19:21:21 back-room sudo[1153]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 19:21:21 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:21 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 19:21:21 back-room systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 28 19:21:21 back-room systemd[1]: mpd.service: Succeeded. Dec 28 19:21:21 back-room systemd[1]: Stopped Music Player Daemon. Dec 28 19:21:21 back-room volumio[1001]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 19:21:21 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:21 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 19:21:21 back-room systemd[1]: Starting Music Player Daemon... Dec 28 19:21:21 back-room volumio[1001]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 19:21:21 back-room volumio[1001]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 19:21:21 back-room volumio[1001]: info: [1766949681408] CoreMusicLibrary::Adding element Last_100 Dec 28 19:21:21 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 19:21:21 back-room volumio[1001]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 19:21:21 back-room volumio[1001]: info: [1766949681413] CoreMusicLibrary::Adding element Webradio Dec 28 19:21:21 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 19:21:21 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 19:21:21 back-room volumio[1001]: info: Initializing BBC Radios Dec 28 19:21:21 back-room sudo[1157]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 19:21:21 back-room sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 19:21:21 back-room sudo[1157]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 28 19:21:21 back-room sudo[1157]: pam_unix(sudo:session): session closed for user root Dec 28 19:21:21 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 19:21:21 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 19:21:21 back-room volumio[1001]: info: AutoStart - onStart Dec 28 19:21:21 back-room volumio[1001]: info: Loading i18n strings for locale en Dec 28 19:21:21 back-room volumio[1001]: info: Volumio Calling Home Dec 28 19:21:21 back-room volumio[1001]: info: MPD Permissions set Dec 28 19:21:21 back-room volumio[1001]: info: MPD Permissions set Dec 28 19:21:21 back-room volumio[1001]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 28 19:21:21 back-room volumio[1001]: info: Volumio called home Dec 28 19:21:22 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:22 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:22 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:22 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:22 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:22 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:22 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:22 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:22 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 19:21:22 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:22 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:22 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:22 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:22 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:22 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:22 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:22 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:22 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:22 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 19:21:22 back-room volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:21:22 back-room volumio[1001]: info: CoreCommandRouter::volumioGetState Dec 28 19:21:22 back-room volumio[1001]: info: CorePlayQueue::getTrack 0 Dec 28 19:21:22 back-room volumio[1001]: info: Starting Shairport Sync Dec 28 19:21:22 back-room volumio[1001]: info: Starting Shairport Sync Dec 28 19:21:22 back-room volumio[1001]: info: Starting Shairport Sync Dec 28 19:21:22 back-room sudo[1213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 28 19:21:22 back-room sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 19:21:22 back-room sudo[1217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 28 19:21:22 back-room sudo[1217]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 19:21:22 back-room sudo[1215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 28 19:21:22 back-room sudo[1215]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 19:21:22 back-room systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 28 19:21:22 back-room systemd[1]: shairport-sync.service: Succeeded. Dec 28 19:21:22 back-room systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 28 19:21:22 back-room systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 28 19:21:22 back-room sudo[1217]: pam_unix(sudo:session): session closed for user root Dec 28 19:21:22 back-room sudo[1215]: pam_unix(sudo:session): session closed for user root Dec 28 19:21:22 back-room volumio[1001]: info: Shairport-Sync Started Dec 28 19:21:22 back-room volumio[1001]: Error adding Membership: Error: addMembership EINVAL Dec 28 19:21:22 back-room volumio[1001]: info: Shairport-Sync Started Dec 28 19:21:22 back-room sudo[1213]: pam_unix(sudo:session): session closed for user root Dec 28 19:21:22 back-room volumio[1001]: info: Shairport-Sync Started Dec 28 19:21:23 back-room volumio[1001]: info: AutoStart - Plugin is starting Dec 28 19:21:23 back-room volumio[1001]: info: CoreCommandRouter::volumioGetQueue Dec 28 19:21:23 back-room volumio[1001]: info: CoreStateMachine::getQueue Dec 28 19:21:23 back-room volumio[1001]: info: CorePlayQueue::getQueue Dec 28 19:21:23 back-room volumio[1001]: info: AutoStart - start playing Dec 28 19:21:23 back-room volumio[1001]: info: AutoStart - start playing with no specific position Dec 28 19:21:23 back-room volumio[1001]: info: CoreCommandRouter::volumioPlay Dec 28 19:21:23 back-room volumio[1001]: info: CoreStateMachine::play index 0 Dec 28 19:21:23 back-room volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 19:21:23 back-room volumio[1001]: info: CoreStateMachine::stop Dec 28 19:21:23 back-room volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 19:21:23 back-room volumio[1001]: info: CoreStateMachine::play index undefined Dec 28 19:21:23 back-room volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 19:21:23 back-room volumio[1001]: info: CorePlayQueue::getTrack 0 Dec 28 19:21:23 back-room volumio[1001]: info: CoreStateMachine::startPlaybackTimer Dec 28 19:21:23 back-room volumio[1001]: info: CorePlayQueue::getTrack 0 Dec 28 19:21:23 back-room volumio[1001]: verbose: ControllerMpd::clearAddPlayTracks USB/1A9C-D582/aaa.mp3 Dec 28 19:21:23 back-room volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 19:21:23 back-room volumio[1001]: info: minidlna: Found miniDLNA version 1.2.1 Dec 28 19:21:23 back-room volumio[1001]: info: minidlna: Starting minidlna.service Dec 28 19:21:23 back-room sudo[1226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Dec 28 19:21:23 back-room sudo[1226]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 19:21:23 back-room volumio[1001]: info: CorePlayQueue::getTrack 0 Dec 28 19:21:23 back-room volumio[1001]: info: CorePlayQueue::getTrack 1766 Dec 28 19:21:23 back-room volumio[1001]: info: Prefetching next song Dec 28 19:21:23 back-room volumio[1001]: info: DOING PREFETCH IN MPD Dec 28 19:21:23 back-room volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "USB/1A9C-D582/Portrait - The Host (2024)/12 - The Men of Renown.mp3" Dec 28 19:21:23 back-room volumio[1001]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 19:21:23 back-room volumio[1001]: TypeError: Cannot read property 'then' of undefined Dec 28 19:21:23 back-room volumio[1001]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) Dec 28 19:21:23 back-room volumio[1001]: at ControllerMpd.prefetch (/volumio/app/plugins/music_service/mpd/index.js:3756:15) Dec 28 19:21:23 back-room volumio[1001]: at CoreStateMachine.increasePlaybackTimer (/volumio/app/statemachine.js:464:18) Dec 28 19:21:23 back-room volumio[1001]: at listOnTimeout (internal/timers.js:554:17) Dec 28 19:21:23 back-room volumio[1001]: at processTimers (internal/timers.js:497:7) Dec 28 19:21:23 back-room volumio[1001]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 19:21:23 back-room systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Dec 28 19:21:23 back-room sudo[1226]: pam_unix(sudo:session): session closed for user root Dec 28 19:21:24 back-room sudo[1243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-28 19:20 Dec 28 19:21:24 back-room sudo[1243]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"