-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Fri 2025-12-26 12:21:37 CET. -- Dec 26 12:20:00 volumio volumio[720]: info: CoreCommandRouter::volumioGetState Dec 26 12:20:10 volumio volumio[720]: info: CoreCommandRouter::volumioGetState Dec 26 12:20:10 volumio volumio[720]: info: Listing playlists Dec 26 12:20:10 volumio volumio[720]: info: Listing playlists Dec 26 12:20:20 volumio volumio[720]: info: CoreCommandRouter::volumioGetState Dec 26 12:20:30 volumio volumio[720]: info: CoreCommandRouter::volumioGetState Dec 26 12:20:30 volumio volumio[720]: info: Listing playlists Dec 26 12:20:30 volumio volumio[720]: info: Listing playlists Dec 26 12:20:40 volumio volumio[720]: info: CoreCommandRouter::volumioGetState Dec 26 12:20:50 volumio volumio[720]: info: CoreCommandRouter::volumioGetState Dec 26 12:20:50 volumio volumio[720]: info: Listing playlists Dec 26 12:20:50 volumio volumio[720]: info: Listing playlists Dec 26 12:21:00 volumio volumio[720]: info: CoreCommandRouter::volumioGetState Dec 26 12:21:10 volumio volumio[720]: info: CoreCommandRouter::volumioGetState Dec 26 12:21:10 volumio volumio[720]: info: Listing playlists Dec 26 12:21:12 volumio volumio[720]: info: Dec 26 12:21:12 volumio volumio[720]: ---------------------------- MPD announces state update: player Dec 26 12:21:12 volumio volumio[720]: info: ControllerMpd::getState Dec 26 12:21:12 volumio volumio[720]: verbose: ControllerMpd::sendMpdCommand status Dec 26 12:21:12 volumio volumio[720]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 26 12:21:12 volumio volumio[720]: info: sendMpdCommand status took 9 milliseconds Dec 26 12:21:12 volumio volumio[720]: verbose: ControllerMpd::parseState Dec 26 12:21:12 volumio volumio[720]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 12:21:12 volumio volumio[720]: info: sendMpdCommand clearerror took 2 milliseconds Dec 26 12:21:12 volumio volumio[720]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 26 12:21:12 volumio volumio[720]: verbose: ControllerMpd::parseTrackInfo Dec 26 12:21:12 volumio volumio[720]: info: ControllerMpd::pushState Dec 26 12:21:12 volumio volumio[720]: info: CoreCommandRouter::servicePushState Dec 26 12:21:12 volumio volumio[720]: info: CorePlayQueue::getTrack 0 Dec 26 12:21:12 volumio volumio[720]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":233234,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Chris Nemmo, Ares Duke, Helena K - Wastin' Time (Original Mix)","artist":"1.FM - Chillout Lounge Radio","album":null,"uri":"http://185.33.21.112:80/chilloutlounge_128","trackType":""} Dec 26 12:21:12 volumio volumio[720]: verbose: CURRENT POSITION 0 Dec 26 12:21:12 volumio volumio[720]: info: CoreStateMachine::syncState stateService pause Dec 26 12:21:12 volumio volumio[720]: info: CoreStateMachine::syncState currentStatus play Dec 26 12:21:12 volumio volumio[720]: info: CoreStateMachine::pushState Dec 26 12:21:12 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 12:21:12 volumio volumio[720]: info: CoreCommandRouter::volumioPushState Dec 26 12:21:12 volumio volumio[720]: info: CoreStateMachine::stPlaybackTimer Dec 26 12:21:12 volumio volumio[720]: info: ------------------------------ 25ms Dec 26 12:21:12 volumio volumio[720]: info: PeppySpectrum ---peppyspectrum status pause Dec 26 12:21:12 volumio volumio[720]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Dec 26 12:21:12 volumio sudo[2109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 26 12:21:12 volumio sudo[2109]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 12:21:12 volumio sudo[2109]: pam_unix(sudo:session): session closed for user root Dec 26 12:21:12 volumio volumio[720]: info: peppyspectrum Daemon Stop Dec 26 12:21:20 volumio volumio[720]: info: CoreCommandRouter::volumioGetState Dec 26 12:21:28 volumio volumio[720]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 26 12:21:28 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 26 12:21:28 volumio volumio[720]: info: Preparing to save Alsa Options, stopping services first Dec 26 12:21:28 volumio volumio[720]: info: CoreCommandRouter::volumioGetState Dec 26 12:21:28 volumio volumio[720]: info: CoreCommandRouter::volumioStop Dec 26 12:21:28 volumio volumio[720]: info: CoreStateMachine::stop Dec 26 12:21:28 volumio volumio[720]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 12:21:28 volumio volumio[720]: info: CoreStateMachine::stPlaybackTimer Dec 26 12:21:28 volumio volumio[720]: info: CoreStateMachine::updateTrackBlock Dec 26 12:21:28 volumio volumio[720]: info: CorePlayQueue::getTrackBlock Dec 26 12:21:28 volumio volumio[720]: info: CoreStateMachine::pushState Dec 26 12:21:28 volumio volumio[720]: info: CorePlayQueue::getTrack 0 Dec 26 12:21:28 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 12:21:28 volumio volumio[720]: info: CoreCommandRouter::volumioPushState Dec 26 12:21:28 volumio volumio[720]: info: CoreStateMachine::serviceStop Dec 26 12:21:28 volumio volumio[720]: info: CorePlayQueue::getTrack 0 Dec 26 12:21:28 volumio volumio[720]: info: CoreCommandRouter::serviceStop Dec 26 12:21:28 volumio volumio[720]: info: [1766748088910] ControllerWebradio::stop Dec 26 12:21:28 volumio volumio[720]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 12:21:28 volumio volumio[720]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"Headphones"},"i2s":true,"i2sid":{"value":"rpi-dac","label":"R-PI DAC"}} Dec 26 12:21:28 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 12:21:28 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 26 12:21:28 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 12:21:28 volumio volumio[720]: info: Enabling I2S DAC: R-PI DAC Dec 26 12:21:28 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Dec 26 12:21:28 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 12:21:28 volumio sudo[2112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Dec 26 12:21:28 volumio sudo[2112]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 12:21:28 volumio sudo[2112]: pam_unix(sudo:session): session closed for user root Dec 26 12:21:28 volumio volumio[720]: info: No Overlays Loaded Dec 26 12:21:28 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 12:21:28 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Dec 26 12:21:28 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 12:21:29 volumio sudo[2116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rpi-dac Dec 26 12:21:29 volumio sudo[2116]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 12:21:29 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Dec 26 12:21:29 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Dec 26 12:21:29 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Dec 26 12:21:29 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Dec 26 12:21:29 volumio sudo[2116]: pam_unix(sudo:session): session closed for user root Dec 26 12:21:29 volumio volumio[720]: warn: Unable to locate the device Headphones a reboot may be required. Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: Found match in i2s Card Database: setting mixer Digital for card R-PI DAC Dec 26 12:21:29 volumio systemd-udevd[2129]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 2' failed with exit code 99. Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 26 12:21:29 volumio volumio[720]: info: Updating Volume Controller Parameters: Device: 2 Name: R-PI DAC Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 26 12:21:29 volumio volumio[720]: info: Disabling external Volume Control Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Dec 26 12:21:29 volumio volumio[720]: info: Preparing to generate the ALSA configuration file Dec 26 12:21:29 volumio volumio[720]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 26 12:21:29 volumio volumio[720]: info: Reading ALSA contributions from plugins. Dec 26 12:21:29 volumio volumio[720]: info: PeppySpectrum ---peppyspectrum status stop Dec 26 12:21:29 volumio volumio[720]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Dec 26 12:21:29 volumio volumio[720]: info: Dec 26 12:21:29 volumio volumio[720]: ---------------------------- MPD announces state update: player Dec 26 12:21:29 volumio volumio[720]: info: sendMpdCommand stop took 324 milliseconds Dec 26 12:21:29 volumio volumio[720]: info: ControllerMpd::getState Dec 26 12:21:29 volumio volumio[720]: verbose: ControllerMpd::sendMpdCommand status Dec 26 12:21:29 volumio sudo[2139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 26 12:21:29 volumio sudo[2139]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 12:21:29 volumio volumio[720]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Digital',0 Dec 26 12:21:29 volumio volumio[720]: info: I2S Param [object Object] successfully enabled Dec 26 12:21:29 volumio sudo[2139]: pam_unix(sudo:session): session closed for user root Dec 26 12:21:29 volumio volumio[720]: info: sendMpdCommand status took 28 milliseconds Dec 26 12:21:29 volumio volumio[720]: verbose: ControllerMpd::parseState Dec 26 12:21:29 volumio volumio[720]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 12:21:29 volumio volumio[720]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 26 12:21:29 volumio volumio[720]: verbose: ControllerMpd::parseTrackInfo Dec 26 12:21:29 volumio volumio[720]: info: ControllerMpd::pushState Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::servicePushState Dec 26 12:21:29 volumio volumio[720]: info: CoreStateMachine::pushState Dec 26 12:21:29 volumio volumio[720]: info: CorePlayQueue::getTrack 0 Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::volumioPushState Dec 26 12:21:29 volumio volumio[720]: info: CorePlayQueue::getTrack 0 Dec 26 12:21:29 volumio volumio[720]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 26 12:21:29 volumio volumio[720]: info: ------------------------------ 48ms Dec 26 12:21:29 volumio volumio[720]: info: peppyspectrum Daemon Stop Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 12:21:29 volumio volumio[720]: info: Getting Alsa Cards List without I2S DAC Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 26 12:21:29 volumio volumio[720]: info: PeppySpectrum ---peppyspectrum status stop Dec 26 12:21:29 volumio volumio[720]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Dec 26 12:21:29 volumio volumio[720]: info: VolumeController:: Volume=undefined Mute =false Dec 26 12:21:29 volumio volumio[720]: info: CoreStateMachine::pushState Dec 26 12:21:29 volumio volumio[720]: info: CorePlayQueue::getTrack 0 Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::volumioPushState Dec 26 12:21:29 volumio sudo[2151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 26 12:21:29 volumio sudo[2151]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 12:21:29 volumio sudo[2151]: pam_unix(sudo:session): session closed for user root Dec 26 12:21:29 volumio volumio[720]: info: PeppySpectrum ---peppyspectrum status stop Dec 26 12:21:29 volumio volumio[720]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Dec 26 12:21:29 volumio volumio[720]: info: peppyspectrum Daemon Stop Dec 26 12:21:29 volumio sudo[2154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 26 12:21:29 volumio sudo[2154]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 12:21:29 volumio sudo[2154]: pam_unix(sudo:session): session closed for user root Dec 26 12:21:29 volumio volumio[720]: info: Asound.conf file written Dec 26 12:21:29 volumio sudo[2157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 26 12:21:29 volumio sudo[2157]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 12:21:29 volumio sudo[2157]: pam_unix(sudo:session): session closed for user root Dec 26 12:21:29 volumio volumio[720]: No state is present for card sndrpirpidac Dec 26 12:21:29 volumio volumio[720]: Found hardware: "RPi-simple" "" "" "" "" Dec 26 12:21:29 volumio volumio[720]: Hardware is initialized using a generic method Dec 26 12:21:29 volumio volumio[720]: No state is present for card sndrpirpidac Dec 26 12:21:29 volumio volumio[720]: info: Output device has changed, restarting MPD Dec 26 12:21:29 volumio sudo[2162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 12:21:29 volumio sudo[2162]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 12:21:29 volumio volumio[720]: info: Output device has changed, restarting Shairport Sync Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 12:21:29 volumio sudo[2162]: pam_unix(sudo:session): session closed for user root Dec 26 12:21:29 volumio sudo[2165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 12:21:29 volumio sudo[2165]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 12:21:29 volumio systemd[1]: Stopping Music Player Daemon... Dec 26 12:21:29 volumio volumio[720]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 12:21:29 volumio systemd[1]: mpd.service: Succeeded. Dec 26 12:21:29 volumio systemd[1]: Stopped Music Player Daemon. Dec 26 12:21:29 volumio volumio[720]: info: Output device has changed, restarting MPD Dec 26 12:21:29 volumio sudo[2172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 12:21:29 volumio sudo[2172]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 12:21:29 volumio volumio[720]: info: Output device has changed, restarting Shairport Sync Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 12:21:29 volumio sudo[2172]: pam_unix(sudo:session): session closed for user root Dec 26 12:21:29 volumio systemd[1]: Starting Music Player Daemon... Dec 26 12:21:29 volumio sudo[2175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 12:21:29 volumio sudo[2175]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 12:21:29 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 26 12:21:29 volumio systemd[1]: mpd.service: Succeeded. Dec 26 12:21:29 volumio systemd[1]: Stopped Music Player Daemon. Dec 26 12:21:29 volumio systemd[1]: Starting Music Player Daemon... Dec 26 12:21:29 volumio volumio[720]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 12:21:29 volumio volumio[720]: info: peppyspectrum Daemon Stop Dec 26 12:21:29 volumio volumio[720]: info: MPD Permissions set Dec 26 12:21:29 volumio volumio[720]: info: MPD Permissions set Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 12:21:29 volumio volumio[720]: info: Starting Shairport Sync Dec 26 12:21:29 volumio volumio[720]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::volumioGetState Dec 26 12:21:29 volumio volumio[720]: info: CorePlayQueue::getTrack 0 Dec 26 12:21:29 volumio volumio[720]: info: CoreCommandRouter::volumioPause Dec 26 12:21:29 volumio volumio[720]: info: CoreStateMachine::pause Dec 26 12:21:29 volumio volumio[720]: info: mpdhttpout --- Volumio set on pause Dec 26 12:21:29 volumio volumio[720]: info: Starting Shairport Sync Dec 26 12:21:29 volumio sudo[2191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 12:21:29 volumio sudo[2191]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 12:21:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 26 12:21:29 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 26 12:21:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 26 12:21:30 volumio volumio[720]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Dec 26 12:21:30 volumio volumio[720]: info: CoreCommandRouter::volumioGetState Dec 26 12:21:30 volumio volumio[720]: info: CorePlayQueue::getTrack 0 Dec 26 12:21:30 volumio volumio[720]: info: CoreCommandRouter::volumioPause Dec 26 12:21:30 volumio volumio[720]: info: CoreStateMachine::pause Dec 26 12:21:30 volumio volumio[720]: info: mpdhttpout --- Volumio set on pause Dec 26 12:21:30 volumio sudo[2194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 12:21:30 volumio sudo[2194]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 12:21:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 26 12:21:30 volumio volumio[720]: info: CoreCommandRouter::volumioGetState Dec 26 12:21:30 volumio volumio[720]: info: CorePlayQueue::getTrack 0 Dec 26 12:21:30 volumio volumio[720]: info: Listing playlists Dec 26 12:21:30 volumio sudo[2191]: pam_unix(sudo:session): session closed for user root Dec 26 12:21:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 26 12:21:30 volumio volumio[720]: info: Shairport-Sync Started Dec 26 12:21:30 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 26 12:21:30 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 26 12:21:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 26 12:21:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 26 12:21:30 volumio sudo[2194]: pam_unix(sudo:session): session closed for user root Dec 26 12:21:30 volumio volumio[720]: info: Shairport-Sync Started Dec 26 12:21:30 volumio mpd[2181]: Dec 26 12:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 26 12:21:30 volumio systemd[1]: Started Music Player Daemon. Dec 26 12:21:30 volumio sudo[2165]: pam_unix(sudo:session): session closed for user root Dec 26 12:21:30 volumio sudo[2175]: pam_unix(sudo:session): session closed for user root Dec 26 12:21:30 volumio volumio[720]: error: MPD error: The expression evaluated to a falsy value: Dec 26 12:21:30 volumio volumio[720]: assert.ok(self.idling) Dec 26 12:21:30 volumio volumio[720]: error: The expression evaluated to a falsy value: Dec 26 12:21:30 volumio volumio[720]: assert.ok(self.idling) Dec 26 12:21:30 volumio volumio[720]: error: updateQueue error: null Dec 26 12:21:30 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Dec 26 12:21:30 volumio volumio[2206]: /bin/bash: /data/plugins/system_hardware/music_services_shield/moveallprocesses.sh: No such file or directory Dec 26 12:21:30 volumio systemd[1]: musicservicesshield.service: Main process exited, code=exited, status=127/n/a Dec 26 12:21:30 volumio systemd[1]: musicservicesshield.service: Failed with result 'exit-code'. Dec 26 12:21:30 volumio systemd[1]: Failed to start Shield Volumio Music Services in the User CPU Set. Dec 26 12:21:32 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 26 12:21:32 volumio sudo[2208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 12:21:32 volumio sudo[2208]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 12:21:32 volumio volumio[720]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 26 12:21:32 volumio systemd[1]: Stopping Music Player Daemon... Dec 26 12:21:32 volumio systemd[1]: mpd.service: Succeeded. Dec 26 12:21:32 volumio systemd[1]: Stopped Music Player Daemon. Dec 26 12:21:32 volumio sudo[2211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 12:21:32 volumio sudo[2211]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 12:21:32 volumio systemd[1]: Starting Music Player Daemon... Dec 26 12:21:32 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 26 12:21:32 volumio systemd[1]: mpd.service: Succeeded. Dec 26 12:21:32 volumio systemd[1]: Stopped Music Player Daemon. Dec 26 12:21:32 volumio systemd[1]: Starting Music Player Daemon... Dec 26 12:21:32 volumio mpd[2215]: Dec 26 12:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 26 12:21:32 volumio systemd[1]: Started Music Player Daemon. Dec 26 12:21:32 volumio sudo[2208]: pam_unix(sudo:session): session closed for user root Dec 26 12:21:32 volumio sudo[2211]: pam_unix(sudo:session): session closed for user root Dec 26 12:21:32 volumio volumio[720]: error: updateQueue error: null Dec 26 12:21:32 volumio volumio[720]: error: updateQueue error: null Dec 26 12:21:32 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Dec 26 12:21:32 volumio volumio[2218]: /bin/bash: /data/plugins/system_hardware/music_services_shield/moveallprocesses.sh: No such file or directory Dec 26 12:21:32 volumio systemd[1]: musicservicesshield.service: Main process exited, code=exited, status=127/n/a Dec 26 12:21:32 volumio systemd[1]: musicservicesshield.service: Failed with result 'exit-code'. Dec 26 12:21:32 volumio systemd[1]: Failed to start Shield Volumio Music Services in the User CPU Set. Dec 26 12:21:33 volumio volumio[720]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 26 12:21:33 volumio volumio[720]: info: CoreStateMachine::getcurrentVolume Dec 26 12:21:33 volumio volumio[720]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 12:21:33 volumio volumio[720]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Digital',0 Dec 26 12:21:33 volumio volumio[720]: info: VolumeController:: Volume=undefined Mute =false Dec 26 12:21:33 volumio volumio[720]: info: CoreStateMachine::pushState Dec 26 12:21:33 volumio volumio[720]: info: CorePlayQueue::getTrack 0 Dec 26 12:21:33 volumio volumio[720]: info: CoreCommandRouter::volumioPushState Dec 26 12:21:33 volumio volumio[720]: info: CoreStateMachine::updateTrackBlock Dec 26 12:21:33 volumio volumio[720]: info: CorePlayQueue::getTrackBlock Dec 26 12:21:33 volumio volumio[720]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 12:21:33 volumio volumio[720]: info: PeppySpectrum ---peppyspectrum status stop Dec 26 12:21:33 volumio volumio[720]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Dec 26 12:21:33 volumio volumio[720]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Digital',0 Dec 26 12:21:33 volumio sudo[2223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 26 12:21:33 volumio sudo[2223]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 12:21:33 volumio sudo[2223]: pam_unix(sudo:session): session closed for user root Dec 26 12:21:33 volumio volumio[720]: info: peppyspectrum Daemon Stop Dec 26 12:21:33 volumio volumio[720]: info: VolumeController:: Volume=undefined Mute =false Dec 26 12:21:33 volumio volumio[720]: info: CoreStateMachine::pushState Dec 26 12:21:33 volumio volumio[720]: info: CorePlayQueue::getTrack 0 Dec 26 12:21:33 volumio volumio[720]: info: CoreCommandRouter::volumioPushState Dec 26 12:21:33 volumio volumio[720]: info: PeppySpectrum ---peppyspectrum status stop Dec 26 12:21:33 volumio volumio[720]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Dec 26 12:21:33 volumio sudo[2227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 26 12:21:33 volumio sudo[2227]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 12:21:33 volumio sudo[2227]: pam_unix(sudo:session): session closed for user root Dec 26 12:21:33 volumio volumio[720]: info: peppyspectrum Daemon Stop Dec 26 12:21:37 volumio volumio[720]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Dec 26 12:21:37 volumio volumio[720]: info: PLUGIN onReboot : networkfs Dec 26 12:21:37 volumio volumio[720]: info: PLUGIN onReboot : audiophonicsonoff Dec 26 12:21:37 volumio volumio[720]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 12:21:37 volumio volumio[720]: TypeError: Cannot read property 'writeSync' of undefined Dec 26 12:21:37 volumio volumio[720]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25) Dec 26 12:21:37 volumio volumio[720]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Dec 26 12:21:37 volumio volumio[720]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Dec 26 12:21:37 volumio volumio[720]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 26 12:21:37 volumio volumio[720]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 26 12:21:37 volumio volumio[720]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Dec 26 12:21:37 volumio volumio[720]: at CoreCommandRouter.reboot (/volumio/app/index.js:1325:22) Dec 26 12:21:37 volumio volumio[720]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:836:33) Dec 26 12:21:37 volumio volumio[720]: at Socket.emit (events.js:315:20) Dec 26 12:21:37 volumio volumio[720]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 26 12:21:37 volumio volumio[720]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Dec 26 12:21:37 volumio volumio[720]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 12:21:37 volumio sudo[2238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-26 12:20 Dec 26 12:21:37 volumio sudo[2238]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"