-- Logs begin at Wed 2024-05-15 05:42:08 CST, end at Wed 2024-05-15 05:49:24 CST. -- May 15 05:48:00 volumio volumio[2154]: info: MyVolumio login type: Token May 15 05:48:07 volumio volumio[2154]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] May 15 05:48:07 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions May 15 05:48:07 volumio volumio[2154]: info: CoreCommandRouter::volumioGetState May 15 05:48:07 volumio volumio[2154]: info: CorePlayQueue::getTrack 0 May 15 05:48:07 volumio volumio[2154]: info: Disable softmixer device for audio device May 15 05:48:07 volumio volumio[2154]: info: Output device has changed, restarting MPD May 15 05:48:07 volumio sudo[2385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 05:48:07 volumio sudo[2385]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 05:48:07 volumio volumio[2154]: info: Output device has changed, restarting Shairport Sync May 15 05:48:07 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:07 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 05:48:07 volumio sudo[2385]: pam_unix(sudo:session): session closed for user root May 15 05:48:07 volumio sudo[2388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 05:48:07 volumio sudo[2388]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 05:48:08 volumio systemd[1]: Stopping Music Player Daemon... May 15 05:48:08 volumio volumio[2154]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 05:48:08 volumio volumio[2154]: info: Restoring Previous Volume level: 50 false false May 15 05:48:08 volumio volumio[2154]: info: Volume configurations have been set May 15 05:48:08 volumio systemd[1]: mpd.service: Succeeded. May 15 05:48:08 volumio systemd[1]: Stopped Music Player Daemon. May 15 05:48:08 volumio systemd[1]: Starting Music Player Daemon... May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 15 05:48:08 volumio volumio[2154]: info: Updating Volume Controller Parameters: Device: 0,3 Name: HDMI 0 Mixer: IEC958 Max Vol: 50 Vol Curve; logarithmic Vol Steps: 1 May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 15 05:48:08 volumio volumio[2154]: info: Disabling external Volume Control May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 05:48:08 volumio volumio[2154]: info: Soft Volume ALSA configuration file deleted May 15 05:48:08 volumio volumio[2154]: info: Preparing to generate the ALSA configuration file May 15 05:48:08 volumio volumio[2154]: info: MPD Permissions set May 15 05:48:08 volumio volumio[2154]: Unable to parse: May 15 05:48:08 volumio volumio[2154]: Simple mixer control 'IEC958',0 May 15 05:48:08 volumio volumio[2154]: Capabilities: pswitch pswitch-joined May 15 05:48:08 volumio volumio[2154]: Playback channels: Mono May 15 05:48:08 volumio volumio[2154]: Mono: Playback [on] May 15 05:48:08 volumio volumio[2154]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 15 05:48:08 volumio volumio[2154]: Unable to parse: May 15 05:48:08 volumio volumio[2154]: Simple mixer control 'IEC958',0 May 15 05:48:08 volumio volumio[2154]: Capabilities: pswitch pswitch-joined May 15 05:48:08 volumio volumio[2154]: Playback channels: Mono May 15 05:48:08 volumio volumio[2154]: Mono: Playback [on] May 15 05:48:08 volumio volumio[2154]: info: VolumeController:: Volume=undefined Mute =false May 15 05:48:08 volumio volumio[2154]: info: CoreStateMachine::pushState May 15 05:48:08 volumio volumio[2154]: info: CorePlayQueue::getTrack 0 May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::volumioPushState May 15 05:48:08 volumio volumio[2154]: info: Starting Shairport Sync May 15 05:48:08 volumio volumio[2154]: info: Asound.conf file written May 15 05:48:08 volumio sudo[2411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 15 05:48:08 volumio sudo[2411]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 05:48:08 volumio sudo[2411]: pam_unix(sudo:session): session closed for user root May 15 05:48:08 volumio sudo[2409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 05:48:08 volumio sudo[2409]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 05:48:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 15 05:48:08 volumio systemd[1]: shairport-sync.service: Succeeded. May 15 05:48:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 15 05:48:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 15 05:48:08 volumio sudo[2409]: pam_unix(sudo:session): session closed for user root May 15 05:48:08 volumio volumio[2154]: Found hardware: "HDA-Intel" "ATI R6xx HDMI" "HDA:1002aa01,00aa0100,00100500" "0x103c" "0x2187" May 15 05:48:08 volumio volumio[2154]: Hardware is initialized using a generic method May 15 05:48:08 volumio volumio[2154]: info: Output device has changed, restarting MPD May 15 05:48:08 volumio sudo[2418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 05:48:08 volumio sudo[2418]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 05:48:08 volumio sudo[2418]: pam_unix(sudo:session): session closed for user root May 15 05:48:08 volumio volumio[2154]: info: Output device has changed, restarting Shairport Sync May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 05:48:08 volumio sudo[2423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 05:48:08 volumio sudo[2423]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 05:48:08 volumio volumio[2154]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 05:48:08 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 15 05:48:08 volumio systemd[1]: mpd.service: Succeeded. May 15 05:48:08 volumio systemd[1]: Stopped Music Player Daemon. May 15 05:48:08 volumio volumio[2154]: info: MPD Permissions set May 15 05:48:08 volumio volumio[2154]: info: Shairport-Sync Started May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:08 volumio systemd[1]: Starting Music Player Daemon... May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 15 05:48:08 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:08 volumio volumio[2154]: info: Starting Shairport Sync May 15 05:48:08 volumio sudo[2436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 05:48:08 volumio sudo[2436]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 05:48:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 15 05:48:08 volumio systemd[1]: shairport-sync.service: Succeeded. May 15 05:48:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 15 05:48:09 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 15 05:48:09 volumio sudo[2436]: pam_unix(sudo:session): session closed for user root May 15 05:48:09 volumio volumio[2154]: info: Shairport-Sync Started May 15 05:48:09 volumio mpd[2428]: May 15 05:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 15 05:48:10 volumio systemd[1]: Started Music Player Daemon. May 15 05:48:10 volumio sudo[2388]: pam_unix(sudo:session): session closed for user root May 15 05:48:10 volumio sudo[2423]: pam_unix(sudo:session): session closed for user root May 15 05:48:10 volumio volumio[2154]: error: MPD error: The expression evaluated to a falsy value: May 15 05:48:10 volumio volumio[2154]: assert.ok(self.idling) May 15 05:48:10 volumio volumio[2154]: error: The expression evaluated to a falsy value: May 15 05:48:10 volumio volumio[2154]: assert.ok(self.idling) May 15 05:48:10 volumio volumio[2154]: error: updateQueue error: null May 15 05:48:12 volumio volumio[2154]: info: VolumeController::SetAlsaVolume50 May 15 05:48:12 volumio volumio[2154]: info: CoreStateMachine::pushState May 15 05:48:12 volumio volumio[2154]: info: CorePlayQueue::getTrack 0 May 15 05:48:12 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 05:48:12 volumio volumio[2154]: info: CoreCommandRouter::volumioPushState May 15 05:48:24 volumio volumio[2154]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] May 15 05:48:24 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions May 15 05:48:24 volumio volumio[2154]: info: CoreCommandRouter::volumioGetState May 15 05:48:24 volumio volumio[2154]: info: CorePlayQueue::getTrack 0 May 15 05:48:24 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions May 15 05:48:24 volumio sudo[2489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 05:48:24 volumio sudo[2489]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 05:48:24 volumio volumio[2154]: info: Restoring Previous Volume level: 50 false false May 15 05:48:24 volumio volumio[2154]: info: Volume configurations have been set May 15 05:48:24 volumio sudo[2489]: pam_unix(sudo:session): session closed for user root May 15 05:48:24 volumio sudo[2492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 05:48:24 volumio sudo[2492]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 05:48:24 volumio systemd[1]: Stopping Music Player Daemon... May 15 05:48:24 volumio systemd[1]: mpd.service: Succeeded. May 15 05:48:24 volumio systemd[1]: Stopped Music Player Daemon. May 15 05:48:24 volumio volumio[2154]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 15 05:48:24 volumio volumio[2154]: info: Updating Volume Controller Parameters: Device: 0,3 Name: HDMI 0 Mixer: IEC958 Max Vol: 50 Vol Curve; logarithmic Vol Steps: 1 May 15 05:48:24 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 15 05:48:24 volumio volumio[2154]: info: Disabling external Volume Control May 15 05:48:24 volumio systemd[1]: Starting Music Player Daemon... May 15 05:48:24 volumio volumio[2154]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 05:48:24 volumio volumio[2154]: info: MPD Permissions set May 15 05:48:24 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:24 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:24 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:24 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:24 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 05:48:24 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 15 05:48:24 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:24 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 15 05:48:24 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 05:48:24 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 15 05:48:24 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:48:24 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:48:24 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 15 05:48:24 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 15 05:48:24 volumio volumio[2154]: Unable to parse: May 15 05:48:24 volumio volumio[2154]: Simple mixer control 'IEC958',0 May 15 05:48:24 volumio volumio[2154]: Capabilities: pswitch pswitch-joined May 15 05:48:24 volumio volumio[2154]: Playback channels: Mono May 15 05:48:24 volumio volumio[2154]: Mono: Playback [on] May 15 05:48:24 volumio volumio[2154]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 15 05:48:24 volumio volumio[2154]: Unable to parse: May 15 05:48:24 volumio volumio[2154]: Simple mixer control 'IEC958',0 May 15 05:48:24 volumio volumio[2154]: Capabilities: pswitch pswitch-joined May 15 05:48:24 volumio volumio[2154]: Playback channels: Mono May 15 05:48:24 volumio volumio[2154]: Mono: Playback [on] May 15 05:48:24 volumio volumio[2154]: info: VolumeController:: Volume=undefined Mute =false May 15 05:48:24 volumio volumio[2154]: info: CoreStateMachine::pushState May 15 05:48:24 volumio volumio[2154]: info: CorePlayQueue::getTrack 0 May 15 05:48:24 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 05:48:24 volumio volumio[2154]: info: CoreCommandRouter::volumioPushState May 15 05:48:25 volumio mpd[2496]: May 15 05:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 15 05:48:25 volumio systemd[1]: Started Music Player Daemon. May 15 05:48:25 volumio sudo[2492]: pam_unix(sudo:session): session closed for user root May 15 05:48:25 volumio volumio[2154]: error: updateQueue error: null May 15 05:48:28 volumio volumio[2154]: info: VolumeController::SetAlsaVolume50 May 15 05:48:28 volumio volumio[2154]: info: CoreStateMachine::pushState May 15 05:48:28 volumio volumio[2154]: info: CorePlayQueue::getTrack 0 May 15 05:48:28 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 05:48:28 volumio volumio[2154]: info: CoreCommandRouter::volumioPushState May 15 05:48:31 volumio volumio[2154]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 15 05:48:31 volumio volumio[2154]: info: Retrying Login Due to Network Error May 15 05:48:31 volumio volumio[2154]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] May 15 05:48:31 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts May 15 05:48:31 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions May 15 05:48:31 volumio sudo[2526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 05:48:31 volumio sudo[2526]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 05:48:31 volumio sudo[2526]: pam_unix(sudo:session): session closed for user root May 15 05:48:31 volumio volumio[2154]: info: MPD Permissions set May 15 05:48:31 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:31 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:31 volumio sudo[2529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 05:48:31 volumio sudo[2529]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 05:48:31 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:31 volumio systemd[1]: Stopping Music Player Daemon... May 15 05:48:31 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 05:48:31 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 15 05:48:32 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:48:32 volumio systemd[1]: mpd.service: Succeeded. May 15 05:48:32 volumio systemd[1]: Stopped Music Player Daemon. May 15 05:48:32 volumio systemd[1]: Starting Music Player Daemon... May 15 05:48:32 volumio mpd[2537]: May 15 05:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 15 05:48:33 volumio systemd[1]: Started Music Player Daemon. May 15 05:48:33 volumio sudo[2529]: pam_unix(sudo:session): session closed for user root May 15 05:48:33 volumio volumio[2154]: error: updateQueue error: null May 15 05:48:41 volumio volumio[2154]: info: MyVolumio login type: Token May 15 05:48:42 volumio volumio[2154]: info: CoreCommandRouter::volumioGetState May 15 05:48:42 volumio volumio[2154]: info: CorePlayQueue::getTrack 0 May 15 05:48:46 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 15 05:48:46 volumio volumio[2154]: info: CURURI: albums:// May 15 05:48:46 volumio volumio[2154]: info: listAlbums - loading Albums from cache May 15 05:48:46 volumio volumio[2154]: info: Preload queue cleared May 15 05:49:07 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 15 05:49:07 volumio volumio[2154]: info: CURURI: albums:///STEVIERA May 15 05:49:07 volumio volumio[2154]: info: Preload queue cleared May 15 05:49:11 volumio volumio[2154]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 15 05:49:11 volumio volumio[2154]: info: Retrying Login Due to Network Error May 15 05:49:15 volumio volumio[2154]: info: Preload queue cleared May 15 05:49:15 volumio volumio[2154]: info: CoreCommandRouter::volumioReplaceandPlayItems May 15 05:49:15 volumio volumio[2154]: info: CoreStateMachine::ClearQueue May 15 05:49:15 volumio volumio[2154]: info: CoreStateMachine::stop May 15 05:49:15 volumio volumio[2154]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 05:49:15 volumio volumio[2154]: info: CoreStateMachine::stPlaybackTimer May 15 05:49:15 volumio volumio[2154]: info: CoreStateMachine::updateTrackBlock May 15 05:49:15 volumio volumio[2154]: info: CorePlayQueue::getTrackBlock May 15 05:49:15 volumio volumio[2154]: info: CoreStateMachine::pushState May 15 05:49:15 volumio volumio[2154]: info: CorePlayQueue::getTrack 0 May 15 05:49:15 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 05:49:15 volumio volumio[2154]: info: CoreCommandRouter::volumioPushState May 15 05:49:15 volumio volumio[2154]: info: CoreStateMachine::serviceStop May 15 05:49:15 volumio volumio[2154]: info: CorePlayQueue::getTrack 0 May 15 05:49:15 volumio volumio[2154]: info: CoreCommandRouter::serviceStop May 15 05:49:15 volumio volumio[2154]: info: ControllerMpd::stop May 15 05:49:15 volumio volumio[2154]: verbose: ControllerMpd::sendMpdCommand stop May 15 05:49:15 volumio volumio[2154]: info: CorePlayQueue::clearPlayQueue May 15 05:49:15 volumio volumio[2154]: info: CorePlayQueue::saveQueue May 15 05:49:15 volumio volumio[2154]: info: CoreCommandRouter::volumioPushQueue May 15 05:49:15 volumio volumio[2154]: info: CoreStateMachine::addQueueItems May 15 05:49:15 volumio volumio[2154]: info: CorePlayQueue::addQueueItems May 15 05:49:15 volumio volumio[2154]: info: Preload queue cleared May 15 05:49:15 volumio volumio[2154]: info: Adding Item to queue: albums:///STEVIERA May 15 05:49:15 volumio volumio[2154]: info: Exploding uri albums:///STEVIERA in service mpd May 15 05:49:15 volumio volumio[2154]: info: sendMpdCommand stop took 31 milliseconds May 15 05:49:15 volumio volumio[2154]: info: CoreCommandRouter::volumioPushQueue May 15 05:49:15 volumio volumio[2154]: info: CorePlayQueue::saveQueue May 15 05:49:15 volumio volumio[2154]: info: CoreStateMachine::updateTrackBlock May 15 05:49:15 volumio volumio[2154]: info: CorePlayQueue::getTrackBlock May 15 05:49:15 volumio volumio[2154]: info: CoreCommandRouter::volumioPlay May 15 05:49:15 volumio volumio[2154]: info: CoreStateMachine::play index 0 May 15 05:49:15 volumio volumio[2154]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 05:49:15 volumio volumio[2154]: info: CoreStateMachine::stop May 15 05:49:15 volumio volumio[2154]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 05:49:15 volumio volumio[2154]: info: CoreStateMachine::play index undefined May 15 05:49:15 volumio volumio[2154]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 05:49:15 volumio volumio[2154]: info: CorePlayQueue::getTrack 0 May 15 05:49:15 volumio volumio[2154]: info: CoreStateMachine::startPlaybackTimer May 15 05:49:15 volumio volumio[2154]: info: CorePlayQueue::getTrack 0 May 15 05:49:15 volumio volumio[2154]: verbose: ControllerMpd::clearAddPlayTracks NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 01.wav May 15 05:49:15 volumio volumio[2154]: verbose: ControllerMpd::sendMpdCommand stop May 15 05:49:15 volumio volumio[2154]: info: sendMpdCommand stop took 2 milliseconds May 15 05:49:15 volumio volumio[2154]: verbose: ControllerMpd::sendMpdCommand clear May 15 05:49:15 volumio volumio[2154]: info: May 15 05:49:15 volumio volumio[2154]: ---------------------------- MPD announces system playlist update May 15 05:49:15 volumio volumio[2154]: info: Ignoring MPD Status Update May 15 05:49:15 volumio volumio[2154]: info: sendMpdCommand clear took 13 milliseconds May 15 05:49:15 volumio volumio[2154]: verbose: ControllerMpd::sendMpdCommand add "NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 01.wav" May 15 05:49:15 volumio volumio[2154]: error: updateQueue error: null May 15 05:49:15 volumio volumio[2154]: info: May 15 05:49:15 volumio volumio[2154]: ---------------------------- MPD announces system playlist update May 15 05:49:15 volumio volumio[2154]: info: Ignoring MPD Status Update May 15 05:49:15 volumio volumio[2154]: info: ------------------------------ 14ms May 15 05:49:15 volumio volumio[2154]: info: sendMpdCommand add "NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 01.wav" took 12 milliseconds May 15 05:49:15 volumio volumio[2154]: verbose: ControllerMpd::sendMpdCommand play May 15 05:49:15 volumio volumio[2154]: info: ------------------------------ 15ms May 15 05:49:15 volumio volumio[2154]: info: sendMpdCommand play took 9 milliseconds May 15 05:49:15 volumio volumio[2154]: info: May 15 05:49:15 volumio volumio[2154]: ---------------------------- MPD announces state update: player May 15 05:49:15 volumio volumio[2154]: info: ControllerMpd::getState May 15 05:49:15 volumio volumio[2154]: verbose: ControllerMpd::sendMpdCommand status May 15 05:49:15 volumio volumio[2154]: info: May 15 05:49:15 volumio volumio[2154]: ---------------------------- MPD announces state update: player May 15 05:49:15 volumio volumio[2154]: info: sendMpdCommand status took 26 milliseconds May 15 05:49:15 volumio volumio[2154]: info: ControllerMpd::getState May 15 05:49:15 volumio volumio[2154]: verbose: ControllerMpd::sendMpdCommand status May 15 05:49:15 volumio volumio[2154]: verbose: ControllerMpd::parseState May 15 05:49:15 volumio volumio[2154]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 05:49:15 volumio volumio[2154]: info: sendMpdCommand status took 11 milliseconds May 15 05:49:15 volumio volumio[2154]: info: sendMpdCommand playlistinfo took 7 milliseconds May 15 05:49:15 volumio volumio[2154]: verbose: ControllerMpd::parseState May 15 05:49:15 volumio volumio[2154]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 05:49:15 volumio volumio[2154]: verbose: ControllerMpd::parseTrackInfo May 15 05:49:15 volumio volumio[2154]: info: ControllerMpd::pushState May 15 05:49:15 volumio volumio[2154]: info: CoreCommandRouter::servicePushState May 15 05:49:15 volumio volumio[2154]: info: CorePlayQueue::getTrack 0 May 15 05:49:15 volumio volumio[2154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":540,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chapter 01","artist":null,"album":"STEVIERA","uri":"NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 01.wav","trackType":"wav"} May 15 05:49:15 volumio volumio[2154]: verbose: CURRENT POSITION 0 May 15 05:49:15 volumio volumio[2154]: info: CoreStateMachine::syncState stateService play May 15 05:49:15 volumio volumio[2154]: info: CoreStateMachine::syncState currentStatus stop May 15 05:49:15 volumio volumio[2154]: info: ------------------------------ 56ms May 15 05:49:15 volumio volumio[2154]: info: sendMpdCommand playlistinfo took 12 milliseconds May 15 05:49:15 volumio volumio[2154]: verbose: ControllerMpd::parseTrackInfo May 15 05:49:15 volumio volumio[2154]: info: ControllerMpd::pushState May 15 05:49:15 volumio volumio[2154]: info: CoreCommandRouter::servicePushState May 15 05:49:15 volumio volumio[2154]: info: CorePlayQueue::getTrack 0 May 15 05:49:15 volumio volumio[2154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":993,"duration":540,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chapter 01","artist":null,"album":"STEVIERA","uri":"NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 01.wav","trackType":"wav"} May 15 05:49:15 volumio volumio[2154]: verbose: CURRENT POSITION 0 May 15 05:49:15 volumio volumio[2154]: info: CoreStateMachine::syncState stateService play May 15 05:49:15 volumio volumio[2154]: info: CoreStateMachine::syncState currentStatus play May 15 05:49:15 volumio volumio[2154]: info: Received an update from plugin. extracting info from payload May 15 05:49:15 volumio volumio[2154]: info: CoreStateMachine::pushState May 15 05:49:15 volumio volumio[2154]: info: CorePlayQueue::getTrack 0 May 15 05:49:15 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 05:49:15 volumio volumio[2154]: info: CoreCommandRouter::volumioPushState May 15 05:49:15 volumio volumio[2154]: info: CoreStateMachine::pushState May 15 05:49:15 volumio volumio[2154]: info: CorePlayQueue::getTrack 0 May 15 05:49:15 volumio volumio[2154]: info: CoreCommandRouter::volumioPushState May 15 05:49:15 volumio volumio[2154]: info: ------------------------------ 64ms May 15 05:49:20 volumio volumio[2154]: info: VolumeController::SetAlsaVolume22 May 15 05:49:20 volumio volumio[2154]: info: CoreStateMachine::pushState May 15 05:49:20 volumio volumio[2154]: info: CorePlayQueue::getTrack 0 May 15 05:49:20 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 05:49:20 volumio volumio[2154]: info: CoreCommandRouter::volumioPushState May 15 05:49:21 volumio volumio[2154]: info: MyVolumio login type: Token May 15 05:49:22 volumio volumio[2154]: info: VolumeController::SetAlsaVolume11 May 15 05:49:23 volumio volumio[2154]: info: CoreStateMachine::pushState May 15 05:49:23 volumio volumio[2154]: info: CorePlayQueue::getTrack 0 May 15 05:49:23 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 05:49:23 volumio volumio[2154]: info: CoreCommandRouter::volumioPushState May 15 05:49:24 volumio volumio[2154]: info: VolumeController::SetAlsaVolume1 May 15 05:49:24 volumio volumio[2154]: info: CoreStateMachine::pushState May 15 05:49:24 volumio volumio[2154]: info: CorePlayQueue::getTrack 0 May 15 05:49:24 volumio volumio[2154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 05:49:24 volumio volumio[2154]: info: CoreCommandRouter::volumioPushState May 15 05:49:24 volumio volumio[2154]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 05:49:24 volumio volumio[2154]: Error: connect ETIMEDOUT 198.44.185.131:443 May 15 05:49:24 volumio volumio[2154]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 15 05:49:24 volumio volumio[2154]: errno: -110, May 15 05:49:24 volumio volumio[2154]: code: 'ETIMEDOUT', May 15 05:49:24 volumio volumio[2154]: syscall: 'connect', May 15 05:49:24 volumio volumio[2154]: address: '198.44.185.131', May 15 05:49:24 volumio volumio[2154]: port: 443 May 15 05:49:24 volumio volumio[2154]: } May 15 05:49:24 volumio volumio[2154]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 05:49:24 volumio sudo[2604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-15 05:48 May 15 05:49:24 volumio sudo[2604]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"