-- Logs begin at Fri 2024-11-08 23:27:21 IST, end at Sun 2024-12-01 12:22:22 IST. -- Dec 01 12:21:03 volumio-ani ntpd[862]: Soliciting pool server 64:ff9b::819a:2e9a Dec 01 12:21:12 volumio-ani volumiologrotate[695]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Dec 01 12:21:12 volumio-ani volumiologrotate[695]: ls: cannot access 'ANI': No such file or directory Dec 01 12:21:44 volumio-ani ntpd[862]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 01 12:21:55 volumio-ani ntpd[862]: Soliciting pool server 2400:8904::f03c:92ff:fe80:f6f4 Dec 01 12:21:59 volumio-ani ntpd[862]: Soliciting pool server 64:ff9b::e8b:3c6a Dec 01 12:21:59 volumio-ani ntpd[862]: Soliciting pool server 64:ff9b::9846:45e8 Dec 01 12:22:12 volumio-ani volumiologrotate[695]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Dec 01 12:22:12 volumio-ani volumiologrotate[695]: ls: cannot access 'ANI': No such file or directory Dec 01 12:22:13 volumio-ani ntpd[862]: Soliciting pool server 192.46.215.141 Dec 01 12:22:19 volumio-ani volumio[898]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 01 12:22:19 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 01 12:22:19 volumio-ani volumio[898]: info: Preparing to save Alsa Options, stopping services first Dec 01 12:22:19 volumio-ani volumio[898]: info: CoreCommandRouter::volumioGetState Dec 01 12:22:19 volumio-ani volumio[898]: info: CorePlayQueue::getTrack 0 Dec 01 12:22:19 volumio-ani volumio[898]: info: CoreCommandRouter::volumioPause Dec 01 12:22:19 volumio-ani volumio[898]: info: CoreStateMachine::pause Dec 01 12:22:19 volumio-ani volumio[898]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"hifiberry-dacplus","label":"HiFiBerry DAC Plus"}} Dec 01 12:22:19 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 01 12:22:19 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 01 12:22:19 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 12:22:19 volumio-ani volumio[898]: info: Enabling I2S DAC: HiFiBerry DAC Plus Dec 01 12:22:19 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Dec 01 12:22:19 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 12:22:19 volumio-ani sudo[3180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Dec 01 12:22:19 volumio-ani sudo[3180]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 12:22:19 volumio-ani sudo[3180]: pam_unix(sudo:session): session closed for user root Dec 01 12:22:19 volumio-ani volumio[898]: info: No Overlays Loaded Dec 01 12:22:19 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 12:22:19 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Dec 01 12:22:19 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 12:22:19 volumio-ani sudo[3183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-dacplus Dec 01 12:22:19 volumio-ani sudo[3183]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 12:22:19 volumio-ani sudo[3183]: pam_unix(sudo:session): session closed for user root Dec 01 12:22:19 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 01 12:22:19 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Dec 01 12:22:19 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 12:22:19 volumio-ani volumio[898]: info: Found match in i2s Card Database: setting mixer Digital for card HiFiBerry DAC Plus Dec 01 12:22:19 volumio-ani volumio[898]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 01 12:22:19 volumio-ani volumio[898]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC Plus Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 01 12:22:19 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 01 12:22:19 volumio-ani volumio[898]: info: Disabling external Volume Control Dec 01 12:22:19 volumio-ani volumio[898]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Dec 01 12:22:20 volumio-ani volumio[898]: info: Preparing to generate the ALSA configuration file Dec 01 12:22:20 volumio-ani volumio[898]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 01 12:22:20 volumio-ani volumio[898]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 01 12:22:20 volumio-ani volumio[898]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 01 12:22:20 volumio-ani volumio[898]: info: Reading ALSA contributions from plugins. Dec 01 12:22:20 volumio-ani volumio[898]: error: Cannot enable I2S Param: Error: Command failed: /usr/bin/sudo /usr/bin/dtoverlay hifiberry-dacplus Dec 01 12:22:20 volumio-ani volumio[898]: * Failed to apply overlay '0_hifiberry-dacplus' (kernel) Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 01 12:22:20 volumio-ani volumio[898]: info: Getting Alsa Cards List without I2S DAC Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 01 12:22:20 volumio-ani volumio[898]: info: VolumeController:: Volume=3 Mute =false Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreStateMachine::pushState Dec 01 12:22:20 volumio-ani volumio[898]: info: CorePlayQueue::getTrack 0 Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::volumioPushState Dec 01 12:22:20 volumio-ani volumio[898]: info: [GPIO_Control] Status changed: pause Dec 01 12:22:20 volumio-ani volumio[898]: info: PeppyMeterBasic ---peppymeterbasic status pause Dec 01 12:22:20 volumio-ani volumio[898]: info: PeppySpectrum ---peppyspectrum status pause Dec 01 12:22:20 volumio-ani sudo[3208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 01 12:22:20 volumio-ani sudo[3208]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 12:22:20 volumio-ani sudo[3210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 01 12:22:20 volumio-ani sudo[3210]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 12:22:20 volumio-ani volumio[898]: info: Asound.conf file unchanged, so no further update is needed Dec 01 12:22:20 volumio-ani volumio[898]: info: Output device has changed, restarting MPD Dec 01 12:22:20 volumio-ani sudo[3210]: pam_unix(sudo:session): session closed for user root Dec 01 12:22:20 volumio-ani volumio[898]: info: Output device has changed, restarting Shairport Sync Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 12:22:20 volumio-ani sudo[3215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 01 12:22:20 volumio-ani sudo[3215]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 12:22:20 volumio-ani sudo[3208]: pam_unix(sudo:session): session closed for user root Dec 01 12:22:20 volumio-ani sudo[3216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 01 12:22:20 volumio-ani sudo[3216]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 12:22:20 volumio-ani sudo[3215]: pam_unix(sudo:session): session closed for user root Dec 01 12:22:20 volumio-ani systemd[1]: musicservicesshield.service: Succeeded. Dec 01 12:22:20 volumio-ani systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Dec 01 12:22:20 volumio-ani systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Dec 01 12:22:20 volumio-ani systemd[1]: Stopping Music Player Daemon... Dec 01 12:22:20 volumio-ani volumio[898]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 01 12:22:20 volumio-ani volumio[898]: info: MPD Permissions set Dec 01 12:22:20 volumio-ani volumio[898]: info: peppymeterbasic Daemon Stop Dec 01 12:22:20 volumio-ani volumio[898]: info: peppyspectrum Daemon Stop Dec 01 12:22:20 volumio-ani systemd[1]: mpd.service: Succeeded. Dec 01 12:22:20 volumio-ani systemd[1]: Stopped Music Player Daemon. Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 12:22:20 volumio-ani volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 12:22:20 volumio-ani volumio[898]: info: Starting Shairport Sync Dec 01 12:22:20 volumio-ani systemd[1]: Starting Music Player Daemon... Dec 01 12:22:20 volumio-ani sudo[3228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 01 12:22:20 volumio-ani sudo[3228]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 12:22:20 volumio-ani systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 01 12:22:20 volumio-ani systemd[1]: shairport-sync.service: Succeeded. Dec 01 12:22:20 volumio-ani systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 01 12:22:20 volumio-ani systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 01 12:22:20 volumio-ani sudo[3228]: pam_unix(sudo:session): session closed for user root Dec 01 12:22:20 volumio-ani volumio[898]: info: Shairport-Sync Started Dec 01 12:22:20 volumio-ani sudo[3222]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 01 12:22:20 volumio-ani sudo[3222]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 12:22:20 volumio-ani sudo[3222]: pam_unix(sudo:session): session closed for user root Dec 01 12:22:21 volumio-ani volumio[898]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Dec 01 12:22:21 volumio-ani volumio[898]: info: PLUGIN onReboot : networkfs Dec 01 12:22:21 volumio-ani volumio[898]: info: PLUGIN onReboot : audiophonicsonoff Dec 01 12:22:21 volumio-ani volumio[898]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 12:22:21 volumio-ani volumio[898]: TypeError: Cannot read property 'writeSync' of undefined Dec 01 12:22:21 volumio-ani volumio[898]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25) Dec 01 12:22:21 volumio-ani volumio[898]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Dec 01 12:22:21 volumio-ani volumio[898]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Dec 01 12:22:21 volumio-ani volumio[898]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 01 12:22:21 volumio-ani volumio[898]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 01 12:22:21 volumio-ani volumio[898]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Dec 01 12:22:21 volumio-ani volumio[898]: at CoreCommandRouter.reboot (/volumio/app/index.js:1345:22) Dec 01 12:22:21 volumio-ani volumio[898]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:864:33) Dec 01 12:22:21 volumio-ani volumio[898]: at Socket.emit (events.js:315:20) Dec 01 12:22:21 volumio-ani volumio[898]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Dec 01 12:22:21 volumio-ani volumio[898]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Dec 01 12:22:21 volumio-ani volumio[898]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 12:22:22 volumio-ani sudo[3245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-01 12:21 Dec 01 12:22:22 volumio-ani sudo[3245]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"