-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Fri 2025-07-18 16:04:49 CST. -- Jul 18 16:04:00 volumio volumio[2985]: info: Jul 18 16:04:00 volumio volumio[2985]: ---------------------------- MPD announces state update: player Jul 18 16:04:00 volumio volumio[2985]: info: ControllerMpd::getState Jul 18 16:04:00 volumio volumio[2985]: verbose: ControllerMpd::sendMpdCommand status Jul 18 16:04:00 volumio volumio[2985]: info: Jul 18 16:04:00 volumio volumio[2985]: ---------------------------- MPD announces state update: player Jul 18 16:04:00 volumio volumio[2985]: info: ControllerMpd::getState Jul 18 16:04:00 volumio volumio[2985]: verbose: ControllerMpd::sendMpdCommand status Jul 18 16:04:00 volumio kernel: usb 1-1.2: USB disconnect, device number 4 Jul 18 16:04:00 volumio volumio[2985]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 18 16:04:00 volumio volumio[2985]: info: sendMpdCommand status took 7 milliseconds Jul 18 16:04:00 volumio volumio[2985]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 18 16:04:00 volumio volumio[2985]: info: sendMpdCommand status took 6 milliseconds Jul 18 16:04:00 volumio volumio[2985]: verbose: ControllerMpd::parseState Jul 18 16:04:00 volumio volumio[2985]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 16:04:00 volumio volumio[2985]: verbose: ControllerMpd::parseState Jul 18 16:04:00 volumio volumio[2985]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 16:04:00 volumio volumio[2985]: info: Jul 18 16:04:00 volumio volumio[2985]: ---------------------------- MPD announces state update: player Jul 18 16:04:00 volumio volumio[2985]: info: ControllerMpd::getState Jul 18 16:04:00 volumio volumio[2985]: verbose: ControllerMpd::sendMpdCommand status Jul 18 16:04:00 volumio volumio[2985]: info: sendMpdCommand clearerror took 4 milliseconds Jul 18 16:04:00 volumio volumio[2985]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 18 16:04:00 volumio volumio[2985]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 18 16:04:00 volumio volumio[2985]: info: sendMpdCommand status took 2 milliseconds Jul 18 16:04:00 volumio volumio[2985]: verbose: ControllerMpd::parseTrackInfo Jul 18 16:04:00 volumio volumio[2985]: verbose: ControllerMpd::parseState Jul 18 16:04:00 volumio volumio[2985]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 16:04:00 volumio volumio[2985]: info: ControllerMpd::pushState Jul 18 16:04:00 volumio volumio[2985]: info: CoreCommandRouter::servicePushState Jul 18 16:04:00 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:00 volumio volumio[2985]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":88835,"duration":226,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2762 Kbps","isStreaming":false,"title":"エウテルペ (欧忒耳佩)","artist":"EGOIST","album":"GREATEST HITS 2011-2017","uri":"USB/KINGSTON/music/EGOIST-欧忒耳佩.flac","trackType":"flac"} Jul 18 16:04:00 volumio volumio[2985]: verbose: CURRENT POSITION 2 Jul 18 16:04:00 volumio volumio[2985]: info: CoreStateMachine::syncState stateService pause Jul 18 16:04:00 volumio volumio[2985]: info: CoreStateMachine::syncState currentStatus play Jul 18 16:04:00 volumio volumio[2985]: info: CoreStateMachine::pushState Jul 18 16:04:00 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:00 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 16:04:00 volumio volumio[2985]: info: CoreCommandRouter::volumioPushState Jul 18 16:04:00 volumio volumio[2985]: info: ControllerMpd::pushState Jul 18 16:04:00 volumio volumio[2985]: info: CoreCommandRouter::servicePushState Jul 18 16:04:00 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:00 volumio volumio[2985]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":88835,"duration":226,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2762 Kbps","isStreaming":false,"title":"エウテルペ (欧忒耳佩)","artist":"EGOIST","album":"GREATEST HITS 2011-2017","uri":"USB/KINGSTON/music/EGOIST-欧忒耳佩.flac","trackType":"flac"} Jul 18 16:04:00 volumio volumio[2985]: verbose: CURRENT POSITION 2 Jul 18 16:04:00 volumio volumio[2985]: info: CoreStateMachine::syncState stateService pause Jul 18 16:04:00 volumio volumio[2985]: info: CoreStateMachine::syncState currentStatus play Jul 18 16:04:00 volumio volumio[2985]: info: CoreStateMachine::pushState Jul 18 16:04:00 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:00 volumio volumio[2985]: info: CoreCommandRouter::volumioPushState Jul 18 16:04:00 volumio volumio[2985]: info: ------------------------------ 27ms Jul 18 16:04:00 volumio volumio[2985]: info: ------------------------------ 25ms Jul 18 16:04:00 volumio volumio[2985]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 18 16:04:00 volumio volumio[2985]: verbose: ControllerMpd::parseTrackInfo Jul 18 16:04:00 volumio volumio[2985]: info: ControllerMpd::pushState Jul 18 16:04:00 volumio volumio[2985]: info: CoreCommandRouter::servicePushState Jul 18 16:04:00 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:00 volumio volumio[2985]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":88835,"duration":226,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2762 Kbps","isStreaming":false,"title":"エウテルペ (欧忒耳佩)","artist":"EGOIST","album":"GREATEST HITS 2011-2017","uri":"USB/KINGSTON/music/EGOIST-欧忒耳佩.flac","trackType":"flac"} Jul 18 16:04:00 volumio volumio[2985]: verbose: CURRENT POSITION 2 Jul 18 16:04:00 volumio volumio[2985]: info: CoreStateMachine::syncState stateService pause Jul 18 16:04:00 volumio volumio[2985]: info: CoreStateMachine::syncState currentStatus play Jul 18 16:04:00 volumio volumio[2985]: info: CoreStateMachine::pushState Jul 18 16:04:00 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:00 volumio volumio[2985]: info: CoreCommandRouter::volumioPushState Jul 18 16:04:00 volumio volumio[2985]: info: ------------------------------ 26ms Jul 18 16:04:00 volumio volumio[2985]: info: Jul 18 16:04:00 volumio volumio[2985]: ---------------------------- USB Audio Device Detached Jul 18 16:04:00 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jul 18 16:04:00 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 18 16:04:00 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jul 18 16:04:13 volumio kernel: hwmon hwmon1: Undervoltage detected! Jul 18 16:04:13 volumio kernel: usb 1-1.2: new full-speed USB device number 5 using xhci_hcd Jul 18 16:04:13 volumio kernel: usb 1-1.2: New USB device found, idVendor=20b1, idProduct=1020, bcdDevice= 1.01 Jul 18 16:04:13 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jul 18 16:04:13 volumio kernel: usb 1-1.2: Product: Shanling UA4 FS Jul 18 16:04:13 volumio kernel: usb 1-1.2: Manufacturer: Shanling Jul 18 16:04:13 volumio kernel: usb 1-1.2: SerialNumber: Shanling UA4 Jul 18 16:04:13 volumio kernel: usb 1-1.2: Warning! Unlikely big volume range (=16256), cval->res is probably wrong. Jul 18 16:04:13 volumio kernel: usb 1-1.2: [3] FU [PCM Playback Volume] ch = 2, val = -16256/0/1 Jul 18 16:04:13 volumio kernel: usb 1-1.2: Warning! Unlikely big volume range (=16256), cval->res is probably wrong. Jul 18 16:04:13 volumio kernel: usb 1-1.2: [3] FU [PCM Playback Volume] ch = 1, val = -16256/0/1 Jul 18 16:04:13 volumio systemd-udevd[4965]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Jul 18 16:04:13 volumio volumio[2985]: info: Jul 18 16:04:13 volumio volumio[2985]: ---------------------------- USB Audio Device Attached Jul 18 16:04:13 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jul 18 16:04:13 volumio volumio[2985]: info: CoreCommandRouter::Close All Modals sent Jul 18 16:04:13 volumio volumio[2985]: info: Preparing to save Alsa Options, stopping services first Jul 18 16:04:13 volumio volumio[2985]: info: CoreCommandRouter::volumioGetState Jul 18 16:04:13 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:13 volumio volumio[2985]: info: CoreCommandRouter::volumioPause Jul 18 16:04:13 volumio volumio[2985]: info: CoreStateMachine::pause Jul 18 16:04:13 volumio volumio[2985]: info: CoreStateMachine::stPlaybackTimer Jul 18 16:04:13 volumio volumio[2985]: info: CoreStateMachine::servicePause Jul 18 16:04:13 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:13 volumio volumio[2985]: info: CoreCommandRouter::servicePause Jul 18 16:04:13 volumio volumio[2985]: info: ControllerMpd::pause Jul 18 16:04:13 volumio volumio[2985]: verbose: ControllerMpd::sendMpdCommand pause Jul 18 16:04:13 volumio volumio[2985]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Shanling UA4 FS","alsacard":"FS"},"i2s":false} Jul 18 16:04:13 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 18 16:04:14 volumio volumio[2985]: info: Setting mixer PCM for card Shanling UA4 FS Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 18 16:04:14 volumio volumio[2985]: info: Updating Volume Controller Parameters: Device: 5 Name: Shanling UA4 FS Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 18 16:04:14 volumio volumio[2985]: info: Disabling external Volume Control Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 16:04:14 volumio volumio[2985]: info: Preparing to generate the ALSA configuration file Jul 18 16:04:14 volumio volumio[2985]: info: Jul 18 16:04:14 volumio volumio[2985]: ---------------------------- MPD announces state update: player Jul 18 16:04:14 volumio volumio[2985]: info: sendMpdCommand pause took 396 milliseconds Jul 18 16:04:14 volumio volumio[2985]: info: ControllerMpd::getState Jul 18 16:04:14 volumio volumio[2985]: verbose: ControllerMpd::sendMpdCommand status Jul 18 16:04:14 volumio volumio[2985]: info: Jul 18 16:04:14 volumio volumio[2985]: ---------------------------- MPD announces state update: player Jul 18 16:04:14 volumio volumio[2985]: info: ControllerMpd::getState Jul 18 16:04:14 volumio volumio[2985]: verbose: ControllerMpd::sendMpdCommand status Jul 18 16:04:14 volumio volumio[2985]: info: Jul 18 16:04:14 volumio volumio[2985]: ---------------------------- MPD announces state update: player Jul 18 16:04:14 volumio volumio[2985]: info: ControllerMpd::getState Jul 18 16:04:14 volumio volumio[2985]: verbose: ControllerMpd::sendMpdCommand status Jul 18 16:04:14 volumio volumio[2985]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 18 16:04:14 volumio volumio[2985]: info: sendMpdCommand status took 45 milliseconds Jul 18 16:04:14 volumio volumio[2985]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 18 16:04:14 volumio volumio[2985]: info: sendMpdCommand status took 45 milliseconds Jul 18 16:04:14 volumio volumio[2985]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 18 16:04:14 volumio volumio[2985]: info: sendMpdCommand status took 45 milliseconds Jul 18 16:04:14 volumio volumio[2985]: verbose: ControllerMpd::parseState Jul 18 16:04:14 volumio volumio[2985]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 16:04:14 volumio volumio[2985]: verbose: ControllerMpd::parseState Jul 18 16:04:14 volumio volumio[2985]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 16:04:14 volumio volumio[2985]: verbose: ControllerMpd::parseState Jul 18 16:04:14 volumio volumio[2985]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 16:04:14 volumio volumio[2985]: info: sendMpdCommand clearerror took 21 milliseconds Jul 18 16:04:14 volumio volumio[2985]: info: sendMpdCommand clearerror took 21 milliseconds Jul 18 16:04:14 volumio volumio[2985]: info: sendMpdCommand clearerror took 19 milliseconds Jul 18 16:04:14 volumio volumio[2985]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 18 16:04:14 volumio volumio[2985]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 18 16:04:14 volumio volumio[2985]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 18 16:04:14 volumio volumio[2985]: verbose: ControllerMpd::parseTrackInfo Jul 18 16:04:14 volumio volumio[2985]: verbose: ControllerMpd::parseTrackInfo Jul 18 16:04:14 volumio volumio[2985]: verbose: ControllerMpd::parseTrackInfo Jul 18 16:04:14 volumio volumio[2985]: info: ControllerMpd::pushState Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::servicePushState Jul 18 16:04:14 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:14 volumio volumio[2985]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":226,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2762 Kbps","isStreaming":false,"title":"エウテルペ (欧忒耳佩)","artist":"EGOIST","album":"GREATEST HITS 2011-2017","uri":"USB/KINGSTON/music/EGOIST-欧忒耳佩.flac","trackType":"flac"} Jul 18 16:04:14 volumio volumio[2985]: verbose: CURRENT POSITION 2 Jul 18 16:04:14 volumio volumio[2985]: info: CoreStateMachine::syncState stateService pause Jul 18 16:04:14 volumio volumio[2985]: info: CoreStateMachine::syncState currentStatus pause Jul 18 16:04:14 volumio volumio[2985]: info: CoreStateMachine::pushState Jul 18 16:04:14 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::volumioPushState Jul 18 16:04:14 volumio volumio[2985]: info: CoreStateMachine::stPlaybackTimer Jul 18 16:04:14 volumio volumio[2985]: info: ControllerMpd::pushState Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::servicePushState Jul 18 16:04:14 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:14 volumio volumio[2985]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":226,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2762 Kbps","isStreaming":false,"title":"エウテルペ (欧忒耳佩)","artist":"EGOIST","album":"GREATEST HITS 2011-2017","uri":"USB/KINGSTON/music/EGOIST-欧忒耳佩.flac","trackType":"flac"} Jul 18 16:04:14 volumio volumio[2985]: verbose: CURRENT POSITION 2 Jul 18 16:04:14 volumio volumio[2985]: info: CoreStateMachine::syncState stateService pause Jul 18 16:04:14 volumio volumio[2985]: info: CoreStateMachine::syncState currentStatus pause Jul 18 16:04:14 volumio volumio[2985]: info: CoreStateMachine::pushState Jul 18 16:04:14 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::volumioPushState Jul 18 16:04:14 volumio volumio[2985]: info: CoreStateMachine::stPlaybackTimer Jul 18 16:04:14 volumio volumio[2985]: info: ControllerMpd::pushState Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::servicePushState Jul 18 16:04:14 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:14 volumio volumio[2985]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":226,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2762 Kbps","isStreaming":false,"title":"エウテルペ (欧忒耳佩)","artist":"EGOIST","album":"GREATEST HITS 2011-2017","uri":"USB/KINGSTON/music/EGOIST-欧忒耳佩.flac","trackType":"flac"} Jul 18 16:04:14 volumio volumio[2985]: verbose: CURRENT POSITION 2 Jul 18 16:04:14 volumio volumio[2985]: info: CoreStateMachine::syncState stateService pause Jul 18 16:04:14 volumio volumio[2985]: info: CoreStateMachine::syncState currentStatus pause Jul 18 16:04:14 volumio volumio[2985]: info: CoreStateMachine::pushState Jul 18 16:04:14 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::volumioPushState Jul 18 16:04:14 volumio volumio[2985]: info: CoreStateMachine::stPlaybackTimer Jul 18 16:04:14 volumio volumio[2985]: info: ------------------------------ 102ms Jul 18 16:04:14 volumio volumio[2985]: info: ------------------------------ 101ms Jul 18 16:04:14 volumio volumio[2985]: info: ------------------------------ 100ms Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 18 16:04:14 volumio volumio[2985]: info: VolumeController:: Volume=41 Mute =false Jul 18 16:04:14 volumio volumio[2985]: info: CoreStateMachine::pushState Jul 18 16:04:14 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::volumioPushState Jul 18 16:04:14 volumio volumio[2985]: info: Asound.conf file written Jul 18 16:04:14 volumio sudo[4986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 18 16:04:14 volumio sudo[4986]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 16:04:14 volumio sudo[4986]: pam_unix(sudo:session): session closed for user root Jul 18 16:04:14 volumio volumio[2985]: No state is present for card FS Jul 18 16:04:14 volumio volumio[2985]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:1020" "" "" Jul 18 16:04:14 volumio volumio[2985]: Hardware is initialized using a generic method Jul 18 16:04:14 volumio volumio[2985]: No state is present for card FS Jul 18 16:04:14 volumio volumio[2985]: info: Output device has changed, restarting MPD Jul 18 16:04:14 volumio sudo[4992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 16:04:14 volumio sudo[4992]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 16:04:14 volumio volumio[2985]: info: Output device has changed, restarting Shairport Sync Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 16:04:14 volumio sudo[4992]: pam_unix(sudo:session): session closed for user root Jul 18 16:04:14 volumio sudo[4995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 16:04:14 volumio sudo[4995]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 16:04:14 volumio systemd[1]: Stopping Music Player Daemon... Jul 18 16:04:14 volumio volumio[2985]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 16:04:14 volumio volumio[2985]: info: Output device has changed, restarting MPD Jul 18 16:04:14 volumio systemd[1]: mpd.service: Succeeded. Jul 18 16:04:14 volumio systemd[1]: Stopped Music Player Daemon. Jul 18 16:04:14 volumio volumio[2985]: info: Output device has changed, restarting Shairport Sync Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 16:04:14 volumio sudo[5001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 16:04:14 volumio sudo[5001]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 16:04:14 volumio volumio[2985]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Jul 18 16:04:14 volumio volumio[2985]: info: MPD Permissions set Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 16:04:14 volumio sudo[5001]: pam_unix(sudo:session): session closed for user root Jul 18 16:04:14 volumio systemd[1]: Starting Music Player Daemon... Jul 18 16:04:14 volumio sudo[5004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 16:04:14 volumio sudo[5004]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 16:04:14 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 18 16:04:14 volumio systemd[1]: mpd.service: Succeeded. Jul 18 16:04:14 volumio systemd[1]: Stopped Music Player Daemon. Jul 18 16:04:14 volumio systemd[1]: Starting Music Player Daemon... Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 16:04:14 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 16:04:14 volumio volumio[2985]: info: Starting Shairport Sync Jul 18 16:04:14 volumio volumio[2985]: info: Starting Shairport Sync Jul 18 16:04:14 volumio sudo[5021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 18 16:04:14 volumio sudo[5021]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 16:04:15 volumio sudo[5024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 18 16:04:15 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 18 16:04:15 volumio sudo[5024]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 16:04:15 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 18 16:04:15 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 18 16:04:15 volumio sudo[5016]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 18 16:04:15 volumio sudo[5016]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 16:04:15 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 18 16:04:15 volumio sudo[5016]: pam_unix(sudo:session): session closed for user root Jul 18 16:04:15 volumio sudo[5021]: pam_unix(sudo:session): session closed for user root Jul 18 16:04:15 volumio volumio[2985]: info: Shairport-Sync Started Jul 18 16:04:15 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 18 16:04:15 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 18 16:04:15 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 18 16:04:15 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 18 16:04:15 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 18 16:04:15 volumio sudo[5024]: pam_unix(sudo:session): session closed for user root Jul 18 16:04:15 volumio volumio[2985]: info: Shairport-Sync Started Jul 18 16:04:15 volumio mpd[5028]: Jul 18 16:04 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 18 16:04:15 volumio systemd[1]: Started Music Player Daemon. Jul 18 16:04:15 volumio sudo[5004]: pam_unix(sudo:session): session closed for user root Jul 18 16:04:15 volumio sudo[4995]: pam_unix(sudo:session): session closed for user root Jul 18 16:04:15 volumio volumio[2985]: error: MPD error: The expression evaluated to a falsy value: Jul 18 16:04:15 volumio volumio[2985]: assert.ok(self.idling) Jul 18 16:04:15 volumio volumio[2985]: error: The expression evaluated to a falsy value: Jul 18 16:04:15 volumio volumio[2985]: assert.ok(self.idling) Jul 18 16:04:15 volumio volumio[2985]: error: updateQueue error: null Jul 18 16:04:17 volumio kernel: hwmon hwmon1: Voltage normalised Jul 18 16:04:18 volumio volumio[2985]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jul 18 16:04:18 volumio volumio[2985]: info: CoreStateMachine::getcurrentVolume Jul 18 16:04:18 volumio volumio[2985]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 16:04:18 volumio volumio[2985]: info: VolumeController:: Volume=41 Mute =false Jul 18 16:04:18 volumio volumio[2985]: info: CoreStateMachine::pushState Jul 18 16:04:18 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:18 volumio volumio[2985]: info: CoreCommandRouter::volumioPushState Jul 18 16:04:18 volumio volumio[2985]: info: CoreStateMachine::updateTrackBlock Jul 18 16:04:18 volumio volumio[2985]: info: CorePlayQueue::getTrackBlock Jul 18 16:04:18 volumio volumio[2985]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 16:04:18 volumio volumio[2985]: info: VolumeController:: Volume=41 Mute =false Jul 18 16:04:18 volumio volumio[2985]: info: CoreStateMachine::pushState Jul 18 16:04:18 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:18 volumio volumio[2985]: info: CoreCommandRouter::volumioPushState Jul 18 16:04:19 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 16:04:21 volumio volumio[2985]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/EGOIST/GREATEST%20HITS%202011-2017/22a0fe03-39d2-4e79-bc36-6c88415da24e.jpg' Jul 18 16:04:22 volumio volumio[2985]: verbose: New Socket.io Connection to 192.168.31.47 from 192.168.31.54 UA: Mozilla/5.0 (Linux; Android 15; 23127PN0CC Build/AQ3A.240627.003) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.7204.67 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jul 18 16:04:22 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 18 16:04:22 volumio volumio[2985]: info: CoreCommandRouter::volumioGetVisibleSources Jul 18 16:04:22 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 16:04:22 volumio volumio[2985]: info: CoreCommandRouter::volumioGetState Jul 18 16:04:22 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:22 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 18 16:04:22 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 18 16:04:22 volumio volumio[2985]: info: Received Get System Info Jul 18 16:04:22 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 16:04:22 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 16:04:22 volumio volumio[2985]: info: Discovery: Getting this device information Jul 18 16:04:22 volumio volumio[2985]: info: CoreCommandRouter::volumioGetState Jul 18 16:04:22 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:22 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 18 16:04:22 volumio volumio[2985]: info: CoreCommandRouter::volumioGetState Jul 18 16:04:22 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:22 volumio volumio[2985]: info: Listing playlists Jul 18 16:04:22 volumio volumio[2985]: info: CoreCommandRouter::volumioGetQueue Jul 18 16:04:22 volumio volumio[2985]: info: CoreStateMachine::getQueue Jul 18 16:04:22 volumio volumio[2985]: info: CorePlayQueue::getQueue Jul 18 16:04:23 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 18 16:04:33 volumio volumio[2985]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 16:04:33 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 18 16:04:33 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 16:04:33 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 18 16:04:33 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 16:04:33 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 16:04:33 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 16:04:33 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 16:04:33 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 16:04:33 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 16:04:33 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 16:04:33 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 18 16:04:33 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 18 16:04:38 volumio volumio[2985]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jul 18 16:04:38 volumio volumio[2985]: info: Preparing to save Alsa Options, stopping services first Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::volumioGetState Jul 18 16:04:38 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::volumioPause Jul 18 16:04:38 volumio volumio[2985]: info: CoreStateMachine::pause Jul 18 16:04:38 volumio volumio[2985]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Shanling UA4 FS"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 18 16:04:38 volumio volumio[2985]: info: Setting mixer PCM for card Shanling UA4 FS Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 18 16:04:38 volumio volumio[2985]: info: Updating Volume Controller Parameters: Device: 5 Name: Shanling UA4 FS Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 18 16:04:38 volumio volumio[2985]: info: Disabling external Volume Control Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 16:04:38 volumio volumio[2985]: info: Preparing to generate the ALSA configuration file Jul 18 16:04:38 volumio volumio[2985]: info: Asound.conf file unchanged, so no further update is needed Jul 18 16:04:38 volumio volumio[2985]: info: Output device has changed, restarting MPD Jul 18 16:04:38 volumio sudo[5123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 16:04:38 volumio sudo[5123]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 16:04:38 volumio volumio[2985]: info: Output device has changed, restarting Shairport Sync Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 16:04:38 volumio sudo[5123]: pam_unix(sudo:session): session closed for user root Jul 18 16:04:38 volumio sudo[5126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 16:04:38 volumio sudo[5126]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 16:04:38 volumio systemd[1]: Stopping Music Player Daemon... Jul 18 16:04:38 volumio volumio[2985]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 16:04:38 volumio systemd[1]: mpd.service: Succeeded. Jul 18 16:04:38 volumio systemd[1]: Stopped Music Player Daemon. Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 18 16:04:38 volumio systemd[1]: Starting Music Player Daemon... Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 18 16:04:38 volumio volumio[2985]: info: MPD Permissions set Jul 18 16:04:38 volumio volumio[2985]: info: VolumeController:: Volume=41 Mute =false Jul 18 16:04:38 volumio volumio[2985]: info: CoreStateMachine::pushState Jul 18 16:04:38 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::volumioPushState Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 16:04:38 volumio volumio[2985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 16:04:38 volumio volumio[2985]: info: Starting Shairport Sync Jul 18 16:04:38 volumio sudo[5133]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 18 16:04:38 volumio sudo[5133]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 16:04:38 volumio sudo[5133]: pam_unix(sudo:session): session closed for user root Jul 18 16:04:38 volumio sudo[5142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 18 16:04:38 volumio sudo[5142]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 16:04:38 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 18 16:04:38 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 18 16:04:38 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 18 16:04:38 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 18 16:04:38 volumio sudo[5142]: pam_unix(sudo:session): session closed for user root Jul 18 16:04:38 volumio volumio[2985]: info: Shairport-Sync Started Jul 18 16:04:39 volumio mpd[5143]: Jul 18 16:04 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 18 16:04:39 volumio systemd[1]: Started Music Player Daemon. Jul 18 16:04:39 volumio sudo[5126]: pam_unix(sudo:session): session closed for user root Jul 18 16:04:39 volumio volumio[2985]: error: updateQueue error: null Jul 18 16:04:42 volumio volumio[2985]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jul 18 16:04:42 volumio volumio[2985]: info: CoreStateMachine::getcurrentVolume Jul 18 16:04:42 volumio volumio[2985]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 16:04:42 volumio volumio[2985]: info: VolumeController:: Volume=41 Mute =false Jul 18 16:04:42 volumio volumio[2985]: info: CoreStateMachine::pushState Jul 18 16:04:42 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:42 volumio volumio[2985]: info: CoreCommandRouter::volumioPushState Jul 18 16:04:42 volumio volumio[2985]: info: CoreStateMachine::updateTrackBlock Jul 18 16:04:42 volumio volumio[2985]: info: CorePlayQueue::getTrackBlock Jul 18 16:04:42 volumio volumio[2985]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 16:04:42 volumio volumio[2985]: info: VolumeController:: Volume=41 Mute =false Jul 18 16:04:42 volumio volumio[2985]: info: CoreStateMachine::pushState Jul 18 16:04:42 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:42 volumio volumio[2985]: info: CoreCommandRouter::volumioPushState Jul 18 16:04:46 volumio volumio[2985]: info: CoreCommandRouter::volumioPlay Jul 18 16:04:46 volumio volumio[2985]: info: CoreStateMachine::play index undefined Jul 18 16:04:46 volumio volumio[2985]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 16:04:46 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:46 volumio volumio[2985]: info: CoreStateMachine::startPlaybackTimer Jul 18 16:04:46 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:46 volumio volumio[2985]: info: ControllerMpd::resume Jul 18 16:04:46 volumio volumio[2985]: verbose: ControllerMpd::sendMpdCommand play Jul 18 16:04:46 volumio volumio[2985]: info: sendMpdCommand play took 0 milliseconds Jul 18 16:04:48 volumio volumio[2985]: info: CoreCommandRouter::volumioPlay Jul 18 16:04:48 volumio volumio[2985]: info: CoreStateMachine::play index undefined Jul 18 16:04:48 volumio volumio[2985]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 16:04:48 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:48 volumio volumio[2985]: info: CoreStateMachine::startPlaybackTimer Jul 18 16:04:48 volumio volumio[2985]: info: CorePlayQueue::getTrack 2 Jul 18 16:04:48 volumio volumio[2985]: info: ControllerMpd::resume Jul 18 16:04:48 volumio volumio[2985]: verbose: ControllerMpd::sendMpdCommand play Jul 18 16:04:48 volumio volumio[2985]: info: sendMpdCommand play took 1 milliseconds Jul 18 16:04:49 volumio volumio[2985]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 16:04:49 volumio volumio[2985]: Error: connect ETIMEDOUT 104.16.252.55:443 Jul 18 16:04:49 volumio volumio[2985]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jul 18 16:04:49 volumio volumio[2985]: errno: -110, Jul 18 16:04:49 volumio volumio[2985]: code: 'ETIMEDOUT', Jul 18 16:04:49 volumio volumio[2985]: syscall: 'connect', Jul 18 16:04:49 volumio volumio[2985]: address: '104.16.252.55', Jul 18 16:04:49 volumio volumio[2985]: port: 443 Jul 18 16:04:49 volumio volumio[2985]: } Jul 18 16:04:49 volumio volumio[2985]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 16:04:49 volumio sudo[5180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-18 16:03 Jul 18 16:04:49 volumio sudo[5180]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"