-- Logs begin at Wed 2025-07-02 20:34:32 CST, end at Wed 2025-07-02 20:37:48 CST. -- Jul 02 20:36:01 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioPause Jul 02 20:36:01 volumio-x86-784 volumio[1185]: info: CoreStateMachine::pause Jul 02 20:36:01 volumio-x86-784 volumio[1185]: info: CoreStateMachine::stPlaybackTimer Jul 02 20:36:01 volumio-x86-784 volumio[1185]: info: CoreStateMachine::servicePause Jul 02 20:36:01 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:36:01 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::servicePause Jul 02 20:36:01 volumio-x86-784 volumio[1185]: info: ControllerMpd::pause Jul 02 20:36:01 volumio-x86-784 volumio[1185]: verbose: ControllerMpd::sendMpdCommand pause Jul 02 20:36:01 volumio-x86-784 volumio[1185]: info: Jul 02 20:36:01 volumio-x86-784 volumio[1185]: ---------------------------- MPD announces state update: player Jul 02 20:36:01 volumio-x86-784 volumio[1185]: info: sendMpdCommand pause took 1 milliseconds Jul 02 20:36:01 volumio-x86-784 volumio[1185]: info: ControllerMpd::getState Jul 02 20:36:01 volumio-x86-784 volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:36:01 volumio-x86-784 volumio[1185]: info: sendMpdCommand status took 1 milliseconds Jul 02 20:36:01 volumio-x86-784 volumio[1185]: verbose: ControllerMpd::parseState Jul 02 20:36:01 volumio-x86-784 volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:36:01 volumio-x86-784 volumio[1185]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 02 20:36:01 volumio-x86-784 volumio[1185]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:36:01 volumio-x86-784 volumio[1185]: info: ControllerMpd::pushState Jul 02 20:36:01 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::servicePushState Jul 02 20:36:01 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:36:01 volumio-x86-784 volumio[1185]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":24109,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"771 Kbps","isStreaming":false,"title":"不浪漫罪名","artist":"王杰","album":"万岁2001","uri":"USB/微peu盘/王杰 - 不浪漫罪名.flac","trackType":"flac"} Jul 02 20:36:01 volumio-x86-784 volumio[1185]: verbose: CURRENT POSITION 2 Jul 02 20:36:01 volumio-x86-784 volumio[1185]: info: CoreStateMachine::syncState stateService pause Jul 02 20:36:01 volumio-x86-784 volumio[1185]: info: CoreStateMachine::syncState currentStatus pause Jul 02 20:36:01 volumio-x86-784 volumio[1185]: info: CoreStateMachine::pushState Jul 02 20:36:01 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:36:01 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:36:01 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioPushState Jul 02 20:36:01 volumio-x86-784 volumio[1185]: info: CoreStateMachine::stPlaybackTimer Jul 02 20:36:01 volumio-x86-784 volumio[1185]: info: ------------------------------ 8ms Jul 02 20:36:06 volumio-x86-784 volumio[1185]: info: MyVolumio login type: Token Jul 02 20:36:08 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioGetState Jul 02 20:36:08 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:36:18 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioGetState Jul 02 20:36:18 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:36:18 volumio-x86-784 volumio[1185]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%88%80%E9%83%8E/2002%E5%B9%B4%E7%9A%84%E7%AC%AC%E4%B8%80%E5%9C%BA%E9%9B%AA/bb3f30f4-0016-4c8e-aa49-6faa199130e5.jpg' Jul 02 20:36:19 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioGetState Jul 02 20:36:19 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:36:22 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioGetState Jul 02 20:36:22 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:36:25 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 20:36:26 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 02 20:36:26 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 20:36:26 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 02 20:36:26 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 20:36:26 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 20:36:26 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 02 20:36:26 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 02 20:36:30 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioGetState Jul 02 20:36:30 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:36:36 volumio-x86-784 volumio[1185]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jul 02 20:36:36 volumio-x86-784 volumio[1185]: info: Retrying Login Due to Network Error Jul 02 20:36:41 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioGetState Jul 02 20:36:41 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:36:41 volumio-x86-784 volumio[1185]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jul 02 20:36:41 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jul 02 20:36:41 volumio-x86-784 volumio[1185]: info: Preparing to save Alsa Options, stopping services first Jul 02 20:36:41 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioGetState Jul 02 20:36:41 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:36:41 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioPause Jul 02 20:36:41 volumio-x86-784 volumio[1185]: info: CoreStateMachine::pause Jul 02 20:36:41 volumio-x86-784 volumio[1185]: info: Saving Audio Output to: {"output_device":{"value":"7","label":"Dummy"}} Jul 02 20:36:41 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 02 20:36:41 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 02 20:36:41 volumio-x86-784 volumio[1185]: info: Setting mixer Master for card Dummy Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: Updating Volume Controller Parameters: Device: 7 Name: Dummy Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: Disabling external Volume Control Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: Preparing to generate the ALSA configuration file Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: Reading ALSA contributions from plugins. Jul 02 20:36:42 volumio-x86-784 volumio[1185]: Unable to parse: Jul 02 20:36:42 volumio-x86-784 volumio[1185]: Simple mixer control 'Master',0 Jul 02 20:36:42 volumio-x86-784 volumio[1185]: Capabilities: volume cswitch Jul 02 20:36:42 volumio-x86-784 volumio[1185]: Playback channels: Front Left - Front Right Jul 02 20:36:42 volumio-x86-784 volumio[1185]: Capture channels: Front Left - Front Right Jul 02 20:36:42 volumio-x86-784 volumio[1185]: Limits: -50 - 100 Jul 02 20:36:42 volumio-x86-784 volumio[1185]: Front Left: 0 [17%] [-30.00dB] Capture [off] Jul 02 20:36:42 volumio-x86-784 volumio[1185]: Front Right: 0 [17%] [-30.00dB] Capture [off] Jul 02 20:36:42 volumio-x86-784 volumio[1185]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 02 20:36:42 volumio-x86-784 volumio[1185]: Unable to parse: Jul 02 20:36:42 volumio-x86-784 volumio[1185]: Simple mixer control 'Master',0 Jul 02 20:36:42 volumio-x86-784 volumio[1185]: Capabilities: volume cswitch Jul 02 20:36:42 volumio-x86-784 volumio[1185]: Playback channels: Front Left - Front Right Jul 02 20:36:42 volumio-x86-784 volumio[1185]: Capture channels: Front Left - Front Right Jul 02 20:36:42 volumio-x86-784 volumio[1185]: Limits: -50 - 100 Jul 02 20:36:42 volumio-x86-784 volumio[1185]: Front Left: 0 [17%] [-30.00dB] Capture [off] Jul 02 20:36:42 volumio-x86-784 volumio[1185]: Front Right: 0 [17%] [-30.00dB] Capture [off] Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: VolumeController:: Volume=undefined Mute =false Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreStateMachine::pushState Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioPushState Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: Asound.conf file written Jul 02 20:36:42 volumio-x86-784 sudo[2682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 02 20:36:42 volumio-x86-784 sudo[2682]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:36:42 volumio-x86-784 sudo[2682]: pam_unix(sudo:session): session closed for user root Jul 02 20:36:42 volumio-x86-784 volumio[1185]: No state is present for card PCH Jul 02 20:36:42 volumio-x86-784 volumio[1185]: Found hardware: "HDA-Intel" "Cirrus Logic CS4206" "HDA:10134206,106b0900,00100302" "0x8086" "0x7270" Jul 02 20:36:42 volumio-x86-784 volumio[1185]: Hardware is initialized using a generic method Jul 02 20:36:42 volumio-x86-784 volumio[1185]: No state is present for card PCH Jul 02 20:36:42 volumio-x86-784 volumio[1185]: No state is present for card NVidia Jul 02 20:36:42 volumio-x86-784 volumio[1185]: Found hardware: "HDA-Intel" "Nvidia GPU 41 HDMI/DP" "HDA:10de0041,102815cc,00100100" "0x1028" "0x15cc" Jul 02 20:36:42 volumio-x86-784 volumio[1185]: Hardware is initialized using a generic method Jul 02 20:36:42 volumio-x86-784 volumio[1185]: No state is present for card NVidia Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: Output device has changed, restarting MPD Jul 02 20:36:42 volumio-x86-784 sudo[2701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 02 20:36:42 volumio-x86-784 sudo[2701]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: Output device has changed, restarting Shairport Sync Jul 02 20:36:42 volumio-x86-784 sudo[2701]: pam_unix(sudo:session): session closed for user root Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 20:36:42 volumio-x86-784 sudo[2704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 02 20:36:42 volumio-x86-784 sudo[2704]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:36:42 volumio-x86-784 systemd[1]: Stopping Music Player Daemon... Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 02 20:36:42 volumio-x86-784 systemd[1]: mpd.service: Succeeded. Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: Output device has changed, restarting MPD Jul 02 20:36:42 volumio-x86-784 systemd[1]: Stopped Music Player Daemon. Jul 02 20:36:42 volumio-x86-784 sudo[2711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 02 20:36:42 volumio-x86-784 sudo[2711]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: Output device has changed, restarting Shairport Sync Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: MPD Permissions set Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:36:42 volumio-x86-784 sudo[2711]: pam_unix(sudo:session): session closed for user root Jul 02 20:36:42 volumio-x86-784 sudo[2714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 02 20:36:42 volumio-x86-784 systemd[1]: Starting Music Player Daemon... Jul 02 20:36:42 volumio-x86-784 sudo[2714]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:36:42 volumio-x86-784 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 02 20:36:42 volumio-x86-784 systemd[1]: mpd.service: Succeeded. Jul 02 20:36:42 volumio-x86-784 systemd[1]: Stopped Music Player Daemon. Jul 02 20:36:42 volumio-x86-784 systemd[1]: Starting Music Player Daemon... Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: Starting Shairport Sync Jul 02 20:36:42 volumio-x86-784 sudo[2723]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 02 20:36:42 volumio-x86-784 sudo[2723]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:36:42 volumio-x86-784 sudo[2723]: pam_unix(sudo:session): session closed for user root Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: Starting Shairport Sync Jul 02 20:36:42 volumio-x86-784 sudo[2734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 02 20:36:42 volumio-x86-784 sudo[2734]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:36:42 volumio-x86-784 sudo[2738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 02 20:36:42 volumio-x86-784 sudo[2738]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:36:42 volumio-x86-784 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 02 20:36:42 volumio-x86-784 systemd[1]: shairport-sync.service: Succeeded. Jul 02 20:36:42 volumio-x86-784 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 02 20:36:42 volumio-x86-784 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 02 20:36:42 volumio-x86-784 sudo[2734]: pam_unix(sudo:session): session closed for user root Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: Shairport-Sync Started Jul 02 20:36:42 volumio-x86-784 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 02 20:36:42 volumio-x86-784 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 02 20:36:42 volumio-x86-784 systemd[1]: shairport-sync.service: Succeeded. Jul 02 20:36:42 volumio-x86-784 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 02 20:36:42 volumio-x86-784 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 02 20:36:42 volumio-x86-784 sudo[2738]: pam_unix(sudo:session): session closed for user root Jul 02 20:36:42 volumio-x86-784 volumio[1185]: info: Shairport-Sync Started Jul 02 20:36:42 volumio-x86-784 mpd[2735]: Jul 02 20:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 02 20:36:42 volumio-x86-784 systemd[1]: Started Music Player Daemon. Jul 02 20:36:42 volumio-x86-784 sudo[2704]: pam_unix(sudo:session): session closed for user root Jul 02 20:36:42 volumio-x86-784 sudo[2714]: pam_unix(sudo:session): session closed for user root Jul 02 20:36:42 volumio-x86-784 volumio[1185]: error: MPD error: The expression evaluated to a falsy value: Jul 02 20:36:42 volumio-x86-784 volumio[1185]: assert.ok(self.idling) Jul 02 20:36:42 volumio-x86-784 volumio[1185]: error: The expression evaluated to a falsy value: Jul 02 20:36:42 volumio-x86-784 volumio[1185]: assert.ok(self.idling) Jul 02 20:36:42 volumio-x86-784 volumio[1185]: error: updateQueue error: null Jul 02 20:36:43 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:36:43 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:36:43 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:36:43 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:36:46 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jul 02 20:36:46 volumio-x86-784 volumio[1185]: info: CoreStateMachine::getcurrentVolume Jul 02 20:36:46 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioRetrievevolume Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Unable to parse: Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Simple mixer control 'Master',0 Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Capabilities: volume cswitch Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Playback channels: Front Left - Front Right Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Capture channels: Front Left - Front Right Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Limits: -50 - 100 Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Front Left: 0 [17%] [-30.00dB] Capture [off] Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Front Right: 0 [17%] [-30.00dB] Capture [off] Jul 02 20:36:46 volumio-x86-784 volumio[1185]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Unable to parse: Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Simple mixer control 'Master',0 Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Capabilities: volume cswitch Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Playback channels: Front Left - Front Right Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Capture channels: Front Left - Front Right Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Limits: -50 - 100 Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Front Left: 0 [17%] [-30.00dB] Capture [off] Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Front Right: 0 [17%] [-30.00dB] Capture [off] Jul 02 20:36:46 volumio-x86-784 volumio[1185]: info: VolumeController:: Volume=undefined Mute =false Jul 02 20:36:46 volumio-x86-784 volumio[1185]: info: CoreStateMachine::pushState Jul 02 20:36:46 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:36:46 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioPushState Jul 02 20:36:46 volumio-x86-784 volumio[1185]: info: CoreStateMachine::updateTrackBlock Jul 02 20:36:46 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrackBlock Jul 02 20:36:46 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioRetrievevolume Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Unable to parse: Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Simple mixer control 'Master',0 Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Capabilities: volume cswitch Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Playback channels: Front Left - Front Right Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Capture channels: Front Left - Front Right Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Limits: -50 - 100 Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Front Left: 0 [17%] [-30.00dB] Capture [off] Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Front Right: 0 [17%] [-30.00dB] Capture [off] Jul 02 20:36:46 volumio-x86-784 volumio[1185]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Unable to parse: Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Simple mixer control 'Master',0 Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Capabilities: volume cswitch Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Playback channels: Front Left - Front Right Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Capture channels: Front Left - Front Right Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Limits: -50 - 100 Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Front Left: 0 [17%] [-30.00dB] Capture [off] Jul 02 20:36:46 volumio-x86-784 volumio[1185]: Front Right: 0 [17%] [-30.00dB] Capture [off] Jul 02 20:36:46 volumio-x86-784 volumio[1185]: info: VolumeController:: Volume=undefined Mute =false Jul 02 20:36:46 volumio-x86-784 volumio[1185]: info: CoreStateMachine::pushState Jul 02 20:36:46 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:36:46 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioPushState Jul 02 20:36:46 volumio-x86-784 volumio[1185]: info: MyVolumio login type: Token Jul 02 20:36:49 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioPlay Jul 02 20:36:49 volumio-x86-784 volumio[1185]: info: CoreStateMachine::play index undefined Jul 02 20:36:49 volumio-x86-784 volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 20:36:49 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:36:49 volumio-x86-784 volumio[1185]: info: CoreStateMachine::startPlaybackTimer Jul 02 20:36:49 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:36:49 volumio-x86-784 volumio[1185]: info: ControllerMpd::resume Jul 02 20:36:49 volumio-x86-784 volumio[1185]: verbose: ControllerMpd::sendMpdCommand play Jul 02 20:36:49 volumio-x86-784 volumio[1185]: info: sendMpdCommand play took 0 milliseconds Jul 02 20:36:50 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioPlay Jul 02 20:36:50 volumio-x86-784 volumio[1185]: info: CoreStateMachine::play index undefined Jul 02 20:36:50 volumio-x86-784 volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 20:36:50 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:36:50 volumio-x86-784 volumio[1185]: info: CoreStateMachine::startPlaybackTimer Jul 02 20:36:50 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:36:50 volumio-x86-784 volumio[1185]: info: ControllerMpd::resume Jul 02 20:36:50 volumio-x86-784 volumio[1185]: verbose: ControllerMpd::sendMpdCommand play Jul 02 20:36:50 volumio-x86-784 volumio[1185]: info: sendMpdCommand play took 0 milliseconds Jul 02 20:36:52 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioGetState Jul 02 20:36:52 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:36:54 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioPlay Jul 02 20:36:54 volumio-x86-784 volumio[1185]: info: CoreStateMachine::play index undefined Jul 02 20:36:54 volumio-x86-784 volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 20:36:54 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:36:54 volumio-x86-784 volumio[1185]: info: CoreStateMachine::startPlaybackTimer Jul 02 20:36:54 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:36:54 volumio-x86-784 volumio[1185]: info: ControllerMpd::resume Jul 02 20:36:54 volumio-x86-784 volumio[1185]: verbose: ControllerMpd::sendMpdCommand play Jul 02 20:36:54 volumio-x86-784 volumio[1185]: info: sendMpdCommand play took 0 milliseconds Jul 02 20:37:03 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioGetState Jul 02 20:37:03 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:37:12 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioGetState Jul 02 20:37:12 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:37:14 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioGetState Jul 02 20:37:14 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:37:15 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 20:37:15 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 02 20:37:15 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 02 20:37:15 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jul 02 20:37:15 volumio-x86-784 volumio[1185]: info: Received Get System Version Jul 02 20:37:15 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 02 20:37:16 volumio-x86-784 volumio[1185]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jul 02 20:37:16 volumio-x86-784 volumio[1185]: info: Retrying Login Due to Network Error Jul 02 20:37:25 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioGetState Jul 02 20:37:25 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:37:26 volumio-x86-784 volumio[1185]: info: MyVolumio login type: Token Jul 02 20:37:36 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioGetState Jul 02 20:37:36 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:37:47 volumio-x86-784 volumio[1185]: info: CoreCommandRouter::volumioGetState Jul 02 20:37:47 volumio-x86-784 volumio[1185]: info: CorePlayQueue::getTrack 2 Jul 02 20:37:47 volumio-x86-784 volumio[1185]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 20:37:48 volumio-x86-784 volumio[1185]: Error: connect ETIMEDOUT 69.63.184.14:443 Jul 02 20:37:48 volumio-x86-784 volumio[1185]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jul 02 20:37:48 volumio-x86-784 volumio[1185]: errno: -110, Jul 02 20:37:48 volumio-x86-784 volumio[1185]: code: 'ETIMEDOUT', Jul 02 20:37:48 volumio-x86-784 volumio[1185]: syscall: 'connect', Jul 02 20:37:48 volumio-x86-784 volumio[1185]: address: '69.63.184.14', Jul 02 20:37:48 volumio-x86-784 volumio[1185]: port: 443 Jul 02 20:37:48 volumio-x86-784 volumio[1185]: } Jul 02 20:37:48 volumio-x86-784 volumio[1185]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 20:37:48 volumio-x86-784 sudo[2955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-02 20:36 Jul 02 20:37:48 volumio-x86-784 sudo[2955]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 10:07:11 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"