-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Tue 2025-12-16 22:44:13 CST. -- Dec 16 22:43:15 volumio-rpi volumio[1104]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 16 22:43:15 volumio-rpi volumio[1104]: info: Retrying Login Due to Network Error Dec 16 22:43:25 volumio-rpi volumio[1104]: info: MyVolumio login type: Token Dec 16 22:43:35 volumio-rpi kernel: usb 1-1.2: USB disconnect, device number 3 Dec 16 22:43:35 volumio-rpi kernel: usb 1-1.2.1: USB disconnect, device number 4 Dec 16 22:43:35 volumio-rpi thd[679]: Error reading device '/dev/input/event0' Dec 16 22:43:35 volumio-rpi thd[679]: Error reading device '/dev/input/event1' Dec 16 22:43:35 volumio-rpi volumio[1104]: info: No valid Plugin REST Endpoint Dec 16 22:43:35 volumio-rpi volumio[1104]: info: Dec 16 22:43:35 volumio-rpi volumio[1104]: ---------------------------- USB Audio Device Detached Dec 16 22:43:35 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 16 22:43:35 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 22:43:35 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 16 22:43:38 volumio-rpi kernel: usb 1-1.1: new full-speed USB device number 5 using xhci_hcd Dec 16 22:43:38 volumio-rpi kernel: usb 1-1.1: New USB device found, idVendor=0a12, idProduct=4010, bcdDevice=16.69 Dec 16 22:43:38 volumio-rpi kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Dec 16 22:43:38 volumio-rpi kernel: hub 1-1.1:1.0: USB hub found Dec 16 22:43:38 volumio-rpi kernel: hub 1-1.1:1.0: 4 ports detected Dec 16 22:43:38 volumio-rpi kernel: usb 1-1.1.1: new full-speed USB device number 6 using xhci_hcd Dec 16 22:43:38 volumio-rpi kernel: usb 1-1.1.1: New USB device found, idVendor=0a12, idProduct=4007, bcdDevice=19.70 Dec 16 22:43:39 volumio-rpi kernel: usb 1-1.1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 16 22:43:39 volumio-rpi kernel: usb 1-1.1.1: Product: QCC5124 Dec 16 22:43:39 volumio-rpi kernel: usb 1-1.1.1: Manufacturer: Xiamen Co.,Ltd Dec 16 22:43:39 volumio-rpi kernel: usb 1-1.1.1: SerialNumber: 20241120 Dec 16 22:43:39 volumio-rpi kernel: usb 1-1.1.1: Not enough bandwidth for new device state. Dec 16 22:43:39 volumio-rpi kernel: usb 1-1.1.1: Not enough bandwidth for altsetting 1 Dec 16 22:43:39 volumio-rpi kernel: usb 1-1.1.1: 2:1: cannot set freq 96000 to ep 0x3 Dec 16 22:43:39 volumio-rpi kernel: input: Xiamen Co.,Ltd QCC5124 as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1.1/1-1.1.1:1.3/0003:0A12:4007.0004/input/input2 Dec 16 22:43:39 volumio-rpi kernel: hid-generic 0003:0A12:4007.0004: input,hidraw0: USB HID v1.11 Device [Xiamen Co.,Ltd QCC5124] on usb-0000:01:00.0-1.1.1/input3 Dec 16 22:43:39 volumio-rpi kernel: input: Xiamen Co.,Ltd QCC5124 as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1.1/1-1.1.1:1.4/0003:0A12:4007.0005/input/input3 Dec 16 22:43:39 volumio-rpi kernel: hid-generic 0003:0A12:4007.0005: input,hidraw1: USB HID v1.11 Keyboard [Xiamen Co.,Ltd QCC5124] on usb-0000:01:00.0-1.1.1/input4 Dec 16 22:43:39 volumio-rpi kernel: hid-generic 0003:0A12:4007.0006: hiddev96,hidraw2: USB HID v1.11 Device [Xiamen Co.,Ltd QCC5124] on usb-0000:01:00.0-1.1.1/input5 Dec 16 22:43:39 volumio-rpi volumio[1104]: info: Dec 16 22:43:39 volumio-rpi volumio[1104]: ---------------------------- USB Audio Device Attached Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::Close All Modals sent Dec 16 22:43:39 volumio-rpi volumio[1104]: info: Preparing to save Alsa Options, stopping services first Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioGetState Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CorePlayQueue::getTrack 0 Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioPause Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreStateMachine::pause Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreStateMachine::stPlaybackTimer Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreStateMachine::servicePause Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CorePlayQueue::getTrack 0 Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::servicePause Dec 16 22:43:39 volumio-rpi volumio[1104]: info: ControllerMpd::pause Dec 16 22:43:39 volumio-rpi volumio[1104]: verbose: ControllerMpd::sendMpdCommand pause Dec 16 22:43:39 volumio-rpi volumio[1104]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"QCC5124","alsacard":"QCC5124"},"i2s":false} Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 22:43:39 volumio-rpi volumio[1104]: info: Setting default mixerSoftMaster for Softvolume device Dec 16 22:43:39 volumio-rpi systemd-udevd[2588]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Dec 16 22:43:39 volumio-rpi systemd-udevd[2610]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 16 22:43:39 volumio-rpi volumio[1104]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 16 22:43:39 volumio-rpi volumio[1104]: info: Disabling external Volume Control Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 22:43:39 volumio-rpi systemd-logind[678]: Watching system buttons on /dev/input/event1 (Xiamen Co.,Ltd QCC5124) Dec 16 22:43:39 volumio-rpi volumio[1104]: info: Enable softmixer device for audio device undefined Dec 16 22:43:39 volumio-rpi volumio[1104]: info: Dec 16 22:43:39 volumio-rpi volumio[1104]: ---------------------------- MPD announces state update: player Dec 16 22:43:39 volumio-rpi volumio[1104]: info: sendMpdCommand pause took 264 milliseconds Dec 16 22:43:39 volumio-rpi volumio[1104]: info: ControllerMpd::getState Dec 16 22:43:39 volumio-rpi volumio[1104]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreStateMachine::pushState Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CorePlayQueue::getTrack 0 Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioPushState Dec 16 22:43:39 volumio-rpi volumio[1104]: info: sendMpdCommand status took 64 milliseconds Dec 16 22:43:39 volumio-rpi volumio[1104]: verbose: ControllerMpd::parseState Dec 16 22:43:39 volumio-rpi volumio[1104]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:43:39 volumio-rpi volumio[1104]: info: Software Volume ALSA configuration written Dec 16 22:43:39 volumio-rpi volumio[1104]: info: Preparing to generate the ALSA configuration file Dec 16 22:43:39 volumio-rpi volumio[1104]: info: No valid Plugin REST Endpoint Dec 16 22:43:39 volumio-rpi volumio[1104]: info: sendMpdCommand playlistinfo took 36 milliseconds Dec 16 22:43:39 volumio-rpi volumio[1104]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:43:39 volumio-rpi volumio[1104]: info: ControllerMpd::pushState Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::servicePushState Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CorePlayQueue::getTrack 0 Dec 16 22:43:39 volumio-rpi volumio[1104]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":130571,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光辉岁月 (粤语)","artist":"Beyond(深度音像店)","album":"环球巨星影音启示录 Beyond(HiFi立体环绕音质)","uri":"INTERNAL/musics/20-BEYOND专辑 42首/003.光辉岁月 (粤语)-BEYOND.mp3","trackType":"mp3"} Dec 16 22:43:39 volumio-rpi volumio[1104]: verbose: CURRENT POSITION 0 Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreStateMachine::syncState stateService pause Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreStateMachine::syncState currentStatus pause Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreStateMachine::pushState Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CorePlayQueue::getTrack 0 Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioPushState Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreStateMachine::stPlaybackTimer Dec 16 22:43:39 volumio-rpi volumio[1104]: info: ------------------------------ 132ms Dec 16 22:43:39 volumio-rpi volumio[1104]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 16 22:43:39 volumio-rpi volumio[1104]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 16 22:43:39 volumio-rpi volumio[1104]: info: Reading ALSA contributions from plugins. Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 16 22:43:39 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 16 22:43:39 volumio-rpi volumio[1104]: info: touch_display: Setting screensaver timeout to 60 seconds. Dec 16 22:43:39 volumio-rpi volumio[1104]: info: Asound.conf file unchanged, so no further update is needed Dec 16 22:43:39 volumio-rpi volumio[1104]: info: Output device has changed, restarting MPD Dec 16 22:43:40 volumio-rpi sudo[2689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 22:43:40 volumio-rpi volumio[1104]: info: Output device has changed, restarting Shairport Sync Dec 16 22:43:40 volumio-rpi sudo[2689]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:43:40 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:43:40 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:43:40 volumio-rpi sudo[2689]: pam_unix(sudo:session): session closed for user root Dec 16 22:43:40 volumio-rpi sudo[2691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 22:43:40 volumio-rpi sudo[2691]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:43:40 volumio-rpi systemd[1]: Stopping Music Player Daemon... Dec 16 22:43:40 volumio-rpi volumio[1104]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:43:40 volumio-rpi systemd[1]: mpd.service: Succeeded. Dec 16 22:43:40 volumio-rpi volumio[1104]: info: MPD Permissions set Dec 16 22:43:40 volumio-rpi systemd[1]: Stopped Music Player Daemon. Dec 16 22:43:40 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:43:40 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:43:40 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:43:40 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:43:40 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:43:40 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:43:40 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:43:40 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:43:40 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:43:40 volumio-rpi volumio[1104]: info: Starting Shairport Sync Dec 16 22:43:40 volumio-rpi systemd[1]: Starting Music Player Daemon... Dec 16 22:43:40 volumio-rpi sudo[2703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 22:43:40 volumio-rpi sudo[2703]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:43:40 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 16 22:43:40 volumio-rpi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 16 22:43:40 volumio-rpi systemd[1]: shairport-sync.service: Succeeded. Dec 16 22:43:40 volumio-rpi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 16 22:43:40 volumio-rpi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 16 22:43:40 volumio-rpi sudo[2703]: pam_unix(sudo:session): session closed for user root Dec 16 22:43:40 volumio-rpi volumio[1104]: info: Shairport-Sync Started Dec 16 22:43:40 volumio-rpi sudo[2697]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 22:43:40 volumio-rpi sudo[2697]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:43:40 volumio-rpi sudo[2697]: pam_unix(sudo:session): session closed for user root Dec 16 22:43:40 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:43:40 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:43:40 volumio-rpi volumio[1104]: error: Upnp client error: Error: This socket has been ended by the other party Dec 16 22:43:40 volumio-rpi mpd[2709]: Dec 16 22:43 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 22:43:40 volumio-rpi systemd[1]: Started Music Player Daemon. Dec 16 22:43:40 volumio-rpi sudo[2691]: pam_unix(sudo:session): session closed for user root Dec 16 22:43:41 volumio-rpi volumio[1104]: error: updateQueue error: null Dec 16 22:43:41 volumio-rpi volumio[1104]: info: peppy_screensaver: Start PeppyMeter Dec 16 22:43:43 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 16 22:43:43 volumio-rpi volumio[1104]: info: CoreStateMachine::getcurrentVolume Dec 16 22:43:43 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 22:43:43 volumio-rpi volumio[1104]: info: CoreStateMachine::pushState Dec 16 22:43:43 volumio-rpi volumio[1104]: info: CorePlayQueue::getTrack 0 Dec 16 22:43:43 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioPushState Dec 16 22:43:43 volumio-rpi volumio[1104]: info: CoreStateMachine::updateTrackBlock Dec 16 22:43:43 volumio-rpi volumio[1104]: info: CorePlayQueue::getTrackBlock Dec 16 22:43:43 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 22:43:43 volumio-rpi volumio[1104]: info: CoreStateMachine::pushState Dec 16 22:43:43 volumio-rpi volumio[1104]: info: CorePlayQueue::getTrack 0 Dec 16 22:43:43 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioPushState Dec 16 22:43:49 volumio-rpi volumio[1104]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 16 22:43:49 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 16 22:43:49 volumio-rpi volumio[1104]: info: Preparing to save Alsa Options, stopping services first Dec 16 22:43:49 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioGetState Dec 16 22:43:49 volumio-rpi volumio[1104]: info: CorePlayQueue::getTrack 0 Dec 16 22:43:49 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioPause Dec 16 22:43:49 volumio-rpi volumio[1104]: info: CoreStateMachine::pause Dec 16 22:43:49 volumio-rpi volumio[1104]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"QCC5124"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Dec 16 22:43:49 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 22:43:50 volumio-rpi volumio[1104]: info: Setting default mixerSoftMaster for Softvolume device Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 16 22:43:50 volumio-rpi volumio[1104]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 16 22:43:50 volumio-rpi volumio[1104]: info: Disabling external Volume Control Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 22:43:50 volumio-rpi volumio[1104]: info: Enable softmixer device for audio device undefined Dec 16 22:43:50 volumio-rpi volumio[1104]: info: Software Volume ALSA configuration written Dec 16 22:43:50 volumio-rpi volumio[1104]: info: Preparing to generate the ALSA configuration file Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 16 22:43:50 volumio-rpi volumio[1104]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 16 22:43:50 volumio-rpi volumio[1104]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 16 22:43:50 volumio-rpi volumio[1104]: info: Reading ALSA contributions from plugins. Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreStateMachine::pushState Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CorePlayQueue::getTrack 0 Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioPushState Dec 16 22:43:50 volumio-rpi volumio[1104]: info: Asound.conf file unchanged, so no further update is needed Dec 16 22:43:50 volumio-rpi volumio[1104]: info: Output device has changed, restarting MPD Dec 16 22:43:50 volumio-rpi sudo[2802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 22:43:50 volumio-rpi volumio[1104]: info: Output device has changed, restarting Shairport Sync Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:43:50 volumio-rpi sudo[2802]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:43:50 volumio-rpi sudo[2802]: pam_unix(sudo:session): session closed for user root Dec 16 22:43:50 volumio-rpi sudo[2804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 22:43:50 volumio-rpi sudo[2804]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:43:50 volumio-rpi systemd[1]: Stopping Music Player Daemon... Dec 16 22:43:50 volumio-rpi volumio[1104]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:43:50 volumio-rpi volumio[1104]: info: MPD Permissions set Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:43:50 volumio-rpi systemd[1]: mpd.service: Succeeded. Dec 16 22:43:50 volumio-rpi systemd[1]: Stopped Music Player Daemon. Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:43:50 volumio-rpi volumio[1104]: info: Starting Shairport Sync Dec 16 22:43:50 volumio-rpi systemd[1]: Starting Music Player Daemon... Dec 16 22:43:50 volumio-rpi sudo[2816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 22:43:50 volumio-rpi sudo[2816]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:43:50 volumio-rpi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 16 22:43:50 volumio-rpi systemd[1]: shairport-sync.service: Succeeded. Dec 16 22:43:50 volumio-rpi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 16 22:43:50 volumio-rpi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 16 22:43:50 volumio-rpi sudo[2816]: pam_unix(sudo:session): session closed for user root Dec 16 22:43:50 volumio-rpi volumio[1104]: info: Shairport-Sync Started Dec 16 22:43:50 volumio-rpi sudo[2811]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 22:43:50 volumio-rpi sudo[2811]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:43:50 volumio-rpi sudo[2811]: pam_unix(sudo:session): session closed for user root Dec 16 22:43:50 volumio-rpi volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:43:51 volumio-rpi mpd[2822]: Dec 16 22:43 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 22:43:51 volumio-rpi systemd[1]: Started Music Player Daemon. Dec 16 22:43:51 volumio-rpi sudo[2804]: pam_unix(sudo:session): session closed for user root Dec 16 22:43:51 volumio-rpi volumio[1104]: error: updateQueue error: null Dec 16 22:43:54 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 16 22:43:54 volumio-rpi volumio[1104]: info: CoreStateMachine::getcurrentVolume Dec 16 22:43:54 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 22:43:54 volumio-rpi volumio[1104]: info: CoreStateMachine::pushState Dec 16 22:43:54 volumio-rpi volumio[1104]: info: CorePlayQueue::getTrack 0 Dec 16 22:43:54 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioPushState Dec 16 22:43:54 volumio-rpi volumio[1104]: info: CoreStateMachine::updateTrackBlock Dec 16 22:43:54 volumio-rpi volumio[1104]: info: CorePlayQueue::getTrackBlock Dec 16 22:43:54 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 22:43:54 volumio-rpi volumio[1104]: info: CoreStateMachine::pushState Dec 16 22:43:54 volumio-rpi volumio[1104]: info: CorePlayQueue::getTrack 0 Dec 16 22:43:54 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioPushState Dec 16 22:43:55 volumio-rpi volumio[1104]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 16 22:43:55 volumio-rpi volumio[1104]: info: Retrying Login Due to Network Error Dec 16 22:43:58 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioGetState Dec 16 22:43:58 volumio-rpi volumio[1104]: info: CorePlayQueue::getTrack 0 Dec 16 22:44:00 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioPlay Dec 16 22:44:00 volumio-rpi volumio[1104]: info: CoreStateMachine::play index undefined Dec 16 22:44:00 volumio-rpi volumio[1104]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 22:44:00 volumio-rpi volumio[1104]: info: CorePlayQueue::getTrack 0 Dec 16 22:44:00 volumio-rpi volumio[1104]: info: CoreStateMachine::startPlaybackTimer Dec 16 22:44:00 volumio-rpi volumio[1104]: info: CorePlayQueue::getTrack 0 Dec 16 22:44:00 volumio-rpi volumio[1104]: info: ControllerMpd::resume Dec 16 22:44:00 volumio-rpi volumio[1104]: verbose: ControllerMpd::sendMpdCommand play Dec 16 22:44:00 volumio-rpi volumio[1104]: info: sendMpdCommand play took 1 milliseconds Dec 16 22:44:02 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioPlay Dec 16 22:44:02 volumio-rpi volumio[1104]: info: CoreStateMachine::play index undefined Dec 16 22:44:02 volumio-rpi volumio[1104]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 22:44:02 volumio-rpi volumio[1104]: info: CorePlayQueue::getTrack 0 Dec 16 22:44:02 volumio-rpi volumio[1104]: info: CoreStateMachine::startPlaybackTimer Dec 16 22:44:02 volumio-rpi volumio[1104]: info: CorePlayQueue::getTrack 0 Dec 16 22:44:02 volumio-rpi volumio[1104]: info: ControllerMpd::resume Dec 16 22:44:02 volumio-rpi volumio[1104]: verbose: ControllerMpd::sendMpdCommand play Dec 16 22:44:02 volumio-rpi volumio[1104]: info: sendMpdCommand play took 3 milliseconds Dec 16 22:44:05 volumio-rpi volumio[1104]: info: MyVolumio login type: Token Dec 16 22:44:06 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioPlay Dec 16 22:44:06 volumio-rpi volumio[1104]: info: CoreStateMachine::play index undefined Dec 16 22:44:06 volumio-rpi volumio[1104]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 22:44:06 volumio-rpi volumio[1104]: info: CorePlayQueue::getTrack 0 Dec 16 22:44:06 volumio-rpi volumio[1104]: info: CoreStateMachine::startPlaybackTimer Dec 16 22:44:06 volumio-rpi volumio[1104]: info: CorePlayQueue::getTrack 0 Dec 16 22:44:06 volumio-rpi volumio[1104]: info: ControllerMpd::resume Dec 16 22:44:06 volumio-rpi volumio[1104]: verbose: ControllerMpd::sendMpdCommand play Dec 16 22:44:06 volumio-rpi volumio[1104]: info: sendMpdCommand play took 1 milliseconds Dec 16 22:44:12 volumio-rpi volumio[1104]: info: CoreCommandRouter::volumioGetQueue Dec 16 22:44:12 volumio-rpi volumio[1104]: info: CoreStateMachine::getQueue Dec 16 22:44:12 volumio-rpi volumio[1104]: info: CorePlayQueue::getQueue Dec 16 22:44:12 volumio-rpi volumio[1104]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/BEYOND/%E9%BB%84%E5%AE%B6%E9%A9%B9%E5%8E%9F%E4%BD%9C%E7%B2%BE%E9%80%89%E9%9B%86/fe005af9-5fd0-4936-ae26-22c748c5721c.jpg' Dec 16 22:44:13 volumio-rpi volumio[1104]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 22:44:13 volumio-rpi volumio[1104]: Error: connect ECONNREFUSED 103.42.176.244:443 Dec 16 22:44:13 volumio-rpi volumio[1104]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Dec 16 22:44:13 volumio-rpi volumio[1104]: errno: -111, Dec 16 22:44:13 volumio-rpi volumio[1104]: code: 'ECONNREFUSED', Dec 16 22:44:13 volumio-rpi volumio[1104]: syscall: 'connect', Dec 16 22:44:13 volumio-rpi volumio[1104]: address: '103.42.176.244', Dec 16 22:44:13 volumio-rpi volumio[1104]: port: 443 Dec 16 22:44:13 volumio-rpi volumio[1104]: } Dec 16 22:44:13 volumio-rpi volumio[1104]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 22:44:13 volumio-rpi sudo[2881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 22:43 Dec 16 22:44:13 volumio-rpi sudo[2881]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"