-- Logs begin at Thu 2025-10-16 20:39:14 CST, end at Thu 2025-10-16 20:46:26 CST. -- Oct 16 20:45:07 volumio volumio[1099]: info: MyVolumio login type: Token Oct 16 20:45:09 volumio sudo[2709]: pam_unix(sudo:session): session closed for user root Oct 16 20:45:11 volumio volumio[1099]: info: CoreCommandRouter::volumioPause Oct 16 20:45:11 volumio volumio[1099]: info: CoreStateMachine::pause Oct 16 20:45:11 volumio volumio[1099]: info: CoreStateMachine::stPlaybackTimer Oct 16 20:45:11 volumio volumio[1099]: info: CoreStateMachine::servicePause Oct 16 20:45:11 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Oct 16 20:45:11 volumio volumio[1099]: info: CoreCommandRouter::servicePause Oct 16 20:45:11 volumio volumio[1099]: info: ControllerMpd::pause Oct 16 20:45:11 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand pause Oct 16 20:45:11 volumio volumio[1099]: info: Oct 16 20:45:11 volumio volumio[1099]: ---------------------------- MPD announces state update: player Oct 16 20:45:11 volumio volumio[1099]: info: sendMpdCommand pause took 1 milliseconds Oct 16 20:45:11 volumio volumio[1099]: info: ControllerMpd::getState Oct 16 20:45:11 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Oct 16 20:45:11 volumio volumio[1099]: info: Oct 16 20:45:11 volumio volumio[1099]: ---------------------------- MPD announces state update: player Oct 16 20:45:11 volumio volumio[1099]: info: ControllerMpd::getState Oct 16 20:45:11 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Oct 16 20:45:11 volumio volumio[1099]: info: sendMpdCommand status took 1 milliseconds Oct 16 20:45:11 volumio volumio[1099]: info: sendMpdCommand status took 0 milliseconds Oct 16 20:45:11 volumio volumio[1099]: verbose: ControllerMpd::parseState Oct 16 20:45:11 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 20:45:11 volumio volumio[1099]: verbose: ControllerMpd::parseState Oct 16 20:45:11 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 20:45:11 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 16 20:45:11 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 16 20:45:11 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Oct 16 20:45:11 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Oct 16 20:45:11 volumio volumio[1099]: info: ControllerMpd::pushState Oct 16 20:45:11 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Oct 16 20:45:11 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Oct 16 20:45:11 volumio volumio[1099]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":58430,"duration":165,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"几多愁.wav","artist":null,"album":null,"uri":"NAS/Fn/music/九阴筝经/几多愁.wav","trackType":"wav"} Oct 16 20:45:11 volumio volumio[1099]: verbose: CURRENT POSITION 1 Oct 16 20:45:11 volumio volumio[1099]: info: CoreStateMachine::syncState stateService pause Oct 16 20:45:11 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus pause Oct 16 20:45:11 volumio volumio[1099]: info: CoreStateMachine::pushState Oct 16 20:45:11 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Oct 16 20:45:11 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 20:45:11 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Oct 16 20:45:11 volumio volumio[1099]: info: CoreStateMachine::stPlaybackTimer Oct 16 20:45:11 volumio volumio[1099]: info: ControllerMpd::pushState Oct 16 20:45:11 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Oct 16 20:45:11 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Oct 16 20:45:11 volumio volumio[1099]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":58430,"duration":165,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"几多愁.wav","artist":null,"album":null,"uri":"NAS/Fn/music/九阴筝经/几多愁.wav","trackType":"wav"} Oct 16 20:45:11 volumio volumio[1099]: verbose: CURRENT POSITION 1 Oct 16 20:45:11 volumio volumio[1099]: info: CoreStateMachine::syncState stateService pause Oct 16 20:45:11 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus pause Oct 16 20:45:11 volumio volumio[1099]: info: CoreStateMachine::pushState Oct 16 20:45:11 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Oct 16 20:45:11 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Oct 16 20:45:11 volumio volumio[1099]: info: CoreStateMachine::stPlaybackTimer Oct 16 20:45:11 volumio volumio[1099]: info: ------------------------------ 11ms Oct 16 20:45:11 volumio volumio[1099]: info: ------------------------------ 10ms Oct 16 20:45:15 volumio ntpd[1088]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 16 20:45:20 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Oct 16 20:45:20 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Oct 16 20:45:20 volumio screensaver.sh[772]: 2680 Oct 16 20:45:20 volumio volumio[1099]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Oct 16 20:45:20 volumio volumio[1099]: No protocol specified Oct 16 20:45:20 volumio volumio[1099]: Exception in thread Thread-4: Oct 16 20:45:20 volumio volumio[1099]: Traceback (most recent call last): Oct 16 20:45:20 volumio volumio[1099]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Oct 16 20:45:20 volumio volumio[1099]: self.run() Oct 16 20:45:20 volumio volumio[1099]: File "/usr/lib/python3.7/threading.py", line 865, in run Oct 16 20:45:20 volumio volumio[1099]: self._target(*self._args, **self._kwargs) Oct 16 20:45:20 volumio volumio[1099]: File "./screensaver/volumio_peppymeter.py", line 232, in meter_thread Oct 16 20:45:20 volumio volumio[1099]: File "./screensaver/volumio_peppymeter.py", line 281, in init_display Oct 16 20:45:20 volumio volumio[1099]: pygame.error: Unable to open a console terminal Oct 16 20:45:20 volumio volumio[1099]: Exception in thread Thread-21: Oct 16 20:45:20 volumio volumio[1099]: Traceback (most recent call last): Oct 16 20:45:20 volumio volumio[1099]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Oct 16 20:45:20 volumio volumio[1099]: self.run() Oct 16 20:45:20 volumio volumio[1099]: File "/usr/lib/python3.7/threading.py", line 865, in run Oct 16 20:45:20 volumio volumio[1099]: self._target(*self._args, **self._kwargs) Oct 16 20:45:20 volumio volumio[1099]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 616, in _handle_eio_message Oct 16 20:45:20 volumio volumio[1099]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Oct 16 20:45:20 volumio volumio[1099]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 500, in _handle_event Oct 16 20:45:20 volumio volumio[1099]: r = self._trigger_event(data[0], namespace, *data[1:]) Oct 16 20:45:20 volumio volumio[1099]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 550, in _trigger_event Oct 16 20:45:20 volumio volumio[1099]: return self.handlers[namespace][event](*args) Oct 16 20:45:20 volumio volumio[1099]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_random.py", line 59, in on_push_state Oct 16 20:45:20 volumio volumio[1099]: self.meter.restart() # vumeter restart Oct 16 20:45:20 volumio volumio[1099]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/peppymeter/vumeter.py", line 147, in restart Oct 16 20:45:20 volumio volumio[1099]: self.stop() Oct 16 20:45:20 volumio volumio[1099]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/peppymeter/vumeter.py", line 119, in stop Oct 16 20:45:20 volumio volumio[1099]: self.meter.stop() Oct 16 20:45:20 volumio volumio[1099]: AttributeError: 'NoneType' object has no attribute 'stop' Oct 16 20:45:20 volumio volumio[1099]: Terminated Oct 16 20:45:23 volumio kernel: usb 1-2: new high-speed USB device number 5 using xhci_hcd Oct 16 20:45:23 volumio kernel: usb 1-2: New USB device found, idVendor=2972, idProduct=0001, bcdDevice= 0.00 Oct 16 20:45:23 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Oct 16 20:45:23 volumio kernel: usb 1-2: Product: FiiO USB Audio Class 2.0 DAC Oct 16 20:45:23 volumio kernel: usb 1-2: Manufacturer: SmartAction Oct 16 20:45:23 volumio kernel: usb 1-2: SerialNumber: 0007 Oct 16 20:45:23 volumio kernel: usb 1-2: 0:2 : does not exist Oct 16 20:45:23 volumio kernel: usbcore: registered new interface driver snd-usb-audio Oct 16 20:45:23 volumio systemd-udevd[2935]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Oct 16 20:45:23 volumio volumio[1099]: info: Oct 16 20:45:23 volumio volumio[1099]: ---------------------------- USB Audio Device Attached Oct 16 20:45:23 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 16 20:45:31 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Oct 16 20:45:31 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Oct 16 20:45:37 volumio volumio[1099]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 16 20:45:37 volumio volumio[1099]: info: Retrying Login Due to Network Error Oct 16 20:45:38 volumio volumio[1099]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 20:45:38 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 16 20:45:38 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 20:45:38 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 20:45:38 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 20:45:38 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 16 20:45:38 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 16 20:45:42 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Oct 16 20:45:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Oct 16 20:45:47 volumio volumio[1099]: info: MyVolumio login type: Token Oct 16 20:45:47 volumio volumio[1099]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 16 20:45:47 volumio volumio[1099]: info: Preparing to save Alsa Options, stopping services first Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Oct 16 20:45:47 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::volumioPause Oct 16 20:45:47 volumio volumio[1099]: info: CoreStateMachine::pause Oct 16 20:45:47 volumio volumio[1099]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"FiiO USB Audio Class 2.0 DAC"}} Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 20:45:47 volumio volumio[1099]: info: Device 5 does not have any Mixer Control Available Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 16 20:45:47 volumio volumio[1099]: info: Updating Volume Controller Parameters: Device: 5 Name: FiiO USB Audio Class 2.0 DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 16 20:45:47 volumio volumio[1099]: info: Disabling external Volume Control Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 20:45:47 volumio volumio[1099]: info: CoreStateMachine::pushState Oct 16 20:45:47 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Oct 16 20:45:47 volumio volumio[1099]: info: Preparing to generate the ALSA configuration file Oct 16 20:45:47 volumio volumio[1099]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Oct 16 20:45:47 volumio volumio[1099]: info: Reading ALSA contributions from plugins. Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 16 20:45:47 volumio volumio[1099]: info: Asound.conf file written Oct 16 20:45:47 volumio sudo[2983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 16 20:45:47 volumio sudo[2983]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 20:45:47 volumio sudo[2983]: pam_unix(sudo:session): session closed for user root Oct 16 20:45:47 volumio volumio[1099]: No state is present for card DAC Oct 16 20:45:47 volumio volumio[1099]: Found hardware: "USB-Audio" "USB Mixer" "USB2972:0001" "" "" Oct 16 20:45:47 volumio volumio[1099]: Hardware is initialized using a generic method Oct 16 20:45:47 volumio volumio[1099]: No state is present for card DAC Oct 16 20:45:47 volumio volumio[1099]: info: Output device has changed, restarting MPD Oct 16 20:45:47 volumio sudo[2988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 16 20:45:47 volumio sudo[2988]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 20:45:47 volumio volumio[1099]: info: Output device has changed, restarting Shairport Sync Oct 16 20:45:47 volumio sudo[2988]: pam_unix(sudo:session): session closed for user root Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 20:45:47 volumio sudo[2991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 16 20:45:47 volumio sudo[2991]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 20:45:47 volumio systemd[1]: Stopping Music Player Daemon... Oct 16 20:45:47 volumio volumio[1099]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 16 20:45:47 volumio volumio[1099]: info: Output device has changed, restarting MPD Oct 16 20:45:47 volumio sudo[2997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 16 20:45:47 volumio sudo[2997]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 20:45:47 volumio volumio[1099]: info: Output device has changed, restarting Shairport Sync Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:47 volumio sudo[2997]: pam_unix(sudo:session): session closed for user root Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 20:45:47 volumio sudo[3000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 16 20:45:47 volumio sudo[3000]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 20:45:47 volumio volumio[1099]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 16 20:45:47 volumio volumio[1099]: info: MPD Permissions set Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 16 20:45:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:47 volumio volumio[1099]: info: Starting Shairport Sync Oct 16 20:45:47 volumio volumio[1099]: info: Starting Shairport Sync Oct 16 20:45:47 volumio sudo[3018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 20:45:47 volumio sudo[3018]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 20:45:47 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 16 20:45:47 volumio sudo[3021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 20:45:47 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 16 20:45:47 volumio sudo[3021]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 20:45:47 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 16 20:45:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 16 20:45:48 volumio sudo[3018]: pam_unix(sudo:session): session closed for user root Oct 16 20:45:48 volumio volumio[1099]: info: Shairport-Sync Started Oct 16 20:45:48 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 16 20:45:48 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 16 20:45:48 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 16 20:45:48 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 16 20:45:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 16 20:45:48 volumio sudo[3021]: pam_unix(sudo:session): session closed for user root Oct 16 20:45:48 volumio volumio[1099]: info: Shairport-Sync Started Oct 16 20:45:48 volumio systemd[1]: mpd.service: Succeeded. Oct 16 20:45:48 volumio systemd[1]: Stopped Music Player Daemon. Oct 16 20:45:48 volumio systemd[1]: Starting Music Player Daemon... Oct 16 20:45:48 volumio sudo[3028]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 16 20:45:48 volumio sudo[3028]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 20:45:48 volumio sudo[3028]: pam_unix(sudo:session): session closed for user root Oct 16 20:45:48 volumio mpd[3030]: Oct 16 20:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 16 20:45:48 volumio systemd[1]: Started Music Player Daemon. Oct 16 20:45:48 volumio sudo[2991]: pam_unix(sudo:session): session closed for user root Oct 16 20:45:48 volumio sudo[3000]: pam_unix(sudo:session): session closed for user root Oct 16 20:45:48 volumio volumio[1099]: error: MPD error: The expression evaluated to a falsy value: Oct 16 20:45:48 volumio volumio[1099]: assert.ok(self.idling) Oct 16 20:45:48 volumio volumio[1099]: error: The expression evaluated to a falsy value: Oct 16 20:45:48 volumio volumio[1099]: assert.ok(self.idling) Oct 16 20:45:48 volumio volumio[1099]: error: updateQueue error: null Oct 16 20:45:48 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:48 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:48 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:48 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 20:45:51 volumio volumio[1099]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 16 20:45:51 volumio volumio[1099]: info: CoreStateMachine::getcurrentVolume Oct 16 20:45:51 volumio volumio[1099]: info: CoreCommandRouter::volumioRetrievevolume Oct 16 20:45:51 volumio volumio[1099]: info: CoreStateMachine::pushState Oct 16 20:45:51 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Oct 16 20:45:51 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Oct 16 20:45:51 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Oct 16 20:45:51 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Oct 16 20:45:53 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Oct 16 20:45:53 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Oct 16 20:45:54 volumio volumio[1099]: info: CoreCommandRouter::volumioPlay Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::play index 0 Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::stop Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::updateTrackBlock Oct 16 20:45:54 volumio volumio[1099]: info: CorePlayQueue::getTrackBlock Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::stPlaybackTimer Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::pushState Oct 16 20:45:54 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Oct 16 20:45:54 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 20:45:54 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::serviceStop Oct 16 20:45:54 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Oct 16 20:45:54 volumio volumio[1099]: info: CoreCommandRouter::serviceStop Oct 16 20:45:54 volumio volumio[1099]: info: ControllerMpd::stop Oct 16 20:45:54 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 20:45:54 volumio volumio[1099]: info: sendMpdCommand stop took 5 milliseconds Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::play index undefined Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 20:45:54 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::startPlaybackTimer Oct 16 20:45:54 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Oct 16 20:45:54 volumio volumio[1099]: verbose: ControllerMpd::clearAddPlayTracks NAS/Fn/music/九阴筝经/但愿人长久.wav Oct 16 20:45:54 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 20:45:54 volumio volumio[1099]: info: sendMpdCommand stop took 0 milliseconds Oct 16 20:45:54 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 20:45:54 volumio volumio[1099]: info: Oct 16 20:45:54 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Oct 16 20:45:54 volumio volumio[1099]: info: Ignoring MPD Status Update Oct 16 20:45:54 volumio volumio[1099]: info: sendMpdCommand clear took 1 milliseconds Oct 16 20:45:54 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand add "NAS/Fn/music/九阴筝经/但愿人长久.wav" Oct 16 20:45:54 volumio volumio[1099]: info: Oct 16 20:45:54 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Oct 16 20:45:54 volumio volumio[1099]: info: Ignoring MPD Status Update Oct 16 20:45:54 volumio volumio[1099]: error: updateQueue error: null Oct 16 20:45:54 volumio volumio[1099]: info: Oct 16 20:45:54 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Oct 16 20:45:54 volumio volumio[1099]: info: Ignoring MPD Status Update Oct 16 20:45:54 volumio volumio[1099]: info: ------------------------------ 2ms Oct 16 20:45:54 volumio volumio[1099]: info: sendMpdCommand add "NAS/Fn/music/九阴筝经/但愿人长久.wav" took 2 milliseconds Oct 16 20:45:54 volumio volumio[1099]: info: ------------------------------ 2ms Oct 16 20:45:54 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand play Oct 16 20:45:54 volumio volumio[1099]: info: Oct 16 20:45:54 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Oct 16 20:45:54 volumio volumio[1099]: info: Ignoring MPD Status Update Oct 16 20:45:54 volumio volumio[1099]: info: ------------------------------ 6ms Oct 16 20:45:54 volumio volumio[1099]: info: sendMpdCommand play took 5 milliseconds Oct 16 20:45:54 volumio volumio[1099]: info: ------------------------------ 4ms Oct 16 20:45:54 volumio volumio[1099]: info: Oct 16 20:45:54 volumio volumio[1099]: ---------------------------- MPD announces state update: player Oct 16 20:45:54 volumio volumio[1099]: info: ControllerMpd::getState Oct 16 20:45:54 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Oct 16 20:45:54 volumio volumio[1099]: info: Oct 16 20:45:54 volumio volumio[1099]: ---------------------------- MPD announces state update: player Oct 16 20:45:54 volumio volumio[1099]: info: ControllerMpd::getState Oct 16 20:45:54 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Oct 16 20:45:54 volumio volumio[1099]: info: Oct 16 20:45:54 volumio volumio[1099]: ---------------------------- MPD announces state update: player Oct 16 20:45:54 volumio volumio[1099]: info: sendMpdCommand status took 9 milliseconds Oct 16 20:45:54 volumio volumio[1099]: info: ControllerMpd::getState Oct 16 20:45:54 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Oct 16 20:45:54 volumio volumio[1099]: info: sendMpdCommand status took 8 milliseconds Oct 16 20:45:54 volumio volumio[1099]: verbose: ControllerMpd::parseState Oct 16 20:45:54 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 20:45:54 volumio volumio[1099]: verbose: ControllerMpd::parseState Oct 16 20:45:54 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 20:45:54 volumio volumio[1099]: info: Oct 16 20:45:54 volumio volumio[1099]: ---------------------------- MPD announces state update: player Oct 16 20:45:54 volumio volumio[1099]: info: ControllerMpd::getState Oct 16 20:45:54 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Oct 16 20:45:54 volumio volumio[1099]: info: sendMpdCommand status took 1 milliseconds Oct 16 20:45:54 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 20:45:54 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 20:45:54 volumio volumio[1099]: info: sendMpdCommand status took 1 milliseconds Oct 16 20:45:54 volumio volumio[1099]: verbose: ControllerMpd::parseState Oct 16 20:45:54 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 20:45:54 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Oct 16 20:45:54 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Oct 16 20:45:54 volumio volumio[1099]: verbose: ControllerMpd::parseState Oct 16 20:45:54 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 20:45:54 volumio volumio[1099]: info: ControllerMpd::pushState Oct 16 20:45:54 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Oct 16 20:45:54 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Oct 16 20:45:54 volumio volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":156,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"但愿人长久.wav","artist":null,"album":null,"uri":"NAS/Fn/music/九阴筝经/但愿人长久.wav","trackType":"wav"} Oct 16 20:45:54 volumio volumio[1099]: verbose: CURRENT POSITION 0 Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::syncState stateService play Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Oct 16 20:45:54 volumio volumio[1099]: info: ControllerMpd::pushState Oct 16 20:45:54 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Oct 16 20:45:54 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Oct 16 20:45:54 volumio volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":56,"duration":156,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"但愿人长久.wav","artist":null,"album":null,"uri":"NAS/Fn/music/九阴筝经/但愿人长久.wav","trackType":"wav"} Oct 16 20:45:54 volumio volumio[1099]: verbose: CURRENT POSITION 0 Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::syncState stateService play Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus play Oct 16 20:45:54 volumio volumio[1099]: info: Received an update from plugin. extracting info from payload Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::pushState Oct 16 20:45:54 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Oct 16 20:45:54 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 20:45:54 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::pushState Oct 16 20:45:54 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Oct 16 20:45:54 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Oct 16 20:45:54 volumio volumio[1099]: info: ------------------------------ 17ms Oct 16 20:45:54 volumio volumio[1099]: info: ------------------------------ 21ms Oct 16 20:45:54 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 16 20:45:54 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 16 20:45:54 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Oct 16 20:45:54 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Oct 16 20:45:54 volumio volumio[1099]: info: ControllerMpd::pushState Oct 16 20:45:54 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Oct 16 20:45:54 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Oct 16 20:45:54 volumio volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":156,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"但愿人长久.wav","artist":null,"album":null,"uri":"NAS/Fn/music/九阴筝经/但愿人长久.wav","trackType":"wav"} Oct 16 20:45:54 volumio volumio[1099]: verbose: CURRENT POSITION 0 Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::syncState stateService play Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus play Oct 16 20:45:54 volumio volumio[1099]: info: Received an update from plugin. extracting info from payload Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::pushState Oct 16 20:45:54 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Oct 16 20:45:54 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 20:45:54 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::pushState Oct 16 20:45:54 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Oct 16 20:45:54 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Oct 16 20:45:54 volumio volumio[1099]: info: ControllerMpd::pushState Oct 16 20:45:54 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Oct 16 20:45:54 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Oct 16 20:45:54 volumio volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":156,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"但愿人长久.wav","artist":null,"album":null,"uri":"NAS/Fn/music/九阴筝经/但愿人长久.wav","trackType":"wav"} Oct 16 20:45:54 volumio volumio[1099]: verbose: CURRENT POSITION 0 Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::syncState stateService play Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus play Oct 16 20:45:54 volumio volumio[1099]: info: Received an update from plugin. extracting info from payload Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::pushState Oct 16 20:45:54 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Oct 16 20:45:54 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Oct 16 20:45:54 volumio volumio[1099]: info: CoreStateMachine::pushState Oct 16 20:45:54 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Oct 16 20:45:54 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Oct 16 20:45:54 volumio volumio[1099]: info: ------------------------------ 41ms Oct 16 20:45:54 volumio volumio[1099]: info: ------------------------------ 39ms Oct 16 20:45:57 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Oct 16 20:45:57 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Oct 16 20:46:04 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Oct 16 20:46:04 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Oct 16 20:46:04 volumio sudo[3052]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Oct 16 20:46:04 volumio sudo[3052]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 20:46:05 volumio volumio[1099]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 16 20:46:05 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Oct 16 20:46:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Oct 16 20:46:06 volumio volumio[1099]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 16 20:46:06 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Oct 16 20:46:06 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Oct 16 20:46:15 volumio kernel: usb 1-2: USB disconnect, device number 5 Oct 16 20:46:15 volumio volumio[1099]: info: Oct 16 20:46:15 volumio volumio[1099]: ---------------------------- USB Audio Device Detached Oct 16 20:46:15 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Oct 16 20:46:15 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 20:46:15 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Oct 16 20:46:15 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=6/ABRT Oct 16 20:46:15 volumio systemd[1]: mpd.service: Failed with result 'signal'. Oct 16 20:46:17 volumio volumio[1099]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 16 20:46:17 volumio volumio[1099]: info: Retrying Login Due to Network Error Oct 16 20:46:25 volumio kernel: usb 1-2: new high-speed USB device number 6 using xhci_hcd Oct 16 20:46:25 volumio kernel: usb 1-2: New USB device found, idVendor=2972, idProduct=0001, bcdDevice= 0.00 Oct 16 20:46:25 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Oct 16 20:46:25 volumio kernel: usb 1-2: Product: FiiO USB Audio Class 2.0 DAC Oct 16 20:46:25 volumio kernel: usb 1-2: Manufacturer: SmartAction Oct 16 20:46:25 volumio kernel: usb 1-2: SerialNumber: 0007 Oct 16 20:46:25 volumio kernel: usb 1-2: 0:2 : does not exist Oct 16 20:46:25 volumio systemd-udevd[3149]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Oct 16 20:46:25 volumio volumio[1099]: info: Oct 16 20:46:25 volumio volumio[1099]: ---------------------------- USB Audio Device Attached Oct 16 20:46:25 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 16 20:46:25 volumio volumio[1099]: info: CoreCommandRouter::Close All Modals sent Oct 16 20:46:25 volumio volumio[1099]: info: Preparing to save Alsa Options, stopping services first Oct 16 20:46:25 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Oct 16 20:46:25 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Oct 16 20:46:25 volumio volumio[1099]: info: CoreCommandRouter::volumioPause Oct 16 20:46:25 volumio volumio[1099]: info: CoreStateMachine::pause Oct 16 20:46:25 volumio volumio[1099]: info: CoreStateMachine::stPlaybackTimer Oct 16 20:46:25 volumio volumio[1099]: info: CoreStateMachine::servicePause Oct 16 20:46:25 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Oct 16 20:46:25 volumio volumio[1099]: info: CoreCommandRouter::servicePause Oct 16 20:46:25 volumio volumio[1099]: info: ControllerMpd::pause Oct 16 20:46:25 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand pause Oct 16 20:46:25 volumio volumio[1099]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"FiiO USB Audio Class 2.0 DAC","alsacard":"DAC"},"i2s":false} Oct 16 20:46:25 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 20:46:25 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 20:46:25 volumio volumio[1099]: info: Device 5 does not have any Mixer Control Available Oct 16 20:46:25 volumio volumio[1099]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 16 20:46:25 volumio volumio[1099]: info: Updating Volume Controller Parameters: Device: 5 Name: FiiO USB Audio Class 2.0 DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 16 20:46:25 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 16 20:46:25 volumio volumio[1099]: info: Disabling external Volume Control Oct 16 20:46:25 volumio volumio[1099]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 20:46:25 volumio volumio[1099]: info: CoreStateMachine::pushState Oct 16 20:46:25 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Oct 16 20:46:25 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 20:46:25 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Oct 16 20:46:25 volumio volumio[1099]: error: MPD error: Error: This socket has been ended by the other party Oct 16 20:46:25 volumio volumio[1099]: error: This socket has been ended by the other party {"code":"EPIPE"} Oct 16 20:46:25 volumio volumio[1099]: error: MPD error: Error: This socket has been ended by the other party Oct 16 20:46:25 volumio volumio[1099]: error: This socket has been ended by the other party {"code":"EPIPE"} Oct 16 20:46:25 volumio volumio[1099]: error: MPD error: Error: This socket has been ended by the other party Oct 16 20:46:25 volumio volumio[1099]: error: This socket has been ended by the other party {"code":"EPIPE"} Oct 16 20:46:25 volumio volumio[1099]: info: Preparing to generate the ALSA configuration file Oct 16 20:46:25 volumio volumio[1099]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 20:46:25 volumio volumio[1099]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Oct 16 20:46:25 volumio volumio[1099]: at Object.readdirSync (fs.js:1048:3) Oct 16 20:46:25 volumio volumio[1099]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Oct 16 20:46:25 volumio volumio[1099]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Oct 16 20:46:25 volumio volumio[1099]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Oct 16 20:46:25 volumio volumio[1099]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Oct 16 20:46:25 volumio volumio[1099]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Oct 16 20:46:25 volumio volumio[1099]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 16 20:46:25 volumio volumio[1099]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Oct 16 20:46:25 volumio volumio[1099]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Oct 16 20:46:25 volumio volumio[1099]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 16 20:46:25 volumio volumio[1099]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 16 20:46:25 volumio volumio[1099]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Oct 16 20:46:25 volumio volumio[1099]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 16 20:46:25 volumio volumio[1099]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 16 20:46:25 volumio volumio[1099]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Oct 16 20:46:25 volumio volumio[1099]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Oct 16 20:46:25 volumio volumio[1099]: errno: -20, Oct 16 20:46:25 volumio volumio[1099]: syscall: 'scandir', Oct 16 20:46:25 volumio volumio[1099]: code: 'ENOTDIR', Oct 16 20:46:25 volumio volumio[1099]: path: '/tmp/peppyrunning' Oct 16 20:46:25 volumio volumio[1099]: } Oct 16 20:46:25 volumio volumio[1099]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 20:46:26 volumio sudo[3180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-16 20:45 Oct 16 20:46:26 volumio sudo[3180]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"