-- Logs begin at Sat 2024-10-19 17:42:48 CST, end at Sat 2024-10-19 17:57:48 CST. -- Oct 19 17:56:30 volumio volumio[995]: info: Clearing queue after UPNP request Oct 19 17:56:30 volumio volumio[995]: info: CoreStateMachine::ClearQueue Oct 19 17:56:30 volumio volumio[995]: info: CoreStateMachine::stop Oct 19 17:56:30 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 17:56:30 volumio volumio[995]: info: CorePlayQueue::clearPlayQueue Oct 19 17:56:30 volumio volumio[995]: info: CorePlayQueue::saveQueue Oct 19 17:56:30 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:56:30 volumio volumio[995]: info: CoreCommandRouter::volumioPushQueue Oct 19 17:56:30 volumio volumio[995]: info: Oct 19 17:56:30 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:56:30 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:56:30 volumio volumio[995]: error: updateQueue error: null Oct 19 17:56:30 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:56:30 volumio volumio[995]: info: Starting UPNP Playback Oct 19 17:56:30 volumio volumio[995]: info: Preparing playback through UPNP Oct 19 17:56:30 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Oct 19 17:56:30 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Oct 19 17:56:30 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 19 17:56:31 volumio volumio[995]: info: Oct 19 17:56:31 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:56:31 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:56:31 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:56:31 volumio volumio[995]: info: Oct 19 17:56:31 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:56:31 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:56:31 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:56:31 volumio volumio[995]: info: Oct 19 17:56:31 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:56:31 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:56:31 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:56:31 volumio volumio[995]: info: Oct 19 17:56:31 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:56:31 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:56:31 volumio volumio[995]: info: Oct 19 17:56:31 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:56:31 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:56:31 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:56:31 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:56:31 volumio volumio[995]: info: Oct 19 17:56:31 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:56:31 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:56:31 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:56:31 volumio volumio[995]: info: Oct 19 17:56:31 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:56:31 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:56:31 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:56:32 volumio volumio[995]: info: Oct 19 17:56:32 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:56:32 volumio volumio[995]: info: sendMpdCommand status took 16 milliseconds Oct 19 17:56:32 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:56:32 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:56:32 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:56:32 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 17:56:32 volumio volumio[995]: info: sendMpdCommand status took 1 milliseconds Oct 19 17:56:32 volumio volumio[995]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 19 17:56:32 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:56:32 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 17:56:32 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Oct 19 17:56:32 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:56:32 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:56:32 volumio volumio[995]: verbose: In UPNP mode Oct 19 17:56:32 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2863,"samplerate":"11.025 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"一千零一个愿望","artist":"4 In Love","album":"谁怕谁","uri":"http://m801.music.126.net/20241019182130/7e2e6d748df710ea9ea9c7510e1b69ca/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/30778643186/9844/f8c3/021a/18a3ff7b4b5cf59302c0c2bed90d9871.flac","trackType":"flac"} Oct 19 17:56:32 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:56:32 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Oct 19 17:56:32 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus stop Oct 19 17:56:32 volumio volumio[995]: info: ------------------------------ 17ms Oct 19 17:56:32 volumio volumio[995]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 19 17:56:32 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Oct 19 17:56:32 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:56:32 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:56:32 volumio volumio[995]: verbose: In UPNP mode Oct 19 17:56:32 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2863,"samplerate":"11.025 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"一千零一个愿望","artist":"4 In Love","album":"谁怕谁","uri":"http://m801.music.126.net/20241019182130/7e2e6d748df710ea9ea9c7510e1b69ca/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/30778643186/9844/f8c3/021a/18a3ff7b4b5cf59302c0c2bed90d9871.flac","trackType":"flac"} Oct 19 17:56:32 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:56:32 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Oct 19 17:56:32 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Oct 19 17:56:32 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Oct 19 17:56:32 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 17:56:32 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:56:32 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 17:56:32 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:56:32 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:56:32 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:56:32 volumio volumio[995]: info: ------------------------------ 5ms Oct 19 17:56:32 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:56:32 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:56:32 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:56:32 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:56:32 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:56:32 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:56:37 volumio volumio[995]: info: Oct 19 17:56:37 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:56:37 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:56:37 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:56:37 volumio volumio[995]: info: sendMpdCommand status took 0 milliseconds Oct 19 17:56:37 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:56:37 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:56:37 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:56:37 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:56:37 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:56:37 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Oct 19 17:56:37 volumio volumio[995]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 19 17:56:37 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:56:37 volumio volumio[995]: info: CoreStateMachine::syncState stateService stop Oct 19 17:56:37 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Oct 19 17:56:37 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:56:37 volumio volumio[995]: info: CoreStateMachine::stPlaybackTimer Oct 19 17:56:37 volumio volumio[995]: info: ------------------------------ 4ms Oct 19 17:56:37 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:56:37 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:56:37 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:56:53 volumio volumio[995]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 19 17:56:53 volumio volumio[995]: info: Preparing to save Alsa Options, stopping services first Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::volumioPause Oct 19 17:56:53 volumio volumio[995]: info: CoreStateMachine::pause Oct 19 17:56:53 volumio volumio[995]: info: Saving Audio Output to: {"output_device":{"value":"1,0","label":"Analog Out"}} Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 19 17:56:53 volumio volumio[995]: info: Found match in Cards Database: setting mixer for card Analog Out Oct 19 17:56:53 volumio volumio[995]: info: Setting mixer Master for card Analog Out Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 19 17:56:53 volumio volumio[995]: info: Updating Volume Controller Parameters: Device: 1,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 19 17:56:53 volumio volumio[995]: info: Disabling external Volume Control Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 17:56:53 volumio volumio[995]: info: Preparing to generate the ALSA configuration file Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 19 17:56:53 volumio volumio[995]: info: Asound.conf file written Oct 19 17:56:53 volumio sudo[3668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 19 17:56:53 volumio sudo[3668]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 17:56:53 volumio sudo[3668]: pam_unix(sudo:session): session closed for user root Oct 19 17:56:53 volumio volumio[995]: No state is present for card HDMI Oct 19 17:56:53 volumio volumio[995]: Found hardware: "HDA-Intel" "Intel Haswell HDMI" "HDA:80862807,80860101,00100000" "0x17aa" "0x309e" Oct 19 17:56:53 volumio volumio[995]: Hardware is initialized using a generic method Oct 19 17:56:53 volumio volumio[995]: No state is present for card HDMI Oct 19 17:56:53 volumio volumio[995]: No state is present for card PCH Oct 19 17:56:53 volumio volumio[995]: Found hardware: "HDA-Intel" "Realtek ALC3239" "HDA:10ec0283,17aa309e,00100003" "0x17aa" "0x309e" Oct 19 17:56:53 volumio volumio[995]: Hardware is initialized using a generic method Oct 19 17:56:53 volumio volumio[995]: No state is present for card PCH Oct 19 17:56:53 volumio volumio[995]: info: Output device has changed, restarting MPD Oct 19 17:56:53 volumio sudo[3673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 19 17:56:53 volumio sudo[3673]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 17:56:53 volumio volumio[995]: info: Output device has changed, restarting Shairport Sync Oct 19 17:56:53 volumio sudo[3673]: pam_unix(sudo:session): session closed for user root Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 17:56:53 volumio sudo[3676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 19 17:56:53 volumio sudo[3676]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 17:56:53 volumio systemd[1]: Stopping Music Player Daemon... Oct 19 17:56:53 volumio volumio[995]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 19 17:56:53 volumio volumio[995]: info: Output device has changed, restarting MPD Oct 19 17:56:53 volumio systemd[1]: mpd.service: Succeeded. Oct 19 17:56:53 volumio systemd[1]: Stopped Music Player Daemon. Oct 19 17:56:53 volumio sudo[3683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 19 17:56:53 volumio sudo[3683]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 17:56:53 volumio volumio[995]: info: Output device has changed, restarting Shairport Sync Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 17:56:53 volumio sudo[3683]: pam_unix(sudo:session): session closed for user root Oct 19 17:56:53 volumio systemd[1]: Starting Music Player Daemon... Oct 19 17:56:53 volumio sudo[3686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 19 17:56:53 volumio sudo[3686]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 17:56:53 volumio volumio[995]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 19 17:56:53 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 19 17:56:53 volumio systemd[1]: mpd.service: Succeeded. Oct 19 17:56:53 volumio systemd[1]: Stopped Music Player Daemon. Oct 19 17:56:53 volumio volumio[995]: info: MPD Permissions set Oct 19 17:56:53 volumio volumio[995]: info: MPD Permissions set Oct 19 17:56:53 volumio volumio[995]: info: VolumeController:: Volume=41 Mute =false Oct 19 17:56:53 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:56:53 volumio systemd[1]: Starting Music Player Daemon... Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 19 17:56:53 volumio sudo[3691]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 19 17:56:53 volumio sudo[3691]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 17:56:53 volumio sudo[3691]: pam_unix(sudo:session): session closed for user root Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 19 17:56:53 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 17:56:53 volumio volumio[995]: info: Starting Shairport Sync Oct 19 17:56:53 volumio volumio[995]: info: Starting Shairport Sync Oct 19 17:56:53 volumio sudo[3708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 19 17:56:53 volumio sudo[3708]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 17:56:53 volumio sudo[3710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 19 17:56:53 volumio sudo[3710]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 17:56:53 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 19 17:56:53 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 19 17:56:53 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 19 17:56:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 19 17:56:53 volumio sudo[3708]: pam_unix(sudo:session): session closed for user root Oct 19 17:56:53 volumio volumio[995]: info: Shairport-Sync Started Oct 19 17:56:53 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 19 17:56:53 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 19 17:56:53 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 19 17:56:53 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 19 17:56:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 19 17:56:53 volumio sudo[3710]: pam_unix(sudo:session): session closed for user root Oct 19 17:56:53 volumio volumio[995]: info: Shairport-Sync Started Oct 19 17:56:53 volumio mpd[3701]: Oct 19 17:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 19 17:56:53 volumio systemd[1]: Started Music Player Daemon. Oct 19 17:56:53 volumio sudo[3686]: pam_unix(sudo:session): session closed for user root Oct 19 17:56:53 volumio sudo[3676]: pam_unix(sudo:session): session closed for user root Oct 19 17:56:53 volumio volumio[995]: error: MPD error: The expression evaluated to a falsy value: Oct 19 17:56:53 volumio volumio[995]: assert.ok(self.idling) Oct 19 17:56:53 volumio volumio[995]: error: The expression evaluated to a falsy value: Oct 19 17:56:53 volumio volumio[995]: assert.ok(self.idling) Oct 19 17:56:53 volumio volumio[995]: error: updateQueue error: null Oct 19 17:56:56 volumio volumio[995]: info: Clearing queue after UPNP request Oct 19 17:56:56 volumio volumio[995]: info: CoreStateMachine::ClearQueue Oct 19 17:56:56 volumio volumio[995]: info: CoreStateMachine::stop Oct 19 17:56:56 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 17:56:56 volumio volumio[995]: info: CorePlayQueue::clearPlayQueue Oct 19 17:56:56 volumio volumio[995]: info: CorePlayQueue::saveQueue Oct 19 17:56:56 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:56:56 volumio volumio[995]: info: CoreCommandRouter::volumioPushQueue Oct 19 17:56:56 volumio volumio[995]: error: Upnp client error: Error: This socket has been ended by the other party Oct 19 17:56:57 volumio volumio[995]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 19 17:56:57 volumio volumio[995]: info: CoreStateMachine::getcurrentVolume Oct 19 17:56:57 volumio volumio[995]: info: CoreCommandRouter::volumioRetrievevolume Oct 19 17:56:57 volumio volumio[995]: info: VolumeController:: Volume=41 Mute =false Oct 19 17:56:57 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:56:57 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Oct 19 17:56:57 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 17:56:57 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:56:57 volumio volumio[995]: info: CoreStateMachine::updateTrackBlock Oct 19 17:56:57 volumio volumio[995]: info: CorePlayQueue::getTrackBlock Oct 19 17:56:57 volumio volumio[995]: info: CoreCommandRouter::volumioRetrievevolume Oct 19 17:56:57 volumio volumio[995]: info: VolumeController:: Volume=41 Mute =false Oct 19 17:56:57 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:56:57 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Oct 19 17:56:57 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:56:58 volumio volumio[995]: info: Starting UPNP Playback Oct 19 17:56:58 volumio volumio[995]: info: Preparing playback through UPNP Oct 19 17:56:58 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Oct 19 17:56:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Oct 19 17:56:58 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 19 17:56:58 volumio volumio[995]: info: Oct 19 17:56:58 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:56:58 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:56:58 volumio volumio[995]: info: Oct 19 17:56:58 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:56:58 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:56:58 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:56:58 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:56:58 volumio volumio[995]: info: Oct 19 17:56:58 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:56:58 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:56:58 volumio volumio[995]: info: Oct 19 17:56:58 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:56:58 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:56:58 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:56:58 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:56:58 volumio volumio[995]: info: Oct 19 17:56:58 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:56:58 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:56:58 volumio volumio[995]: info: Oct 19 17:56:58 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:56:58 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:56:58 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:56:58 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:56:58 volumio volumio[995]: info: Oct 19 17:56:58 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:56:58 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:56:58 volumio volumio[995]: info: Oct 19 17:56:58 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:56:58 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:56:58 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:56:58 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:56:58 volumio volumio[995]: info: Oct 19 17:56:58 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:56:58 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:56:58 volumio volumio[995]: info: Oct 19 17:56:58 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:56:58 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:56:58 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:56:58 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:56:58 volumio volumio[995]: info: Oct 19 17:56:58 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:56:58 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:56:58 volumio volumio[995]: info: Oct 19 17:56:58 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:56:58 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:56:58 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:56:58 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:56:58 volumio volumio[995]: info: Oct 19 17:56:58 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:56:58 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:56:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:56:58 volumio volumio[995]: info: Oct 19 17:56:58 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:56:58 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:56:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:56:58 volumio volumio[995]: info: sendMpdCommand status took 2 milliseconds Oct 19 17:56:58 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:56:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 17:56:58 volumio volumio[995]: info: Oct 19 17:56:58 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:56:58 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:56:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:56:58 volumio volumio[995]: info: sendMpdCommand status took 2 milliseconds Oct 19 17:56:58 volumio volumio[995]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 19 17:56:58 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:56:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 17:56:58 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Oct 19 17:56:58 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:56:58 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:56:58 volumio volumio[995]: verbose: In UPNP mode Oct 19 17:56:58 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1248,"samplerate":"8 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"我的未来式","artist":"郭采洁","album":"隐形超人","uri":"http://m801.music.126.net/20241019182156/5cec9af1fce6d8088eb98450c8389a5f/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/17289658266/5317/1435/e715/ce374a68ee97be2d60745d3b204942d7.flac","trackType":"flac"} Oct 19 17:56:58 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:56:58 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Oct 19 17:56:58 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus stop Oct 19 17:56:58 volumio volumio[995]: info: ------------------------------ 4ms Oct 19 17:56:58 volumio volumio[995]: info: Oct 19 17:56:58 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:56:58 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:56:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:56:58 volumio volumio[995]: info: sendMpdCommand status took 1 milliseconds Oct 19 17:56:58 volumio volumio[995]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 19 17:56:58 volumio volumio[995]: info: sendMpdCommand status took 0 milliseconds Oct 19 17:56:58 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:56:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 17:56:58 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Oct 19 17:56:58 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:56:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 17:56:58 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:56:58 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:56:58 volumio volumio[995]: verbose: In UPNP mode Oct 19 17:56:58 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1248,"samplerate":"8 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"我的未来式","artist":"郭采洁","album":"隐形超人","uri":"http://m801.music.126.net/20241019182156/5cec9af1fce6d8088eb98450c8389a5f/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/17289658266/5317/1435/e715/ce374a68ee97be2d60745d3b204942d7.flac","trackType":"flac"} Oct 19 17:56:58 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:56:58 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Oct 19 17:56:58 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Oct 19 17:56:58 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Oct 19 17:56:58 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 17:56:58 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:56:58 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 17:56:58 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:56:58 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:56:58 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:56:58 volumio volumio[995]: info: ------------------------------ 8ms Oct 19 17:56:58 volumio volumio[995]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 19 17:56:58 volumio volumio[995]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 19 17:56:58 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Oct 19 17:56:58 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Oct 19 17:56:58 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:56:58 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:56:58 volumio volumio[995]: verbose: In UPNP mode Oct 19 17:56:58 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1248,"samplerate":"8 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"我的未来式","artist":"郭采洁","album":"隐形超人","uri":"http://m801.music.126.net/20241019182156/5cec9af1fce6d8088eb98450c8389a5f/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/17289658266/5317/1435/e715/ce374a68ee97be2d60745d3b204942d7.flac","trackType":"flac"} Oct 19 17:56:58 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:56:58 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Oct 19 17:56:58 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Oct 19 17:56:58 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Oct 19 17:56:58 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 17:56:58 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:56:58 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:56:58 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:56:58 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:56:58 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:56:58 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:56:58 volumio volumio[995]: verbose: In UPNP mode Oct 19 17:56:58 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1248,"samplerate":"8 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"我的未来式","artist":"郭采洁","album":"隐形超人","uri":"http://m801.music.126.net/20241019182156/5cec9af1fce6d8088eb98450c8389a5f/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/17289658266/5317/1435/e715/ce374a68ee97be2d60745d3b204942d7.flac","trackType":"flac"} Oct 19 17:56:58 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:56:58 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Oct 19 17:56:58 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Oct 19 17:56:58 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Oct 19 17:56:58 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 17:56:58 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:56:58 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:56:58 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:56:58 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:56:58 volumio volumio[995]: info: ------------------------------ 16ms Oct 19 17:56:59 volumio volumio[995]: info: ------------------------------ 16ms Oct 19 17:56:59 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:56:59 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:56:59 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:56:59 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:56:59 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:56:59 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:56:59 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:56:59 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:56:59 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:56:59 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:56:59 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:56:59 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:56:59 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:56:59 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:56:59 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:56:59 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:56:59 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:56:59 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:57:10 volumio volumio[995]: info: Oct 19 17:57:10 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:57:10 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:57:10 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:57:10 volumio volumio[995]: info: Oct 19 17:57:10 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:57:10 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:57:10 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:57:10 volumio volumio[995]: info: sendMpdCommand status took 1 milliseconds Oct 19 17:57:10 volumio volumio[995]: info: sendMpdCommand status took 1 milliseconds Oct 19 17:57:10 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:57:10 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:57:10 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:57:10 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:57:10 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:10 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:10 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Oct 19 17:57:10 volumio volumio[995]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 19 17:57:10 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:57:10 volumio volumio[995]: info: CoreStateMachine::syncState stateService stop Oct 19 17:57:10 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Oct 19 17:57:10 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:10 volumio volumio[995]: info: CoreStateMachine::stPlaybackTimer Oct 19 17:57:10 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:57:10 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:57:10 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:10 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 17:57:10 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:10 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Oct 19 17:57:10 volumio volumio[995]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 19 17:57:10 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:57:10 volumio volumio[995]: info: CoreStateMachine::syncState stateService stop Oct 19 17:57:10 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus stop Oct 19 17:57:10 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:10 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:10 volumio volumio[995]: info: No code Oct 19 17:57:10 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:10 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:10 volumio volumio[995]: info: ------------------------------ 10ms Oct 19 17:57:10 volumio volumio[995]: info: ------------------------------ 10ms Oct 19 17:57:10 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:57:10 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:57:10 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:57:11 volumio volumio[995]: info: Clearing queue after UPNP request Oct 19 17:57:11 volumio volumio[995]: info: CoreStateMachine::ClearQueue Oct 19 17:57:11 volumio volumio[995]: info: CoreStateMachine::stop Oct 19 17:57:11 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 17:57:11 volumio volumio[995]: info: CorePlayQueue::clearPlayQueue Oct 19 17:57:11 volumio volumio[995]: info: CorePlayQueue::saveQueue Oct 19 17:57:11 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:11 volumio volumio[995]: info: CoreCommandRouter::volumioPushQueue Oct 19 17:57:11 volumio volumio[995]: info: Oct 19 17:57:11 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:11 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:11 volumio volumio[995]: info: Oct 19 17:57:11 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:11 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:11 volumio volumio[995]: error: updateQueue error: null Oct 19 17:57:11 volumio volumio[995]: error: updateQueue error: null Oct 19 17:57:11 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:57:11 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:11 volumio volumio[995]: info: Starting UPNP Playback Oct 19 17:57:11 volumio volumio[995]: info: Preparing playback through UPNP Oct 19 17:57:11 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Oct 19 17:57:11 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Oct 19 17:57:11 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 19 17:57:12 volumio volumio[995]: info: Oct 19 17:57:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:12 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:12 volumio volumio[995]: info: Oct 19 17:57:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:12 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:12 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:57:12 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:12 volumio volumio[995]: info: Oct 19 17:57:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:12 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:12 volumio volumio[995]: info: Oct 19 17:57:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:12 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:12 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:57:12 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:57:12 volumio volumio[995]: info: Oct 19 17:57:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:12 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:12 volumio volumio[995]: info: Oct 19 17:57:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:12 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:12 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:12 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:12 volumio volumio[995]: info: Oct 19 17:57:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:12 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:12 volumio volumio[995]: info: Oct 19 17:57:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:12 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:12 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:12 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:12 volumio volumio[995]: info: Oct 19 17:57:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:12 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:12 volumio volumio[995]: info: Oct 19 17:57:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:12 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:12 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:57:12 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:57:12 volumio volumio[995]: info: Oct 19 17:57:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:12 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:12 volumio volumio[995]: info: Oct 19 17:57:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:12 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:12 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:12 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:12 volumio volumio[995]: info: Oct 19 17:57:12 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:57:12 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:57:12 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:57:12 volumio volumio[995]: info: Oct 19 17:57:12 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:57:12 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:57:12 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:57:12 volumio volumio[995]: info: Oct 19 17:57:12 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:57:12 volumio volumio[995]: info: sendMpdCommand status took 1 milliseconds Oct 19 17:57:12 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:57:12 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:57:12 volumio volumio[995]: info: sendMpdCommand status took 0 milliseconds Oct 19 17:57:12 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:57:12 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 17:57:12 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:57:12 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 17:57:12 volumio volumio[995]: info: Oct 19 17:57:12 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:57:12 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:57:12 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:57:12 volumio volumio[995]: info: sendMpdCommand status took 1 milliseconds Oct 19 17:57:12 volumio volumio[995]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 19 17:57:12 volumio volumio[995]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 19 17:57:12 volumio volumio[995]: info: sendMpdCommand status took 0 milliseconds Oct 19 17:57:12 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:57:12 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 17:57:12 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Oct 19 17:57:12 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Oct 19 17:57:12 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:57:12 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 17:57:12 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:57:12 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:57:12 volumio volumio[995]: verbose: In UPNP mode Oct 19 17:57:12 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"8 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"我的未来式","artist":"郭采洁","album":"隐形超人","uri":"http://m801.music.126.net/20241019182211/805e9fca92c3dee0367e20095989f24a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/17289658217/78a0/7f83/a4f5/dcd8dbf87c53cf32559b3edf34d09ea7.m4a","trackType":"m4a"} Oct 19 17:57:12 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:57:12 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Oct 19 17:57:12 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus stop Oct 19 17:57:12 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:57:12 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:57:12 volumio volumio[995]: verbose: In UPNP mode Oct 19 17:57:12 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"8 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"我的未来式","artist":"郭采洁","album":"隐形超人","uri":"http://m801.music.126.net/20241019182211/805e9fca92c3dee0367e20095989f24a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/17289658217/78a0/7f83/a4f5/dcd8dbf87c53cf32559b3edf34d09ea7.m4a","trackType":"m4a"} Oct 19 17:57:12 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:57:12 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Oct 19 17:57:12 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Oct 19 17:57:12 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Oct 19 17:57:12 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 17:57:12 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:12 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 17:57:12 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:12 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:12 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:12 volumio volumio[995]: info: ------------------------------ 5ms Oct 19 17:57:12 volumio volumio[995]: info: ------------------------------ 7ms Oct 19 17:57:12 volumio volumio[995]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 19 17:57:12 volumio volumio[995]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 19 17:57:12 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Oct 19 17:57:12 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Oct 19 17:57:12 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:57:12 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:57:12 volumio volumio[995]: verbose: In UPNP mode Oct 19 17:57:12 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"8 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"我的未来式","artist":"郭采洁","album":"隐形超人","uri":"http://m801.music.126.net/20241019182211/805e9fca92c3dee0367e20095989f24a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/17289658217/78a0/7f83/a4f5/dcd8dbf87c53cf32559b3edf34d09ea7.m4a","trackType":"m4a"} Oct 19 17:57:12 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:57:12 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Oct 19 17:57:12 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Oct 19 17:57:12 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Oct 19 17:57:12 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 17:57:12 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:12 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:12 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:12 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:12 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:57:12 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:57:12 volumio volumio[995]: verbose: In UPNP mode Oct 19 17:57:12 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"8 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"我的未来式","artist":"郭采洁","album":"隐形超人","uri":"http://m801.music.126.net/20241019182211/805e9fca92c3dee0367e20095989f24a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/17289658217/78a0/7f83/a4f5/dcd8dbf87c53cf32559b3edf34d09ea7.m4a","trackType":"m4a"} Oct 19 17:57:12 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:57:12 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Oct 19 17:57:12 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Oct 19 17:57:12 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Oct 19 17:57:12 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 17:57:12 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:12 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:12 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:12 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:12 volumio volumio[995]: info: ------------------------------ 17ms Oct 19 17:57:12 volumio volumio[995]: info: ------------------------------ 16ms Oct 19 17:57:12 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:57:12 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:57:12 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:57:12 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:57:12 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:57:12 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:57:12 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:57:12 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:57:12 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:57:12 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:57:12 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:57:12 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:57:12 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:57:12 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:57:12 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:57:12 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:57:12 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:57:12 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:57:13 volumio volumio[995]: info: Oct 19 17:57:13 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:57:13 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:57:13 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:57:13 volumio volumio[995]: info: Oct 19 17:57:13 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:57:13 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:57:13 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:57:13 volumio volumio[995]: info: sendMpdCommand status took 1 milliseconds Oct 19 17:57:13 volumio volumio[995]: info: sendMpdCommand status took 1 milliseconds Oct 19 17:57:13 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:57:13 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:57:13 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:57:13 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:57:13 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:13 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:13 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Oct 19 17:57:13 volumio volumio[995]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 19 17:57:13 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:57:13 volumio volumio[995]: info: CoreStateMachine::syncState stateService stop Oct 19 17:57:13 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Oct 19 17:57:13 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:13 volumio volumio[995]: info: CoreStateMachine::stPlaybackTimer Oct 19 17:57:13 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:57:13 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:57:13 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:13 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 17:57:13 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:13 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Oct 19 17:57:13 volumio volumio[995]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 19 17:57:13 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:57:13 volumio volumio[995]: info: CoreStateMachine::syncState stateService stop Oct 19 17:57:13 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus stop Oct 19 17:57:13 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:13 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:13 volumio volumio[995]: info: No code Oct 19 17:57:13 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:13 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:13 volumio volumio[995]: info: ------------------------------ 12ms Oct 19 17:57:13 volumio volumio[995]: info: ------------------------------ 13ms Oct 19 17:57:13 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:57:13 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:57:13 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:57:16 volumio volumio[995]: info: Clearing queue after UPNP request Oct 19 17:57:16 volumio volumio[995]: info: CoreStateMachine::ClearQueue Oct 19 17:57:16 volumio volumio[995]: info: CoreStateMachine::stop Oct 19 17:57:16 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 17:57:16 volumio volumio[995]: info: CorePlayQueue::clearPlayQueue Oct 19 17:57:16 volumio volumio[995]: info: CorePlayQueue::saveQueue Oct 19 17:57:16 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:16 volumio volumio[995]: info: CoreCommandRouter::volumioPushQueue Oct 19 17:57:16 volumio volumio[995]: info: Oct 19 17:57:16 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:16 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:16 volumio volumio[995]: info: Oct 19 17:57:16 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:16 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:16 volumio volumio[995]: error: updateQueue error: null Oct 19 17:57:16 volumio volumio[995]: error: updateQueue error: null Oct 19 17:57:16 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:16 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:16 volumio volumio[995]: info: Starting UPNP Playback Oct 19 17:57:16 volumio volumio[995]: info: Preparing playback through UPNP Oct 19 17:57:16 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Oct 19 17:57:16 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Oct 19 17:57:16 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 19 17:57:17 volumio volumio[995]: info: Oct 19 17:57:17 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:17 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:17 volumio volumio[995]: info: Oct 19 17:57:17 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:17 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:17 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:57:17 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:57:17 volumio volumio[995]: info: Oct 19 17:57:17 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:17 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:17 volumio volumio[995]: info: Oct 19 17:57:17 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:17 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:17 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:57:17 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:57:17 volumio volumio[995]: info: Oct 19 17:57:17 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:17 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:17 volumio volumio[995]: info: Oct 19 17:57:17 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:17 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:17 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:17 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:17 volumio volumio[995]: info: Oct 19 17:57:17 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:17 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:17 volumio volumio[995]: info: Oct 19 17:57:17 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:17 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:17 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:57:17 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:17 volumio volumio[995]: info: Oct 19 17:57:17 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:17 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:17 volumio volumio[995]: info: Oct 19 17:57:17 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:17 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:17 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:57:17 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:57:17 volumio volumio[995]: info: Oct 19 17:57:17 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:17 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:17 volumio volumio[995]: info: Oct 19 17:57:17 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:17 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:17 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:17 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:57:17 volumio volumio[995]: info: Oct 19 17:57:17 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:57:17 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:57:17 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:57:17 volumio volumio[995]: info: Oct 19 17:57:17 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:57:17 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:57:17 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:57:17 volumio volumio[995]: info: Oct 19 17:57:17 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:57:17 volumio volumio[995]: info: sendMpdCommand status took 29 milliseconds Oct 19 17:57:17 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:57:17 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:57:17 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:57:17 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 17:57:17 volumio volumio[995]: info: sendMpdCommand status took 30 milliseconds Oct 19 17:57:17 volumio volumio[995]: info: sendMpdCommand status took 0 milliseconds Oct 19 17:57:17 volumio volumio[995]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 19 17:57:17 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:57:17 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 17:57:17 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:57:17 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 17:57:17 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Oct 19 17:57:17 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:57:17 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:57:17 volumio volumio[995]: verbose: In UPNP mode Oct 19 17:57:17 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":267,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"下一个天亮","artist":"郭静","album":"下一个天亮","uri":"http://m801.music.126.net/20241019182216/b935b49df92ee7adcd3595406969a477/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/14121562057/0c0d/fcd9/8971/05fea6e3a821265491fa7b7f687e02f6.m4a","trackType":"m4a"} Oct 19 17:57:17 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:57:17 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Oct 19 17:57:17 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus stop Oct 19 17:57:17 volumio volumio[995]: info: ------------------------------ 32ms Oct 19 17:57:17 volumio volumio[995]: info: Oct 19 17:57:17 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:57:17 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:57:17 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:57:17 volumio volumio[995]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 19 17:57:17 volumio volumio[995]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 19 17:57:17 volumio volumio[995]: info: sendMpdCommand status took 1 milliseconds Oct 19 17:57:17 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Oct 19 17:57:17 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Oct 19 17:57:17 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:57:17 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 17:57:17 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:57:17 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:57:17 volumio volumio[995]: verbose: In UPNP mode Oct 19 17:57:17 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":386,"duration":267,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"下一个天亮","artist":"郭静","album":"下一个天亮","uri":"http://m801.music.126.net/20241019182216/b935b49df92ee7adcd3595406969a477/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/14121562057/0c0d/fcd9/8971/05fea6e3a821265491fa7b7f687e02f6.m4a","trackType":"m4a"} Oct 19 17:57:17 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:57:17 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Oct 19 17:57:17 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Oct 19 17:57:17 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Oct 19 17:57:17 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 17:57:17 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:17 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 17:57:17 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:17 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:17 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:17 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:57:17 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:57:17 volumio volumio[995]: verbose: In UPNP mode Oct 19 17:57:17 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":267,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"下一个天亮","artist":"郭静","album":"下一个天亮","uri":"http://m801.music.126.net/20241019182216/b935b49df92ee7adcd3595406969a477/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/14121562057/0c0d/fcd9/8971/05fea6e3a821265491fa7b7f687e02f6.m4a","trackType":"m4a"} Oct 19 17:57:17 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:57:17 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Oct 19 17:57:17 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Oct 19 17:57:17 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Oct 19 17:57:17 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 17:57:17 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:17 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:17 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:17 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:17 volumio volumio[995]: info: ------------------------------ 38ms Oct 19 17:57:17 volumio volumio[995]: info: ------------------------------ 9ms Oct 19 17:57:17 volumio volumio[995]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 19 17:57:17 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Oct 19 17:57:17 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:57:17 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:57:17 volumio volumio[995]: verbose: In UPNP mode Oct 19 17:57:17 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":267,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"下一个天亮","artist":"郭静","album":"下一个天亮","uri":"http://m801.music.126.net/20241019182216/b935b49df92ee7adcd3595406969a477/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/14121562057/0c0d/fcd9/8971/05fea6e3a821265491fa7b7f687e02f6.m4a","trackType":"m4a"} Oct 19 17:57:17 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:57:17 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Oct 19 17:57:17 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Oct 19 17:57:17 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Oct 19 17:57:17 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 17:57:17 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:17 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:17 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:17 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:17 volumio volumio[995]: info: ------------------------------ 12ms Oct 19 17:57:17 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:57:17 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:57:17 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:57:17 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:57:17 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:57:17 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:57:17 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:57:17 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:57:17 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:57:17 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:57:17 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:57:17 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:57:17 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:57:17 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:57:17 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:57:17 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:57:17 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:57:17 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:57:19 volumio kernel: snd_hda_intel 0000:00:1b.0: IRQ timing workaround is activated for card #1. Suggest a bigger bdl_pos_adj. Oct 19 17:57:25 volumio volumio[995]: info: Clearing queue after UPNP request Oct 19 17:57:25 volumio volumio[995]: info: CoreStateMachine::ClearQueue Oct 19 17:57:25 volumio volumio[995]: info: CoreStateMachine::stop Oct 19 17:57:25 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 17:57:25 volumio volumio[995]: info: CoreStateMachine::stPlaybackTimer Oct 19 17:57:25 volumio volumio[995]: info: CoreStateMachine::updateTrackBlock Oct 19 17:57:25 volumio volumio[995]: info: CorePlayQueue::getTrackBlock Oct 19 17:57:25 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:25 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Oct 19 17:57:25 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 17:57:25 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:25 volumio volumio[995]: info: CoreStateMachine::serviceStop Oct 19 17:57:25 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Oct 19 17:57:25 volumio volumio[995]: info: ControllerMpd::stop Oct 19 17:57:25 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 17:57:25 volumio volumio[995]: info: CorePlayQueue::clearPlayQueue Oct 19 17:57:25 volumio volumio[995]: info: CorePlayQueue::saveQueue Oct 19 17:57:25 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:25 volumio volumio[995]: info: CoreCommandRouter::volumioPushQueue Oct 19 17:57:25 volumio volumio[995]: info: Oct 19 17:57:25 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:57:25 volumio volumio[995]: info: sendMpdCommand stop took 6 milliseconds Oct 19 17:57:25 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:57:25 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:57:25 volumio volumio[995]: info: Oct 19 17:57:25 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:25 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:25 volumio volumio[995]: info: sendMpdCommand status took 0 milliseconds Oct 19 17:57:25 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:57:25 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:57:25 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:57:25 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:25 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Oct 19 17:57:25 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:25 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Oct 19 17:57:25 volumio volumio[995]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 19 17:57:25 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:57:25 volumio volumio[995]: info: CoreStateMachine::syncState stateService stop Oct 19 17:57:25 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus stop Oct 19 17:57:25 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:25 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Oct 19 17:57:25 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:25 volumio volumio[995]: info: No code Oct 19 17:57:25 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:25 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Oct 19 17:57:25 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:25 volumio volumio[995]: info: ------------------------------ 9ms Oct 19 17:57:25 volumio volumio[995]: info: Oct 19 17:57:25 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:57:25 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:57:25 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:57:25 volumio volumio[995]: error: updateQueue error: null Oct 19 17:57:25 volumio volumio[995]: info: ------------------------------ 9ms Oct 19 17:57:25 volumio volumio[995]: info: sendMpdCommand status took 1 milliseconds Oct 19 17:57:25 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:57:25 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:57:25 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:57:25 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:25 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Oct 19 17:57:25 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:25 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Oct 19 17:57:25 volumio volumio[995]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 19 17:57:25 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:57:25 volumio volumio[995]: info: CoreStateMachine::syncState stateService stop Oct 19 17:57:25 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus stop Oct 19 17:57:25 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:25 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Oct 19 17:57:25 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:25 volumio volumio[995]: info: No code Oct 19 17:57:25 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:25 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Oct 19 17:57:25 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:25 volumio volumio[995]: info: ------------------------------ 10ms Oct 19 17:57:25 volumio volumio[995]: info: Oct 19 17:57:25 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:25 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:25 volumio volumio[995]: error: updateQueue error: null Oct 19 17:57:25 volumio volumio[995]: info: ------------------------------ 3ms Oct 19 17:57:25 volumio volumio[995]: info: Starting UPNP Playback Oct 19 17:57:25 volumio volumio[995]: info: Preparing playback through UPNP Oct 19 17:57:25 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Oct 19 17:57:25 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Oct 19 17:57:25 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 19 17:57:26 volumio volumio[995]: info: Oct 19 17:57:26 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:26 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:26 volumio volumio[995]: info: Oct 19 17:57:26 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:26 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:26 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:26 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:26 volumio volumio[995]: info: Oct 19 17:57:26 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:26 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:26 volumio volumio[995]: info: Oct 19 17:57:26 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:26 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:26 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:57:26 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:26 volumio volumio[995]: info: Oct 19 17:57:26 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:26 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:26 volumio volumio[995]: info: Oct 19 17:57:26 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:26 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:26 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:26 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:26 volumio volumio[995]: info: Oct 19 17:57:26 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:26 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:26 volumio volumio[995]: info: Oct 19 17:57:26 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:26 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:26 volumio volumio[995]: info: ------------------------------ 1ms Oct 19 17:57:26 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:26 volumio volumio[995]: info: Oct 19 17:57:26 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:26 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:26 volumio volumio[995]: info: Oct 19 17:57:26 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:26 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:26 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:26 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:26 volumio volumio[995]: info: Oct 19 17:57:26 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:26 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:26 volumio volumio[995]: info: Oct 19 17:57:26 volumio volumio[995]: ---------------------------- MPD announces system playlist update Oct 19 17:57:26 volumio volumio[995]: info: Ignoring MPD Status Update Oct 19 17:57:26 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:26 volumio volumio[995]: info: ------------------------------ 0ms Oct 19 17:57:26 volumio volumio[995]: info: Oct 19 17:57:26 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:57:26 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:57:26 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:57:26 volumio volumio[995]: info: Oct 19 17:57:26 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:57:26 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:57:26 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:57:26 volumio volumio[995]: info: Oct 19 17:57:26 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:57:26 volumio volumio[995]: info: sendMpdCommand status took 1 milliseconds Oct 19 17:57:26 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:57:26 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:57:26 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:57:26 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 17:57:26 volumio volumio[995]: info: sendMpdCommand status took 0 milliseconds Oct 19 17:57:26 volumio volumio[995]: info: sendMpdCommand status took 1 milliseconds Oct 19 17:57:26 volumio volumio[995]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 19 17:57:26 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:57:26 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 17:57:26 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:57:26 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 17:57:26 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Oct 19 17:57:26 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:57:26 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:57:26 volumio volumio[995]: verbose: In UPNP mode Oct 19 17:57:26 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":391,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"挪威的森林","artist":"伍佰","album":"我们的主打歌","uri":"http://m8.music.126.net/20241019182225/6fef54856bca0a8192c833d334731443/ymusic/73ab/367b/ae2a/bc7b6001616812830d0ec75129dae1f0.mp3","trackType":"mp3"} Oct 19 17:57:26 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:57:26 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Oct 19 17:57:26 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus stop Oct 19 17:57:26 volumio volumio[995]: info: ------------------------------ 2ms Oct 19 17:57:26 volumio volumio[995]: info: Oct 19 17:57:26 volumio volumio[995]: ---------------------------- MPD announces state update: player Oct 19 17:57:26 volumio volumio[995]: info: ControllerMpd::getState Oct 19 17:57:26 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Oct 19 17:57:26 volumio volumio[995]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 19 17:57:26 volumio volumio[995]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 19 17:57:26 volumio volumio[995]: info: sendMpdCommand status took 0 milliseconds Oct 19 17:57:26 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Oct 19 17:57:26 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Oct 19 17:57:26 volumio volumio[995]: verbose: ControllerMpd::parseState Oct 19 17:57:26 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 17:57:26 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:57:26 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:57:26 volumio volumio[995]: verbose: In UPNP mode Oct 19 17:57:26 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":391,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"挪威的森林","artist":"伍佰","album":"我们的主打歌","uri":"http://m8.music.126.net/20241019182225/6fef54856bca0a8192c833d334731443/ymusic/73ab/367b/ae2a/bc7b6001616812830d0ec75129dae1f0.mp3","trackType":"mp3"} Oct 19 17:57:26 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:57:26 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Oct 19 17:57:26 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Oct 19 17:57:26 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Oct 19 17:57:26 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 17:57:26 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:26 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 17:57:26 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:26 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:26 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:26 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:57:26 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:57:26 volumio volumio[995]: verbose: In UPNP mode Oct 19 17:57:26 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":391,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"挪威的森林","artist":"伍佰","album":"我们的主打歌","uri":"http://m8.music.126.net/20241019182225/6fef54856bca0a8192c833d334731443/ymusic/73ab/367b/ae2a/bc7b6001616812830d0ec75129dae1f0.mp3","trackType":"mp3"} Oct 19 17:57:26 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:57:26 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Oct 19 17:57:26 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Oct 19 17:57:26 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Oct 19 17:57:26 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 17:57:26 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:26 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:26 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:26 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:26 volumio volumio[995]: info: ------------------------------ 10ms Oct 19 17:57:26 volumio volumio[995]: info: ------------------------------ 10ms Oct 19 17:57:26 volumio volumio[995]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 19 17:57:26 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Oct 19 17:57:26 volumio volumio[995]: info: ControllerMpd::pushState Oct 19 17:57:26 volumio volumio[995]: info: CoreCommandRouter::servicePushState Oct 19 17:57:26 volumio volumio[995]: verbose: In UPNP mode Oct 19 17:57:26 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":391,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"挪威的森林","artist":"伍佰","album":"我们的主打歌","uri":"http://m8.music.126.net/20241019182225/6fef54856bca0a8192c833d334731443/ymusic/73ab/367b/ae2a/bc7b6001616812830d0ec75129dae1f0.mp3","trackType":"mp3"} Oct 19 17:57:26 volumio volumio[995]: verbose: CURRENT POSITION 0 Oct 19 17:57:26 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Oct 19 17:57:26 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Oct 19 17:57:26 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Oct 19 17:57:26 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 17:57:26 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:26 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:26 volumio volumio[995]: info: CoreStateMachine::pushState Oct 19 17:57:26 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Oct 19 17:57:26 volumio volumio[995]: info: ------------------------------ 13ms Oct 19 17:57:26 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:57:26 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:57:26 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:57:26 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:57:26 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:57:26 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:57:26 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:57:26 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:57:26 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:57:26 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:57:26 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:57:26 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:57:26 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:57:26 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:57:26 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:57:26 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Oct 19 17:57:26 volumio volumio[995]: info: CoreStateMachine::getQueue Oct 19 17:57:26 volumio volumio[995]: info: CorePlayQueue::getQueue Oct 19 17:57:47 volumio systemd[1]: Starting Cleanup of Temporary Directories... Oct 19 17:57:47 volumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Oct 19 17:57:47 volumio systemd[1]: Started Cleanup of Temporary Directories. Oct 19 17:57:48 volumio volumio[995]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 19 17:57:48 volumio volumio[995]: Error: connect ETIMEDOUT 159.65.107.38:443 Oct 19 17:57:48 volumio volumio[995]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 19 17:57:48 volumio volumio[995]: errno: -110, Oct 19 17:57:48 volumio volumio[995]: code: 'ETIMEDOUT', Oct 19 17:57:48 volumio volumio[995]: syscall: 'connect', Oct 19 17:57:48 volumio volumio[995]: address: '159.65.107.38', Oct 19 17:57:48 volumio volumio[995]: port: 443 Oct 19 17:57:48 volumio volumio[995]: } Oct 19 17:57:48 volumio volumio[995]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 19 17:57:48 volumio sudo[3887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-19 17:56 Oct 19 17:57:48 volumio sudo[3887]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"