-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2025-12-06 20:37:44 CET. -- Dec 06 20:36:43 volumio5 volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 20:36:43 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 06 20:36:43 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 06 20:36:43 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 06 20:36:43 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Dec 06 20:36:43 volumio5 volumio[1037]: info: Received Get System Version Dec 06 20:36:43 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 20:36:43 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Dec 06 20:36:55 volumio5 volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 20:36:55 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 06 20:36:55 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 20:36:55 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 06 20:36:55 volumio5 volumio[1037]: info: Getting Alsa Cards List without I2S DAC Dec 06 20:36:55 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 06 20:36:55 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 20:36:55 volumio5 volumio[1037]: Invalid card number. Dec 06 20:36:55 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 20:36:55 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 20:36:55 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 20:36:55 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 20:36:55 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 20:36:55 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 20:36:55 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 20:36:55 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 06 20:36:55 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 06 20:36:58 volumio5 volumio[1037]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 06 20:36:58 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 06 20:36:58 volumio5 volumio[1037]: info: Preparing to save Alsa Options, stopping services first Dec 06 20:36:58 volumio5 volumio[1037]: info: CoreCommandRouter::volumioGetState Dec 06 20:36:58 volumio5 volumio[1037]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"Headphones"},"i2s":true,"i2sid":{"value":"hifiberry-dacplushd","label":"HiFiBerry DAC2 HD"}} Dec 06 20:36:58 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 06 20:36:58 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 06 20:36:58 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 20:36:58 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Dec 06 20:36:58 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 20:36:58 volumio5 volumio[1037]: warn: Unable to locate the device Headphones a reboot may be required. Dec 06 20:36:58 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 06 20:36:58 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Dec 06 20:36:58 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 20:36:58 volumio5 volumio[1037]: info: Found match in i2s Card Database: setting mixer DAC for card HiFiBerry DAC2 HD Dec 06 20:36:58 volumio5 volumio[1037]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 06 20:36:58 volumio5 volumio[1037]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC2 HD Mixer: DAC Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 06 20:36:58 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 06 20:36:58 volumio5 volumio[1037]: info: Disabling external Volume Control Dec 06 20:36:58 volumio5 volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 20:36:58 volumio5 volumio[1037]: info: Preparing to generate the ALSA configuration file Dec 06 20:36:58 volumio5 volumio[1037]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 06 20:36:58 volumio5 volumio[1037]: info: Asound.conf file unchanged, so no further update is needed Dec 06 20:36:58 volumio5 volumio[1037]: info: Output device has changed, restarting MPD Dec 06 20:36:58 volumio5 sudo[2688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 06 20:36:58 volumio5 sudo[2688]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 20:36:58 volumio5 volumio[1037]: info: VolumeController:: Volume=undefined Mute =false Dec 06 20:36:58 volumio5 volumio[1037]: info: CoreStateMachine::pushState Dec 06 20:36:58 volumio5 sudo[2688]: pam_unix(sudo:session): session closed for user root Dec 06 20:36:58 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 20:36:58 volumio5 volumio[1037]: info: CoreCommandRouter::volumioPushState Dec 06 20:36:58 volumio5 sudo[2691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 20:36:58 volumio5 sudo[2691]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 20:36:58 volumio5 systemd[1]: Stopping Music Player Daemon... Dec 06 20:36:58 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 06 20:36:58 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 20:36:58 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 06 20:36:58 volumio5 volumio[1037]: info: Getting Alsa Cards List without I2S DAC Dec 06 20:36:58 volumio5 systemd[1]: mpd.service: Succeeded. Dec 06 20:36:58 volumio5 systemd[1]: Stopped Music Player Daemon. Dec 06 20:36:58 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 06 20:36:58 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 20:36:59 volumio5 volumio[1037]: Invalid card number. Dec 06 20:36:59 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 20:36:59 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 20:36:59 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 20:36:59 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 20:36:59 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 20:36:59 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 20:36:59 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 20:36:59 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 06 20:36:59 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 06 20:36:59 volumio5 volumio[1037]: info: MPD Permissions set Dec 06 20:36:59 volumio5 systemd[1]: Starting Music Player Daemon... Dec 06 20:36:59 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 20:36:59 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 20:36:59 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 20:36:59 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 20:36:59 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 20:36:59 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 20:36:59 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 20:36:59 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 20:36:59 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 20:36:59 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 20:36:59 volumio5 sudo[2698]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 06 20:36:59 volumio5 sudo[2698]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 20:36:59 volumio5 sudo[2698]: pam_unix(sudo:session): session closed for user root Dec 06 20:36:59 volumio5 mpd[2708]: Dec 06 20:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 06 20:36:59 volumio5 systemd[1]: Started Music Player Daemon. Dec 06 20:36:59 volumio5 sudo[2691]: pam_unix(sudo:session): session closed for user root Dec 06 20:36:59 volumio5 volumio[1037]: error: updateQueue error: null Dec 06 20:37:02 volumio5 sudo[2715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 06 20:37:02 volumio5 sudo[2715]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 20:37:02 volumio5 systemd[1]: Stopping MPD Monitor Service... Dec 06 20:37:02 volumio5 systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 06 20:37:02 volumio5 systemd[1]: mpd_monitor.service: Succeeded. Dec 06 20:37:02 volumio5 systemd[1]: Stopped MPD Monitor Service. Dec 06 20:37:02 volumio5 volumio[1037]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 06 20:37:02 volumio5 volumio[1037]: info: CoreStateMachine::getcurrentVolume Dec 06 20:37:02 volumio5 volumio[1037]: info: CoreCommandRouter::volumioRetrievevolume Dec 06 20:37:02 volumio5 volumio[1037]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 06 20:37:02 volumio5 systemd[1]: Started MPD Monitor Service. Dec 06 20:37:02 volumio5 sudo[2715]: pam_unix(sudo:session): session closed for user root Dec 06 20:37:02 volumio5 mpd_monitor.sh[2717]: MPD Monitor Service: Starting MPD Monitor Service Dec 06 20:37:02 volumio5 volumio[1037]: info: Successfully started MPD Monitor Dec 06 20:37:02 volumio5 volumio[1037]: info: VolumeController:: Volume=undefined Mute =false Dec 06 20:37:02 volumio5 volumio[1037]: info: CoreStateMachine::pushState Dec 06 20:37:02 volumio5 volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 20:37:02 volumio5 volumio[1037]: info: CoreCommandRouter::volumioPushState Dec 06 20:37:02 volumio5 volumio[1037]: info: CoreStateMachine::updateTrackBlock Dec 06 20:37:02 volumio5 volumio[1037]: info: CorePlayQueue::getTrackBlock Dec 06 20:37:02 volumio5 volumio[1037]: info: CoreCommandRouter::volumioRetrievevolume Dec 06 20:37:02 volumio5 volumio[1037]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 06 20:37:02 volumio5 volumio[1037]: info: VolumeController:: Volume=undefined Mute =false Dec 06 20:37:02 volumio5 volumio[1037]: info: CoreStateMachine::pushState Dec 06 20:37:02 volumio5 volumio[1037]: info: CoreCommandRouter::volumioPushState Dec 06 20:37:44 volumio5 volumio[1037]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Dec 06 20:37:44 volumio5 volumio[1037]: info: PLUGIN onReboot : networkfs Dec 06 20:37:44 volumio5 sudo[2792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/DiskStation2 Dec 06 20:37:44 volumio5 sudo[2792]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 20:37:44 volumio5 sudo[2792]: pam_unix(sudo:session): session closed for user root Dec 06 20:37:44 volumio5 volumio[1037]: umount: /mnt/NAS/DiskStation2: not mounted. Dec 06 20:37:44 volumio5 volumio[1037]: error: Cannot umount share DiskStation2 : Error: Command failed: /usr/bin/sudo /bin/umount -f /mnt/NAS/DiskStation2 Dec 06 20:37:44 volumio5 volumio[1037]: umount: /mnt/NAS/DiskStation2: not mounted. Dec 06 20:37:44 volumio5 volumio[1037]: info: PLUGIN onReboot : audiophonicsonoff Dec 06 20:37:44 volumio5 volumio[1037]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 20:37:44 volumio5 volumio[1037]: TypeError: Cannot read property 'writeSync' of undefined Dec 06 20:37:44 volumio5 volumio[1037]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Dec 06 20:37:44 volumio5 volumio[1037]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Dec 06 20:37:44 volumio5 volumio[1037]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Dec 06 20:37:44 volumio5 volumio[1037]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 06 20:37:44 volumio5 volumio[1037]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 06 20:37:44 volumio5 volumio[1037]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Dec 06 20:37:44 volumio5 volumio[1037]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) Dec 06 20:37:44 volumio5 volumio[1037]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33) Dec 06 20:37:44 volumio5 volumio[1037]: at Socket.emit (events.js:315:20) Dec 06 20:37:44 volumio5 volumio[1037]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Dec 06 20:37:44 volumio5 volumio[1037]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Dec 06 20:37:44 volumio5 volumio[1037]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 20:37:44 volumio5 sudo[2803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-06 20:36 Dec 06 20:37:44 volumio5 sudo[2803]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"