Jan 11 13:37:00 volumio-keuken volumio[1280]: info: PeppySpectrum ---peppyspectrum status pause Jan 11 13:37:00 volumio-keuken volumio[1280]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 11 13:37:00 volumio-keuken sudo[2385]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jan 11 13:37:00 volumio-keuken sudo[2385]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 13:37:00 volumio-keuken sudo[2387]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jan 11 13:37:00 volumio-keuken sudo[2387]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 13:37:00 volumio-keuken sudo[2385]: pam_unix(sudo:session): session closed for user root Jan 11 13:37:00 volumio-keuken sudo[2387]: pam_unix(sudo:session): session closed for user root Jan 11 13:37:00 volumio-keuken volumio[1280]: info: peppymeterbasic Daemon Stop Jan 11 13:37:00 volumio-keuken volumio[1280]: info: peppyspectrum Daemon Stop Jan 11 13:37:00 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:00+01:00" level=debug msg="handling resume player command from 702f1f8fa5f27622631900771b0267769ffaf71b" Jan 11 13:37:00 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:00+01:00" level=trace msg="seek to 274481ms (diff: 128ms, samples: 12104612, bytes: 10962683)" uri="spotify:track:6nSOhDfZOulWp0pnEHFrnn" Jan 11 13:37:01 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:01+01:00" level=warning msg="failed handling dealer request" error="failed starting playback: ALSA error at snd_pcm_hw_params_set_buffer_time_near: Invalid argument" Jan 11 13:37:01 volumio-keuken volumio[1280]: SPOTIFY: SETTING SPOTIFY VOLUME 86 Jan 11 13:37:01 volumio-keuken volumio[1280]: info: Sending Spotify command with payload to local API: /player/volume Jan 11 13:37:01 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:01+01:00" level=debug msg="update volume requested to 56360/65535" Jan 11 13:37:01 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:01+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 11 13:37:01 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:01+01:00" level=trace msg="emitting websocket event: volume" Jan 11 13:37:01 volumio-keuken volumio[1280]: SPOTIFY: received: {"type":"volume","data":{"value":86,"max":100}} Jan 11 13:37:01 volumio-keuken volumio[1280]: SPOTIFY: RECEIVED SPOTIFY VOLUME 86 Jan 11 13:37:06 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:06+01:00" level=debug msg="handling resume player command from 702f1f8fa5f27622631900771b0267769ffaf71b" Jan 11 13:37:06 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:06+01:00" level=trace msg="seek to 274481ms (diff: 128ms, samples: 12104612, bytes: 10962683)" uri="spotify:track:6nSOhDfZOulWp0pnEHFrnn" Jan 11 13:37:06 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:06+01:00" level=warning msg="failed handling dealer request" error="failed seeking before play: ALSA error at snd_pcm_drop: Input/output error" Jan 11 13:37:26 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:26+01:00" level=debug msg="handling seek_to player command from 702f1f8fa5f27622631900771b0267769ffaf71b" Jan 11 13:37:26 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:26+01:00" level=warning msg="failed getting output device delay" error="ALSA error at snd_pcm_delay: Input/output error" Jan 11 13:37:26 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:26+01:00" level=debug msg="seek track to 0ms" Jan 11 13:37:26 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:26+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:6nSOhDfZOulWp0pnEHFrnn" Jan 11 13:37:26 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:26+01:00" level=warning msg="failed handling dealer request" error="failed seeking stream: ALSA error at snd_pcm_drop: Input/output error" Jan 11 13:37:27 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:27+01:00" level=debug msg="handling seek_to player command from 702f1f8fa5f27622631900771b0267769ffaf71b" Jan 11 13:37:27 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:27+01:00" level=warning msg="failed getting output device delay" error="ALSA error at snd_pcm_delay: Input/output error" Jan 11 13:37:27 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:27+01:00" level=debug msg="seek track to 0ms" Jan 11 13:37:27 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:27+01:00" level=debug msg="vorbis: corrupt or missing data in bitstream" uri="spotify:track:6nSOhDfZOulWp0pnEHFrnn" Jan 11 13:37:27 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:27+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:6nSOhDfZOulWp0pnEHFrnn" Jan 11 13:37:27 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:27+01:00" level=warning msg="failed handling dealer request" error="failed seeking stream: ALSA error at snd_pcm_drop: Input/output error" Jan 11 13:37:27 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:27+01:00" level=debug msg="handling seek_to player command from 702f1f8fa5f27622631900771b0267769ffaf71b" Jan 11 13:37:27 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:27+01:00" level=warning msg="failed getting output device delay" error="ALSA error at snd_pcm_delay: Input/output error" Jan 11 13:37:27 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:27+01:00" level=debug msg="seek track to 0ms" Jan 11 13:37:27 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:27+01:00" level=debug msg="vorbis: corrupt or missing data in bitstream" uri="spotify:track:6nSOhDfZOulWp0pnEHFrnn" Jan 11 13:37:27 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:27+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:6nSOhDfZOulWp0pnEHFrnn" Jan 11 13:37:27 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:27+01:00" level=warning msg="failed handling dealer request" error="failed seeking stream: ALSA error at snd_pcm_drop: Input/output error" Jan 11 13:37:27 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:27+01:00" level=debug msg="handling seek_to player command from 702f1f8fa5f27622631900771b0267769ffaf71b" Jan 11 13:37:27 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:27+01:00" level=warning msg="failed getting output device delay" error="ALSA error at snd_pcm_delay: Input/output error" Jan 11 13:37:27 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:27+01:00" level=debug msg="seek track to 0ms" Jan 11 13:37:27 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:27+01:00" level=debug msg="vorbis: corrupt or missing data in bitstream" uri="spotify:track:6nSOhDfZOulWp0pnEHFrnn" Jan 11 13:37:27 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:27+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:6nSOhDfZOulWp0pnEHFrnn" Jan 11 13:37:27 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:27+01:00" level=warning msg="failed handling dealer request" error="failed seeking stream: ALSA error at snd_pcm_drop: Input/output error" Jan 11 13:37:29 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:29+01:00" level=trace msg="sent dealer ping" Jan 11 13:37:29 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:29+01:00" level=trace msg="received dealer pong" Jan 11 13:37:35 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:35+01:00" level=debug msg="handling resume player command from 702f1f8fa5f27622631900771b0267769ffaf71b" Jan 11 13:37:35 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:35+01:00" level=debug msg="vorbis: corrupt or missing data in bitstream" uri="spotify:track:6nSOhDfZOulWp0pnEHFrnn" Jan 11 13:37:35 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:35+01:00" level=trace msg="seek to 274481ms (diff: 128ms, samples: 12104612, bytes: 10962683)" uri="spotify:track:6nSOhDfZOulWp0pnEHFrnn" Jan 11 13:37:35 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:35+01:00" level=warning msg="failed handling dealer request" error="failed seeking before play: ALSA error at snd_pcm_drop: Input/output error" Jan 11 13:37:39 volumio-keuken volumio[1280]: info: CoreCommandRouter::volumioGetState Jan 11 13:37:40 volumio-keuken volumio[1280]: info: CoreCommandRouter::volumioVolatilePlay Jan 11 13:37:40 volumio-keuken volumio[1280]: info: CoreStateMachine::volatilePlay Jan 11 13:37:40 volumio-keuken volumio[1280]: info: Spotify Play Jan 11 13:37:40 volumio-keuken volumio[1280]: info: Sending Spotify command to local API: /player/resume Jan 11 13:37:40 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:40+01:00" level=trace msg="seek to 274481ms (diff: 128ms, samples: 12104612, bytes: 10962683)" uri="spotify:track:6nSOhDfZOulWp0pnEHFrnn" Jan 11 13:37:57 volumio-keuken volumio[1280]: verbose: New Socket.io Connection to 192.168.0.213 from 192.168.0.175 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jan 11 13:37:57 volumio-keuken volumio[1280]: info: CoreCommandRouter::volumioGetState Jan 11 13:37:57 volumio-keuken volumio[1280]: info: Received Get System Info Jan 11 13:37:57 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 13:37:57 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 13:37:57 volumio-keuken volumio[1280]: info: Discovery: Getting this device information Jan 11 13:37:57 volumio-keuken volumio[1280]: info: CoreCommandRouter::volumioGetState Jan 11 13:37:57 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 13:37:57 volumio-keuken volumio[1280]: info: CoreCommandRouter::volumioGetState Jan 11 13:37:57 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 11 13:37:57 volumio-keuken volumio[1280]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 13:37:57 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 13:37:57 volumio-keuken volumio[1280]: info: CoreCommandRouter::volumioGetState Jan 11 13:37:57 volumio-keuken volumio[1280]: info: CoreCommandRouter::volumioGetState Jan 11 13:37:57 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 11 13:37:57 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 11 13:37:57 volumio-keuken volumio[1280]: info: Received Get System Info Jan 11 13:37:57 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 13:37:57 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 13:37:57 volumio-keuken volumio[1280]: info: Discovery: Getting this device information Jan 11 13:37:57 volumio-keuken volumio[1280]: info: CoreCommandRouter::volumioGetState Jan 11 13:37:57 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 13:37:57 volumio-keuken volumio[1280]: info: CoreCommandRouter::volumioGetState Jan 11 13:37:57 volumio-keuken volumio[1280]: info: Listing playlists Jan 11 13:37:57 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 11 13:37:59 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:59+01:00" level=trace msg="sent dealer ping" Jan 11 13:37:59 volumio-keuken go-librespot[1681]: time="2026-01-11T13:37:59+01:00" level=trace msg="received dealer pong" Jan 11 13:38:07 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:38:11 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alarm-clock , getSleep Jan 11 13:38:21 volumio-keuken volumio[1280]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 13:38:21 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 11 13:38:21 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:38:21 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 11 13:38:21 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:38:21 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:38:21 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:38:21 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:38:21 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:38:21 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:38:21 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:38:21 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 11 13:38:21 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jan 11 13:38:27 volumio-keuken volumio[1280]: info: Preparing to save Alsa Options, stopping services first Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CoreCommandRouter::volumioGetState Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CoreCommandRouter::volumioPause Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CoreStateMachine::pause Jan 11 13:38:27 volumio-keuken volumio[1280]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 11 13:38:27 volumio-keuken volumio[1280]: info: Enabling PI HDMI Output Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 11 13:38:27 volumio-keuken volumio[1280]: info: Found match in Cards Database: setting mixer PCM for card HDMI Out Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 11 13:38:27 volumio-keuken volumio[1280]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI Out Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 11 13:38:27 volumio-keuken volumio[1280]: info: Disabling external Volume Control Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 13:38:27 volumio-keuken volumio[1280]: info: Preparing to generate the ALSA configuration file Jan 11 13:38:27 volumio-keuken volumio[1280]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Jan 11 13:38:27 volumio-keuken volumio[1280]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jan 11 13:38:27 volumio-keuken volumio[1280]: info: Reading ALSA contributions from plugins. Jan 11 13:38:27 volumio-keuken volumio[1280]: info: Raspberry PI HDMI Output Enabled Jan 11 13:38:27 volumio-keuken sudo[2562]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl store Jan 11 13:38:27 volumio-keuken sudo[2562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 11 13:38:27 volumio-keuken sudo[2562]: pam_unix(sudo:session): session closed for user root Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 11 13:38:27 volumio-keuken volumio[1280]: info: Alsa Settings successfully stored Jan 11 13:38:27 volumio-keuken volumio[1280]: info: VolumeController:: Volume=86 Mute =false Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CoreStateMachine::pushState Jan 11 13:38:27 volumio-keuken volumio[1280]: info: CoreCommandRouter::volumioPushState Jan 11 13:38:27 volumio-keuken volumio[1280]: info: PeppyMeterBasic ---peppymeterbasic status pause Jan 11 13:38:28 volumio-keuken volumio[1280]: info: PeppySpectrum ---peppyspectrum status pause Jan 11 13:38:28 volumio-keuken sudo[2576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jan 11 13:38:28 volumio-keuken volumio[1280]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 11 13:38:28 volumio-keuken sudo[2576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 13:38:28 volumio-keuken sudo[2578]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jan 11 13:38:28 volumio-keuken sudo[2578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 13:38:28 volumio-keuken volumio[1280]: info: Asound.conf file unchanged, so no further update is needed Jan 11 13:38:28 volumio-keuken volumio[1280]: info: Output device has changed, restarting MPD Jan 11 13:38:28 volumio-keuken volumio[1280]: info: Output device has changed, restarting Shairport Sync Jan 11 13:38:28 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:38:28 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:38:28 volumio-keuken sudo[2582]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 13:38:28 volumio-keuken sudo[2582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 13:38:28 volumio-keuken sudo[2582]: pam_unix(sudo:session): session closed for user root Jan 11 13:38:28 volumio-keuken sudo[2584]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 13:38:28 volumio-keuken sudo[2584]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 13:38:28 volumio-keuken volumio[1280]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 13:38:28 volumio-keuken volumio[1280]: info: MPD Permissions set Jan 11 13:38:28 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:38:28 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:38:28 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:38:28 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:38:28 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:38:28 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:38:28 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:38:28 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:38:28 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:38:28 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:38:28 volumio-keuken volumio[1280]: info: Starting Shairport Sync Jan 11 13:38:28 volumio-keuken sudo[2578]: pam_unix(sudo:session): session closed for user root Jan 11 13:38:28 volumio-keuken sudo[2576]: pam_unix(sudo:session): session closed for user root Jan 11 13:38:28 volumio-keuken volumio[1280]: info: peppyspectrum Daemon Stop Jan 11 13:38:28 volumio-keuken volumio[1280]: info: peppymeterbasic Daemon Stop Jan 11 13:38:28 volumio-keuken sudo[2598]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 11 13:38:28 volumio-keuken sudo[2598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 13:38:28 volumio-keuken systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 11 13:38:28 volumio-keuken systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 11 13:38:28 volumio-keuken systemd[1]: shairport-sync.service: Deactivated successfully. Jan 11 13:38:28 volumio-keuken systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 11 13:38:28 volumio-keuken systemd[1]: shairport-sync.service: Consumed 2.600s CPU time. Jan 11 13:38:28 volumio-keuken systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 11 13:38:28 volumio-keuken systemd[1]: mpd.service: Deactivated successfully. Jan 11 13:38:28 volumio-keuken sudo[2598]: pam_unix(sudo:session): session closed for user root Jan 11 13:38:28 volumio-keuken systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 11 13:38:28 volumio-keuken systemd[1]: mpd.service: Consumed 3.424s CPU time. Jan 11 13:38:28 volumio-keuken volumio[1280]: info: Shairport-Sync Started Jan 11 13:38:28 volumio-keuken systemd[1]: mpd.socket: Deactivated successfully. Jan 11 13:38:28 volumio-keuken systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 11 13:38:28 volumio-keuken systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 11 13:38:28 volumio-keuken systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 11 13:38:28 volumio-keuken systemd[1]: Starting mpd.service - Music Player Daemon... Jan 11 13:38:28 volumio-keuken sudo[2603]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 11 13:38:28 volumio-keuken sudo[2603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 11 13:38:28 volumio-keuken sudo[2603]: pam_unix(sudo:session): session closed for user root Jan 11 13:38:28 volumio-keuken go-librespot[1681]: time="2026-01-11T13:38:28+01:00" level=trace msg="received accesspoint ping" Jan 11 13:38:28 volumio-keuken go-librespot[1681]: time="2026-01-11T13:38:28+01:00" level=trace msg="received accesspoint pong ack" Jan 11 13:38:29 volumio-keuken go-librespot[1681]: time="2026-01-11T13:38:29+01:00" level=trace msg="sent dealer ping" Jan 11 13:38:29 volumio-keuken go-librespot[1681]: time="2026-01-11T13:38:29+01:00" level=trace msg="received dealer pong" Jan 11 13:38:31 volumio-keuken mpd[2619]: 2026-01-11T13:38:31 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 11 13:38:31 volumio-keuken systemd[1]: Started mpd.service - Music Player Daemon. Jan 11 13:38:31 volumio-keuken sudo[2584]: pam_unix(sudo:session): session closed for user root Jan 11 13:38:31 volumio-keuken volumio[1280]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 11 13:38:31 volumio-keuken volumio[1280]: info: CoreStateMachine::getcurrentVolume Jan 11 13:38:31 volumio-keuken volumio[1280]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 13:38:31 volumio-keuken volumio[1280]: error: updateQueue error: null Jan 11 13:38:31 volumio-keuken volumio[1280]: info: VolumeController:: Volume=86 Mute =false Jan 11 13:38:31 volumio-keuken volumio[1280]: info: CoreStateMachine::pushState Jan 11 13:38:31 volumio-keuken volumio[1280]: info: CoreCommandRouter::volumioPushState Jan 11 13:38:31 volumio-keuken volumio[1280]: info: CoreStateMachine::updateTrackBlock Jan 11 13:38:31 volumio-keuken volumio[1280]: info: CorePlayQueue::getTrackBlock Jan 11 13:38:31 volumio-keuken volumio[1280]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 13:38:31 volumio-keuken volumio[1280]: info: PeppyMeterBasic ---peppymeterbasic status pause Jan 11 13:38:31 volumio-keuken volumio[1280]: info: PeppySpectrum ---peppyspectrum status pause Jan 11 13:38:31 volumio-keuken volumio[1280]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 11 13:38:31 volumio-keuken sudo[2626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jan 11 13:38:31 volumio-keuken sudo[2626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 13:38:31 volumio-keuken sudo[2628]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jan 11 13:38:31 volumio-keuken sudo[2628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 13:38:31 volumio-keuken volumio[1280]: info: VolumeController:: Volume=86 Mute =false Jan 11 13:38:31 volumio-keuken volumio[1280]: info: CoreStateMachine::pushState Jan 11 13:38:31 volumio-keuken volumio[1280]: info: CoreCommandRouter::volumioPushState Jan 11 13:38:31 volumio-keuken volumio[1280]: info: PeppyMeterBasic ---peppymeterbasic status pause Jan 11 13:38:31 volumio-keuken volumio[1280]: info: PeppySpectrum ---peppyspectrum status pause Jan 11 13:38:31 volumio-keuken volumio[1280]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 11 13:38:32 volumio-keuken sudo[2633]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jan 11 13:38:32 volumio-keuken sudo[2626]: pam_unix(sudo:session): session closed for user root Jan 11 13:38:32 volumio-keuken sudo[2633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 13:38:32 volumio-keuken volumio[1280]: info: peppymeterbasic Daemon Stop Jan 11 13:38:32 volumio-keuken sudo[2635]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jan 11 13:38:32 volumio-keuken sudo[2635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 13:38:32 volumio-keuken sudo[2628]: pam_unix(sudo:session): session closed for user root Jan 11 13:38:32 volumio-keuken volumio[1280]: info: peppyspectrum Daemon Stop Jan 11 13:38:32 volumio-keuken sudo[2633]: pam_unix(sudo:session): session closed for user root Jan 11 13:38:32 volumio-keuken volumio[1280]: info: peppymeterbasic Daemon Stop Jan 11 13:38:32 volumio-keuken sudo[2635]: pam_unix(sudo:session): session closed for user root Jan 11 13:38:32 volumio-keuken volumio[1280]: info: peppyspectrum Daemon Stop Jan 11 13:38:34 volumio-keuken sudo[2653]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 11 13:38:34 volumio-keuken sudo[2653]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 13:38:34 volumio-keuken systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 11 13:38:34 volumio-keuken systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 11 13:38:34 volumio-keuken systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 11 13:38:34 volumio-keuken systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 11 13:38:34 volumio-keuken sudo[2653]: pam_unix(sudo:session): session closed for user root Jan 11 13:38:34 volumio-keuken mpd_monitor.sh[2655]: MPD Monitor Service: Starting MPD Monitor Service Jan 11 13:38:34 volumio-keuken volumio[1280]: info: Successfully started MPD Monitor Jan 11 13:38:41 volumio-keuken volumio[1280]: info: CoreCommandRouter::volumioGetState Jan 11 13:38:44 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 13:38:44 volumio-keuken volumio[1280]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 11 13:38:49 volumio-keuken sudo[2675]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jan 11 13:38:49 volumio-keuken sudo[2675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 13:38:49 volumio-keuken sudo[2675]: pam_unix(sudo:session): session closed for user root Jan 11 13:38:49 volumio-keuken sudo[2677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Jan 11 13:38:49 volumio-keuken sudo[2677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 13:38:49 volumio-keuken sudo[2679]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Jan 11 13:38:49 volumio-keuken volumio[1280]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jan 11 13:38:49 volumio-keuken sudo[2679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 13:38:49 volumio-keuken volumio[1280]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 13:38:49 volumio-keuken volumio[1280]: TypeError: Cannot read properties of undefined (reading 'split') Jan 11 13:38:49 volumio-keuken volumio[1280]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Jan 11 13:38:49 volumio-keuken volumio[1280]: at ChildProcess.exithandler (node:child_process:413:7) Jan 11 13:38:49 volumio-keuken volumio[1280]: at ChildProcess.emit (node:events:514:28) Jan 11 13:38:49 volumio-keuken volumio[1280]: at maybeClose (node:internal/child_process:1105:16) Jan 11 13:38:49 volumio-keuken volumio[1280]: at Socket. (node:internal/child_process:457:11) Jan 11 13:38:49 volumio-keuken volumio[1280]: at Socket.emit (node:events:514:28) Jan 11 13:38:49 volumio-keuken volumio[1280]: at Pipe. (node:net:337:12) Jan 11 13:38:49 volumio-keuken volumio[1280]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 13:38:49 volumio-keuken sudo[2683]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Jan 11 13:38:49 volumio-keuken sudo[2683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 13:38:49 volumio-keuken systemd[1]: Reloading. Jan 11 13:38:50 volumio-keuken sudo[2717]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-11 13:37' Jan 11 13:38:50 volumio-keuken sudo[2717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"