-- Logs begin at Thu 2019-02-14 13:11:58 MSK, end at Sat 2025-05-31 21:19:33 MSK. -- May 31 21:18:07 volumio volumio[1194]: info: Enabling plugin fusiondsp May 31 21:18:07 volumio volumio[1194]: info: Loading plugin "fusiondsp"... May 31 21:18:07 volumio volumio[1194]: info: Applying required configuration parameters for plugin fusiondsp May 31 21:18:07 volumio volumio[1194]: info: Preparing to generate the ALSA configuration file May 31 21:18:07 volumio volumio[1194]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 31 21:18:07 volumio volumio[1194]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 31 21:18:07 volumio volumio[1194]: info: Reading ALSA contributions from plugins. May 31 21:18:07 volumio volumio[1194]: info: Asound.conf file unchanged, so no further update is needed May 31 21:18:07 volumio volumio[1194]: info: Output device has changed, restarting MPD May 31 21:18:07 volumio sudo[3022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 21:18:07 volumio sudo[3022]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:18:07 volumio sudo[3022]: pam_unix(sudo:session): session closed for user root May 31 21:18:07 volumio volumio[1194]: info: Output device has changed, restarting Shairport Sync May 31 21:18:07 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:18:07 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:18:07 volumio sudo[3026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 21:18:07 volumio sudo[3026]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:18:07 volumio systemd[1]: Stopping Music Player Daemon... May 31 21:18:07 volumio volumio[1194]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 21:18:07 volumio volumio[1194]: info: PLUGIN START: fusiondsp May 31 21:18:07 volumio volumio[1194]: info: Loading i18n strings for locale ru May 31 21:18:07 volumio volumio[1194]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. May 31 21:18:07 volumio volumio[1194]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. May 31 21:18:07 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 31 21:18:07 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:18:07 volumio volumio[1194]: info: FusionDsp - mixtype--------------------- Software May 31 21:18:07 volumio volumio[1194]: info: Preparing to generate the ALSA configuration file May 31 21:18:07 volumio systemd[1]: mpd.service: Succeeded. May 31 21:18:07 volumio systemd[1]: Stopped Music Player Daemon. May 31 21:18:07 volumio volumio[1194]: info: Done. May 31 21:18:07 volumio volumio[1194]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 31 21:18:07 volumio volumio[1194]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 31 21:18:07 volumio volumio[1194]: info: Reading ALSA contributions from plugins. May 31 21:18:07 volumio volumio[1194]: info: MPD Permissions set May 31 21:18:07 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:18:07 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:18:07 volumio systemd[1]: Starting Music Player Daemon... May 31 21:18:07 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:18:07 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:18:07 volumio volumio[1194]: info: Starting Shairport Sync May 31 21:18:07 volumio volumio[1194]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. May 31 21:18:07 volumio volumio[1194]: info: CoreCommandRouter::volumioGetState May 31 21:18:07 volumio volumio[1194]: info: CorePlayQueue::getTrack 755 May 31 21:18:07 volumio volumio[1194]: info: CoreCommandRouter::volumioPause May 31 21:18:07 volumio volumio[1194]: info: CoreStateMachine::pause May 31 21:18:07 volumio volumio[1194]: info: CoreStateMachine::stPlaybackTimer May 31 21:18:07 volumio volumio[1194]: info: CoreStateMachine::servicePause May 31 21:18:07 volumio volumio[1194]: info: CorePlayQueue::getTrack 755 May 31 21:18:07 volumio volumio[1194]: info: CoreCommandRouter::servicePause May 31 21:18:07 volumio volumio[1194]: info: ControllerMpd::pause May 31 21:18:07 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand pause May 31 21:18:07 volumio volumio[1194]: info: mpdhttpout --- Volumio set on pause May 31 21:18:07 volumio volumio[1194]: error: MPD error: Error: This socket has been ended by the other party May 31 21:18:07 volumio volumio[1194]: error: This socket has been ended by the other party {"code":"EPIPE"} May 31 21:18:07 volumio volumio[1194]: error: MPD error: Error: This socket has been ended by the other party May 31 21:18:07 volumio volumio[1194]: error: This socket has been ended by the other party {"code":"EPIPE"} May 31 21:18:07 volumio volumio[1194]: error: MPD error: Error: This socket has been ended by the other party May 31 21:18:07 volumio volumio[1194]: error: This socket has been ended by the other party {"code":"EPIPE"} May 31 21:18:07 volumio volumio[1194]: info: Asound.conf file unchanged, so no further update is needed May 31 21:18:07 volumio volumio[1194]: info: Output device has changed, restarting MPD May 31 21:18:07 volumio sudo[3037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 21:18:07 volumio sudo[3037]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:18:08 volumio sudo[3040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 21:18:08 volumio sudo[3040]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:18:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 31 21:18:08 volumio sudo[3031]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 21:18:08 volumio sudo[3031]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:18:08 volumio systemd[1]: shairport-sync.service: Succeeded. May 31 21:18:08 volumio volumio[1194]: info: Output device has changed, restarting Shairport Sync May 31 21:18:08 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:18:08 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:18:08 volumio sudo[3040]: pam_unix(sudo:session): session closed for user root May 31 21:18:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 31 21:18:08 volumio sudo[3031]: pam_unix(sudo:session): session closed for user root May 31 21:18:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 31 21:18:08 volumio sudo[3044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 21:18:08 volumio sudo[3044]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:18:08 volumio sudo[3037]: pam_unix(sudo:session): session closed for user root May 31 21:18:08 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 31 21:18:08 volumio systemd[1]: mpd.service: Succeeded. May 31 21:18:08 volumio systemd[1]: Stopped Music Player Daemon. May 31 21:18:08 volumio systemd[1]: Starting Music Player Daemon... May 31 21:18:08 volumio volumio[1194]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 21:18:08 volumio volumio[1194]: info: MPD Permissions set May 31 21:18:08 volumio volumio[1194]: info: Shairport-Sync Started May 31 21:18:08 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:18:08 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:18:08 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:18:08 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:18:08 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:18:08 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:18:08 volumio volumio[1194]: info: Starting Shairport Sync May 31 21:18:08 volumio sudo[3052]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 21:18:08 volumio sudo[3052]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:18:08 volumio volumio[1194]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. May 31 21:18:08 volumio volumio[1194]: info: CoreCommandRouter::volumioGetState May 31 21:18:08 volumio volumio[1194]: info: CorePlayQueue::getTrack 755 May 31 21:18:08 volumio volumio[1194]: info: mpdhttpout --- Volumio set on pause May 31 21:18:08 volumio sudo[3052]: pam_unix(sudo:session): session closed for user root May 31 21:18:08 volumio sudo[3060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 21:18:08 volumio sudo[3060]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:18:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 31 21:18:08 volumio systemd[1]: shairport-sync.service: Succeeded. May 31 21:18:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 31 21:18:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 31 21:18:08 volumio sudo[3060]: pam_unix(sudo:session): session closed for user root May 31 21:18:08 volumio volumio[1194]: info: Shairport-Sync Started May 31 21:18:08 volumio mpd[3061]: May 31 21:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 31 21:18:08 volumio systemd[1]: Started Music Player Daemon. May 31 21:18:08 volumio sudo[3044]: pam_unix(sudo:session): session closed for user root May 31 21:18:08 volumio sudo[3026]: pam_unix(sudo:session): session closed for user root May 31 21:18:08 volumio volumio[1194]: error: MPD error: The expression evaluated to a falsy value: May 31 21:18:08 volumio volumio[1194]: assert.ok(self.idling) May 31 21:18:08 volumio volumio[1194]: error: The expression evaluated to a falsy value: May 31 21:18:08 volumio volumio[1194]: assert.ok(self.idling) May 31 21:18:08 volumio volumio[1194]: error: updateQueue error: null May 31 21:18:09 volumio volumio[1194]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 31 21:18:09 volumio volumio[1194]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo May 31 21:18:09 volumio volumio[1194]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 31 21:18:09 volumio volumio[1194]: info: camilladsp service started and running in background, instance 2 May 31 21:18:09 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:18:09 volumio volumio[1194]: cannot open device 'volumioHw': Device or resource busy May 31 21:18:09 volumio volumio[1194]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 31 21:18:09 volumio volumio[1194]: cannot open device 'volumioHw': Device or resource busy May 31 21:18:09 volumio volumio[1194]: info: FusionDsp loaded May 31 21:18:09 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 21:18:09 volumio sudo[3084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 31 21:18:10 volumio sudo[3084]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:18:10 volumio volumio[1194]: info: FusionDsp - Reporting Fusion DSP Enabled May 31 21:18:10 volumio volumio[1194]: info: Adding Signal Path Element [object Object] May 31 21:18:10 volumio volumio[1194]: info: Adding fusiondspeq DSP Signal Path Element May 31 21:18:10 volumio volumio[1194]: info: FusionDsp - ---- installed callbackRead May 31 21:18:10 volumio volumio[1194]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 May 31 21:18:10 volumio volumio[1194]: info: FusionDsp - ---- read samplerate from file: 384000 May 31 21:18:10 volumio volumio[1194]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 31 21:18:10 volumio volumio[1194]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 May 31 21:18:10 volumio volumio[1194]: info: FusionDsp - ---- read samplerate from file: 384000 May 31 21:18:10 volumio volumio[1194]: info: camilladsp stopping service pid 3074... May 31 21:18:10 volumio volumio[1194]: info: camilladsp service terminated, instance 2 May 31 21:18:10 volumio volumio[1194]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 31 21:18:10 volumio volumio[1194]: info: camilladsp service started and running in background, instance 2 May 31 21:18:10 volumio volumio[1194]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 31 21:18:10 volumio volumio[1194]: error: FusionDsp - WebSocket error: [object Object] May 31 21:18:10 volumio volumio[1194]: error: FusionDsp - WebSocket error: [object Object] May 31 21:18:10 volumio volumio[1194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 May 31 21:18:10 volumio systemd[1]: Started FusionDsp Daemon. May 31 21:18:10 volumio sudo[3084]: pam_unix(sudo:session): session closed for user root May 31 21:18:10 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 31 21:18:10 volumio sudo[3097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 21:18:10 volumio sudo[3097]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:18:10 volumio systemd[1]: Stopping Music Player Daemon... May 31 21:18:10 volumio systemd[1]: mpd.service: Succeeded. May 31 21:18:10 volumio systemd[1]: Stopped Music Player Daemon. May 31 21:18:10 volumio systemd[1]: Starting Music Player Daemon... May 31 21:18:10 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 31 21:18:10 volumio sudo[3101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 21:18:10 volumio volumio[1194]: info: FusionDsp - Stopping FusionDsp service May 31 21:18:10 volumio volumio[1194]: info: camilladsp stopping service pid 3089... May 31 21:18:10 volumio sudo[3101]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:18:10 volumio sudo[3099]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 21:18:10 volumio sudo[3099]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:18:10 volumio sudo[3099]: pam_unix(sudo:session): session closed for user root May 31 21:18:10 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 31 21:18:10 volumio systemd[1]: mpd.service: Succeeded. May 31 21:18:10 volumio volumio[1194]: info: camilladsp service terminated, instance 2 May 31 21:18:10 volumio systemd[1]: Stopped Music Player Daemon. May 31 21:18:10 volumio systemd[1]: Starting Music Player Daemon... May 31 21:18:10 volumio volumio[1194]: info: Disabling plugin fusiondsp May 31 21:18:10 volumio volumio[1194]: info: Preparing to generate the ALSA configuration file May 31 21:18:10 volumio sudo[3109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service May 31 21:18:10 volumio sudo[3109]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:18:10 volumio volumio[1194]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 31 21:18:10 volumio volumio[1194]: info: Reading ALSA contributions from plugins. May 31 21:18:10 volumio systemd[1]: Stopping FusionDsp Daemon... May 31 21:18:10 volumio systemd[1]: fusiondsp.service: Main process exited, code=killed, status=15/TERM May 31 21:18:10 volumio systemd[1]: fusiondsp.service: Succeeded. May 31 21:18:10 volumio systemd[1]: Stopped FusionDsp Daemon. May 31 21:18:10 volumio volumio[1194]: info: Asound.conf file written May 31 21:18:10 volumio sudo[3107]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 21:18:10 volumio sudo[3107]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:18:10 volumio sudo[3107]: pam_unix(sudo:session): session closed for user root May 31 21:18:10 volumio sudo[3109]: pam_unix(sudo:session): session closed for user root May 31 21:18:10 volumio sudo[3114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 31 21:18:10 volumio sudo[3114]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:18:10 volumio sudo[3114]: pam_unix(sudo:session): session closed for user root May 31 21:18:10 volumio volumio[1194]: info: Output device has changed, restarting MPD May 31 21:18:10 volumio sudo[3119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 21:18:10 volumio sudo[3119]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:18:10 volumio sudo[3119]: pam_unix(sudo:session): session closed for user root May 31 21:18:10 volumio volumio[1194]: info: Output device has changed, restarting Shairport Sync May 31 21:18:10 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:18:10 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:18:10 volumio sudo[3122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 21:18:10 volumio sudo[3122]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:18:10 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 31 21:18:10 volumio systemd[1]: mpd.service: Succeeded. May 31 21:18:10 volumio systemd[1]: Stopped Music Player Daemon. May 31 21:18:10 volumio volumio[1194]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 21:18:10 volumio volumio[1194]: info: Done. May 31 21:18:10 volumio volumio[1194]: info: MPD Permissions set May 31 21:18:10 volumio volumio[1194]: info: FusionDsp - Reporting Fusion DSP Disabled May 31 21:18:10 volumio volumio[1194]: info: Removing fusiondspeq DSP Signal Path Element May 31 21:18:10 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:18:10 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:18:10 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:18:10 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:18:10 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:18:10 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:18:10 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:18:10 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:18:10 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:18:10 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:18:10 volumio volumio[1194]: info: Starting Shairport Sync May 31 21:18:10 volumio volumio[1194]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. May 31 21:18:10 volumio systemd[1]: Starting Music Player Daemon... May 31 21:18:10 volumio volumio[1194]: info: CoreCommandRouter::volumioGetState May 31 21:18:10 volumio volumio[1194]: info: CorePlayQueue::getTrack 755 May 31 21:18:10 volumio volumio[1194]: info: mpdhttpout --- Volumio set on pause May 31 21:18:10 volumio sudo[3133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 21:18:10 volumio sudo[3133]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:18:10 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 31 21:18:10 volumio systemd[1]: shairport-sync.service: Succeeded. May 31 21:18:10 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 31 21:18:10 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 31 21:18:10 volumio volumio[1194]: info: Shairport-Sync Started May 31 21:18:10 volumio sudo[3133]: pam_unix(sudo:session): session closed for user root May 31 21:18:10 volumio sudo[3127]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 21:18:10 volumio sudo[3127]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:18:10 volumio sudo[3127]: pam_unix(sudo:session): session closed for user root May 31 21:18:11 volumio mpd[3139]: May 31 21:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 31 21:18:11 volumio systemd[1]: Started Music Player Daemon. May 31 21:18:11 volumio sudo[3101]: pam_unix(sudo:session): session closed for user root May 31 21:18:11 volumio sudo[3122]: pam_unix(sudo:session): session closed for user root May 31 21:18:11 volumio sudo[3097]: pam_unix(sudo:session): session closed for user root May 31 21:18:11 volumio volumio[1194]: error: MPD error: The expression evaluated to a falsy value: May 31 21:18:11 volumio volumio[1194]: assert.ok(self.idling) May 31 21:18:11 volumio volumio[1194]: error: The expression evaluated to a falsy value: May 31 21:18:11 volumio volumio[1194]: assert.ok(self.idling) May 31 21:18:11 volumio volumio[1194]: error: MPD error: The expression evaluated to a falsy value: May 31 21:18:11 volumio volumio[1194]: assert.ok(self.idling) May 31 21:18:11 volumio volumio[1194]: error: The expression evaluated to a falsy value: May 31 21:18:11 volumio volumio[1194]: assert.ok(self.idling) May 31 21:18:11 volumio volumio[1194]: error: updateQueue error: null May 31 21:18:12 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 31 21:18:12 volumio sudo[3160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 21:18:12 volumio sudo[3160]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:18:12 volumio systemd[1]: Stopping Music Player Daemon... May 31 21:18:12 volumio systemd[1]: mpd.service: Succeeded. May 31 21:18:12 volumio systemd[1]: Stopped Music Player Daemon. May 31 21:18:13 volumio systemd[1]: Starting Music Player Daemon... May 31 21:18:13 volumio sudo[3162]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 21:18:13 volumio sudo[3162]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:18:13 volumio sudo[3162]: pam_unix(sudo:session): session closed for user root May 31 21:18:13 volumio mpd[3164]: May 31 21:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 31 21:18:13 volumio systemd[1]: Started Music Player Daemon. May 31 21:18:13 volumio sudo[3160]: pam_unix(sudo:session): session closed for user root May 31 21:18:13 volumio volumio[1194]: error: updateQueue error: null May 31 21:18:31 volumio volumio[1194]: info: Preload queue cleared May 31 21:18:31 volumio volumio[1194]: info: CoreCommandRouter::volumioAddQueueItems May 31 21:18:31 volumio volumio[1194]: info: CoreStateMachine::addQueueItems May 31 21:18:31 volumio volumio[1194]: info: CorePlayQueue::addQueueItems May 31 21:18:31 volumio volumio[1194]: info: Preload queue cleared May 31 21:18:31 volumio volumio[1194]: info: Adding Item to queue: albums://Paul%20McCartney/Pure%20McCartney May 31 21:18:31 volumio volumio[1194]: info: Exploding uri albums://Paul%20McCartney/Pure%20McCartney in service mpd May 31 21:18:31 volumio volumio[1194]: info: CoreCommandRouter::volumioPushQueue May 31 21:18:31 volumio volumio[1194]: info: CorePlayQueue::saveQueue May 31 21:18:31 volumio volumio[1194]: info: CoreStateMachine::updateTrackBlock May 31 21:18:31 volumio volumio[1194]: info: CorePlayQueue::getTrackBlock May 31 21:18:31 volumio volumio[1194]: info: CoreCommandRouter::volumioPlay May 31 21:18:31 volumio volumio[1194]: info: CoreStateMachine::play index 758 May 31 21:18:31 volumio volumio[1194]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 21:18:31 volumio volumio[1194]: info: CoreStateMachine::stop May 31 21:18:31 volumio volumio[1194]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 21:18:31 volumio volumio[1194]: info: CoreStateMachine::updateTrackBlock May 31 21:18:31 volumio volumio[1194]: info: CorePlayQueue::getTrackBlock May 31 21:18:31 volumio volumio[1194]: info: CoreStateMachine::stPlaybackTimer May 31 21:18:31 volumio volumio[1194]: info: CoreStateMachine::pushState May 31 21:18:31 volumio volumio[1194]: info: CorePlayQueue::getTrack 755 May 31 21:18:31 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 21:18:31 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState May 31 21:18:31 volumio volumio[1194]: info: CoreStateMachine::serviceStop May 31 21:18:31 volumio volumio[1194]: info: CorePlayQueue::getTrack 755 May 31 21:18:31 volumio volumio[1194]: info: CoreCommandRouter::serviceStop May 31 21:18:31 volumio volumio[1194]: info: ControllerMpd::stop May 31 21:18:31 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand stop May 31 21:18:31 volumio volumio[1194]: info: sendMpdCommand stop took 7 milliseconds May 31 21:18:31 volumio volumio[1194]: info: CoreStateMachine::play index undefined May 31 21:18:31 volumio volumio[1194]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 21:18:31 volumio volumio[1194]: info: CorePlayQueue::getTrack 758 May 31 21:18:31 volumio volumio[1194]: info: CoreStateMachine::startPlaybackTimer May 31 21:18:31 volumio volumio[1194]: info: CorePlayQueue::getTrack 758 May 31 21:18:31 volumio volumio[1194]: verbose: ControllerMpd::clearAddPlayTracks USB/Новый_том/Paul McCartney ‎Pure McCartney/A1. Maybe I'm Amazed.dsf May 31 21:18:31 volumio volumio[1194]: info: Setting Volume to 100 automatically for DSD May 31 21:18:31 volumio volumio[1194]: info: VolumeController::SetAlsaVolume100 May 31 21:18:31 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand stop May 31 21:18:31 volumio volumio[1194]: info: CoreStateMachine::pushState May 31 21:18:31 volumio volumio[1194]: info: CorePlayQueue::getTrack 758 May 31 21:18:31 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 21:18:31 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState May 31 21:18:31 volumio volumio[1194]: info: [ASDebug] CurState: stop PrevState: play May 31 21:18:31 volumio volumio[1194]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 31 21:18:31 volumio volumio[1194]: info: [ASDebug] CurState: stop PrevState: play May 31 21:18:31 volumio volumio[1194]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 31 21:18:31 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 21:18:31 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 21:18:32 volumio volumio[1194]: info: sendMpdCommand stop took 251 milliseconds May 31 21:18:32 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand clear May 31 21:18:32 volumio volumio[1194]: info: May 31 21:18:32 volumio volumio[1194]: ---------------------------- MPD announces system playlist update May 31 21:18:32 volumio volumio[1194]: info: Ignoring MPD Status Update May 31 21:18:32 volumio volumio[1194]: info: sendMpdCommand clear took 23 milliseconds May 31 21:18:32 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand add "USB/Новый_том/Paul McCartney ‎Pure McCartney/A1. Maybe I'm Amazed.dsf" May 31 21:18:32 volumio volumio[1194]: error: updateQueue error: null May 31 21:18:32 volumio volumio[1194]: info: May 31 21:18:32 volumio volumio[1194]: ---------------------------- MPD announces system playlist update May 31 21:18:32 volumio volumio[1194]: info: Ignoring MPD Status Update May 31 21:18:32 volumio volumio[1194]: info: ------------------------------ 6ms May 31 21:18:32 volumio volumio[1194]: info: sendMpdCommand add "USB/Новый_том/Paul McCartney ‎Pure McCartney/A1. Maybe I'm Amazed.dsf" took 5 milliseconds May 31 21:18:32 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand play May 31 21:18:32 volumio volumio[1194]: info: touch_display: Setting screensaver timeout to 203 seconds. May 31 21:18:32 volumio volumio[1194]: info: May 31 21:18:32 volumio volumio[1194]: ---------------------------- MPD announces state update: player May 31 21:18:32 volumio volumio[1194]: info: ------------------------------ 342ms May 31 21:18:32 volumio volumio[1194]: info: sendMpdCommand play took 341 milliseconds May 31 21:18:32 volumio volumio[1194]: info: ControllerMpd::getState May 31 21:18:32 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand status May 31 21:18:32 volumio volumio[1194]: info: May 31 21:18:32 volumio volumio[1194]: ---------------------------- MPD announces state update: player May 31 21:18:32 volumio volumio[1194]: info: ControllerMpd::getState May 31 21:18:32 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand status May 31 21:18:32 volumio volumio[1194]: info: sendMpdCommand status took 4 milliseconds May 31 21:18:32 volumio volumio[1194]: verbose: ControllerMpd::parseState May 31 21:18:32 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 21:18:32 volumio volumio[1194]: info: sendMpdCommand status took 2 milliseconds May 31 21:18:32 volumio volumio[1194]: verbose: ControllerMpd::parseState May 31 21:18:32 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 21:18:32 volumio volumio[1194]: info: sendMpdCommand playlistinfo took 3 milliseconds May 31 21:18:32 volumio volumio[1194]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 21:18:32 volumio volumio[1194]: verbose: ControllerMpd::parseTrackInfo May 31 21:18:32 volumio volumio[1194]: info: ControllerMpd::pushState May 31 21:18:32 volumio volumio[1194]: info: CoreCommandRouter::servicePushState May 31 21:18:32 volumio volumio[1194]: info: CorePlayQueue::getTrack 758 May 31 21:18:32 volumio volumio[1194]: verbose: STATE SERVICE {"status":"play","position":0,"seek":509,"duration":229,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"A1. Maybe I'm Amazed","artist":"Paul McCartney","album":"Pure McCartney","uri":"USB/Новый_том/Paul McCartney ‎Pure McCartney/A1. Maybe I'm Amazed.dsf","trackType":"dsf"} May 31 21:18:32 volumio volumio[1194]: verbose: CURRENT POSITION 758 May 31 21:18:32 volumio volumio[1194]: info: CoreStateMachine::syncState stateService play May 31 21:18:32 volumio volumio[1194]: info: CoreStateMachine::syncState currentStatus stop May 31 21:18:32 volumio volumio[1194]: info: ControllerMpd::pushState May 31 21:18:32 volumio volumio[1194]: info: CoreCommandRouter::servicePushState May 31 21:18:32 volumio volumio[1194]: info: CorePlayQueue::getTrack 758 May 31 21:18:32 volumio volumio[1194]: verbose: STATE SERVICE {"status":"play","position":0,"seek":509,"duration":229,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"A1. Maybe I'm Amazed","artist":"Paul McCartney","album":"Pure McCartney","uri":"USB/Новый_том/Paul McCartney ‎Pure McCartney/A1. Maybe I'm Amazed.dsf","trackType":"dsf"} May 31 21:18:32 volumio volumio[1194]: verbose: CURRENT POSITION 758 May 31 21:18:32 volumio volumio[1194]: info: CoreStateMachine::syncState stateService play May 31 21:18:32 volumio volumio[1194]: info: CoreStateMachine::syncState currentStatus play May 31 21:18:32 volumio volumio[1194]: info: Received an update from plugin. extracting info from payload May 31 21:18:32 volumio volumio[1194]: info: CoreStateMachine::pushState May 31 21:18:32 volumio volumio[1194]: info: CorePlayQueue::getTrack 758 May 31 21:18:32 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 21:18:32 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState May 31 21:18:32 volumio volumio[1194]: info: CoreStateMachine::pushState May 31 21:18:32 volumio volumio[1194]: info: CorePlayQueue::getTrack 758 May 31 21:18:32 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState May 31 21:18:32 volumio volumio[1194]: info: ------------------------------ 32ms May 31 21:18:32 volumio volumio[1194]: info: ------------------------------ 53ms May 31 21:18:32 volumio volumio[1194]: info: [ASDebug] CurState: play PrevState: play May 31 21:18:32 volumio volumio[1194]: info: [ASDebug] CurState: play PrevState: play May 31 21:18:32 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 21:18:32 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 21:18:32 volumio volumio[1194]: info: touch_display: Setting screensaver timeout to 0 seconds. May 31 21:18:37 volumio volumio[1194]: info: CoreCommandRouter::volumioSeek May 31 21:18:37 volumio volumio[1194]: info: CoreStateMachine::seek May 31 21:18:37 volumio volumio[1194]: info: CorePlayQueue::getTrack 758 May 31 21:18:37 volumio volumio[1194]: info: TRACKBLOCK {"uri":"music-library/USB/Новый_том/Paul McCartney ‎Pure McCartney/A1. Maybe I'm Amazed.dsf","service":"mpd","name":"A1. Maybe I'm Amazed","artist":"Paul McCartney","album":"Pure McCartney","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=960&web=Paul%20McCartney/Pure%20McCartney/extralarge&path=%2Fmnt%2FUSB%2F%D0%9D%D0%BE%D0%B2%D1%8B%D0%B9_%D1%82%D0%BE%D0%BC%2FPaul%20McCartney%20%E2%80%8EPure%20McCartney&metadata=false","duration":229,"trackType":"dsf","samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2} May 31 21:18:37 volumio volumio[1194]: info: CoreStateMachine::startPlaybackTimer May 31 21:18:37 volumio volumio[1194]: info: CorePlayQueue::getTrack 758 May 31 21:18:37 volumio volumio[1194]: info: ControllerMpd::seek May 31 21:18:37 volumio volumio[1194]: info: CoreStateMachine::pushState May 31 21:18:37 volumio volumio[1194]: info: CorePlayQueue::getTrack 758 May 31 21:18:37 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 21:18:37 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState May 31 21:18:37 volumio volumio[1194]: info: [ASDebug] CurState: play PrevState: play May 31 21:18:37 volumio volumio[1194]: error: null May 31 21:18:37 volumio volumio[1194]: info: May 31 21:18:37 volumio volumio[1194]: ---------------------------- MPD announces state update: player May 31 21:18:37 volumio volumio[1194]: info: ControllerMpd::getState May 31 21:18:37 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand status May 31 21:18:37 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 21:18:37 volumio volumio[1194]: info: sendMpdCommand status took 5 milliseconds May 31 21:18:37 volumio volumio[1194]: verbose: ControllerMpd::parseState May 31 21:18:37 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 21:18:37 volumio volumio[1194]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 21:18:37 volumio volumio[1194]: verbose: ControllerMpd::parseTrackInfo May 31 21:18:37 volumio volumio[1194]: info: ControllerMpd::pushState May 31 21:18:37 volumio volumio[1194]: info: CoreCommandRouter::servicePushState May 31 21:18:37 volumio volumio[1194]: info: CorePlayQueue::getTrack 758 May 31 21:18:37 volumio volumio[1194]: verbose: STATE SERVICE {"status":"play","position":0,"seek":60000,"duration":229,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"A1. Maybe I'm Amazed","artist":"Paul McCartney","album":"Pure McCartney","uri":"USB/Новый_том/Paul McCartney ‎Pure McCartney/A1. Maybe I'm Amazed.dsf","trackType":"dsf"} May 31 21:18:37 volumio volumio[1194]: verbose: CURRENT POSITION 758 May 31 21:18:37 volumio volumio[1194]: info: CoreStateMachine::syncState stateService play May 31 21:18:37 volumio volumio[1194]: info: CoreStateMachine::syncState currentStatus play May 31 21:18:37 volumio volumio[1194]: info: Received an update from plugin. extracting info from payload May 31 21:18:37 volumio volumio[1194]: info: CoreStateMachine::pushState May 31 21:18:37 volumio volumio[1194]: info: CorePlayQueue::getTrack 758 May 31 21:18:37 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState May 31 21:18:37 volumio volumio[1194]: info: CoreStateMachine::pushState May 31 21:18:37 volumio volumio[1194]: info: CorePlayQueue::getTrack 758 May 31 21:18:37 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState May 31 21:18:37 volumio volumio[1194]: info: ------------------------------ 34ms May 31 21:18:37 volumio volumio[1194]: info: [ASDebug] CurState: play PrevState: play May 31 21:18:37 volumio volumio[1194]: info: [ASDebug] CurState: play PrevState: play May 31 21:18:37 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 21:18:37 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 21:18:46 volumio nmbd[762]: [2025/05/31 21:18:46.458651, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 31 21:18:46 volumio nmbd[762]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.105 for name WORKGROUP<1d>. May 31 21:18:46 volumio nmbd[762]: This response was from IP 192.168.0.122, reporting an IP address of 192.168.0.122. May 31 21:18:46 volumio nmbd[762]: [2025/05/31 21:18:46.460024, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 31 21:18:46 volumio nmbd[762]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.163 for name WORKGROUP<1d>. May 31 21:18:46 volumio nmbd[762]: This response was from IP 192.168.0.122, reporting an IP address of 192.168.0.122. May 31 21:18:53 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 21:18:53 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 31 21:18:56 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 31 21:18:56 volumio volumio[1194]: info: Disabling plugin mpdoutput May 31 21:18:56 volumio volumio[1194]: info: Done. May 31 21:18:56 volumio sudo[3283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 21:18:56 volumio sudo[3283]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:18:57 volumio systemd[1]: Stopping Music Player Daemon... May 31 21:18:57 volumio systemd[1]: mpd.service: Succeeded. May 31 21:18:57 volumio systemd[1]: Stopped Music Player Daemon. May 31 21:18:57 volumio systemd[1]: Starting Music Player Daemon... May 31 21:18:57 volumio sudo[3285]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 21:18:57 volumio sudo[3285]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:18:57 volumio sudo[3285]: pam_unix(sudo:session): session closed for user root May 31 21:18:57 volumio mpd[3288]: May 31 21:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 31 21:18:57 volumio systemd[1]: Started Music Player Daemon. May 31 21:18:57 volumio sudo[3283]: pam_unix(sudo:session): session closed for user root May 31 21:18:57 volumio volumio[1194]: error: updateQueue error: null May 31 21:18:58 volumio volumio[1194]: info: Disabling plugin 80s80s May 31 21:18:58 volumio volumio[1194]: info: Done. May 31 21:19:00 volumio go-librespot[1489]: time="2025-05-31T21:19:00+03:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" May 31 21:19:00 volumio volumio[1194]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify May 31 21:19:00 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 21:19:00 volumio volumio[1194]: Cannot find translation for source YouTube Music May 31 21:19:00 volumio volumio[1194]: Cannot find translation for source 80s80s Radio May 31 21:19:00 volumio sudo[3293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service May 31 21:19:00 volumio sudo[3293]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:19:00 volumio volumio[1194]: info: Disabling plugin spop May 31 21:19:00 volumio volumio[1194]: info: Done. May 31 21:19:00 volumio volumio[1194]: info: Connection to go-librespot Websocket closed May 31 21:19:00 volumio systemd[1]: Stopping go-librespot Daemon... May 31 21:19:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM May 31 21:19:00 volumio systemd[1]: go-librespot-daemon.service: Succeeded. May 31 21:19:00 volumio systemd[1]: Stopped go-librespot Daemon. May 31 21:19:00 volumio sudo[3293]: pam_unix(sudo:session): session closed for user root May 31 21:19:01 volumio volumio[1194]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesYouTube Music May 31 21:19:01 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 21:19:01 volumio volumio[1194]: Cannot find translation for source 80s80s Radio May 31 21:19:01 volumio volumio[1194]: info: Disabling plugin ytmusic May 31 21:19:01 volumio volumio[1194]: info: Done. May 31 21:19:02 volumio volumio[1194]: info: [ASDebug] Port: 24 May 31 21:19:02 volumio volumio[1194]: info: [ASDebug] Inverted: false May 31 21:19:02 volumio volumio[1194]: info: [ASDebug] Delay: 720 May 31 21:19:04 volumio volumio[1194]: info: AutoStart - onStop May 31 21:19:04 volumio volumio[1194]: info: Disabling plugin autostart May 31 21:19:04 volumio volumio[1194]: info: Done. May 31 21:19:05 volumio volumio[1194]: info: GPIO-Buttons stopped May 31 21:19:05 volumio volumio[1194]: info: Disabling plugin gpio-buttons May 31 21:19:05 volumio volumio[1194]: info: Done. May 31 21:19:07 volumio volumio[1194]: info: Stopping Pirate audio service May 31 21:19:07 volumio volumio[1194]: info: Disabling plugin pirateaudio May 31 21:19:07 volumio volumio[1194]: info: Done. May 31 21:19:07 volumio sudo[3311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop pirateaudio.service May 31 21:19:07 volumio sudo[3311]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 21:19:07 volumio sudo[3311]: pam_unix(sudo:session): session closed for user root May 31 21:19:09 volumio volumio[1194]: info: Disabling plugin Systeminfo May 31 21:19:09 volumio volumio[1194]: info: Done. May 31 21:19:15 volumio volumio[1194]: info: Enabling plugin ampswitch May 31 21:19:15 volumio volumio[1194]: info: Loading plugin "ampswitch"... May 31 21:19:15 volumio volumio[1194]: info: Applying required configuration parameters for plugin ampswitch May 31 21:19:15 volumio volumio[1194]: info: PLUGIN START: ampswitch May 31 21:19:15 volumio volumio[1194]: info: Done. May 31 21:19:15 volumio volumio[1194]: info: CoreCommandRouter::volumioGetState May 31 21:19:15 volumio volumio[1194]: info: CorePlayQueue::getTrack 758 May 31 21:19:15 volumio volumio[1194]: info: [ASDebug] CurState: play PrevState: play May 31 21:19:15 volumio volumio[1194]: info: [ASDebug] CurState: play PrevState: play May 31 21:19:15 volumio volumio[1194]: info: [ASDebug] CurState: play PrevState: play May 31 21:19:16 volumio volumio[1194]: info: [ASDebug] Port: 24 May 31 21:19:16 volumio volumio[1194]: info: [ASDebug] Inverted: false May 31 21:19:16 volumio volumio[1194]: info: [ASDebug] Delay: 720 May 31 21:19:29 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 31 21:19:29 volumio volumio[1194]: info: Preload queue cleared May 31 21:19:30 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 31 21:19:30 volumio volumio[1194]: info: Preload queue cleared May 31 21:19:31 volumio volumio[1194]: info: Preload queue cleared May 31 21:19:31 volumio volumio[1194]: info: CoreCommandRouter::volumioAddQueueItems May 31 21:19:31 volumio volumio[1194]: info: CoreStateMachine::addQueueItems May 31 21:19:31 volumio volumio[1194]: info: CorePlayQueue::addQueueItems May 31 21:19:31 volumio volumio[1194]: info: Preload queue cleared May 31 21:19:31 volumio volumio[1194]: info: Adding Item to queue: https://stream.rockantenne.de/blues-rock/stream/mp3?aw_0_1st.playerid=tunein.com May 31 21:19:31 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 31 21:19:31 volumio volumio[1194]: info: CoreCommandRouter::volumioPushQueue May 31 21:19:31 volumio volumio[1194]: info: CorePlayQueue::saveQueue May 31 21:19:31 volumio volumio[1194]: info: CoreStateMachine::updateTrackBlock May 31 21:19:31 volumio volumio[1194]: info: CorePlayQueue::getTrackBlock May 31 21:19:31 volumio volumio[1194]: info: CoreCommandRouter::volumioPlay May 31 21:19:31 volumio volumio[1194]: info: CoreStateMachine::play index 799 May 31 21:19:31 volumio volumio[1194]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 21:19:31 volumio volumio[1194]: info: CoreStateMachine::stop May 31 21:19:31 volumio volumio[1194]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 21:19:31 volumio volumio[1194]: info: CoreStateMachine::stPlaybackTimer May 31 21:19:31 volumio volumio[1194]: info: CoreStateMachine::updateTrackBlock May 31 21:19:31 volumio volumio[1194]: info: CorePlayQueue::getTrackBlock May 31 21:19:31 volumio volumio[1194]: info: CoreStateMachine::pushState May 31 21:19:31 volumio volumio[1194]: info: CorePlayQueue::getTrack 758 May 31 21:19:31 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 21:19:31 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState May 31 21:19:31 volumio volumio[1194]: info: CoreStateMachine::serviceStop May 31 21:19:31 volumio volumio[1194]: info: CorePlayQueue::getTrack 758 May 31 21:19:31 volumio volumio[1194]: info: CoreCommandRouter::serviceStop May 31 21:19:31 volumio volumio[1194]: info: ControllerMpd::stop May 31 21:19:31 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand stop May 31 21:19:31 volumio volumio[1194]: info: [ASDebug] CurState: stop PrevState: play May 31 21:19:31 volumio volumio[1194]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 31 21:19:31 volumio volumio[1194]: info: [ASDebug] CurState: stop PrevState: play May 31 21:19:31 volumio volumio[1194]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 31 21:19:32 volumio volumio[1194]: info: sendMpdCommand stop took 117 milliseconds May 31 21:19:32 volumio volumio[1194]: info: CoreStateMachine::play index undefined May 31 21:19:32 volumio volumio[1194]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 21:19:32 volumio volumio[1194]: info: CorePlayQueue::getTrack 799 May 31 21:19:32 volumio volumio[1194]: info: CoreStateMachine::startPlaybackTimer May 31 21:19:32 volumio volumio[1194]: info: CorePlayQueue::getTrack 799 May 31 21:19:32 volumio volumio[1194]: info: [1748715572075] ControllerWebradio::clearAddPlayTrack May 31 21:19:32 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand stop May 31 21:19:32 volumio volumio[1194]: info: sendMpdCommand stop took 40 milliseconds May 31 21:19:32 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand clear May 31 21:19:32 volumio volumio[1194]: info: May 31 21:19:32 volumio volumio[1194]: ---------------------------- MPD announces system playlist update May 31 21:19:32 volumio volumio[1194]: info: Ignoring MPD Status Update May 31 21:19:32 volumio volumio[1194]: info: sendMpdCommand clear took 2 milliseconds May 31 21:19:32 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand load "https://stream.rockantenne.de/blues-rock/stream/mp3?aw_0_1st.playerid=tunein.com" May 31 21:19:32 volumio volumio[1194]: error: updateQueue error: null May 31 21:19:32 volumio volumio[1194]: info: ------------------------------ 3ms May 31 21:19:32 volumio volumio[1194]: info: touch_display: Setting screensaver timeout to 203 seconds. May 31 21:19:32 volumio volumio[1194]: info: [ASDebug] Togle GPIO: OFF May 31 21:19:32 volumio volumio[1194]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 21:19:32 volumio volumio[1194]: Error: ESPIPE: invalid seek, write May 31 21:19:32 volumio volumio[1194]: at Object.writeSync (fs.js:711:3) May 31 21:19:32 volumio volumio[1194]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) May 31 21:19:32 volumio volumio[1194]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) May 31 21:19:32 volumio volumio[1194]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) May 31 21:19:32 volumio volumio[1194]: at listOnTimeout (internal/timers.js:554:17) May 31 21:19:32 volumio volumio[1194]: at processTimers (internal/timers.js:497:7) { May 31 21:19:32 volumio volumio[1194]: errno: -29, May 31 21:19:32 volumio volumio[1194]: syscall: 'write', May 31 21:19:32 volumio volumio[1194]: code: 'ESPIPE' May 31 21:19:32 volumio volumio[1194]: } May 31 21:19:32 volumio volumio[1194]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 21:19:33 volumio sudo[3433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-31 21:18 May 31 21:19:33 volumio sudo[3433]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"